2024-02-28T21:53:34.831Z,1709157214.831 [Supervisor](DEBUG): Initializing supervisor. 2024-02-28T21:53:34.835Z,1709157214.835 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-02-28T21:53:34.836Z,1709157214.836 [SyncHandler](INFO): Protected caller Thread ID is 837 2024-02-28T21:53:34.836Z,1709157214.836 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-02-28T21:53:34.837Z,1709157214.837 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-02-28T21:53:34.837Z,1709157214.837 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2024-02-28T21:53:34.841Z,1709157214.841 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-02-28T21:53:34.859Z,1709157214.859 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-02-28T21:53:34.860Z,1709157214.860 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-02-28T21:53:34.860Z,1709157214.860 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2024-02-28T21:53:34.865Z,1709157214.865 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-02-28T21:53:34.866Z,1709157214.866 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-02-28T21:53:34.866Z,1709157214.866 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2024-02-28T21:53:34.868Z,1709157214.868 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-02-28T21:53:34.869Z,1709157214.869 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-02-28T21:53:34.869Z,1709157214.869 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2024-02-28T21:53:34.873Z,1709157214.873 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-02-28T21:53:34.873Z,1709157214.873 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-02-28T21:53:34.877Z,1709157214.877 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-02-28T21:53:35.114Z,1709157215.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-02-28T21:53:35.116Z,1709157215.116 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-02-28T21:53:35.225Z,1709157215.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-02-28T21:53:35.227Z,1709157215.227 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-02-28T21:53:35.324Z,1709157215.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-02-28T21:53:35.326Z,1709157215.326 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-02-28T21:53:35.817Z,1709157215.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-02-28T21:53:35.818Z,1709157215.818 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-02-28T21:53:36.175Z,1709157216.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-02-28T21:53:36.177Z,1709157216.177 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-02-28T21:53:36.520Z,1709157216.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-02-28T21:53:36.521Z,1709157216.521 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-02-28T21:53:36.719Z,1709157216.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-02-28T21:53:36.720Z,1709157216.720 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-02-28T21:53:36.802Z,1709157216.802 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-02-28T21:53:36.885Z,1709157216.885 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-02-28T21:53:37.302Z,1709157217.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-02-28T21:53:37.304Z,1709157217.304 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-02-28T21:53:37.593Z,1709157217.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-02-28T21:53:37.593Z,1709157217.593 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-02-28T21:53:38.222Z,1709157218.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-02-28T21:53:38.574Z,1709157218.574 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-02-28T21:53:39.066Z,1709157219.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-02-28T21:53:39.068Z,1709157219.068 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-02-28T21:53:39.318Z,1709157219.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-02-28T21:53:39.318Z,1709157219.318 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-02-28T21:53:39.461Z,1709157219.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-02-28T21:53:39.463Z,1709157219.463 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-02-28T21:53:40.363Z,1709157220.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-02-28T21:53:40.365Z,1709157220.365 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2024-02-28T21:53:40.367Z,1709157220.367 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2024-02-28T21:53:40.453Z,1709157220.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2024-02-28T21:53:40.586Z,1709157220.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2024-02-28T21:53:40.866Z,1709157220.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-02-28T21:53:40.867Z,1709157220.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2024-02-28T21:53:40.993Z,1709157220.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2024-02-28T21:53:41.095Z,1709157221.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2024-02-28T21:53:41.190Z,1709157221.190 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2024-02-28T21:53:41.299Z,1709157221.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2024-02-28T21:53:41.395Z,1709157221.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2024-02-28T21:53:41.481Z,1709157221.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2024-02-28T21:53:41.584Z,1709157221.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/workSite.cfg 2024-02-28T21:53:41.667Z,1709157221.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2024-02-28T21:53:41.834Z,1709157221.834 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-02-28T21:53:41.845Z,1709157221.845 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-02-28T21:53:41.917Z,1709157221.917 [DepthRateCalculator] Loaded 2024-02-28T21:53:41.917Z,1709157221.917 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-02-28T21:53:41.923Z,1709157221.923 [PitchRateCalculator] Loaded 2024-02-28T21:53:41.923Z,1709157221.923 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-02-28T21:53:41.934Z,1709157221.934 [SpeedCalculator] Loaded 2024-02-28T21:53:41.934Z,1709157221.934 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-02-28T21:53:41.939Z,1709157221.939 [YawRateCalculator] Loaded 2024-02-28T21:53:41.940Z,1709157221.940 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-02-28T21:53:41.961Z,1709157221.961 [ElevatorOffsetCalculator] Loaded 2024-02-28T21:53:41.962Z,1709157221.962 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-02-28T21:53:41.962Z,1709157221.962 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-02-28T21:53:41.963Z,1709157221.963 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-02-28T21:53:42.006Z,1709157222.006 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-02-28T21:53:42.007Z,1709157222.007 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-02-28T21:53:42.022Z,1709157222.022 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-02-28T21:53:42.023Z,1709157222.023 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-02-28T21:53:42.807Z,1709157222.807 [AHRS_M2] Loaded 2024-02-28T21:53:42.807Z,1709157222.807 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-02-28T21:53:43.057Z,1709157223.057 [BackseatComponent] Loaded 2024-02-28T21:53:43.057Z,1709157223.057 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-02-28T21:53:43.058Z,1709157223.058 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0 2024-02-28T21:53:43.059Z,1709157223.059 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 927 2024-02-28T21:53:43.062Z,1709157223.062 [LcmUniversalReporter] Loaded 2024-02-28T21:53:43.062Z,1709157223.062 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-02-28T21:53:43.873Z,1709157223.873 [BPC1] Loaded 2024-02-28T21:53:43.873Z,1709157223.873 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-02-28T21:53:44.020Z,1709157224.020 [DataOverHttps] Loaded 2024-02-28T21:53:44.020Z,1709157224.020 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-02-28T21:53:44.021Z,1709157224.021 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0 2024-02-28T21:53:44.021Z,1709157224.021 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 928 2024-02-28T21:53:44.042Z,1709157224.042 [Depth_Keller] Loaded 2024-02-28T21:53:44.042Z,1709157224.042 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-02-28T21:53:44.047Z,1709157224.047 [DropWeight] Loaded 2024-02-28T21:53:44.048Z,1709157224.048 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-02-28T21:53:44.066Z,1709157224.066 [MultiRay] Loaded 2024-02-28T21:53:44.066Z,1709157224.066 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2024-02-28T21:53:44.128Z,1709157224.128 [NAL9602] Loaded 2024-02-28T21:53:44.128Z,1709157224.128 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-02-28T21:53:44.158Z,1709157224.158 [Onboard] Loaded 2024-02-28T21:53:44.158Z,1709157224.158 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-02-28T21:53:44.159Z,1709157224.159 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0 2024-02-28T21:53:44.159Z,1709157224.159 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 929 2024-02-28T21:53:44.165Z,1709157224.165 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2024-02-28T21:53:44.177Z,1709157224.177 [PowerOnly] Loaded 2024-02-28T21:53:44.177Z,1709157224.177 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-02-28T21:53:44.189Z,1709157224.189 [Power24vConverter] Loaded 2024-02-28T21:53:44.189Z,1709157224.189 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-02-28T21:53:44.204Z,1709157224.204 [Radio_Surface] Loaded 2024-02-28T21:53:44.204Z,1709157224.204 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-02-28T21:53:44.205Z,1709157224.205 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0 2024-02-28T21:53:44.205Z,1709157224.205 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 930 2024-02-28T21:53:44.297Z,1709157224.297 [DAT] Loaded 2024-02-28T21:53:44.297Z,1709157224.297 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-02-28T21:53:44.298Z,1709157224.298 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0 2024-02-28T21:53:44.299Z,1709157224.299 [DAT ThreadHandler](INFO): Protected caller Thread ID is 931 2024-02-28T21:53:44.299Z,1709157224.299 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-02-28T21:53:44.300Z,1709157224.300 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-02-28T21:53:44.739Z,1709157224.739 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-02-28T21:53:44.740Z,1709157224.740 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-02-28T21:53:44.928Z,1709157224.928 [DeadReckonUsingSpeedCalculator] Loaded 2024-02-28T21:53:44.929Z,1709157224.929 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-02-28T21:53:44.966Z,1709157224.966 [NavChart] Loaded 2024-02-28T21:53:44.966Z,1709157224.966 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-02-28T21:53:45.014Z,1709157225.014 [UniversalFixResidualReporter] Loaded 2024-02-28T21:53:45.014Z,1709157225.014 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-02-28T21:53:45.015Z,1709157225.015 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-02-28T21:53:45.016Z,1709157225.016 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-02-28T21:53:45.273Z,1709157225.273 [SBIT](DEBUG): Construct Startup Built In Test. 2024-02-28T21:53:45.287Z,1709157225.287 [SBIT] Loaded 2024-02-28T21:53:45.288Z,1709157225.288 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-02-28T21:53:45.290Z,1709157225.290 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-02-28T21:53:45.304Z,1709157225.304 [IBIT] Loaded 2024-02-28T21:53:45.304Z,1709157225.304 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-02-28T21:53:45.310Z,1709157225.310 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-02-28T21:53:45.415Z,1709157225.415 [CBIT] Loaded 2024-02-28T21:53:45.415Z,1709157225.415 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-02-28T21:53:45.416Z,1709157225.416 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-02-28T21:53:45.416Z,1709157225.416 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-02-28T21:53:45.641Z,1709157225.641 [CTD_Seabird] Loaded 2024-02-28T21:53:45.641Z,1709157225.641 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-02-28T21:53:45.642Z,1709157225.642 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0 2024-02-28T21:53:45.643Z,1709157225.643 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 932 2024-02-28T21:53:45.663Z,1709157225.663 [PAR_Licor] Loaded 2024-02-28T21:53:45.663Z,1709157225.663 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-02-28T21:53:45.705Z,1709157225.705 [WetLabsBB2FL] Loaded 2024-02-28T21:53:45.705Z,1709157225.705 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-02-28T21:53:45.706Z,1709157225.706 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0 2024-02-28T21:53:45.707Z,1709157225.707 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 933 2024-02-28T21:53:45.708Z,1709157225.708 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-02-28T21:53:45.708Z,1709157225.708 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-02-28T21:53:45.874Z,1709157225.874 [BuoyancyServo] Loaded 2024-02-28T21:53:45.874Z,1709157225.874 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-02-28T21:53:45.896Z,1709157225.896 [ElevatorServo] Loaded 2024-02-28T21:53:45.896Z,1709157225.896 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-02-28T21:53:45.917Z,1709157225.917 [MassServo] Loaded 2024-02-28T21:53:45.918Z,1709157225.918 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-02-28T21:53:45.938Z,1709157225.938 [RudderServo] Loaded 2024-02-28T21:53:45.938Z,1709157225.938 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-02-28T21:53:45.954Z,1709157225.954 [ThrusterHE] Loaded 2024-02-28T21:53:45.954Z,1709157225.954 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-02-28T21:53:45.955Z,1709157225.955 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-02-28T21:53:45.955Z,1709157225.955 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-02-28T21:53:46.025Z,1709157226.025 [VerticalControl](DEBUG): Construct VerticalControl. 2024-02-28T21:53:46.076Z,1709157226.076 [VerticalControl] Loaded 2024-02-28T21:53:46.076Z,1709157226.076 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-02-28T21:53:46.079Z,1709157226.079 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-02-28T21:53:46.118Z,1709157226.118 [HorizontalControl] Loaded 2024-02-28T21:53:46.118Z,1709157226.118 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-02-28T21:53:46.120Z,1709157226.120 [SpeedControl](DEBUG): Construct SpeedControl. 2024-02-28T21:53:46.122Z,1709157226.122 [SpeedControl] Loaded 2024-02-28T21:53:46.122Z,1709157226.122 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-02-28T21:53:46.125Z,1709157226.125 [LoopControl](DEBUG): Construct LoopControl. 2024-02-28T21:53:46.126Z,1709157226.126 [LoopControl] Loaded 2024-02-28T21:53:46.126Z,1709157226.126 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-02-28T21:53:46.126Z,1709157226.126 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-02-28T21:53:46.127Z,1709157226.127 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-02-28T21:53:46.272Z,1709157226.272 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-02-28T21:53:46.273Z,1709157226.273 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-02-28T21:53:46.459Z,1709157226.459 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-02-28T21:53:46.460Z,1709157226.460 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-02-28T21:53:46.871Z,1709157226.871 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-02-28T21:53:46.871Z,1709157226.871 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-02-28T21:53:46.957Z,1709157226.957 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-02-28T21:53:46.964Z,1709157226.964 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-02-28T21:53:46.967Z,1709157226.967 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-02-28T21:53:46.978Z,1709157226.978 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-02-28T21:53:46.979Z,1709157226.979 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C104E0 2024-02-28T21:53:46.979Z,1709157226.979 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 934 2024-02-28T21:53:46.984Z,1709157226.984 [Supervisor](INFO): Main Thread ID is 829 2024-02-28T21:53:46.984Z,1709157226.984 [Supervisor](DEBUG): Running supervisor. 2024-02-28T21:53:46.985Z,1709157226.985 [CommandExec ThreadHandler](INFO): Handler Thread ID is 935 2024-02-28T21:53:46.985Z,1709157226.985 [CommandExec](INFO): Initializing the command executive. 2024-02-28T21:53:46.987Z,1709157226.987 [CommandLine ThreadHandler](INFO): Handler Thread ID is 936 2024-02-28T21:53:46.989Z,1709157226.989 [controlThread ThreadHandler](INFO): Handler Thread ID is 937 2024-02-28T21:53:46.989Z,1709157226.989 [controlThread](DEBUG): Initializing ControlThread 2024-02-28T21:53:46.990Z,1709157226.990 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-02-28T21:53:46.990Z,1709157226.990 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-02-28T21:53:46.991Z,1709157226.991 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-02-28T21:53:46.991Z,1709157226.991 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-02-28T21:53:46.991Z,1709157226.991 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-02-28T21:53:46.998Z,1709157226.998 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-28T21:53:46.999Z,1709157226.999 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-02-28T21:53:46.999Z,1709157226.999 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-02-28T21:53:46.999Z,1709157226.999 [SBIT](INFO): Initialize SBIT Component. 2024-02-28T21:53:46.000Z,1709157227.000 [SBIT](IMPORTANT): git: 2024-02-28 2024-02-28T21:53:47.000Z,1709157227.000 [SBIT](INFO): git hash: 6d890a75647ce81d8a618abe9965886d38e67e29 2024-02-28T21:53:47.000Z,1709157227.000 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-02-28T21:53:47.002Z,1709157227.002 [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 2024-02-28T21:53:47.003Z,1709157227.003 [SBIT](INFO): Beginning SBIT in 21.000000 seconds. 2024-02-28T21:53:47.003Z,1709157227.003 [IBIT](INFO): Initialize IBIT Component. 2024-02-28T21:53:47.004Z,1709157227.004 [CBIT](DEBUG): Initialize CBIT Component. 2024-02-28T21:53:47.005Z,1709157227.005 [logger ThreadHandler](INFO): Handler Thread ID is 938 2024-02-28T21:53:47.015Z,1709157227.015 [CBIT](DEBUG): Initialized mux pins. 2024-02-28T21:53:47.015Z,1709157227.015 [CBIT](DEBUG): Initializing the watchdog timer. 2024-02-28T21:53:47.023Z,1709157227.023 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 939 2024-02-28T21:53:47.035Z,1709157227.035 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 940 2024-02-28T21:53:47.037Z,1709157227.037 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-02-28T21:53:47.039Z,1709157227.039 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-02-28T21:53:47.040Z,1709157227.040 [CBIT](DEBUG): Initializing heartbeat. 2024-02-28T21:53:47.047Z,1709157227.047 [Onboard ThreadHandler](INFO): Handler Thread ID is 941 2024-02-28T21:53:47.065Z,1709157227.065 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 942 2024-02-28T21:53:47.088Z,1709157227.088 [DAT ThreadHandler](INFO): Handler Thread ID is 943 2024-02-28T21:53:47.089Z,1709157227.089 [DAT](INFO): Powering up 2024-02-28T21:53:47.089Z,1709157227.089 [DAT](DEBUG): Initializing DAT. 2024-02-28T21:53:47.092Z,1709157227.092 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 945 2024-02-28T21:53:47.093Z,1709157227.093 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-02-28T21:53:47.096Z,1709157227.096 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 947 2024-02-28T21:53:47.100Z,1709157227.100 [WetLabsBB2FL](INFO): Powering up 2024-02-28T21:53:47.101Z,1709157227.101 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 949 2024-02-28T21:53:47.108Z,1709157227.108 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-02-28T21:53:47.108Z,1709157227.108 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-02-28T21:53:47.109Z,1709157227.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-02-28T21:53:47.109Z,1709157227.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-02-28T21:53:47.109Z,1709157227.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-02-28T21:53:47.109Z,1709157227.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-02-28T21:53:47.109Z,1709157227.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-02-28T21:53:47.109Z,1709157227.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-02-28T21:53:47.111Z,1709157227.111 [CBIT](DEBUG): Deactivating GF circuits. 2024-02-28T21:53:47.111Z,1709157227.111 [CBIT](DEBUG): Deactivating emergency mode. 2024-02-28T21:53:47.151Z,1709157227.151 [CBIT](DEBUG): Backplane powered. 2024-02-28T21:53:47.156Z,1709157227.156 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-02-28T21:53:47.158Z,1709157227.158 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-02-28T21:53:47.159Z,1709157227.159 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-02-28T21:53:47.159Z,1709157227.159 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-02-28T21:53:47.160Z,1709157227.160 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-02-28T21:53:47.161Z,1709157227.161 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-02-28T21:53:47.161Z,1709157227.161 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-02-28T21:53:47.167Z,1709157227.167 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-02-28T21:53:47.228Z,1709157227.228 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-02-28T21:53:47.232Z,1709157227.232 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-28T21:53:47.255Z,1709157227.255 [MissionManager](DEBUG): mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-02-28T21:53:47.256Z,1709157227.256 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2024-02-28T21:53:47.310Z,1709157227.310 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-02-28T21:53:47.311Z,1709157227.311 [Default:A.Wait](DEBUG): Construct Wait. 2024-02-28T21:53:47.314Z,1709157227.314 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-28T21:53:47.337Z,1709157227.337 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-02-28T21:53:47.348Z,1709157227.348 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-02-28T21:53:47.354Z,1709157227.354 [Default:E.Execute](DEBUG): Construct Execute. 2024-02-28T21:53:47.358Z,1709157227.358 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-02-28T21:53:47.369Z,1709157227.369 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-02-28T21:53:47.386Z,1709157227.386 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-02-28T21:53:47.471Z,1709157227.471 [Radio_Surface](INFO): Powering up 2024-02-28T21:53:47.525Z,1709157227.525 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2024-02-28T21:53:47.526Z,1709157227.526 [MultiRay](INFO): Powering up MultiRay Lights 2024-02-28T21:53:47.560Z,1709157227.560 [PowerOnly](INFO): Powering up loadControl 2024-02-28T21:53:47.580Z,1709157227.580 [Power24vConverter](INFO): Powering up. 2024-02-28T21:53:47.588Z,1709157227.588 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-02-28T21:53:47.612Z,1709157227.612 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-02-28T21:53:47.619Z,1709157227.619 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-02-28T21:53:47.620Z,1709157227.620 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-02-28T21:53:47.627Z,1709157227.627 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-02-28T21:53:47.628Z,1709157227.628 [MassServo](DEBUG): Initializing EZServoServo. 2024-02-28T21:53:47.636Z,1709157227.636 [MassServo](DEBUG): Initializing MassServo. 2024-02-28T21:53:47.637Z,1709157227.637 [RudderServo](DEBUG): Initializing EZServoServo. 2024-02-28T21:53:47.643Z,1709157227.643 [RudderServo](DEBUG): Initializing RudderServo. 2024-02-28T21:53:47.644Z,1709157227.644 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-02-28T21:53:47.651Z,1709157227.651 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-02-28T21:53:49.711Z,1709157229.711 [WetLabsBB2FL](INFO): Powering down 2024-02-28T21:53:53.184Z,1709157233.184 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-02-28T21:53:54.124Z,1709157234.124 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-02-28T21:53:59.944Z,1709157239.944 [DAT](INFO): DAT read: 2024-02-28T21:53:59.945Z,1709157239.945 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-02-28T21:54:01.709Z,1709157241.709 [DAT](INFO): DAT read: MF Frequency Band 2024-02-28T21:54:01.712Z,1709157241.712 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-02-28T21:54:01.713Z,1709157241.713 [DAT](INFO): DAT read: Feb 28 2024 21:53:55 2024-02-28T21:54:02.972Z,1709157242.972 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-02-28T21:54:02.973Z,1709157242.973 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-02-28T21:54:02.974Z,1709157242.974 [DAT](INFO): commRate: 800 2024-02-28T21:54:05.039Z,1709157245.039 [DAT](INFO): entering command mode 2024-02-28T21:54:05.239Z,1709157245.239 [DAT](INFO): DAT read: 2024-02-28T21:54:05.240Z,1709157245.240 [DAT](INFO): DAT read: user:1> 2024-02-28T21:54:05.240Z,1709157245.240 [DAT](INFO): setting verbose to 3 2024-02-28T21:54:05.492Z,1709157245.492 [DAT](INFO): DAT read: user:1> 2024-02-28T21:54:05.493Z,1709157245.493 [DAT](INFO): DAT read: Verbose | 3 2024-02-28T21:54:05.493Z,1709157245.493 [DAT](INFO): set verbose to 3 2024-02-28T21:54:05.493Z,1709157245.493 [DAT](INFO): setting DatVerbose to 27440 2024-02-28T21:54:05.744Z,1709157245.744 [DAT](INFO): DAT read: user:2> 2024-02-28T21:54:05.745Z,1709157245.745 [DAT](INFO): DAT read: DatVerbose | 27440 2024-02-28T21:54:05.745Z,1709157245.745 [DAT](INFO): set DatVerbose to 27440 2024-02-28T21:54:05.746Z,1709157245.746 [DAT](INFO): setting transmit power to 8 2024-02-28T21:54:05.996Z,1709157245.996 [DAT](INFO): DAT read: user:3> 2024-02-28T21:54:05.997Z,1709157245.997 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-02-28T21:54:05.997Z,1709157245.997 [DAT](INFO): set transmit power to 8 2024-02-28T21:54:05.997Z,1709157245.997 [DAT](INFO): setting local address to 9 2024-02-28T21:54:06.248Z,1709157246.248 [DAT](INFO): DAT read: user:4> 2024-02-28T21:54:06.249Z,1709157246.249 [DAT](INFO): DAT read: LocalAddr | 9 2024-02-28T21:54:06.249Z,1709157246.249 [DAT](INFO): set local address to 9 2024-02-28T21:54:06.250Z,1709157246.250 [DAT](INFO): Setting time to: 21:54:6 And date to:2/28/2024 2024-02-28T21:54:06.500Z,1709157246.500 [DAT](INFO): DAT read: user:5> 2024-02-28T21:54:06.501Z,1709157246.501 [DAT](INFO): DAT read: Wed Feb 28, 2024 21:54:06 2024-02-28T21:54:06.501Z,1709157246.501 [DAT](INFO): Local DAT time set to Wed Feb 28, 2024 21:54:06 2024-02-28T21:54:08.536Z,1709157248.536 [SBIT](IMPORTANT): Beginning Startup BIT 2024-02-28T21:54:08.540Z,1709157248.540 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-28T21:54:14.181Z,1709157254.181 [NAL9602](INFO): Powering up NAL9602 2024-02-28T21:54:19.491Z,1709157259.491 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006790 CHAN A1 (24V): 0.000577 CHAN A2 (12V): -0.005712 CHAN A3 (5V): -0.002292 CHAN B0 (3.3V): -0.001399 CHAN B1 (3.15aV): -0.001543 CHAN B2 (3.15bV): -0.001561 CHAN B3 (GND): -0.000597 OPEN: 0.005143 Full Scale: +/- 1 mA 2024-02-28T21:54:25.115Z,1709157265.115 [NAL9602](INFO): NAL9602 initialized 2024-02-28T21:55:02.680Z,1709157302.680 [SBIT](IMPORTANT): SBIT PASSED 2024-02-28T21:55:02.680Z,1709157302.680 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-02-28T21:55:02.681Z,1709157302.681 [SBIT](IMPORTANT): DAT.missionCritical=1 bool; 2024-02-28T21:55:02.681Z,1709157302.681 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool; 2024-02-28T21:55:02.681Z,1709157302.681 [SBIT](IMPORTANT): PowerOnly.sampleTime1=0 second; 2024-02-28T21:55:02.681Z,1709157302.681 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool; 2024-02-28T21:55:02.681Z,1709157302.681 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=276.938122 cubic_centimeter; 2024-02-28T21:55:02.681Z,1709157302.681 [SBIT](IMPORTANT): VerticalControl.massDefault=-0.304479 millimeter; 2024-02-28T21:55:03.082Z,1709157303.082 [MissionManager](IMPORTANT): Started mission Startup 2024-02-28T21:55:03.082Z,1709157303.082 [Startup] Running Loop=1 2024-02-28T21:55:03.083Z,1709157303.083 [Startup](DEBUG): Aggregate::initialize Startup 2024-02-28T21:55:03.083Z,1709157303.083 [Startup:A.GoToSurface] Running Loop=1 2024-02-28T21:55:03.087Z,1709157303.087 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-28T21:55:03.088Z,1709157303.088 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-28T21:55:03.088Z,1709157303.088 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-28T21:55:03.088Z,1709157303.088 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-28T21:55:03.089Z,1709157303.089 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-28T21:55:03.089Z,1709157303.089 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-28T21:55:03.090Z,1709157303.090 [Startup:StartupSatComms] Running Loop=1 2024-02-28T21:55:03.091Z,1709157303.091 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-02-28T21:55:03.091Z,1709157303.091 [Startup:StartupSatComms:A] Running Loop=1 2024-02-28T21:55:03.475Z,1709157303.475 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-02-28T21:55:32.173Z,1709157332.173 [NAL9602](INFO): SBD MO Status=0, MOMSN=15649, MT Status=0, MTMSN=0 2024-02-28T21:55:32.173Z,1709157332.173 [NAL9602](INFO): No messages in MT queue 2024-02-28T21:56:03.310Z,1709157363.310 [Startup:StartupSatComms:A](INFO): Timed out from 2024-02-28T21:55:03.1Z 2024-02-28T21:56:03.310Z,1709157363.310 [Startup:StartupSatComms:A] Stopped 2024-02-28T21:56:03.310Z,1709157363.310 [Startup:StartupSatComms:B] Running Loop=1 2024-02-28T21:56:03.705Z,1709157363.705 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-28T21:56:34.838Z,1709157394.838 [NAL9602](INFO): SBD MO Status=2, MOMSN=15650, MT Status=2, MTMSN=0 2024-02-28T21:56:34.838Z,1709157394.838 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-28T21:56:47.016Z,1709157407.016 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-02-28T21:56:47.016Z,1709157407.016 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-02-28T21:56:47.017Z,1709157407.017 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-02-28T21:56:47.017Z,1709157407.017 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-02-28T21:56:47.027Z,1709157407.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-02-28T21:56:47.027Z,1709157407.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-02-28T21:56:47.429Z,1709157407.429 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-02-28T21:56:47.429Z,1709157407.429 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-02-28T21:56:47.430Z,1709157407.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-02-28T21:56:47.430Z,1709157407.430 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-02-28T21:56:47.829Z,1709157407.829 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-28T21:56:57.924Z,1709157417.924 [NAL9602](INFO): SBD MO Status=2, MOMSN=15650, MT Status=2, MTMSN=0 2024-02-28T21:56:57.924Z,1709157417.924 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-28T21:56:59.130Z,1709157419.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215658.00,A,3648.15184,N,12147.22281,W,0.214,0.00,280224,,,A*72 2024-02-28T21:56:59.134Z,1709157419.134 [NAL9602](INFO): GPS fix at 20240228T215658: (36.802531, -121.787047) 2024-02-28T21:57:03.605Z,1709157423.605 [Startup:StartupSatComms:B](INFO): Timed out from 2024-02-28T21:56:03.3Z 2024-02-28T21:57:03.605Z,1709157423.605 [Startup:StartupSatComms:B] Stopped 2024-02-28T21:57:03.605Z,1709157423.605 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-02-28T21:57:03.605Z,1709157423.605 [Startup:StartupSatComms] Stopped 2024-02-28T21:57:03.605Z,1709157423.605 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-02-28T21:57:03.606Z,1709157423.606 [Startup](INFO): Completed Startup 2024-02-28T21:57:03.606Z,1709157423.606 [MissionManager](INFO): Startup is completed. 2024-02-28T21:57:03.606Z,1709157423.606 [MissionManager](INFO): Uninitializing Mission Startup 2024-02-28T21:57:03.606Z,1709157423.606 [Startup] Stopped 2024-02-28T21:57:03.607Z,1709157423.607 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-02-28T21:57:03.607Z,1709157423.607 [Startup:A.GoToSurface] Stopped 2024-02-28T21:57:03.607Z,1709157423.607 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-28T21:57:04.032Z,1709157424.032 [MissionManager](IMPORTANT): Started mission Default 2024-02-28T21:57:04.033Z,1709157424.033 [Default] Running Loop=1 2024-02-28T21:57:04.033Z,1709157424.033 [Default](DEBUG): Aggregate::initialize Default 2024-02-28T21:57:04.033Z,1709157424.033 [Default:B.GoToSurface] Running Loop=1 2024-02-28T21:57:04.033Z,1709157424.033 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-28T21:57:04.033Z,1709157424.033 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-02-28T21:57:04.033Z,1709157424.033 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-02-28T21:57:04.034Z,1709157424.034 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-02-28T21:57:04.034Z,1709157424.034 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-02-28T21:57:04.034Z,1709157424.034 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-28T21:57:04.035Z,1709157424.035 [Default:A.Wait] Running Loop=1 2024-02-28T21:57:04.035Z,1709157424.035 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-02-28T21:57:17.417Z,1709157437.417 [Default:A.Wait](INFO): Done Waiting. 2024-02-28T21:57:17.417Z,1709157437.417 [Default:A.Wait] Stopped 2024-02-28T21:57:17.417Z,1709157437.417 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-02-28T21:57:17.798Z,1709157437.798 [Default:CheckIn] Running Loop=1 2024-02-28T21:57:17.798Z,1709157437.798 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-28T21:57:17.798Z,1709157437.798 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-28T21:57:18.212Z,1709157438.212 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-02-28T21:57:21.333Z,1709157441.333 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004530 2024-02-28T21:57:23.846Z,1709157443.846 [NAL9602](INFO): SBD MO Status=2, MOMSN=15650, MT Status=2, MTMSN=0 2024-02-28T21:57:23.846Z,1709157443.846 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-28T21:57:25.058Z,1709157445.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215723.00,A,3648.15038,N,12147.22222,W,0.661,0.00,280224,,,A*76 2024-02-28T21:57:25.060Z,1709157445.060 [NAL9602](INFO): GPS fix at 20240228T215723: (36.802506, -121.787037) 2024-02-28T21:57:25.093Z,1709157445.093 [Default:CheckIn:Read_GPS] Stopped 2024-02-28T21:57:25.094Z,1709157445.094 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-28T21:57:25.504Z,1709157445.504 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-02-28T21:57:32.751Z,1709157452.751 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240228T180842/Courier0112.lzma 2024-02-28T21:57:33.753Z,1709157453.753 [DataOverHttps](INFO): Moved sent file to Logs/20240228T180842/Courier0112.lzma.bak 2024-02-28T21:57:33.754Z,1709157453.754 [DataOverHttps](INFO): SBD MOMSN=19302709 2024-02-28T21:57:49.739Z,1709157469.739 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240228T180842/Courier0115.lzma 2024-02-28T21:57:50.741Z,1709157470.741 [DataOverHttps](INFO): Moved sent file to Logs/20240228T180842/Courier0115.lzma.bak 2024-02-28T21:57:50.741Z,1709157470.741 [DataOverHttps](INFO): SBD MOMSN=19302711 2024-02-28T21:57:57.760Z,1709157477.760 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-28T21:58:06.848Z,1709157486.848 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240228T215334/Courier0000.lzma 2024-02-28T21:58:07.849Z,1709157487.849 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Courier0000.lzma.bak 2024-02-28T21:58:07.849Z,1709157487.849 [DataOverHttps](INFO): SBD MOMSN=19302713 2024-02-28T21:58:23.705Z,1709157503.705 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240228T215334/Courier0004.lzma 2024-02-28T21:58:24.705Z,1709157504.705 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Courier0004.lzma.bak 2024-02-28T21:58:24.705Z,1709157504.705 [DataOverHttps](INFO): SBD MOMSN=19302716 2024-02-28T21:58:40.644Z,1709157520.644 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240228T180842/Express0110.lzma 2024-02-28T21:58:41.645Z,1709157521.645 [DataOverHttps](INFO): Moved sent file to Logs/20240228T180842/Express0110.lzma.bak 2024-02-28T21:58:41.645Z,1709157521.645 [DataOverHttps](INFO): SBD MOMSN=19302719 2024-02-28T21:58:57.604Z,1709157537.604 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20240228T180842/Express0113.lzma 2024-02-28T21:58:58.605Z,1709157538.605 [DataOverHttps](INFO): Moved sent file to Logs/20240228T180842/Express0113.lzma.bak 2024-02-28T21:58:58.605Z,1709157538.605 [DataOverHttps](INFO): SBD MOMSN=19302722 2024-02-28T21:59:14.794Z,1709157554.794 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240228T180842/Express0116.lzma 2024-02-28T21:59:15.797Z,1709157555.797 [DataOverHttps](INFO): Moved sent file to Logs/20240228T180842/Express0116.lzma.bak 2024-02-28T21:59:15.798Z,1709157555.798 [DataOverHttps](INFO): SBD MOMSN=19302726 2024-02-28T21:59:31.617Z,1709157571.617 [DataOverHttps](INFO): Sending 765 bytes from file Logs/20240228T215334/Express0001.lzma 2024-02-28T21:59:32.617Z,1709157572.617 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Express0001.lzma.bak 2024-02-28T21:59:32.617Z,1709157572.617 [DataOverHttps](INFO): SBD MOMSN=19302728 2024-02-28T21:59:48.843Z,1709157588.843 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20240228T215334/Express0005.lzma 2024-02-28T21:59:49.845Z,1709157589.845 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Express0005.lzma.bak 2024-02-28T21:59:49.845Z,1709157589.845 [DataOverHttps](INFO): SBD MOMSN=19302746 2024-02-28T21:59:50.928Z,1709157590.928 [Default:CheckIn:Read_Iridium] Stopped 2024-02-28T21:59:50.928Z,1709157590.928 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-28T21:59:50.929Z,1709157590.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-28T22:02:17.950Z,1709157737.950 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2024-02-28T22:02:17.953Z,1709157737.953 [BPC1](INFO): Received data from all battery sticks. 2024-02-28T22:04:51.487Z,1709157891.487 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-28T22:04:51.491Z,1709157891.491 [Default:CheckIn:C.Wait] Stopped 2024-02-28T22:04:51.491Z,1709157891.491 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-28T22:04:51.491Z,1709157891.491 [Default:CheckIn:D] Running Loop=1 2024-02-28T22:04:51.884Z,1709157891.884 [Default:CheckIn:D] Stopped 2024-02-28T22:04:51.884Z,1709157891.884 [Default:CheckIn:E] Running Loop=1 2024-02-28T22:04:52.288Z,1709157892.288 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.797518 min 2024-02-28T22:04:52.288Z,1709157892.288 [Default:CheckIn:E] Stopped 2024-02-28T22:04:52.288Z,1709157892.288 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-28T22:04:52.288Z,1709157892.288 [Default:CheckIn] Stopped 2024-02-28T22:04:52.288Z,1709157892.288 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-28T22:04:52.289Z,1709157892.289 [Default:CheckIn](INFO): Running loop #2 2024-02-28T22:04:52.289Z,1709157892.289 [Default:CheckIn] Running Loop=2 2024-02-28T22:04:52.289Z,1709157892.289 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-28T22:04:52.289Z,1709157892.289 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-28T22:04:54.298Z,1709157894.298 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220453.00,A,3648.14973,N,12147.22333,W,0.078,0.00,280224,,,A*7C 2024-02-28T22:04:54.301Z,1709157894.301 [NAL9602](INFO): GPS fix at 20240228T220453: (36.802495, -121.787055) 2024-02-28T22:04:54.313Z,1709157894.313 [Default:CheckIn:Read_GPS] Stopped 2024-02-28T22:04:54.313Z,1709157894.313 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-28T22:05:01.691Z,1709157901.691 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240228T215334/Courier0007.lzma 2024-02-28T22:05:02.693Z,1709157902.693 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Courier0007.lzma.bak 2024-02-28T22:05:02.693Z,1709157902.693 [DataOverHttps](INFO): SBD MOMSN=19302755 2024-02-28T22:05:18.555Z,1709157918.555 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20240228T215334/Express0008.lzma 2024-02-28T22:05:18.936Z,1709157918.936 [NAL9602](INFO): SBD MO Status=2, MOMSN=15650, MT Status=2, MTMSN=0 2024-02-28T22:05:18.937Z,1709157918.937 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-28T22:05:19.557Z,1709157919.557 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Express0008.lzma.bak 2024-02-28T22:05:19.557Z,1709157919.557 [DataOverHttps](INFO): SBD MOMSN=19302758 2024-02-28T22:05:20.597Z,1709157920.597 [Default:CheckIn:Read_Iridium] Stopped 2024-02-28T22:05:20.597Z,1709157920.597 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-28T22:05:20.597Z,1709157920.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-28T22:05:52.470Z,1709157952.470 [NAL9602](INFO): SBD MO Status=1, MOMSN=15650, MT Status=0, MTMSN=0 2024-02-28T22:05:52.470Z,1709157952.470 [NAL9602](INFO): No messages in MT queue 2024-02-28T22:06:23.171Z,1709157983.171 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-28T22:10:21.139Z,1709158221.139 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-28T22:10:21.139Z,1709158221.139 [Default:CheckIn:C.Wait] Stopped 2024-02-28T22:10:21.139Z,1709158221.139 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-28T22:10:21.140Z,1709158221.140 [Default:CheckIn:D] Running Loop=1 2024-02-28T22:10:21.552Z,1709158221.552 [Default:CheckIn:D] Stopped 2024-02-28T22:10:21.552Z,1709158221.552 [Default:CheckIn:E] Running Loop=1 2024-02-28T22:10:21.960Z,1709158221.960 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.291985 min 2024-02-28T22:10:21.960Z,1709158221.960 [Default:CheckIn:E] Stopped 2024-02-28T22:10:21.960Z,1709158221.960 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-28T22:10:21.960Z,1709158221.960 [Default:CheckIn] Stopped 2024-02-28T22:10:21.960Z,1709158221.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-28T22:10:21.960Z,1709158221.960 [Default:CheckIn](INFO): Running loop #3 2024-02-28T22:10:21.960Z,1709158221.960 [Default:CheckIn] Running Loop=3 2024-02-28T22:10:21.961Z,1709158221.961 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-28T22:10:21.961Z,1709158221.961 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-28T22:10:23.957Z,1709158223.957 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221024.00,A,3648.35008,N,12147.11168,W,5.423,353.78,280224,,,A*76 2024-02-28T22:10:23.959Z,1709158223.959 [NAL9602](INFO): GPS fix at 20240228T221024: (36.805835, -121.785195) 2024-02-28T22:10:23.971Z,1709158223.971 [Default:CheckIn:Read_GPS] Stopped 2024-02-28T22:10:23.972Z,1709158223.972 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-28T22:10:30.918Z,1709158230.918 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240228T215334/Courier0010.lzma 2024-02-28T22:10:31.921Z,1709158231.921 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Courier0010.lzma.bak 2024-02-28T22:10:31.921Z,1709158231.921 [DataOverHttps](INFO): SBD MOMSN=19302763 2024-02-28T22:10:47.867Z,1709158247.867 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240228T215334/Express0011.lzma 2024-02-28T22:10:48.869Z,1709158248.869 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Express0011.lzma.bak 2024-02-28T22:10:48.869Z,1709158248.869 [DataOverHttps](INFO): SBD MOMSN=19302766 2024-02-28T22:10:50.249Z,1709158250.249 [Default:CheckIn:Read_Iridium] Stopped 2024-02-28T22:10:50.249Z,1709158250.249 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-28T22:10:50.249Z,1709158250.249 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-28T22:10:55.066Z,1709158255.066 [NAL9602](INFO): SBD MO Status=2, MOMSN=15651, MT Status=2, MTMSN=0 2024-02-28T22:10:55.066Z,1709158255.066 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-28T22:11:30.618Z,1709158290.618 [NAL9602](INFO): SBD MO Status=2, MOMSN=15651, MT Status=2, MTMSN=0 2024-02-28T22:11:30.618Z,1709158290.618 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-28T22:12:21.522Z,1709158341.522 [NAL9602](INFO): SBD MO Status=1, MOMSN=15651, MT Status=0, MTMSN=0 2024-02-28T22:12:21.522Z,1709158341.522 [NAL9602](INFO): No messages in MT queue 2024-02-28T22:12:52.228Z,1709158372.228 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-28T22:15:50.825Z,1709158550.825 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-28T22:15:50.825Z,1709158550.825 [Default:CheckIn:C.Wait] Stopped 2024-02-28T22:15:50.825Z,1709158550.825 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-28T22:15:50.825Z,1709158550.825 [Default:CheckIn:D] Running Loop=1 2024-02-28T22:15:51.214Z,1709158551.214 [Default:CheckIn:D] Stopped 2024-02-28T22:15:51.214Z,1709158551.214 [Default:CheckIn:E] Running Loop=1 2024-02-28T22:15:51.617Z,1709158551.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.786359 min 2024-02-28T22:15:51.617Z,1709158551.617 [Default:CheckIn:E] Stopped 2024-02-28T22:15:51.617Z,1709158551.617 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-28T22:15:51.617Z,1709158551.617 [Default:CheckIn] Stopped 2024-02-28T22:15:51.617Z,1709158551.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-28T22:15:51.617Z,1709158551.617 [Default:CheckIn](INFO): Running loop #4 2024-02-28T22:15:51.618Z,1709158551.618 [Default:CheckIn] Running Loop=4 2024-02-28T22:15:51.618Z,1709158551.618 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-28T22:15:51.618Z,1709158551.618 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-28T22:15:53.621Z,1709158553.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221553.00,A,3648.33580,N,12147.58461,W,8.747,268.52,280224,,,A*7C 2024-02-28T22:15:53.623Z,1709158553.623 [NAL9602](INFO): GPS fix at 20240228T221553: (36.805597, -121.793077) 2024-02-28T22:15:53.656Z,1709158553.656 [Default:CheckIn:Read_GPS] Stopped 2024-02-28T22:15:53.656Z,1709158553.656 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-28T22:16:01.274Z,1709158561.274 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240228T215334/Courier0013.lzma 2024-02-28T22:16:02.277Z,1709158562.277 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Courier0013.lzma.bak 2024-02-28T22:16:02.277Z,1709158562.277 [DataOverHttps](INFO): SBD MOMSN=19302777 2024-02-28T22:16:07.363Z,1709158567.363 [NAL9602](INFO): SBD MO Status=1, MOMSN=15652, MT Status=0, MTMSN=0 2024-02-28T22:16:07.363Z,1709158567.363 [NAL9602](INFO): No messages in MT queue 2024-02-28T22:16:19.255Z,1709158579.255 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240228T215334/Express0014.lzma 2024-02-28T22:16:20.257Z,1709158580.257 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Express0014.lzma.bak 2024-02-28T22:16:20.257Z,1709158580.257 [DataOverHttps](INFO): SBD MOMSN=19302780 2024-02-28T22:16:21.522Z,1709158581.522 [Default:CheckIn:Read_Iridium] Stopped 2024-02-28T22:16:21.522Z,1709158581.522 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-28T22:16:21.523Z,1709158581.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-28T22:16:38.059Z,1709158598.059 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-28T22:21:22.092Z,1709158882.092 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-28T22:21:22.092Z,1709158882.092 [Default:CheckIn:C.Wait] Stopped 2024-02-28T22:21:22.092Z,1709158882.092 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-28T22:21:22.092Z,1709158882.092 [Default:CheckIn:D] Running Loop=1 2024-02-28T22:21:22.497Z,1709158882.497 [Default:CheckIn:D] Stopped 2024-02-28T22:21:22.497Z,1709158882.497 [Default:CheckIn:E] Running Loop=1 2024-02-28T22:21:22.891Z,1709158882.891 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.307743 min 2024-02-28T22:21:22.891Z,1709158882.891 [Default:CheckIn:E] Stopped 2024-02-28T22:21:22.891Z,1709158882.891 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-28T22:21:22.892Z,1709158882.892 [Default:CheckIn] Stopped 2024-02-28T22:21:22.892Z,1709158882.892 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-28T22:21:22.892Z,1709158882.892 [Default:CheckIn](INFO): Running loop #5 2024-02-28T22:21:22.892Z,1709158882.892 [Default:CheckIn] Running Loop=5 2024-02-28T22:21:22.892Z,1709158882.892 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-28T22:21:22.892Z,1709158882.892 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-28T22:21:24.906Z,1709158884.906 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222124.00,A,3648.26625,N,12148.82571,W,12.305,272.10,280224,,,A*4F 2024-02-28T22:21:24.908Z,1709158884.908 [NAL9602](INFO): GPS fix at 20240228T222124: (36.804437, -121.813762) 2024-02-28T22:21:24.921Z,1709158884.921 [Default:CheckIn:Read_GPS] Stopped 2024-02-28T22:21:24.921Z,1709158884.921 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-28T22:21:41.474Z,1709158901.474 [NAL9602](INFO): SBD MO Status=1, MOMSN=15653, MT Status=0, MTMSN=0 2024-02-28T22:21:41.474Z,1709158901.474 [NAL9602](INFO): No messages in MT queue 2024-02-28T22:21:55.983Z,1709158915.983 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240228T215334/Courier0016.lzma 2024-02-28T22:21:56.985Z,1709158916.985 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Courier0016.lzma.bak 2024-02-28T22:21:56.985Z,1709158916.985 [DataOverHttps](INFO): SBD MOMSN=19302785 2024-02-28T22:22:12.168Z,1709158932.168 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-28T22:23:08.031Z,1709158988.031 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240228T215334/Express0017.lzma 2024-02-28T22:23:09.033Z,1709158989.033 [DataOverHttps](INFO): Moved sent file to Logs/20240228T215334/Express0017.lzma.bak 2024-02-28T22:23:09.033Z,1709158989.033 [DataOverHttps](INFO): SBD MOMSN=19302790 2024-02-28T22:23:10.372Z,1709158990.372 [Default:CheckIn:Read_Iridium] Stopped 2024-02-28T22:23:10.372Z,1709158990.372 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-28T22:23:10.372Z,1709158990.372 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-28T22:24:56.716Z,1709159096.716 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.635689 2024-02-28T22:26:01.975Z,1709159161.975 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:26:37.147Z,1709159197.147 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:27:12.295Z,1709159232.295 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:27:47.435Z,1709159267.435 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:28:10.938Z,1709159290.938 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-28T22:28:10.938Z,1709159290.938 [Default:CheckIn:C.Wait] Stopped 2024-02-28T22:28:10.938Z,1709159290.938 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-28T22:28:10.938Z,1709159290.938 [Default:CheckIn:D] Running Loop=1 2024-02-28T22:28:11.343Z,1709159291.343 [Default:CheckIn:D] Stopped 2024-02-28T22:28:11.344Z,1709159291.344 [Default:CheckIn:E] Running Loop=1 2024-02-28T22:28:11.749Z,1709159291.749 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.121847 min 2024-02-28T22:28:11.749Z,1709159291.749 [Default:CheckIn:E] Stopped 2024-02-28T22:28:11.749Z,1709159291.749 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-28T22:28:11.749Z,1709159291.749 [Default:CheckIn] Stopped 2024-02-28T22:28:11.750Z,1709159291.750 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-28T22:28:11.750Z,1709159291.750 [Default:CheckIn](INFO): Running loop #6 2024-02-28T22:28:11.750Z,1709159291.750 [Default:CheckIn] Running Loop=6 2024-02-28T22:28:11.750Z,1709159291.750 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-28T22:28:11.750Z,1709159291.750 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-28T22:28:13.754Z,1709159293.754 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222813.00,A,3648.15482,N,12148.95381,W,0.447,154.93,280224,,,A*7C 2024-02-28T22:28:13.757Z,1709159293.757 [NAL9602](INFO): GPS fix at 20240228T222813: (36.802580, -121.815897) 2024-02-28T22:28:13.823Z,1709159293.823 [Default:CheckIn:Read_GPS] Stopped 2024-02-28T22:28:13.823Z,1709159293.823 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-28T22:28:22.575Z,1709159302.575 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:28:29.981Z,1709159309.981 [NAL9602](INFO): SBD MO Status=1, MOMSN=15654, MT Status=0, MTMSN=0 2024-02-28T22:28:30.039Z,1709159310.039 [NAL9602](INFO): Sent 73 bytes from file Logs/20240228T215334/Courier0019.lzma 2024-02-28T22:28:30.039Z,1709159310.039 [NAL9602](INFO): Packets left to send: 0 2024-02-28T22:28:38.934Z,1709159318.934 [NAL9602](INFO): SBD MO Status=1, MOMSN=15655, MT Status=0, MTMSN=0 2024-02-28T22:28:38.983Z,1709159318.983 [NAL9602](INFO): Sent 129 bytes from file Logs/20240228T215334/Express0020.lzma 2024-02-28T22:28:38.984Z,1709159318.984 [NAL9602](INFO): Packets left to send: 0 2024-02-28T22:28:47.010Z,1709159327.010 [NAL9602](INFO): SBD MO Status=0, MOMSN=15656, MT Status=0, MTMSN=0 2024-02-28T22:28:47.093Z,1709159327.093 [Default:CheckIn:Read_Iridium] Stopped 2024-02-28T22:28:47.093Z,1709159327.093 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-28T22:28:47.093Z,1709159327.093 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-28T22:28:57.715Z,1709159337.715 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:29:17.714Z,1709159357.714 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-28T22:29:32.855Z,1709159372.855 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:30:07.997Z,1709159407.997 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:30:29.439Z,1709159429.439 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003072 2024-02-28T22:31:31.589Z,1709159491.589 [DataOverHttps](IMPORTANT): SBD MTMSN=20240228T223131 2024-02-28T22:31:39.055Z,1709159499.055 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 15 min;set transit.NeedCommsTime 15 min;set transit.Latitude 36.835 degree;set transit.Longitude -121.855 degree;run 2024-02-28T22:31:39.122Z,1709159499.122 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2024-02-28T22:31:39.122Z,1709159499.122 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2024-02-28T22:31:39.122Z,1709159499.122 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl 2024-02-28T22:31:39.125Z,1709159499.125 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl 2024-02-28T22:31:39.738Z,1709159499.738 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into: 60 30 NaN NaN 0 0 10 1 20 1 7 1 2024-02-28T22:31:39.744Z,1709159499.744 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2024-02-28T22:31:39.747Z,1709159499.747 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2024-02-28T22:31:39.750Z,1709159499.750 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2024-02-28T22:31:39.753Z,1709159499.753 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2024-02-28T22:31:39.768Z,1709159499.768 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2024-02-28T22:31:39.776Z,1709159499.776 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2024-02-28T22:31:39.778Z,1709159499.778 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2024-02-28T22:31:39.789Z,1709159499.789 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2024-02-28T22:31:39.794Z,1709159499.794 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2024-02-28T22:31:39.805Z,1709159499.805 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2024-02-28T22:31:39.810Z,1709159499.810 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2024-02-28T22:31:39.821Z,1709159499.821 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2024-02-28T22:31:39.826Z,1709159499.826 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2024-02-28T22:31:39.835Z,1709159499.835 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2024-02-28T22:31:39.835Z,1709159499.835 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2024-02-28T22:31:39.836Z,1709159499.836 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2024-02-28T22:31:40.184Z,1709159500.184 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2024-02-28T22:31:40.189Z,1709159500.189 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h 2024-02-28T22:31:40.192Z,1709159500.192 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2024-02-28T22:31:40.196Z,1709159500.196 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2024-02-28T22:31:40.198Z,1709159500.198 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2024-02-28T22:31:40.201Z,1709159500.201 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2024-02-28T22:31:40.204Z,1709159500.204 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2024-02-28T22:31:40.208Z,1709159500.208 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2024-02-28T22:31:40.210Z,1709159500.210 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2024-02-28T22:31:40.217Z,1709159500.217 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-28T22:31:40.258Z,1709159500.258 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-02-28T22:31:40.258Z,1709159500.258 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-02-28T22:31:40.272Z,1709159500.272 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-02-28T22:31:40.448Z,1709159500.448 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-02-28T22:31:40.452Z,1709159500.452 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2024-02-28T22:31:40.467Z,1709159500.467 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-02-28T22:31:40.470Z,1709159500.470 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2024-02-28T22:31:40.473Z,1709159500.473 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-02-28T22:31:40.474Z,1709159500.474 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-02-28T22:31:40.495Z,1709159500.495 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-02-28T22:31:40.516Z,1709159500.516 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-02-28T22:31:40.540Z,1709159500.540 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-02-28T22:31:40.540Z,1709159500.540 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-02-28T22:31:40.541Z,1709159500.541 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-02-28T22:31:40.570Z,1709159500.570 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-02-28T22:31:40.574Z,1709159500.574 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool 2024-02-28T22:31:40.589Z,1709159500.589 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-02-28T22:31:40.593Z,1709159500.593 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2024-02-28T22:31:40.597Z,1709159500.597 [transit:MassHold.Pitch](DEBUG): Construct. 2024-02-28T22:31:40.620Z,1709159500.620 [transit:Transit:A.Pitch](DEBUG): Construct. 2024-02-28T22:31:40.627Z,1709159500.627 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2024-02-28T22:31:40.639Z,1709159500.639 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2024-02-28T22:31:40.676Z,1709159500.676 [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" } } 2024-02-28T22:31:40.676Z,1709159500.676 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit 2024-02-28T22:31:46.753Z,1709159506.753 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 15 minute 2024-02-28T22:31:46.754Z,1709159506.754 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 15 minute 2024-02-28T22:31:46.755Z,1709159506.755 [CommandExec](IMPORTANT): got command set transit.Latitude 36.835 degree 2024-02-28T22:31:46.756Z,1709159506.756 [CommandExec](IMPORTANT): got command set transit.Longitude -121.855 degree 2024-02-28T22:31:46.756Z,1709159506.756 [CommandExec](IMPORTANT): got command run 2024-02-28T22:31:46.758Z,1709159506.758 [CommandExec](IMPORTANT): Running 2024-02-28T22:31:46.836Z,1709159506.836 [Default] Stopped 2024-02-28T22:31:46.836Z,1709159506.836 [Default](DEBUG): Aggregate::uninitialize Default 2024-02-28T22:31:46.836Z,1709159506.836 [Default:B.GoToSurface] Stopped 2024-02-28T22:31:46.836Z,1709159506.836 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-28T22:31:46.837Z,1709159506.837 [Default:CheckIn] Stopped 2024-02-28T22:31:46.837Z,1709159506.837 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-28T22:31:46.837Z,1709159506.837 [Default:CheckIn:C.Wait] Stopped 2024-02-28T22:31:46.837Z,1709159506.837 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-28T22:31:46.837Z,1709159506.837 [MissionManager](IMPORTANT): Started mission transit 2024-02-28T22:31:46.837Z,1709159506.837 [transit] Running Loop=1 2024-02-28T22:31:46.837Z,1709159506.837 [transit](DEBUG): Aggregate::initialize transit 2024-02-28T22:31:46.837Z,1709159506.837 [transit:StandardEnvelopes] Running Loop=1 2024-02-28T22:31:46.838Z,1709159506.838 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2024-02-28T22:31:46.838Z,1709159506.838 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-02-28T22:31:46.838Z,1709159506.838 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-02-28T22:31:46.838Z,1709159506.838 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-02-28T22:31:46.838Z,1709159506.838 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-02-28T22:31:46.838Z,1709159506.838 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-02-28T22:31:46.838Z,1709159506.838 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-02-28T22:31:46.838Z,1709159506.838 [transit:BackseatDriver] Running Loop=1 2024-02-28T22:31:46.839Z,1709159506.839 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver 2024-02-28T22:31:46.839Z,1709159506.839 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-02-28T22:31:46.843Z,1709159506.843 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2024-02-28T22:31:46.843Z,1709159506.843 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-02-28T22:31:46.843Z,1709159506.843 [transit:MassHold.Pitch] Running Loop=1 2024-02-28T22:31:46.843Z,1709159506.843 [transit:MassHold.Pitch](DEBUG): Initialize. 2024-02-28T22:31:46.844Z,1709159506.844 [transit:B] Running Loop=1 2024-02-28T22:31:46.844Z,1709159506.844 [transit:MassHold.Pitch] Running Loop=1 2024-02-28T22:31:46.845Z,1709159506.845 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2024-02-28T22:31:46.845Z,1709159506.845 [transit:BackseatDriver] Running Loop=1 2024-02-28T22:31:46.845Z,1709159506.845 [transit:StandardEnvelopes] Running Loop=1 2024-02-28T22:31:46.845Z,1709159506.845 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-02-28T22:31:46.846Z,1709159506.846 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-02-28T22:31:46.846Z,1709159506.846 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-02-28T22:31:46.851Z,1709159506.851 [transit:B] Stopped 2024-02-28T22:31:46.851Z,1709159506.851 [transit:C] Running Loop=1 2024-02-28T22:31:47.207Z,1709159507.207 [transit:C] Stopped 2024-02-28T22:31:47.207Z,1709159507.207 [transit:E] Running Loop=1 2024-02-28T22:31:47.622Z,1709159507.622 [transit:E] Stopped 2024-02-28T22:31:47.622Z,1709159507.622 [transit:F] Running Loop=1 2024-02-28T22:31:48.009Z,1709159508.009 [transit:F] Stopped 2024-02-28T22:31:48.009Z,1709159508.009 [transit:G] Running Loop=1 2024-02-28T22:31:48.413Z,1709159508.413 [transit:G] Stopped 2024-02-28T22:31:48.413Z,1709159508.413 [transit:Transit] Running Loop=1 2024-02-28T22:31:48.413Z,1709159508.413 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2024-02-28T22:31:48.413Z,1709159508.413 [transit:Transit:A.Pitch] Running Loop=1 2024-02-28T22:31:48.413Z,1709159508.413 [transit:Transit:A.Pitch](DEBUG): Initialize. 2024-02-28T22:31:48.413Z,1709159508.413 [transit:Transit:B.SetSpeed] Running Loop=1 2024-02-28T22:31:48.413Z,1709159508.413 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2024-02-28T22:31:48.414Z,1709159508.414 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2024-02-28T22:31:48.414Z,1709159508.414 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2024-02-28T22:31:48.416Z,1709159508.416 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.834999,-121.855003 2024-02-28T22:31:48.417Z,1709159508.417 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2024-02-28T22:31:48.826Z,1709159508.826 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.834999,-121.855003 2024-02-28T22:31:48.827Z,1709159508.827 [transit:Transit:B.SetSpeed] Running Loop=1 2024-02-28T22:31:48.827Z,1709159508.827 [transit:Transit:A.Pitch] Running Loop=1 2024-02-28T22:33:48.501Z,1709159628.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:33:51.511Z,1709159631.511 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247158 2024-02-28T22:34:01.029Z,1709159641.029 [Radio_Surface](INFO): Powering down 2024-02-28T22:34:23.639Z,1709159663.639 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:34:24.643Z,1709159664.643 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-02-28T22:43:14.825Z,1709160194.825 [transit:NeedComms] Running Loop=1 2024-02-28T22:43:14.825Z,1709160194.825 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2024-02-28T22:43:14.825Z,1709160194.825 [transit:NeedComms:B.GoToSurface] Running Loop=1 2024-02-28T22:43:14.825Z,1709160194.825 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-02-28T22:43:14.825Z,1709160194.825 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2024-02-28T22:43:14.826Z,1709160194.826 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2024-02-28T22:43:14.826Z,1709160194.826 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2024-02-28T22:43:14.826Z,1709160194.826 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2024-02-28T22:43:14.827Z,1709160194.827 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-02-28T22:43:14.827Z,1709160194.827 [transit:NeedComms:A] Running Loop=1 2024-02-28T22:43:14.829Z,1709160194.829 [transit:NeedComms:A](INFO): last time_fix was: 1709159293.000000 second since 1970/01/01T00:00:00Z 2024-02-28T22:43:14.829Z,1709160194.829 [transit:NeedComms:A] Stopped 2024-02-28T22:43:50.788Z,1709160230.788 [transit:NeedComms:C] Running Loop=1 2024-02-28T22:43:51.175Z,1709160231.175 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-02-28T22:43:51.291Z,1709160231.291 [Radio_Surface](INFO): Powering up 2024-02-28T22:43:57.048Z,1709160237.048 [DataOverHttps](INFO): Radio surface powered ON. 2024-02-28T22:44:27.183Z,1709160267.183 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:45:02.344Z,1709160302.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-28T22:45:24.894Z,1709160324.894 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=15657, MT Status=1, MTMSN=673 2024-02-28T22:45:24.894Z,1709160324.894 [NAL9602](INFO): Data available in MT queue