2023-09-12T22:02:52.343Z,1694556172.343 [Supervisor](DEBUG): Initializing supervisor. 2023-09-12T22:02:52.347Z,1694556172.347 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-09-12T22:02:52.347Z,1694556172.347 [SyncHandler](INFO): Protected caller Thread ID is 5773 2023-09-12T22:02:52.348Z,1694556172.348 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-09-12T22:02:52.349Z,1694556172.349 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-09-12T22:02:52.349Z,1694556172.349 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5774 2023-09-12T22:02:52.353Z,1694556172.353 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-09-12T22:02:52.373Z,1694556172.373 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-09-12T22:02:52.374Z,1694556172.374 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-09-12T22:02:52.375Z,1694556172.375 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5775 2023-09-12T22:02:52.379Z,1694556172.379 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-09-12T22:02:52.380Z,1694556172.380 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-09-12T22:02:52.380Z,1694556172.380 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5776 2023-09-12T22:02:52.383Z,1694556172.383 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-09-12T22:02:52.384Z,1694556172.384 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-09-12T22:02:52.384Z,1694556172.384 [logger ThreadHandler](INFO): Protected caller Thread ID is 5777 2023-09-12T22:02:52.388Z,1694556172.388 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-09-12T22:02:52.388Z,1694556172.388 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-09-12T22:02:52.394Z,1694556172.394 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-09-12T22:02:52.677Z,1694556172.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-09-12T22:02:52.678Z,1694556172.678 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-09-12T22:02:52.794Z,1694556172.794 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-09-12T22:02:52.796Z,1694556172.796 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-09-12T22:02:52.893Z,1694556172.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-09-12T22:02:52.894Z,1694556172.894 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-09-12T22:02:53.370Z,1694556173.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-09-12T22:02:53.371Z,1694556173.371 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-09-12T22:02:53.807Z,1694556173.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-09-12T22:02:53.809Z,1694556173.809 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-09-12T22:02:54.139Z,1694556174.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-09-12T22:02:54.139Z,1694556174.139 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-09-12T22:02:54.327Z,1694556174.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-09-12T22:02:54.328Z,1694556174.328 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-09-12T22:02:54.420Z,1694556174.420 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-09-12T22:02:54.504Z,1694556174.504 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-09-12T22:02:54.900Z,1694556174.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-09-12T22:02:54.902Z,1694556174.902 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-09-12T22:02:55.178Z,1694556175.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-09-12T22:02:55.180Z,1694556175.180 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-09-12T22:02:55.697Z,1694556175.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-09-12T22:02:55.699Z,1694556175.699 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-09-12T22:02:55.925Z,1694556175.925 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-09-12T22:02:55.925Z,1694556175.925 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-09-12T22:02:56.160Z,1694556176.160 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-09-12T22:02:56.160Z,1694556176.160 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-09-12T22:02:56.303Z,1694556176.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-09-12T22:02:56.304Z,1694556176.304 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-09-12T22:02:57.506Z,1694556177.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-09-12T22:02:57.508Z,1694556177.508 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-09-12T22:02:57.512Z,1694556177.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-09-12T22:02:57.601Z,1694556177.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-09-12T22:02:57.739Z,1694556177.739 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-09-12T22:02:58.007Z,1694556178.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-09-12T22:02:58.009Z,1694556178.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-09-12T22:02:58.135Z,1694556178.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-09-12T22:02:58.242Z,1694556178.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-09-12T22:02:58.339Z,1694556178.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-09-12T22:02:58.449Z,1694556178.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-09-12T22:02:58.547Z,1694556178.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-09-12T22:02:58.642Z,1694556178.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-09-12T22:02:58.745Z,1694556178.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-09-12T22:02:58.912Z,1694556178.912 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2023-09-12T22:02:58.912Z,1694556178.912 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-09-12T22:02:58.934Z,1694556178.934 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-09-12T22:02:59.003Z,1694556179.003 [DepthRateCalculator] Loaded 2023-09-12T22:02:59.003Z,1694556179.003 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-09-12T22:02:59.009Z,1694556179.009 [PitchRateCalculator] Loaded 2023-09-12T22:02:59.009Z,1694556179.009 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-09-12T22:02:59.019Z,1694556179.019 [SpeedCalculator] Loaded 2023-09-12T22:02:59.019Z,1694556179.019 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-09-12T22:02:59.024Z,1694556179.024 [YawRateCalculator] Loaded 2023-09-12T22:02:59.024Z,1694556179.024 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-09-12T22:02:59.045Z,1694556179.045 [ElevatorOffsetCalculator] Loaded 2023-09-12T22:02:59.046Z,1694556179.046 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-09-12T22:02:59.046Z,1694556179.046 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-09-12T22:02:59.048Z,1694556179.048 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-09-12T22:02:59.091Z,1694556179.091 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-09-12T22:02:59.091Z,1694556179.091 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-09-12T22:02:59.107Z,1694556179.107 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-09-12T22:02:59.107Z,1694556179.107 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-09-12T22:02:59.813Z,1694556179.813 [AHRS_M2] Loaded 2023-09-12T22:02:59.813Z,1694556179.813 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-09-12T22:02:59.000Z,1694556180.000 [BackseatComponent] Loaded 2023-09-12T22:03:00.000Z,1694556180.000 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-09-12T22:03:00.001Z,1694556180.001 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0 2023-09-12T22:03:00.002Z,1694556180.002 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5860 2023-09-12T22:03:00.004Z,1694556180.004 [LcmUniversalReporter] Loaded 2023-09-12T22:03:00.005Z,1694556180.005 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-09-12T22:03:00.801Z,1694556180.801 [BPC1] Loaded 2023-09-12T22:03:00.801Z,1694556180.801 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-09-12T22:03:00.928Z,1694556180.928 [DataOverHttps] Loaded 2023-09-12T22:03:00.929Z,1694556180.929 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-09-12T22:03:00.930Z,1694556180.930 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0 2023-09-12T22:03:00.930Z,1694556180.930 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5861 2023-09-12T22:03:00.954Z,1694556180.954 [Depth_Keller] Loaded 2023-09-12T22:03:00.954Z,1694556180.954 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-09-12T22:03:00.959Z,1694556180.959 [DropWeight] Loaded 2023-09-12T22:03:00.959Z,1694556180.959 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-09-12T22:03:01.021Z,1694556181.021 [NAL9602] Loaded 2023-09-12T22:03:01.021Z,1694556181.021 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-09-12T22:03:01.051Z,1694556181.051 [Onboard] Loaded 2023-09-12T22:03:01.051Z,1694556181.051 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-09-12T22:03:01.052Z,1694556181.052 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0 2023-09-12T22:03:01.052Z,1694556181.052 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5862 2023-09-12T22:03:01.058Z,1694556181.058 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-09-12T22:03:01.070Z,1694556181.070 [PowerOnly] Loaded 2023-09-12T22:03:01.070Z,1694556181.070 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-09-12T22:03:01.081Z,1694556181.081 [Power24vConverter] Loaded 2023-09-12T22:03:01.081Z,1694556181.081 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-09-12T22:03:01.095Z,1694556181.095 [Radio_Surface] Loaded 2023-09-12T22:03:01.096Z,1694556181.096 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-09-12T22:03:01.097Z,1694556181.097 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0 2023-09-12T22:03:01.097Z,1694556181.097 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5863 2023-09-12T22:03:01.098Z,1694556181.098 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-09-12T22:03:01.099Z,1694556181.099 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-09-12T22:03:01.238Z,1694556181.238 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-09-12T22:03:01.238Z,1694556181.238 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-09-12T22:03:01.279Z,1694556181.279 [DeadReckonUsingSpeedCalculator] Loaded 2023-09-12T22:03:01.279Z,1694556181.279 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-09-12T22:03:01.293Z,1694556181.293 [NavChart] Loaded 2023-09-12T22:03:01.294Z,1694556181.294 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-09-12T22:03:01.299Z,1694556181.299 [UniversalFixResidualReporter] Loaded 2023-09-12T22:03:01.299Z,1694556181.299 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-09-12T22:03:01.300Z,1694556181.300 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-09-12T22:03:01.301Z,1694556181.301 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-09-12T22:03:01.472Z,1694556181.472 [SBIT](DEBUG): Construct Startup Built In Test. 2023-09-12T22:03:01.490Z,1694556181.490 [SBIT] Loaded 2023-09-12T22:03:01.490Z,1694556181.490 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-09-12T22:03:01.493Z,1694556181.493 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-09-12T22:03:01.506Z,1694556181.506 [IBIT] Loaded 2023-09-12T22:03:01.507Z,1694556181.507 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-09-12T22:03:01.512Z,1694556181.512 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-09-12T22:03:01.615Z,1694556181.615 [CBIT] Loaded 2023-09-12T22:03:01.616Z,1694556181.616 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-09-12T22:03:01.616Z,1694556181.616 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-09-12T22:03:01.617Z,1694556181.617 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-09-12T22:03:01.840Z,1694556181.840 [CTD_Seabird] Loaded 2023-09-12T22:03:01.840Z,1694556181.840 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-09-12T22:03:01.841Z,1694556181.841 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0 2023-09-12T22:03:01.842Z,1694556181.842 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5864 2023-09-12T22:03:01.865Z,1694556181.865 [PAR_Licor] Loaded 2023-09-12T22:03:01.866Z,1694556181.866 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-09-12T22:03:01.906Z,1694556181.906 [WetLabsBB2FL] Loaded 2023-09-12T22:03:01.906Z,1694556181.906 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-09-12T22:03:01.907Z,1694556181.907 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0 2023-09-12T22:03:01.908Z,1694556181.908 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5865 2023-09-12T22:03:01.909Z,1694556181.909 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-09-12T22:03:01.909Z,1694556181.909 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-09-12T22:03:02.075Z,1694556182.075 [BuoyancyServo] Loaded 2023-09-12T22:03:02.076Z,1694556182.076 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-09-12T22:03:02.098Z,1694556182.098 [ElevatorServo] Loaded 2023-09-12T22:03:02.098Z,1694556182.098 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-09-12T22:03:02.120Z,1694556182.120 [MassServo] Loaded 2023-09-12T22:03:02.120Z,1694556182.120 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-09-12T22:03:02.140Z,1694556182.140 [RudderServo] Loaded 2023-09-12T22:03:02.140Z,1694556182.140 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-09-12T22:03:02.156Z,1694556182.156 [ThrusterHE] Loaded 2023-09-12T22:03:02.156Z,1694556182.156 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-09-12T22:03:02.157Z,1694556182.157 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-09-12T22:03:02.157Z,1694556182.157 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-09-12T22:03:02.398Z,1694556182.398 [VerticalControl](DEBUG): Construct VerticalControl. 2023-09-12T22:03:02.590Z,1694556182.590 [VerticalControl] Loaded 2023-09-12T22:03:02.590Z,1694556182.590 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-09-12T22:03:02.593Z,1694556182.593 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-09-12T22:03:02.669Z,1694556182.669 [HorizontalControl] Loaded 2023-09-12T22:03:02.670Z,1694556182.670 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-09-12T22:03:02.672Z,1694556182.672 [SpeedControl](DEBUG): Construct SpeedControl. 2023-09-12T22:03:02.674Z,1694556182.674 [SpeedControl] Loaded 2023-09-12T22:03:02.674Z,1694556182.674 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-09-12T22:03:02.677Z,1694556182.677 [LoopControl](DEBUG): Construct LoopControl. 2023-09-12T22:03:02.677Z,1694556182.677 [LoopControl] Loaded 2023-09-12T22:03:02.677Z,1694556182.677 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-09-12T22:03:02.678Z,1694556182.678 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-09-12T22:03:02.678Z,1694556182.678 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-09-12T22:03:02.889Z,1694556182.889 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-09-12T22:03:02.890Z,1694556182.890 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-09-12T22:03:03.071Z,1694556183.071 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-09-12T22:03:03.071Z,1694556183.071 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-09-12T22:03:03.432Z,1694556183.432 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-09-12T22:03:03.432Z,1694556183.432 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-09-12T22:03:03.535Z,1694556183.535 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC 2023-09-12T22:03:03.536Z,1694556183.536 [StratificationFrontDetector](DEBUG): (re)initializing 2023-09-12T22:03:03.536Z,1694556183.536 [StratificationFrontDetector] Loaded 2023-09-12T22:03:03.536Z,1694556183.536 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2023-09-12T22:03:03.537Z,1694556183.537 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-09-12T22:03:03.543Z,1694556183.543 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-09-12T22:03:03.546Z,1694556183.546 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-09-12T22:03:03.557Z,1694556183.557 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-09-12T22:03:03.558Z,1694556183.558 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0 2023-09-12T22:03:03.558Z,1694556183.558 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5866 2023-09-12T22:03:03.564Z,1694556183.564 [Supervisor](INFO): Main Thread ID is 5772 2023-09-12T22:03:03.564Z,1694556183.564 [Supervisor](DEBUG): Running supervisor. 2023-09-12T22:03:03.565Z,1694556183.565 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5867 2023-09-12T22:03:03.565Z,1694556183.565 [CommandExec](INFO): Initializing the command executive. 2023-09-12T22:03:03.567Z,1694556183.567 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5868 2023-09-12T22:03:03.570Z,1694556183.570 [controlThread ThreadHandler](INFO): Handler Thread ID is 5869 2023-09-12T22:03:03.571Z,1694556183.571 [controlThread](DEBUG): Initializing ControlThread 2023-09-12T22:03:03.572Z,1694556183.572 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-09-12T22:03:03.572Z,1694556183.572 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-09-12T22:03:03.572Z,1694556183.572 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-09-12T22:03:03.573Z,1694556183.573 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-09-12T22:03:03.573Z,1694556183.573 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-09-12T22:03:03.583Z,1694556183.583 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-12T22:03:03.583Z,1694556183.583 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-09-12T22:03:03.584Z,1694556183.584 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-09-12T22:03:03.584Z,1694556183.584 [SBIT](INFO): Initialize SBIT Component. 2023-09-12T22:03:03.584Z,1694556183.584 [SBIT](IMPORTANT): git: 2023-08-21 2023-09-12T22:03:03.585Z,1694556183.585 [SBIT](INFO): git hash: a90fa4c688a1aaa9675a08dc769bbd0e140c3a66 2023-09-12T22:03:03.585Z,1694556183.585 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-09-12T22:03:03.586Z,1694556183.586 [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-12T22:03:03.587Z,1694556183.587 [SBIT](INFO): Beginning SBIT in 59.000000 seconds. 2023-09-12T22:03:03.588Z,1694556183.588 [IBIT](INFO): Initialize IBIT Component. 2023-09-12T22:03:03.589Z,1694556183.589 [CBIT](DEBUG): Initialize CBIT Component. 2023-09-12T22:03:03.590Z,1694556183.590 [logger ThreadHandler](INFO): Handler Thread ID is 5870 2023-09-12T22:03:03.602Z,1694556183.602 [CBIT](DEBUG): Initialized mux pins. 2023-09-12T22:03:03.602Z,1694556183.602 [CBIT](DEBUG): Initializing the watchdog timer. 2023-09-12T22:03:03.610Z,1694556183.610 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5871 2023-09-12T22:03:03.622Z,1694556183.622 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5872 2023-09-12T22:03:03.623Z,1694556183.623 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-09-12T22:03:03.626Z,1694556183.626 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-09-12T22:03:03.626Z,1694556183.626 [CBIT](DEBUG): Initializing heartbeat. 2023-09-12T22:03:03.642Z,1694556183.642 [Onboard ThreadHandler](INFO): Handler Thread ID is 5873 2023-09-12T22:03:03.659Z,1694556183.659 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5874 2023-09-12T22:03:03.678Z,1694556183.678 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5875 2023-09-12T22:03:03.679Z,1694556183.679 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-09-12T22:03:03.683Z,1694556183.683 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5877 2023-09-12T22:03:03.686Z,1694556183.686 [WetLabsBB2FL](INFO): Powering up 2023-09-12T22:03:03.688Z,1694556183.688 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5879 2023-09-12T22:03:03.693Z,1694556183.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-09-12T22:03:03.693Z,1694556183.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-09-12T22:03:03.694Z,1694556183.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-09-12T22:03:03.695Z,1694556183.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-09-12T22:03:03.695Z,1694556183.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-09-12T22:03:03.695Z,1694556183.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-09-12T22:03:03.695Z,1694556183.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-09-12T22:03:03.695Z,1694556183.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-09-12T22:03:03.698Z,1694556183.698 [CBIT](DEBUG): Deactivating GF circuits. 2023-09-12T22:03:03.698Z,1694556183.698 [CBIT](DEBUG): Deactivating emergency mode. 2023-09-12T22:03:03.738Z,1694556183.738 [CBIT](DEBUG): Backplane powered. 2023-09-12T22:03:03.743Z,1694556183.743 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-09-12T22:03:03.745Z,1694556183.745 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-09-12T22:03:03.746Z,1694556183.746 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-09-12T22:03:03.746Z,1694556183.746 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-09-12T22:03:03.747Z,1694556183.747 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-09-12T22:03:03.786Z,1694556183.786 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-12T22:03:03.822Z,1694556183.822 [MissionManager](DEBUG): 2023-09-12T22:03:03.823Z,1694556183.823 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-09-12T22:03:03.884Z,1694556183.884 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-09-12T22:03:03.885Z,1694556183.885 [Default:A.Wait](DEBUG): Construct Wait. 2023-09-12T22:03:03.887Z,1694556183.887 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-09-12T22:03:03.911Z,1694556183.911 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-09-12T22:03:03.913Z,1694556183.913 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-09-12T22:03:03.957Z,1694556183.957 [Default:E.Execute](DEBUG): Construct Execute. 2023-09-12T22:03:03.961Z,1694556183.961 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-09-12T22:03:03.966Z,1694556183.966 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-09-12T22:03:03.972Z,1694556183.972 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-09-12T22:03:04.049Z,1694556184.049 [Power24vConverter](INFO): Powering up. 2023-09-12T22:03:04.066Z,1694556184.066 [Radio_Surface](INFO): Powering up 2023-09-12T22:03:04.100Z,1694556184.100 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-09-12T22:03:04.106Z,1694556184.106 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-09-12T22:03:04.107Z,1694556184.107 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-09-12T22:03:04.114Z,1694556184.114 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-09-12T22:03:04.115Z,1694556184.115 [MassServo](DEBUG): Initializing EZServoServo. 2023-09-12T22:03:04.126Z,1694556184.126 [MassServo](DEBUG): Initializing MassServo. 2023-09-12T22:03:04.127Z,1694556184.127 [RudderServo](DEBUG): Initializing EZServoServo. 2023-09-12T22:03:04.134Z,1694556184.134 [RudderServo](DEBUG): Initializing RudderServo. 2023-09-12T22:03:04.135Z,1694556184.135 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-09-12T22:03:04.142Z,1694556184.142 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-09-12T22:03:06.298Z,1694556186.298 [WetLabsBB2FL](INFO): Powering down 2023-09-12T22:03:09.888Z,1694556189.888 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-09-12T22:03:10.711Z,1694556190.711 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-09-12T22:03:14.168Z,1694556194.168 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008551 2023-09-12T22:03:31.047Z,1694556211.047 [NAL9602](INFO): Powering up NAL9602 2023-09-12T22:03:41.971Z,1694556221.971 [NAL9602](INFO): NAL9602 initialized 2023-09-12T22:03:44.377Z,1694556224.377 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2023-09-12T22:03:44.377Z,1694556224.377 [CommandExec](IMPORTANT): platform_mass_position -0.997284 cm 2023-09-12T22:03:51.319Z,1694556231.319 [CommandExec](IMPORTANT): got command show variable limitAft 2023-09-12T22:03:51.444Z,1694556231.444 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft (millimeter) 2023-09-12T22:04:03.004Z,1694556243.004 [SBIT](IMPORTANT): Beginning Startup BIT 2023-09-12T22:04:03.008Z,1694556243.008 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-12T22:04:06.704Z,1694556246.704 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitAft 2023-09-12T22:04:06.704Z,1694556246.704 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft -27.000000 mm 2023-09-12T22:04:06.705Z,1694556246.705 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitFwd 2023-09-12T22:04:06.705Z,1694556246.705 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd 38.000000 mm 2023-09-12T22:04:13.938Z,1694556253.938 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006229 CHAN A1 (24V): 0.001213 CHAN A2 (12V): -0.004870 CHAN A3 (5V): -0.002952 CHAN B0 (3.3V): -0.000852 CHAN B1 (3.15aV): -0.001075 CHAN B2 (3.15bV): -0.001308 CHAN B3 (GND): 0.000162 OPEN: 0.004753 Full Scale: +/- 1 mA 2023-09-12T22:04:20.332Z,1694556260.332 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 0 centimeter persist 2023-09-12T22:04:26.963Z,1694556266.963 [CommandExec](IMPORTANT): got command get platform_mass_position 2023-09-12T22:04:26.963Z,1694556266.963 [CommandExec](IMPORTANT): platform_mass_position -0.008635 m 2023-09-12T22:04:31.436Z,1694556271.436 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2023-09-12T22:04:31.437Z,1694556271.437 [CommandExec](IMPORTANT): platform_mass_position -1.210872 cm 2023-09-12T22:04:43.606Z,1694556283.606 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position 2023-09-12T22:04:43.829Z,1694556283.829 [Reporter](INFO): platform_buoyancy_position 962.500854 cc 2023-09-12T22:04:51.746Z,1694556291.746 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2023-09-12T22:04:51.747Z,1694556291.747 [CommandExec](IMPORTANT): platform_mass_position -0.996963 cm 2023-09-12T22:04:57.142Z,1694556297.142 [SBIT](IMPORTANT): SBIT PASSED 2023-09-12T22:04:57.142Z,1694556297.142 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-09-12T22:04:57.143Z,1694556297.143 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour; 2023-09-12T22:04:57.143Z,1694556297.143 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-09-12T22:04:57.143Z,1694556297.143 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express none _.ayeris_particle_shape; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express none _.ayeris_particle_slope; 2023-09-12T22:04:57.144Z,1694556297.144 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2023-09-12T22:04:57.145Z,1694556297.145 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2023-09-12T22:04:57.145Z,1694556297.145 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2023-09-12T22:04:57.145Z,1694556297.145 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool; 2023-09-12T22:04:57.145Z,1694556297.145 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool; 2023-09-12T22:04:57.145Z,1694556297.145 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=381.902688 cubic_centimeter; 2023-09-12T22:04:57.145Z,1694556297.145 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter; 2023-09-12T22:04:57.145Z,1694556297.145 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter; 2023-09-12T22:04:57.145Z,1694556297.145 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter; 2023-09-12T22:04:57.146Z,1694556297.146 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2023-09-12T22:04:57.525Z,1694556297.525 [MissionManager](IMPORTANT): Started mission Startup 2023-09-12T22:04:57.525Z,1694556297.525 [Startup] Running Loop=1 2023-09-12T22:04:57.525Z,1694556297.525 [Startup](DEBUG): Aggregate::initialize Startup 2023-09-12T22:04:57.526Z,1694556297.526 [Startup:A.GoToSurface] Running Loop=1 2023-09-12T22:04:57.526Z,1694556297.526 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-12T22:04:57.526Z,1694556297.526 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-12T22:04:57.527Z,1694556297.527 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-12T22:04:57.527Z,1694556297.527 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-12T22:04:57.528Z,1694556297.528 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-12T22:04:57.528Z,1694556297.528 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-12T22:04:57.529Z,1694556297.529 [Startup:StartupSatComms] Running Loop=1 2023-09-12T22:04:57.529Z,1694556297.529 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-09-12T22:04:57.529Z,1694556297.529 [Startup:StartupSatComms:A] Running Loop=1 2023-09-12T22:04:57.938Z,1694556297.938 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-09-12T22:05:02.912Z,1694556302.912 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2023-09-12T22:05:02.912Z,1694556302.912 [CommandExec](IMPORTANT): platform_mass_position -0.715175 cm 2023-09-12T22:05:07.799Z,1694556307.799 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2023-09-12T22:05:07.799Z,1694556307.799 [CommandExec](IMPORTANT): platform_mass_position -0.337669 cm 2023-09-12T22:05:11.811Z,1694556311.811 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2023-09-12T22:05:11.812Z,1694556311.812 [CommandExec](IMPORTANT): platform_mass_position -0.019419 cm 2023-09-12T22:05:26.451Z,1694556326.451 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 500 cubic_centimeter 2023-09-12T22:05:26.455Z,1694556326.455 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2023-09-12T22:05:26.652Z,1694556326.652 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-09-12T22:05:32.777Z,1694556332.777 [Reporter](INFO): platform_buoyancy_position 962.366577 cc 2023-09-12T22:05:33.568Z,1694556333.568 [Reporter](INFO): platform_buoyancy_position 951.756836 cc 2023-09-12T22:05:33.955Z,1694556333.955 [Reporter](INFO): platform_buoyancy_position 949.608032 cc 2023-09-12T22:05:34.364Z,1694556334.364 [Reporter](INFO): platform_buoyancy_position 947.459229 cc 2023-09-12T22:05:34.780Z,1694556334.780 [Reporter](INFO): platform_buoyancy_position 943.027344 cc 2023-09-12T22:05:35.163Z,1694556335.163 [Reporter](INFO): platform_buoyancy_position 938.998413 cc 2023-09-12T22:05:35.575Z,1694556335.575 [Reporter](INFO): platform_buoyancy_position 934.566528 cc 2023-09-12T22:05:35.990Z,1694556335.990 [Reporter](INFO): platform_buoyancy_position 929.194458 cc 2023-09-12T22:05:36.380Z,1694556336.380 [Reporter](INFO): platform_buoyancy_position 923.822510 cc 2023-09-12T22:05:36.784Z,1694556336.784 [Reporter](INFO): platform_buoyancy_position 919.524902 cc 2023-09-12T22:05:37.196Z,1694556337.196 [Reporter](INFO): platform_buoyancy_position 913.078491 cc 2023-09-12T22:05:37.615Z,1694556337.615 [Reporter](INFO): platform_buoyancy_position 908.646606 cc 2023-09-12T22:05:37.999Z,1694556337.999 [Reporter](INFO): platform_buoyancy_position 902.200195 cc 2023-09-12T22:05:38.404Z,1694556338.404 [Reporter](INFO): platform_buoyancy_position 897.634033 cc 2023-09-12T22:05:38.832Z,1694556338.832 [Reporter](INFO): platform_buoyancy_position 891.456177 cc 2023-09-12T22:05:39.242Z,1694556339.242 [Reporter](INFO): platform_buoyancy_position 887.158569 cc 2023-09-12T22:05:39.628Z,1694556339.628 [Reporter](INFO): platform_buoyancy_position 880.846436 cc 2023-09-12T22:05:40.032Z,1694556340.032 [Reporter](INFO): platform_buoyancy_position 876.414551 cc 2023-09-12T22:05:40.448Z,1694556340.448 [Reporter](INFO): platform_buoyancy_position 871.848389 cc 2023-09-12T22:05:40.861Z,1694556340.861 [Reporter](INFO): platform_buoyancy_position 865.670532 cc 2023-09-12T22:05:41.240Z,1694556341.240 [Reporter](INFO): platform_buoyancy_position 860.835815 cc 2023-09-12T22:05:41.650Z,1694556341.650 [Reporter](INFO): platform_buoyancy_position 855.060913 cc 2023-09-12T22:05:42.069Z,1694556342.069 [Reporter](INFO): platform_buoyancy_position 850.360352 cc 2023-09-12T22:05:42.468Z,1694556342.468 [Reporter](INFO): platform_buoyancy_position 844.316895 cc 2023-09-12T22:05:42.854Z,1694556342.854 [Reporter](INFO): platform_buoyancy_position 840.019287 cc 2023-09-12T22:05:43.236Z,1694556343.236 [Reporter](INFO): platform_buoyancy_position 833.841431 cc 2023-09-12T22:05:43.636Z,1694556343.636 [Reporter](INFO): platform_buoyancy_position 829.275269 cc 2023-09-12T22:05:44.061Z,1694556344.061 [Reporter](INFO): platform_buoyancy_position 822.828857 cc 2023-09-12T22:05:44.468Z,1694556344.468 [Reporter](INFO): platform_buoyancy_position 818.396973 cc 2023-09-12T22:05:44.947Z,1694556344.947 [Reporter](INFO): platform_buoyancy_position 811.950562 cc 2023-09-12T22:05:45.263Z,1694556345.263 [Reporter](INFO): platform_buoyancy_position 807.787292 cc 2023-09-12T22:05:45.725Z,1694556345.725 [Reporter](INFO): platform_buoyancy_position 801.340881 cc 2023-09-12T22:05:46.100Z,1694556346.100 [Reporter](INFO): platform_buoyancy_position 797.043274 cc 2023-09-12T22:05:46.496Z,1694556346.496 [Reporter](INFO): platform_buoyancy_position 792.208496 cc 2023-09-12T22:05:46.900Z,1694556346.900 [Reporter](INFO): platform_buoyancy_position 786.164978 cc 2023-09-12T22:05:47.290Z,1694556347.290 [Reporter](INFO): platform_buoyancy_position 781.867371 cc 2023-09-12T22:05:47.699Z,1694556347.699 [Reporter](INFO): platform_buoyancy_position 775.555298 cc 2023-09-12T22:05:48.092Z,1694556348.092 [Reporter](INFO): platform_buoyancy_position 771.123352 cc 2023-09-12T22:05:48.489Z,1694556348.489 [Reporter](INFO): platform_buoyancy_position 764.811279 cc 2023-09-12T22:05:48.900Z,1694556348.900 [Reporter](INFO): platform_buoyancy_position 760.513672 cc 2023-09-12T22:05:49.313Z,1694556349.313 [Reporter](INFO): platform_buoyancy_position 756.081787 cc 2023-09-12T22:05:49.712Z,1694556349.712 [Reporter](INFO): platform_buoyancy_position 749.635376 cc 2023-09-12T22:05:50.103Z,1694556350.103 [Reporter](INFO): platform_buoyancy_position 745.203491 cc 2023-09-12T22:05:50.544Z,1694556350.544 [Reporter](INFO): platform_buoyancy_position 739.025696 cc 2023-09-12T22:05:50.929Z,1694556350.929 [Reporter](INFO): platform_buoyancy_position 732.982178 cc 2023-09-12T22:05:51.324Z,1694556351.324 [Reporter](INFO): platform_buoyancy_position 728.281677 cc 2023-09-12T22:05:51.716Z,1694556351.716 [Reporter](INFO): platform_buoyancy_position 723.446838 cc 2023-09-12T22:05:52.131Z,1694556352.131 [Reporter](INFO): platform_buoyancy_position 717.403381 cc 2023-09-12T22:05:52.542Z,1694556352.542 [Reporter](INFO): platform_buoyancy_position 712.434265 cc 2023-09-12T22:05:52.936Z,1694556352.936 [Reporter](INFO): platform_buoyancy_position 706.659363 cc 2023-09-12T22:05:53.352Z,1694556353.352 [Reporter](INFO): platform_buoyancy_position 701.958862 cc 2023-09-12T22:05:53.744Z,1694556353.744 [Reporter](INFO): platform_buoyancy_position 696.183960 cc 2023-09-12T22:05:54.158Z,1694556354.158 [Reporter](INFO): platform_buoyancy_position 691.349182 cc 2023-09-12T22:05:54.552Z,1694556354.552 [Reporter](INFO): platform_buoyancy_position 685.305664 cc 2023-09-12T22:05:54.989Z,1694556354.989 [Reporter](INFO): platform_buoyancy_position 680.873779 cc 2023-09-12T22:05:55.384Z,1694556355.384 [Reporter](INFO): platform_buoyancy_position 674.427368 cc 2023-09-12T22:05:55.774Z,1694556355.774 [Reporter](INFO): platform_buoyancy_position 669.995483 cc 2023-09-12T22:05:56.183Z,1694556356.183 [Reporter](INFO): platform_buoyancy_position 663.951965 cc 2023-09-12T22:05:56.576Z,1694556356.576 [Reporter](INFO): platform_buoyancy_position 659.385742 cc 2023-09-12T22:05:56.971Z,1694556356.971 [Reporter](INFO): platform_buoyancy_position 653.073669 cc 2023-09-12T22:05:57.387Z,1694556357.387 [Reporter](INFO): platform_buoyancy_position 648.641785 cc 2023-09-12T22:05:57.744Z,1694556357.744 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-12T22:04:57.5Z 2023-09-12T22:05:57.744Z,1694556357.744 [Startup:StartupSatComms:A] Stopped 2023-09-12T22:05:57.744Z,1694556357.744 [Startup:StartupSatComms:B] Running Loop=1 2023-09-12T22:05:57.804Z,1694556357.804 [Reporter](INFO): platform_buoyancy_position 642.866882 cc 2023-09-12T22:05:58.158Z,1694556358.158 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-12T22:05:58.223Z,1694556358.223 [Reporter](INFO): platform_buoyancy_position 638.032043 cc 2023-09-12T22:05:59.132Z,1694556359.132 [Reporter](INFO): platform_buoyancy_position 627.019470 cc 2023-09-12T22:05:59.289Z,1694556359.289 [Reporter](INFO): platform_buoyancy_position 623.393372 cc 2023-09-12T22:05:59.676Z,1694556359.676 [Reporter](INFO): platform_buoyancy_position 618.558533 cc 2023-09-12T22:06:00.068Z,1694556360.068 [Reporter](INFO): platform_buoyancy_position 614.126648 cc 2023-09-12T22:06:00.449Z,1694556360.449 [Reporter](INFO): platform_buoyancy_position 608.083130 cc 2023-09-12T22:06:00.864Z,1694556360.864 [Reporter](INFO): platform_buoyancy_position 603.516968 cc 2023-09-12T22:06:01.264Z,1694556361.264 [Reporter](INFO): platform_buoyancy_position 597.204834 cc 2023-09-12T22:06:01.655Z,1694556361.655 [Reporter](INFO): platform_buoyancy_position 592.772949 cc 2023-09-12T22:06:02.072Z,1694556362.072 [Reporter](INFO): platform_buoyancy_position 586.595154 cc 2023-09-12T22:06:02.490Z,1694556362.490 [Reporter](INFO): platform_buoyancy_position 582.163269 cc 2023-09-12T22:06:02.891Z,1694556362.891 [Reporter](INFO): platform_buoyancy_position 575.985474 cc 2023-09-12T22:06:03.313Z,1694556363.313 [Reporter](INFO): platform_buoyancy_position 571.284973 cc 2023-09-12T22:06:03.673Z,1694556363.673 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-09-12T22:06:03.673Z,1694556363.673 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-09-12T22:06:03.679Z,1694556363.679 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-09-12T22:06:03.679Z,1694556363.679 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-09-12T22:06:03.755Z,1694556363.755 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-09-12T22:06:03.755Z,1694556363.755 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-09-12T22:06:03.759Z,1694556363.759 [Reporter](INFO): platform_buoyancy_position 565.107178 cc 2023-09-12T22:06:04.111Z,1694556364.111 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-09-12T22:06:04.111Z,1694556364.111 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-09-12T22:06:04.111Z,1694556364.111 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-09-12T22:06:04.111Z,1694556364.111 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-09-12T22:06:04.113Z,1694556364.113 [Reporter](INFO): platform_buoyancy_position 560.675232 cc 2023-09-12T22:06:04.472Z,1694556364.472 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-09-12T22:06:04.525Z,1694556364.525 [Reporter](INFO): platform_buoyancy_position 554.228882 cc 2023-09-12T22:06:04.892Z,1694556364.892 [Reporter](INFO): platform_buoyancy_position 549.931274 cc 2023-09-12T22:06:05.319Z,1694556365.319 [Reporter](INFO): platform_buoyancy_position 545.499329 cc 2023-09-12T22:06:05.749Z,1694556365.749 [Reporter](INFO): platform_buoyancy_position 539.052979 cc 2023-09-12T22:06:06.117Z,1694556366.117 [Reporter](INFO): platform_buoyancy_position 533.949524 cc 2023-09-12T22:06:06.509Z,1694556366.509 [Reporter](INFO): platform_buoyancy_position 528.308960 cc 2023-09-12T22:06:06.855Z,1694556366.855 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20230906T164946/Courier0868.lzma 2023-09-12T22:06:06.913Z,1694556366.913 [Reporter](INFO): platform_buoyancy_position 524.011353 cc 2023-09-12T22:06:07.315Z,1694556367.315 [Reporter](INFO): platform_buoyancy_position 517.699280 cc 2023-09-12T22:06:07.729Z,1694556367.729 [Reporter](INFO): platform_buoyancy_position 513.401672 cc 2023-09-12T22:06:07.856Z,1694556367.856 [DataOverHttps](INFO): Moved sent file to Logs/20230906T164946/Courier0868.lzma.bak 2023-09-12T22:06:07.856Z,1694556367.856 [DataOverHttps](INFO): SBD MOMSN=18764487 2023-09-12T22:06:08.176Z,1694556368.176 [Reporter](INFO): platform_buoyancy_position 508.298279 cc 2023-09-12T22:06:10.302Z,1694556370.302 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 0.5 centimeter persist 2023-09-12T22:06:22.092Z,1694556382.092 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter 2023-09-12T22:06:24.368Z,1694556384.368 [Reporter](INFO): platform_buoyancy_position 504.672180 cc 2023-09-12T22:06:24.738Z,1694556384.738 [Reporter](INFO): platform_buoyancy_position 506.552338 cc 2023-09-12T22:06:25.163Z,1694556385.163 [Reporter](INFO): platform_buoyancy_position 504.672180 cc 2023-09-12T22:06:25.536Z,1694556385.536 [Reporter](INFO): platform_buoyancy_position 496.211243 cc 2023-09-12T22:06:25.928Z,1694556385.928 [Reporter](INFO): platform_buoyancy_position 493.928162 cc 2023-09-12T22:06:26.444Z,1694556386.444 [Reporter](INFO): platform_buoyancy_position 489.764862 cc 2023-09-12T22:06:26.733Z,1694556386.733 [Reporter](INFO): platform_buoyancy_position 487.481750 cc 2023-09-12T22:06:27.156Z,1694556387.156 [Reporter](INFO): platform_buoyancy_position 481.169678 cc 2023-09-12T22:06:27.561Z,1694556387.561 [Reporter](INFO): platform_buoyancy_position 476.737732 cc 2023-09-12T22:06:27.960Z,1694556387.960 [Reporter](INFO): platform_buoyancy_position 470.425659 cc 2023-09-12T22:06:28.344Z,1694556388.344 [Reporter](INFO): platform_buoyancy_position 465.993774 cc 2023-09-12T22:06:28.752Z,1694556388.752 [Reporter](INFO): platform_buoyancy_position 459.681641 cc 2023-09-12T22:06:29.156Z,1694556389.156 [Reporter](INFO): platform_buoyancy_position 455.384033 cc 2023-09-12T22:06:29.552Z,1694556389.552 [Reporter](INFO): platform_buoyancy_position 448.937622 cc 2023-09-12T22:06:29.960Z,1694556389.960 [Reporter](INFO): platform_buoyancy_position 444.640076 cc 2023-09-12T22:06:30.376Z,1694556390.376 [Reporter](INFO): platform_buoyancy_position 438.462219 cc 2023-09-12T22:06:30.793Z,1694556390.793 [Reporter](INFO): platform_buoyancy_position 433.761719 cc 2023-09-12T22:06:31.203Z,1694556391.203 [Reporter](INFO): platform_buoyancy_position 427.449646 cc 2023-09-12T22:06:31.613Z,1694556391.613 [Reporter](INFO): platform_buoyancy_position 423.017761 cc 2023-09-12T22:06:32.004Z,1694556392.004 [Reporter](INFO): platform_buoyancy_position 417.511475 cc 2023-09-12T22:06:32.407Z,1694556392.407 [Reporter](INFO): platform_buoyancy_position 412.408020 cc 2023-09-12T22:06:32.815Z,1694556392.815 [Reporter](INFO): platform_buoyancy_position 407.841858 cc 2023-09-12T22:06:33.145Z,1694556393.145 [NAL9602](INFO): SBD MO Status=2, MOMSN=13499, MT Status=2, MTMSN=0 2023-09-12T22:06:33.145Z,1694556393.145 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-12T22:06:33.248Z,1694556393.248 [Reporter](INFO): platform_buoyancy_position 401.529724 cc 2023-09-12T22:06:33.642Z,1694556393.642 [Reporter](INFO): platform_buoyancy_position 395.351929 cc 2023-09-12T22:06:34.018Z,1694556394.018 [Reporter](INFO): platform_buoyancy_position 390.920044 cc 2023-09-12T22:06:34.422Z,1694556394.422 [Reporter](INFO): platform_buoyancy_position 386.488159 cc 2023-09-12T22:06:34.801Z,1694556394.801 [Reporter](INFO): platform_buoyancy_position 380.176025 cc 2023-09-12T22:06:35.118Z,1694556395.118 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230912T220252/Courier0000.lzma 2023-09-12T22:06:35.217Z,1694556395.217 [Reporter](INFO): platform_buoyancy_position 375.878418 cc 2023-09-12T22:06:35.624Z,1694556395.624 [Reporter](INFO): platform_buoyancy_position 369.432068 cc 2023-09-12T22:06:36.024Z,1694556396.024 [Reporter](INFO): platform_buoyancy_position 365.000122 cc 2023-09-12T22:06:36.120Z,1694556396.120 [DataOverHttps](INFO): Moved sent file to Logs/20230912T220252/Courier0000.lzma.bak 2023-09-12T22:06:36.120Z,1694556396.120 [DataOverHttps](INFO): SBD MOMSN=18764491 2023-09-12T22:06:36.412Z,1694556396.412 [Reporter](INFO): platform_buoyancy_position 359.762451 cc 2023-09-12T22:06:36.828Z,1694556396.828 [Reporter](INFO): platform_buoyancy_position 354.256165 cc 2023-09-12T22:06:37.247Z,1694556397.247 [Reporter](INFO): platform_buoyancy_position 349.824219 cc 2023-09-12T22:06:37.661Z,1694556397.661 [Reporter](INFO): platform_buoyancy_position 343.646423 cc 2023-09-12T22:06:38.104Z,1694556398.104 [Reporter](INFO): platform_buoyancy_position 337.065735 cc 2023-09-12T22:06:38.472Z,1694556398.472 [Reporter](INFO): platform_buoyancy_position 332.768127 cc 2023-09-12T22:06:38.903Z,1694556398.903 [Reporter](INFO): platform_buoyancy_position 326.456055 cc 2023-09-12T22:06:39.288Z,1694556399.288 [Reporter](INFO): platform_buoyancy_position 322.024109 cc 2023-09-12T22:06:39.716Z,1694556399.716 [Reporter](INFO): platform_buoyancy_position 315.712036 cc 2023-09-12T22:06:40.136Z,1694556400.136 [Reporter](INFO): platform_buoyancy_position 311.280151 cc 2023-09-12T22:06:40.479Z,1694556400.479 [Reporter](INFO): platform_buoyancy_position 306.848236 cc 2023-09-12T22:06:40.888Z,1694556400.888 [Reporter](INFO): platform_buoyancy_position 300.536133 cc 2023-09-12T22:06:41.284Z,1694556401.284 [Reporter](INFO): platform_buoyancy_position 296.238525 cc 2023-09-12T22:06:41.706Z,1694556401.706 [Reporter](INFO): platform_buoyancy_position 290.060730 cc 2023-09-12T22:06:42.096Z,1694556402.096 [Reporter](INFO): platform_buoyancy_position 285.494537 cc 2023-09-12T22:06:42.504Z,1694556402.504 [Reporter](INFO): platform_buoyancy_position 279.182434 cc 2023-09-12T22:06:42.915Z,1694556402.915 [Reporter](INFO): platform_buoyancy_position 274.616241 cc 2023-09-12T22:06:43.330Z,1694556403.330 [Reporter](INFO): platform_buoyancy_position 268.438446 cc 2023-09-12T22:06:43.732Z,1694556403.732 [Reporter](INFO): platform_buoyancy_position 263.872223 cc 2023-09-12T22:06:44.124Z,1694556404.124 [Reporter](INFO): platform_buoyancy_position 257.694427 cc 2023-09-12T22:06:44.533Z,1694556404.533 [Reporter](INFO): platform_buoyancy_position 253.262558 cc 2023-09-12T22:06:44.943Z,1694556404.943 [Reporter](INFO): platform_buoyancy_position 246.950424 cc 2023-09-12T22:06:45.319Z,1694556405.319 [Reporter](INFO): platform_buoyancy_position 242.652832 cc 2023-09-12T22:06:45.724Z,1694556405.724 [Reporter](INFO): platform_buoyancy_position 238.220947 cc 2023-09-12T22:06:46.121Z,1694556406.121 [Reporter](INFO): platform_buoyancy_position 231.774536 cc 2023-09-12T22:06:46.540Z,1694556406.540 [Reporter](INFO): platform_buoyancy_position 227.476929 cc 2023-09-12T22:06:46.924Z,1694556406.924 [Reporter](INFO): platform_buoyancy_position 221.164825 cc 2023-09-12T22:06:47.332Z,1694556407.332 [Reporter](INFO): platform_buoyancy_position 216.732941 cc 2023-09-12T22:06:47.770Z,1694556407.770 [Reporter](INFO): platform_buoyancy_position 212.435333 cc 2023-09-12T22:06:48.162Z,1694556408.162 [Reporter](INFO): platform_buoyancy_position 208.137726 cc 2023-09-12T22:06:54.623Z,1694556414.623 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20230906T164946/Express0869.lzma 2023-09-12T22:06:55.624Z,1694556415.624 [DataOverHttps](INFO): Moved sent file to Logs/20230906T164946/Express0869.lzma.bak 2023-09-12T22:06:55.624Z,1694556415.624 [DataOverHttps](INFO): SBD MOMSN=18764494 2023-09-12T22:06:57.816Z,1694556417.816 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-12T22:05:57.7Z 2023-09-12T22:06:57.816Z,1694556417.816 [Startup:StartupSatComms:B] Stopped 2023-09-12T22:06:57.816Z,1694556417.816 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-09-12T22:06:57.817Z,1694556417.817 [Startup:StartupSatComms] Stopped 2023-09-12T22:06:57.817Z,1694556417.817 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-09-12T22:06:57.817Z,1694556417.817 [Startup](INFO): Completed Startup 2023-09-12T22:06:57.822Z,1694556417.822 [MissionManager](INFO): Startup is completed. 2023-09-12T22:06:57.822Z,1694556417.822 [MissionManager](INFO): Uninitializing Mission Startup 2023-09-12T22:06:57.822Z,1694556417.822 [Startup] Stopped 2023-09-12T22:06:57.822Z,1694556417.822 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-09-12T22:06:57.822Z,1694556417.822 [Startup:A.GoToSurface] Stopped 2023-09-12T22:06:57.822Z,1694556417.822 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-12T22:06:58.204Z,1694556418.204 [MissionManager](IMPORTANT): Started mission Default 2023-09-12T22:06:58.204Z,1694556418.204 [Default] Running Loop=1 2023-09-12T22:06:58.204Z,1694556418.204 [Default](DEBUG): Aggregate::initialize Default 2023-09-12T22:06:58.204Z,1694556418.204 [Default:B.GoToSurface] Running Loop=1 2023-09-12T22:06:58.204Z,1694556418.204 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-09-12T22:06:58.205Z,1694556418.205 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-09-12T22:06:58.205Z,1694556418.205 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-09-12T22:06:58.205Z,1694556418.205 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-09-12T22:06:58.205Z,1694556418.205 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-09-12T22:06:58.206Z,1694556418.206 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-09-12T22:06:58.206Z,1694556418.206 [Default:A.Wait] Running Loop=1 2023-09-12T22:06:58.206Z,1694556418.206 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-09-12T22:06:59.653Z,1694556419.653 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1 centimeter persist 2023-09-12T22:07:02.641Z,1694556422.641 [NAL9602](INFO): SBD MO Status=0, MOMSN=13499, MT Status=0, MTMSN=0 2023-09-12T22:07:02.641Z,1694556422.641 [NAL9602](INFO): No messages in MT queue 2023-09-12T22:07:03.875Z,1694556423.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220702.00,A,3648.17426,N,12147.28663,W,2.274,137.42,120923,,,A*75 2023-09-12T22:07:03.883Z,1694556423.883 [NAL9602](INFO): GPS fix at 20230912T220702: (36.802904, -121.788111) 2023-09-12T22:07:11.356Z,1694556431.356 [Default:A.Wait](INFO): Done Waiting. 2023-09-12T22:07:11.357Z,1694556431.357 [Default:A.Wait] Stopped 2023-09-12T22:07:11.357Z,1694556431.357 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-09-12T22:07:11.828Z,1694556431.828 [Default:CheckIn] Running Loop=1 2023-09-12T22:07:11.828Z,1694556431.828 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-12T22:07:11.828Z,1694556431.828 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-12T22:07:12.170Z,1694556432.170 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-09-12T22:07:13.753Z,1694556433.753 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220712.00,A,3648.17807,N,12147.29535,W,3.460,137.42,120923,,,A*78 2023-09-12T22:07:13.756Z,1694556433.756 [NAL9602](INFO): GPS fix at 20230912T220712: (36.802968, -121.788256) 2023-09-12T22:07:13.805Z,1694556433.805 [Default:CheckIn:Read_GPS] Stopped 2023-09-12T22:07:13.805Z,1694556433.805 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-12T22:07:14.197Z,1694556434.197 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-09-12T22:07:18.305Z,1694556438.305 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 150 cubic_centimeter 2023-09-12T22:07:20.440Z,1694556440.440 [Reporter](INFO): platform_buoyancy_position 201.825623 cc 2023-09-12T22:07:20.830Z,1694556440.830 [Reporter](INFO): platform_buoyancy_position 201.959930 cc 2023-09-12T22:07:21.136Z,1694556441.136 [Reporter](INFO): platform_buoyancy_position 201.691315 cc 2023-09-12T22:07:21.508Z,1694556441.508 [Reporter](INFO): platform_buoyancy_position 196.990814 cc 2023-09-12T22:07:21.921Z,1694556441.921 [Reporter](INFO): platform_buoyancy_position 193.096130 cc 2023-09-12T22:07:22.164Z,1694556442.164 [DataOverHttps](INFO): Sending 1341 bytes from file Logs/20230912T220252/Express0001.lzma 2023-09-12T22:07:22.304Z,1694556442.304 [Reporter](INFO): platform_buoyancy_position 188.798523 cc 2023-09-12T22:07:22.716Z,1694556442.716 [Reporter](INFO): platform_buoyancy_position 184.500916 cc 2023-09-12T22:07:23.118Z,1694556443.118 [Reporter](INFO): platform_buoyancy_position 178.054535 cc 2023-09-12T22:07:23.164Z,1694556443.164 [DataOverHttps](INFO): Moved sent file to Logs/20230912T220252/Express0001.lzma.bak 2023-09-12T22:07:23.164Z,1694556443.164 [DataOverHttps](INFO): SBD MOMSN=18764497 2023-09-12T22:07:23.500Z,1694556443.500 [Reporter](INFO): platform_buoyancy_position 173.756927 cc 2023-09-12T22:07:23.928Z,1694556443.928 [Reporter](INFO): platform_buoyancy_position 167.444824 cc 2023-09-12T22:07:24.336Z,1694556444.336 [Reporter](INFO): platform_buoyancy_position 163.147217 cc 2023-09-12T22:07:24.785Z,1694556444.785 [Reporter](INFO): platform_buoyancy_position 158.849640 cc 2023-09-12T22:07:45.577Z,1694556465.577 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230912T220252/Courier0004.lzma 2023-09-12T22:07:46.535Z,1694556466.535 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-12T22:07:46.576Z,1694556466.576 [DataOverHttps](INFO): Moved sent file to Logs/20230912T220252/Courier0004.lzma.bak 2023-09-12T22:07:46.576Z,1694556466.576 [DataOverHttps](INFO): SBD MOMSN=18764552 2023-09-12T22:07:52.526Z,1694556472.526 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter 2023-09-12T22:07:54.441Z,1694556474.441 [Reporter](INFO): platform_buoyancy_position 152.268921 cc 2023-09-12T22:07:55.244Z,1694556475.244 [Reporter](INFO): platform_buoyancy_position 150.254425 cc 2023-09-12T22:07:55.612Z,1694556475.612 [Reporter](INFO): platform_buoyancy_position 145.956818 cc 2023-09-12T22:07:56.032Z,1694556476.032 [Reporter](INFO): platform_buoyancy_position 143.539429 cc 2023-09-12T22:07:56.431Z,1694556476.431 [Reporter](INFO): platform_buoyancy_position 138.033112 cc 2023-09-12T22:07:56.832Z,1694556476.832 [Reporter](INFO): platform_buoyancy_position 132.929718 cc 2023-09-12T22:07:57.224Z,1694556477.224 [Reporter](INFO): platform_buoyancy_position 128.094910 cc 2023-09-12T22:07:57.642Z,1694556477.642 [Reporter](INFO): platform_buoyancy_position 122.320038 cc 2023-09-12T22:07:58.064Z,1694556478.064 [Reporter](INFO): platform_buoyancy_position 117.888123 cc 2023-09-12T22:07:58.484Z,1694556478.484 [Reporter](INFO): platform_buoyancy_position 111.441711 cc 2023-09-12T22:07:58.860Z,1694556478.860 [Reporter](INFO): platform_buoyancy_position 107.144135 cc 2023-09-12T22:08:05.382Z,1694556485.382 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20230912T220252/Express0005.lzma 2023-09-12T22:08:06.384Z,1694556486.384 [DataOverHttps](INFO): Moved sent file to Logs/20230912T220252/Express0005.lzma.bak 2023-09-12T22:08:06.384Z,1694556486.384 [DataOverHttps](INFO): SBD MOMSN=18764555 2023-09-12T22:08:09.729Z,1694556489.729 [Default:CheckIn:Read_Iridium] Stopped 2023-09-12T22:08:09.729Z,1694556489.729 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-12T22:08:09.734Z,1694556489.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-12T22:08:46.023Z,1694556526.023 [CommandExec](IMPORTANT): got command maintain clear 2023-09-12T22:08:46.109Z,1694556526.109 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-09-12T22:08:48.177Z,1694556528.177 [Reporter](INFO): platform_buoyancy_position 102.980835 cc 2023-09-12T22:08:48.531Z,1694556528.531 [Reporter](INFO): platform_buoyancy_position 102.846527 cc 2023-09-12T22:08:48.960Z,1694556528.960 [Reporter](INFO): platform_buoyancy_position 102.980835 cc 2023-09-12T22:08:49.361Z,1694556529.361 [Reporter](INFO): platform_buoyancy_position 106.338318 cc 2023-09-12T22:08:49.740Z,1694556529.740 [Reporter](INFO): platform_buoyancy_position 111.441711 cc 2023-09-12T22:08:50.156Z,1694556530.156 [Reporter](INFO): platform_buoyancy_position 115.739319 cc 2023-09-12T22:08:50.578Z,1694556530.578 [Reporter](INFO): platform_buoyancy_position 120.171234 cc 2023-09-12T22:08:50.956Z,1694556530.956 [Reporter](INFO): platform_buoyancy_position 126.483337 cc 2023-09-12T22:08:51.371Z,1694556531.371 [Reporter](INFO): platform_buoyancy_position 131.049530 cc 2023-09-12T22:08:51.779Z,1694556531.779 [Reporter](INFO): platform_buoyancy_position 137.227325 cc 2023-09-12T22:08:52.192Z,1694556532.192 [Reporter](INFO): platform_buoyancy_position 141.793518 cc 2023-09-12T22:08:52.572Z,1694556532.572 [Reporter](INFO): platform_buoyancy_position 147.837036 cc 2023-09-12T22:08:52.977Z,1694556532.977 [Reporter](INFO): platform_buoyancy_position 152.403229 cc 2023-09-12T22:08:53.392Z,1694556533.392 [Reporter](INFO): platform_buoyancy_position 158.715332 cc 2023-09-12T22:08:53.808Z,1694556533.808 [Reporter](INFO): platform_buoyancy_position 163.012939 cc 2023-09-12T22:08:54.256Z,1694556534.256 [Reporter](INFO): platform_buoyancy_position 169.593628 cc 2023-09-12T22:08:54.604Z,1694556534.604 [Reporter](INFO): platform_buoyancy_position 173.891235 cc 2023-09-12T22:08:55.080Z,1694556535.080 [Reporter](INFO): platform_buoyancy_position 180.337616 cc 2023-09-12T22:08:55.444Z,1694556535.444 [Reporter](INFO): platform_buoyancy_position 184.500916 cc 2023-09-12T22:08:55.855Z,1694556535.855 [Reporter](INFO): platform_buoyancy_position 191.081635 cc 2023-09-12T22:08:56.229Z,1694556536.229 [Reporter](INFO): platform_buoyancy_position 195.379242 cc 2023-09-12T22:08:56.629Z,1694556536.629 [Reporter](INFO): platform_buoyancy_position 199.676819 cc 2023-09-12T22:08:57.030Z,1694556537.030 [Reporter](INFO): platform_buoyancy_position 205.988922 cc 2023-09-12T22:08:57.424Z,1694556537.424 [Reporter](INFO): platform_buoyancy_position 210.286530 cc 2023-09-12T22:08:57.828Z,1694556537.828 [Reporter](INFO): platform_buoyancy_position 214.718445 cc 2023-09-12T22:08:58.240Z,1694556538.240 [Reporter](INFO): platform_buoyancy_position 221.164825 cc 2023-09-12T22:08:58.663Z,1694556538.663 [Reporter](INFO): platform_buoyancy_position 227.342621 cc 2023-09-12T22:08:59.032Z,1694556539.032 [Reporter](INFO): platform_buoyancy_position 231.774536 cc 2023-09-12T22:08:59.444Z,1694556539.444 [Reporter](INFO): platform_buoyancy_position 238.220947 cc 2023-09-12T22:08:59.860Z,1694556539.860 [Reporter](INFO): platform_buoyancy_position 242.518524 cc 2023-09-12T22:09:00.268Z,1694556540.268 [Reporter](INFO): platform_buoyancy_position 248.964935 cc 2023-09-12T22:09:00.656Z,1694556540.656 [Reporter](INFO): platform_buoyancy_position 253.262558 cc 2023-09-12T22:09:01.063Z,1694556541.063 [Reporter](INFO): platform_buoyancy_position 258.634521 cc 2023-09-12T22:09:01.472Z,1694556541.472 [Reporter](INFO): platform_buoyancy_position 264.006531 cc 2023-09-12T22:09:01.884Z,1694556541.884 [Reporter](INFO): platform_buoyancy_position 270.318634 cc 2023-09-12T22:09:02.285Z,1694556542.285 [Reporter](INFO): platform_buoyancy_position 274.884827 cc 2023-09-12T22:09:02.668Z,1694556542.668 [Reporter](INFO): platform_buoyancy_position 279.451050 cc 2023-09-12T22:09:03.092Z,1694556543.092 [Reporter](INFO): platform_buoyancy_position 285.494537 cc 2023-09-12T22:09:03.496Z,1694556543.496 [Reporter](INFO): platform_buoyancy_position 291.135132 cc 2023-09-12T22:09:03.884Z,1694556543.884 [Reporter](INFO): platform_buoyancy_position 296.238525 cc 2023-09-12T22:09:04.284Z,1694556544.284 [Reporter](INFO): platform_buoyancy_position 300.670441 cc 2023-09-12T22:09:04.704Z,1694556544.704 [Reporter](INFO): platform_buoyancy_position 306.982544 cc 2023-09-12T22:09:05.124Z,1694556545.124 [Reporter](INFO): platform_buoyancy_position 311.280151 cc 2023-09-12T22:09:05.516Z,1694556545.516 [Reporter](INFO): platform_buoyancy_position 317.726562 cc 2023-09-12T22:09:05.932Z,1694556545.932 [Reporter](INFO): platform_buoyancy_position 322.158447 cc 2023-09-12T22:09:06.366Z,1694556546.366 [Reporter](INFO): platform_buoyancy_position 328.604858 cc 2023-09-12T22:09:06.752Z,1694556546.752 [Reporter](INFO): platform_buoyancy_position 332.902466 cc 2023-09-12T22:09:07.149Z,1694556547.149 [Reporter](INFO): platform_buoyancy_position 339.214539 cc 2023-09-12T22:09:07.563Z,1694556547.563 [Reporter](INFO): platform_buoyancy_position 343.646423 cc 2023-09-12T22:09:07.948Z,1694556547.948 [Reporter](INFO): platform_buoyancy_position 350.092834 cc 2023-09-12T22:09:08.584Z,1694556548.584 [Reporter](INFO): platform_buoyancy_position 356.673523 cc 2023-09-12T22:09:08.809Z,1694556548.809 [Reporter](INFO): platform_buoyancy_position 360.836853 cc 2023-09-12T22:09:09.200Z,1694556549.200 [Reporter](INFO): platform_buoyancy_position 365.134460 cc 2023-09-12T22:09:09.568Z,1694556549.568 [Reporter](INFO): platform_buoyancy_position 369.566345 cc 2023-09-12T22:09:09.948Z,1694556549.948 [Reporter](INFO): platform_buoyancy_position 375.744141 cc 2023-09-12T22:09:10.353Z,1694556550.353 [Reporter](INFO): platform_buoyancy_position 380.176025 cc 2023-09-12T22:09:10.772Z,1694556550.772 [Reporter](INFO): platform_buoyancy_position 386.488159 cc 2023-09-12T22:09:11.184Z,1694556551.184 [Reporter](INFO): platform_buoyancy_position 390.920044 cc 2023-09-12T22:09:11.561Z,1694556551.561 [Reporter](INFO): platform_buoyancy_position 397.232117 cc 2023-09-12T22:09:11.965Z,1694556551.965 [Reporter](INFO): platform_buoyancy_position 401.664062 cc 2023-09-12T22:09:12.387Z,1694556552.387 [Reporter](INFO): platform_buoyancy_position 407.976135 cc 2023-09-12T22:09:12.797Z,1694556552.797 [Reporter](INFO): platform_buoyancy_position 412.273743 cc 2023-09-12T22:09:13.172Z,1694556553.172 [Reporter](INFO): platform_buoyancy_position 418.720154 cc 2023-09-12T22:09:13.580Z,1694556553.580 [Reporter](INFO): platform_buoyancy_position 423.152039 cc 2023-09-12T22:09:14.008Z,1694556554.008 [Reporter](INFO): platform_buoyancy_position 429.464172 cc 2023-09-12T22:09:14.416Z,1694556554.416 [Reporter](INFO): platform_buoyancy_position 433.761719 cc 2023-09-12T22:09:14.796Z,1694556554.796 [Reporter](INFO): platform_buoyancy_position 438.193665 cc 2023-09-12T22:09:15.204Z,1694556555.204 [Reporter](INFO): platform_buoyancy_position 444.505737 cc 2023-09-12T22:09:15.616Z,1694556555.616 [Reporter](INFO): platform_buoyancy_position 448.803345 cc 2023-09-12T22:09:16.032Z,1694556556.032 [Reporter](INFO): platform_buoyancy_position 455.249756 cc 2023-09-12T22:09:16.413Z,1694556556.413 [Reporter](INFO): platform_buoyancy_position 459.681641 cc 2023-09-12T22:09:16.812Z,1694556556.812 [Reporter](INFO): platform_buoyancy_position 465.993774 cc 2023-09-12T22:09:17.232Z,1694556557.232 [Reporter](INFO): platform_buoyancy_position 470.291321 cc 2023-09-12T22:09:17.633Z,1694556557.633 [Reporter](INFO): platform_buoyancy_position 476.872070 cc 2023-09-12T22:09:18.024Z,1694556558.024 [Reporter](INFO): platform_buoyancy_position 481.035339 cc 2023-09-12T22:09:18.429Z,1694556558.429 [Reporter](INFO): platform_buoyancy_position 485.467224 cc 2023-09-12T22:09:18.849Z,1694556558.849 [Reporter](INFO): platform_buoyancy_position 491.913635 cc 2023-09-12T22:09:19.261Z,1694556559.261 [Reporter](INFO): platform_buoyancy_position 496.211243 cc 2023-09-12T22:09:19.656Z,1694556559.656 [Reporter](INFO): platform_buoyancy_position 502.657684 cc 2023-09-12T22:09:20.048Z,1694556560.048 [Reporter](INFO): platform_buoyancy_position 506.955261 cc 2023-09-12T22:09:20.469Z,1694556560.469 [Reporter](INFO): platform_buoyancy_position 513.267334 cc 2023-09-12T22:09:20.880Z,1694556560.880 [Reporter](INFO): platform_buoyancy_position 519.713745 cc 2023-09-12T22:09:21.324Z,1694556561.324 [Reporter](INFO): platform_buoyancy_position 524.011353 cc 2023-09-12T22:09:21.671Z,1694556561.671 [Reporter](INFO): platform_buoyancy_position 528.577576 cc 2023-09-12T22:09:22.161Z,1694556562.161 [Reporter](INFO): platform_buoyancy_position 534.889648 cc 2023-09-12T22:09:22.508Z,1694556562.508 [Reporter](INFO): platform_buoyancy_position 539.455872 cc 2023-09-12T22:09:22.915Z,1694556562.915 [Reporter](INFO): platform_buoyancy_position 545.633667 cc 2023-09-12T22:09:23.313Z,1694556563.313 [Reporter](INFO): platform_buoyancy_position 551.945740 cc 2023-09-12T22:09:23.692Z,1694556563.692 [Reporter](INFO): platform_buoyancy_position 556.243347 cc 2023-09-12T22:09:24.112Z,1694556564.112 [Reporter](INFO): platform_buoyancy_position 560.675232 cc 2023-09-12T22:09:24.516Z,1694556564.516 [Reporter](INFO): platform_buoyancy_position 566.853027 cc 2023-09-12T22:09:24.895Z,1694556564.895 [Reporter](INFO): platform_buoyancy_position 571.419250 cc 2023-09-12T22:09:25.315Z,1694556565.315 [Reporter](INFO): platform_buoyancy_position 576.254028 cc 2023-09-12T22:09:25.724Z,1694556565.724 [Reporter](INFO): platform_buoyancy_position 582.028931 cc 2023-09-12T22:09:26.130Z,1694556566.130 [Reporter](INFO): platform_buoyancy_position 586.729431 cc 2023-09-12T22:09:26.512Z,1694556566.512 [Reporter](INFO): platform_buoyancy_position 592.772949 cc 2023-09-12T22:09:26.928Z,1694556566.928 [Reporter](INFO): platform_buoyancy_position 597.204834 cc 2023-09-12T22:09:27.336Z,1694556567.336 [Reporter](INFO): platform_buoyancy_position 603.516968 cc 2023-09-12T22:09:27.736Z,1694556567.736 [Reporter](INFO): platform_buoyancy_position 607.948853 cc 2023-09-12T22:09:28.124Z,1694556568.124 [Reporter](INFO): platform_buoyancy_position 614.395264 cc 2023-09-12T22:09:28.541Z,1694556568.541 [Reporter](INFO): platform_buoyancy_position 618.558533 cc 2023-09-12T22:09:28.979Z,1694556568.979 [Reporter](INFO): platform_buoyancy_position 625.139282 cc 2023-09-12T22:09:29.336Z,1694556569.336 [Reporter](INFO): platform_buoyancy_position 629.436890 cc 2023-09-12T22:09:29.755Z,1694556569.755 [Reporter](INFO): platform_buoyancy_position 634.271667 cc 2023-09-12T22:09:30.176Z,1694556570.176 [Reporter](INFO): platform_buoyancy_position 640.180847 cc 2023-09-12T22:09:30.565Z,1694556570.565 [Reporter](INFO): platform_buoyancy_position 644.747070 cc 2023-09-12T22:09:30.969Z,1694556570.969 [Reporter](INFO): platform_buoyancy_position 650.790588 cc 2023-09-12T22:09:31.404Z,1694556571.404 [Reporter](INFO): platform_buoyancy_position 657.236938 cc 2023-09-12T22:09:31.772Z,1694556571.772 [Reporter](INFO): platform_buoyancy_position 661.668884 cc 2023-09-12T22:09:32.184Z,1694556572.184 [Reporter](INFO): platform_buoyancy_position 667.846680 cc 2023-09-12T22:09:32.589Z,1694556572.589 [Reporter](INFO): platform_buoyancy_position 672.412842 cc 2023-09-12T22:09:33.004Z,1694556573.004 [Reporter](INFO): platform_buoyancy_position 678.859253 cc 2023-09-12T22:09:33.389Z,1694556573.389 [Reporter](INFO): platform_buoyancy_position 683.156860 cc 2023-09-12T22:09:33.792Z,1694556573.792 [Reporter](INFO): platform_buoyancy_position 687.454468 cc 2023-09-12T22:09:34.219Z,1694556574.219 [Reporter](INFO): platform_buoyancy_position 693.766541 cc 2023-09-12T22:09:34.696Z,1694556574.696 [Reporter](INFO): platform_buoyancy_position 700.347290 cc 2023-09-12T22:09:35.041Z,1694556575.041 [Reporter](INFO): platform_buoyancy_position 704.510559 cc 2023-09-12T22:09:35.536Z,1694556575.536 [Reporter](INFO): platform_buoyancy_position 710.956970 cc 2023-09-12T22:09:35.869Z,1694556575.869 [Reporter](INFO): platform_buoyancy_position 715.388855 cc 2023-09-12T22:09:36.228Z,1694556576.228 [Reporter](INFO): platform_buoyancy_position 719.686462 cc 2023-09-12T22:09:36.648Z,1694556576.648 [Reporter](INFO): platform_buoyancy_position 725.998596 cc 2023-09-12T22:09:37.028Z,1694556577.028 [Reporter](INFO): platform_buoyancy_position 730.430481 cc 2023-09-12T22:09:37.448Z,1694556577.448 [Reporter](INFO): platform_buoyancy_position 736.742554 cc 2023-09-12T22:09:37.836Z,1694556577.836 [Reporter](INFO): platform_buoyancy_position 741.040161 cc 2023-09-12T22:09:38.281Z,1694556578.281 [Reporter](INFO): platform_buoyancy_position 747.486572 cc 2023-09-12T22:09:38.653Z,1694556578.653 [Reporter](INFO): platform_buoyancy_position 751.784180 cc 2023-09-12T22:09:39.102Z,1694556579.102 [Reporter](INFO): platform_buoyancy_position 758.230591 cc 2023-09-12T22:09:39.449Z,1694556579.449 [Reporter](INFO): platform_buoyancy_position 762.528198 cc 2023-09-12T22:09:39.876Z,1694556579.876 [Reporter](INFO): platform_buoyancy_position 766.825745 cc 2023-09-12T22:09:40.269Z,1694556580.269 [Reporter](INFO): platform_buoyancy_position 772.063477 cc 2023-09-12T22:09:40.701Z,1694556580.701 [Reporter](INFO): platform_buoyancy_position 777.704102 cc 2023-09-12T22:09:41.068Z,1694556581.068 [Reporter](INFO): platform_buoyancy_position 784.016174 cc 2023-09-12T22:09:41.456Z,1694556581.456 [Reporter](INFO): platform_buoyancy_position 788.448059 cc 2023-09-12T22:09:41.888Z,1694556581.888 [Reporter](INFO): platform_buoyancy_position 794.760193 cc 2023-09-12T22:09:42.311Z,1694556582.311 [Reporter](INFO): platform_buoyancy_position 799.326355 cc 2023-09-12T22:09:42.712Z,1694556582.712 [Reporter](INFO): platform_buoyancy_position 805.638489 cc 2023-09-12T22:09:43.122Z,1694556583.122 [Reporter](INFO): platform_buoyancy_position 809.936096 cc 2023-09-12T22:09:43.503Z,1694556583.503 [Reporter](INFO): platform_buoyancy_position 816.248169 cc 2023-09-12T22:09:43.926Z,1694556583.926 [Reporter](INFO): platform_buoyancy_position 820.814392 cc 2023-09-12T22:09:44.332Z,1694556584.332 [Reporter](INFO): platform_buoyancy_position 826.857910 cc 2023-09-12T22:09:44.712Z,1694556584.712 [Reporter](INFO): platform_buoyancy_position 831.289795 cc 2023-09-12T22:09:45.132Z,1694556585.132 [Reporter](INFO): platform_buoyancy_position 837.467529 cc 2023-09-12T22:09:45.497Z,1694556585.497 [Reporter](INFO): platform_buoyancy_position 842.033813 cc 2023-09-12T22:09:45.912Z,1694556585.912 [Reporter](INFO): platform_buoyancy_position 846.331421 cc 2023-09-12T22:09:46.329Z,1694556586.329 [Reporter](INFO): platform_buoyancy_position 852.912109 cc 2023-09-12T22:09:46.708Z,1694556586.708 [Reporter](INFO): platform_buoyancy_position 857.209717 cc 2023-09-12T22:09:47.116Z,1694556587.116 [Reporter](INFO): platform_buoyancy_position 863.656128 cc 2023-09-12T22:09:47.531Z,1694556587.531 [Reporter](INFO): platform_buoyancy_position 867.819336 cc 2023-09-12T22:09:48.004Z,1694556588.004 [Reporter](INFO): platform_buoyancy_position 874.400024 cc 2023-09-12T22:09:48.356Z,1694556588.356 [Reporter](INFO): platform_buoyancy_position 878.697632 cc 2023-09-12T22:09:48.760Z,1694556588.760 [Reporter](INFO): platform_buoyancy_position 885.009766 cc 2023-09-12T22:09:49.241Z,1694556589.241 [Reporter](INFO): platform_buoyancy_position 889.978882 cc 2023-09-12T22:09:49.571Z,1694556589.571 [Reporter](INFO): platform_buoyancy_position 895.753784 cc 2023-09-12T22:09:49.976Z,1694556589.976 [Reporter](INFO): platform_buoyancy_position 900.051392 cc 2023-09-12T22:09:50.381Z,1694556590.381 [Reporter](INFO): platform_buoyancy_position 906.497803 cc 2023-09-12T22:09:50.760Z,1694556590.760 [Reporter](INFO): platform_buoyancy_position 911.063965 cc 2023-09-12T22:09:51.172Z,1694556591.172 [Reporter](INFO): platform_buoyancy_position 917.241821 cc 2023-09-12T22:09:51.577Z,1694556591.577 [Reporter](INFO): platform_buoyancy_position 921.673706 cc 2023-09-12T22:09:51.961Z,1694556591.961 [Reporter](INFO): platform_buoyancy_position 925.971313 cc 2023-09-12T22:09:52.372Z,1694556592.372 [Reporter](INFO): platform_buoyancy_position 932.283325 cc 2023-09-12T22:09:52.788Z,1694556592.788 [Reporter](INFO): platform_buoyancy_position 936.715332 cc 2023-09-12T22:09:53.204Z,1694556593.204 [Reporter](INFO): platform_buoyancy_position 941.147217 cc 2023-09-12T22:09:53.597Z,1694556593.597 [Reporter](INFO): platform_buoyancy_position 945.310425 cc 2023-09-12T22:10:00.791Z,1694556600.791 [CommandExec](IMPORTANT): got command report clear 2023-09-12T22:10:28.687Z,1694556628.687 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-12T22:10:28.690Z,1694556628.690 [BPC1](INFO): Received data from all battery sticks. 2023-09-12T22:13:05.071Z,1694556785.071 [Power24vConverter](INFO): Powering down. 2023-09-12T22:13:10.337Z,1694556790.337 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-12T22:13:10.337Z,1694556790.337 [Default:CheckIn:C.Wait] Stopped 2023-09-12T22:13:10.337Z,1694556790.337 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-12T22:13:10.337Z,1694556790.337 [Default:CheckIn:D] Running Loop=1 2023-09-12T22:13:10.747Z,1694556790.747 [Default:CheckIn:D] Stopped 2023-09-12T22:13:10.747Z,1694556790.747 [Default:CheckIn:E] Running Loop=1 2023-09-12T22:13:11.143Z,1694556791.143 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.209047 min 2023-09-12T22:13:11.143Z,1694556791.143 [Default:CheckIn:E] Stopped 2023-09-12T22:13:11.143Z,1694556791.143 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-12T22:13:11.144Z,1694556791.144 [Default:CheckIn] Stopped 2023-09-12T22:13:11.144Z,1694556791.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-12T22:13:11.144Z,1694556791.144 [Default:CheckIn](INFO): Running loop #2 2023-09-12T22:13:11.144Z,1694556791.144 [Default:CheckIn] Running Loop=2 2023-09-12T22:13:11.144Z,1694556791.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-12T22:13:11.144Z,1694556791.144 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-12T22:13:13.157Z,1694556793.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221312.00,A,3648.16690,N,12147.28321,W,0.272,137.42,120923,,,A*78 2023-09-12T22:13:13.159Z,1694556793.159 [NAL9602](INFO): GPS fix at 20230912T221312: (36.802782, -121.788054) 2023-09-12T22:13:13.171Z,1694556793.171 [Default:CheckIn:Read_GPS] Stopped 2023-09-12T22:13:13.171Z,1694556793.171 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-12T22:13:19.338Z,1694556799.338 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230912T220252/Courier0007.lzma 2023-09-12T22:13:20.340Z,1694556800.340 [DataOverHttps](INFO): Moved sent file to Logs/20230912T220252/Courier0007.lzma.bak 2023-09-12T22:13:20.340Z,1694556800.340 [DataOverHttps](INFO): SBD MOMSN=18764562 2023-09-12T22:13:40.834Z,1694556820.834 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20230912T220252/Express0008.lzma 2023-09-12T22:13:41.836Z,1694556821.836 [DataOverHttps](INFO): Moved sent file to Logs/20230912T220252/Express0008.lzma.bak 2023-09-12T22:13:41.836Z,1694556821.836 [DataOverHttps](INFO): SBD MOMSN=18764565 2023-09-12T22:13:47.589Z,1694556827.589 [Default:CheckIn:Read_Iridium] Stopped 2023-09-12T22:13:47.589Z,1694556827.589 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-12T22:13:47.589Z,1694556827.589 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-12T22:16:34.567Z,1694556994.567 [CommandExec](IMPORTANT): got command strobe off 2023-09-12T22:16:34.567Z,1694556994.567 [CommandExec](IMPORTANT): Deactivating strobe 2023-09-12T22:16:43.644Z,1694557003.644 [CommandExec](IMPORTANT): got command show variable buoyancyneutral 2023-09-12T22:16:43.678Z,1694557003.678 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral (cubic_centimeter) 2023-09-12T22:16:43.713Z,1694557003.713 [CommandExec](IMPORTANT): Simulator.buoyancyNeutralOffset (cubic_centimeter) 2023-09-12T22:17:00.607Z,1694557020.607 [CommandExec](IMPORTANT): got command get VerticalControl.buoyancyNeutral 2023-09-12T22:17:00.607Z,1694557020.607 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral 381.902679 cc 2023-09-12T22:17:28.086Z,1694557048.086 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 200 cubic_centimeter persist 2023-09-12T22:17:35.062Z,1694557055.062 [CommandExec](IMPORTANT): got command quit 2023-09-12T22:17:36.066Z,1694557056.066 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-09-12T22:17:36.066Z,1694557056.066 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:36.066Z,1694557056.066 [CommandExec](INFO): Uninitializing the command executive. 2023-09-12T22:17:36.066Z,1694557056.066 [CommandExec](INFO): Uninitializing the command scheduler. 2023-09-12T22:17:36.067Z,1694557056.067 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:36.070Z,1694557056.070 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-09-12T22:17:36.070Z,1694557056.070 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-09-12T22:17:36.070Z,1694557056.070 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:36.071Z,1694557056.071 [NavChartDb](INFO): Join timeout helper Thread ID is 5943 2023-09-12T22:17:36.350Z,1694557056.350 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:36.350Z,1694557056.350 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:36.362Z,1694557056.362 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-09-12T22:17:36.362Z,1694557056.362 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:36.362Z,1694557056.362 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5944 2023-09-12T22:17:36.818Z,1694557056.818 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:36.818Z,1694557056.818 [WetLabsBB2FL](INFO): Powering down 2023-09-12T22:17:36.819Z,1694557056.819 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:36.834Z,1694557056.834 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-09-12T22:17:36.834Z,1694557056.834 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:36.834Z,1694557056.834 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5945 2023-09-12T22:17:37.450Z,1694557057.450 [CTD_Seabird](INFO): Powering down 2023-09-12T22:17:37.470Z,1694557057.470 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:37.470Z,1694557057.470 [CTD_Seabird](INFO): Powering down 2023-09-12T22:17:37.482Z,1694557057.482 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:37.486Z,1694557057.486 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-09-12T22:17:37.486Z,1694557057.486 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:37.486Z,1694557057.486 [Radio_Surface](INFO): Join timeout helper Thread ID is 5946 2023-09-12T22:17:37.774Z,1694557057.774 [Radio_Surface](INFO): Powering down 2023-09-12T22:17:37.775Z,1694557057.775 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:37.775Z,1694557057.775 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:37.794Z,1694557057.794 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-09-12T22:17:37.794Z,1694557057.794 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:37.794Z,1694557057.794 [Onboard](INFO): Join timeout helper Thread ID is 5947 2023-09-12T22:17:38.190Z,1694557058.190 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-09-12T22:17:39.362Z,1694557059.362 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:39.362Z,1694557059.362 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:39.375Z,1694557059.375 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-09-12T22:17:39.375Z,1694557059.375 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:39.375Z,1694557059.375 [DataOverHttps](INFO): Join timeout helper Thread ID is 5948 2023-09-12T22:17:40.198Z,1694557060.198 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:40.198Z,1694557060.198 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.210Z,1694557060.210 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-09-12T22:17:40.211Z,1694557060.211 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.211Z,1694557060.211 [BackseatComponent](INFO): Join timeout helper Thread ID is 5949 2023-09-12T22:17:40.282Z,1694557060.282 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:40.282Z,1694557060.282 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.284Z,1694557060.284 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-09-12T22:17:40.284Z,1694557060.284 [logger ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.284Z,1694557060.284 [logger](INFO): Join timeout helper Thread ID is 5950 2023-09-12T22:17:40.326Z,1694557060.326 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:40.326Z,1694557060.326 [logger ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.342Z,1694557060.342 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-09-12T22:17:40.343Z,1694557060.343 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.343Z,1694557060.343 [CommandLine](INFO): Join timeout helper Thread ID is 5951 2023-09-12T22:17:40.414Z,1694557060.414 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:40.414Z,1694557060.414 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.422Z,1694557060.422 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-09-12T22:17:40.423Z,1694557060.423 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.423Z,1694557060.423 [CommandExec](INFO): Join timeout helper Thread ID is 5952 2023-09-12T22:17:40.424Z,1694557060.424 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-09-12T22:17:40.424Z,1694557060.424 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.425Z,1694557060.425 [controlThread](INFO): Join timeout helper Thread ID is 5953 2023-09-12T22:17:40.654Z,1694557060.654 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-09-12T22:17:40.654Z,1694557060.654 [controlThread](DEBUG): Uninitializing ControlThread 2023-09-12T22:17:40.655Z,1694557060.655 [AHRS_M2](INFO): Powering down 2023-09-12T22:17:40.727Z,1694557060.727 [NAL9602](INFO): Powering down 2023-09-12T22:17:40.729Z,1694557060.729 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-09-12T22:17:40.729Z,1694557060.729 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-09-12T22:17:40.730Z,1694557060.730 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-09-12T22:17:40.731Z,1694557060.731 [MissionManager](INFO): Uninitializing Mission Default 2023-09-12T22:17:40.731Z,1694557060.731 [Default] Stopped 2023-09-12T22:17:40.731Z,1694557060.731 [Default](DEBUG): Aggregate::uninitialize Default 2023-09-12T22:17:40.731Z,1694557060.731 [Default:B.GoToSurface] Stopped 2023-09-12T22:17:40.731Z,1694557060.731 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-09-12T22:17:40.731Z,1694557060.731 [Default:CheckIn] Stopped 2023-09-12T22:17:40.731Z,1694557060.731 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-12T22:17:40.731Z,1694557060.731 [Default:CheckIn:C.Wait] Stopped 2023-09-12T22:17:40.731Z,1694557060.731 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-12T22:17:40.734Z,1694557060.734 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-09-12T22:17:40.735Z,1694557060.735 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-09-12T22:17:40.735Z,1694557060.735 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-09-12T22:17:40.735Z,1694557060.735 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-09-12T22:17:40.735Z,1694557060.735 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-09-12T22:17:40.736Z,1694557060.736 [BuoyancyServo](INFO): Powering down 2023-09-12T22:17:40.750Z,1694557060.750 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-09-12T22:17:40.750Z,1694557060.750 [ElevatorServo](INFO): Powering down 2023-09-12T22:17:40.751Z,1694557060.751 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-09-12T22:17:40.751Z,1694557060.751 [MassServo](INFO): Powering down 2023-09-12T22:17:40.752Z,1694557060.752 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-09-12T22:17:40.752Z,1694557060.752 [RudderServo](INFO): Powering down 2023-09-12T22:17:40.753Z,1694557060.753 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-09-12T22:17:40.753Z,1694557060.753 [ThrusterHE](INFO): Powering down 2023-09-12T22:17:40.754Z,1694557060.754 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-09-12T22:17:40.754Z,1694557060.754 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-09-12T22:17:40.754Z,1694557060.754 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-09-12T22:17:40.755Z,1694557060.755 [CBIT](DEBUG): Powering off loads. 2023-09-12T22:17:40.766Z,1694557060.766 [CBIT](DEBUG): Disabling WDT. 2023-09-12T22:17:40.778Z,1694557060.778 [CBIT](DEBUG): Opening all GF detection circuits. 2023-09-12T22:17:40.779Z,1694557060.779 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.795Z,1694557060.795 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.799Z,1694557060.799 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.805Z,1694557060.805 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.849Z,1694557060.849 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.897Z,1694557060.897 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.901Z,1694557060.901 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.952Z,1694557060.952 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-09-12T22:17:40.999Z,1694557060.999 [logger ThreadHandler](INFO): Thread cancelled.