2023-10-11T21:05:30.221Z,1697058330.221 [Supervisor](DEBUG): Initializing supervisor. 2023-10-11T21:05:30.241Z,1697058330.241 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-10-11T21:05:30.241Z,1697058330.241 [SyncHandler](INFO): Protected caller Thread ID is 8108 2023-10-11T21:05:30.242Z,1697058330.242 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-10-11T21:05:30.260Z,1697058330.260 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-10-11T21:05:30.272Z,1697058330.272 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8109 2023-10-11T21:05:30.276Z,1697058330.276 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-10-11T21:05:30.322Z,1697058330.322 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-10-11T21:05:30.324Z,1697058330.324 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-10-11T21:05:30.324Z,1697058330.324 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8113 2023-10-11T21:05:30.340Z,1697058330.340 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-10-11T21:05:30.352Z,1697058330.352 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-10-11T21:05:30.352Z,1697058330.352 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8115 2023-10-11T21:05:30.354Z,1697058330.354 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-10-11T21:05:30.361Z,1697058330.361 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-10-11T21:05:30.361Z,1697058330.361 [logger ThreadHandler](INFO): Protected caller Thread ID is 8117 2023-10-11T21:05:30.365Z,1697058330.365 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-10-11T21:05:30.365Z,1697058330.365 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-10-11T21:05:30.367Z,1697058330.367 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-10-11T21:05:30.467Z,1697058330.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-10-11T21:05:30.468Z,1697058330.468 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-10-11T21:05:31.139Z,1697058331.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-10-11T21:05:31.139Z,1697058331.139 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-10-11T21:05:31.352Z,1697058331.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-10-11T21:05:31.352Z,1697058331.352 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-10-11T21:05:31.433Z,1697058331.433 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-10-11T21:05:31.562Z,1697058331.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-10-11T21:05:31.563Z,1697058331.563 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-10-11T21:05:31.646Z,1697058331.646 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-10-11T21:05:31.753Z,1697058331.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-10-11T21:05:31.753Z,1697058331.753 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-10-11T21:05:32.123Z,1697058332.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-10-11T21:05:32.123Z,1697058332.123 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-10-11T21:05:32.667Z,1697058332.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-10-11T21:05:32.668Z,1697058332.668 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-10-11T21:05:32.878Z,1697058332.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-10-11T21:05:32.879Z,1697058332.879 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-10-11T21:05:33.064Z,1697058333.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-10-11T21:05:33.064Z,1697058333.064 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-10-11T21:05:33.611Z,1697058333.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-10-11T21:05:33.612Z,1697058333.612 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-10-11T21:05:33.889Z,1697058333.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-10-11T21:05:33.889Z,1697058333.889 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-10-11T21:05:34.235Z,1697058334.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-10-11T21:05:34.236Z,1697058334.236 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-10-11T21:05:34.607Z,1697058334.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-10-11T21:05:34.608Z,1697058334.608 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-10-11T21:05:34.952Z,1697058334.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-10-11T21:05:34.955Z,1697058334.955 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2023-10-11T21:05:34.956Z,1697058334.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2023-10-11T21:05:35.186Z,1697058335.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2023-10-11T21:05:35.615Z,1697058335.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2023-10-11T21:05:35.721Z,1697058335.721 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2023-10-11T21:05:35.809Z,1697058335.809 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2023-10-11T21:05:35.906Z,1697058335.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2023-10-11T21:05:36.022Z,1697058336.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2023-10-11T21:05:36.286Z,1697058336.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-10-11T21:05:36.286Z,1697058336.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2023-10-11T21:05:36.381Z,1697058336.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2023-10-11T21:05:36.480Z,1697058336.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2023-10-11T21:05:36.619Z,1697058336.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2023-10-11T21:05:36.720Z,1697058336.720 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2023-10-11T21:05:36.720Z,1697058336.720 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-10-11T21:05:36.734Z,1697058336.734 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-10-11T21:05:36.831Z,1697058336.831 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-10-11T21:05:36.832Z,1697058336.832 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-10-11T21:05:36.881Z,1697058336.881 [VerticalControl](DEBUG): Construct VerticalControl. 2023-10-11T21:05:36.941Z,1697058336.941 [VerticalControl] Loaded 2023-10-11T21:05:36.941Z,1697058336.941 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-10-11T21:05:36.944Z,1697058336.944 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-10-11T21:05:36.984Z,1697058336.984 [HorizontalControl] Loaded 2023-10-11T21:05:36.985Z,1697058336.985 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-10-11T21:05:36.987Z,1697058336.987 [SpeedControl](DEBUG): Construct SpeedControl. 2023-10-11T21:05:36.990Z,1697058336.990 [SpeedControl] Loaded 2023-10-11T21:05:36.990Z,1697058336.990 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-10-11T21:05:36.993Z,1697058336.993 [LoopControl](DEBUG): Construct LoopControl. 2023-10-11T21:05:36.993Z,1697058336.993 [LoopControl] Loaded 2023-10-11T21:05:36.994Z,1697058336.994 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-10-11T21:05:36.994Z,1697058336.994 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-10-11T21:05:36.994Z,1697058336.994 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-10-11T21:05:37.043Z,1697058337.043 [DepthRateCalculator] Loaded 2023-10-11T21:05:37.043Z,1697058337.043 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-10-11T21:05:37.048Z,1697058337.048 [PitchRateCalculator] Loaded 2023-10-11T21:05:37.049Z,1697058337.049 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-10-11T21:05:37.059Z,1697058337.059 [SpeedCalculator] Loaded 2023-10-11T21:05:37.059Z,1697058337.059 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-10-11T21:05:37.063Z,1697058337.063 [YawRateCalculator] Loaded 2023-10-11T21:05:37.063Z,1697058337.063 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-10-11T21:05:37.082Z,1697058337.082 [ElevatorOffsetCalculator] Loaded 2023-10-11T21:05:37.082Z,1697058337.082 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-10-11T21:05:37.082Z,1697058337.082 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-10-11T21:05:37.083Z,1697058337.083 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-10-11T21:05:37.109Z,1697058337.109 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-10-11T21:05:37.109Z,1697058337.109 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-10-11T21:05:37.214Z,1697058337.214 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-10-11T21:05:37.215Z,1697058337.215 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-10-11T21:05:37.507Z,1697058337.507 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-10-11T21:05:37.508Z,1697058337.508 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-10-11T21:05:37.650Z,1697058337.650 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-10-11T21:05:37.651Z,1697058337.651 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-10-11T21:05:38.187Z,1697058338.187 [AHRS_M2] Loaded 2023-10-11T21:05:38.188Z,1697058338.188 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-10-11T21:05:38.223Z,1697058338.223 [BackseatComponent] Loaded 2023-10-11T21:05:38.223Z,1697058338.223 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-10-11T21:05:38.224Z,1697058338.224 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4098F4E0 2023-10-11T21:05:38.224Z,1697058338.224 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8220 2023-10-11T21:05:38.227Z,1697058338.227 [LcmUniversalReporter] Loaded 2023-10-11T21:05:38.227Z,1697058338.227 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-10-11T21:05:39.040Z,1697058339.040 [BPC1] Loaded 2023-10-11T21:05:39.040Z,1697058339.040 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-10-11T21:05:39.113Z,1697058339.113 [DataOverHttps] Loaded 2023-10-11T21:05:39.113Z,1697058339.113 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-10-11T21:05:39.114Z,1697058339.114 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0 2023-10-11T21:05:39.114Z,1697058339.114 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8221 2023-10-11T21:05:39.135Z,1697058339.135 [Depth_Keller] Loaded 2023-10-11T21:05:39.135Z,1697058339.135 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-10-11T21:05:39.140Z,1697058339.140 [DropWeight] Loaded 2023-10-11T21:05:39.140Z,1697058339.140 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-10-11T21:05:39.203Z,1697058339.203 [NAL9602] Loaded 2023-10-11T21:05:39.203Z,1697058339.203 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-10-11T21:05:39.233Z,1697058339.233 [Onboard] Loaded 2023-10-11T21:05:39.233Z,1697058339.233 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-10-11T21:05:39.234Z,1697058339.234 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0 2023-10-11T21:05:39.234Z,1697058339.234 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8222 2023-10-11T21:05:39.240Z,1697058339.240 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1 2023-10-11T21:05:39.252Z,1697058339.252 [PowerOnly] Loaded 2023-10-11T21:05:39.252Z,1697058339.252 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-10-11T21:05:39.264Z,1697058339.264 [Power24vConverter] Loaded 2023-10-11T21:05:39.264Z,1697058339.264 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-10-11T21:05:39.277Z,1697058339.277 [Radio_Surface] Loaded 2023-10-11T21:05:39.277Z,1697058339.277 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-10-11T21:05:39.278Z,1697058339.278 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1F4E0 2023-10-11T21:05:39.278Z,1697058339.278 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8223 2023-10-11T21:05:39.370Z,1697058339.370 [DAT] Loaded 2023-10-11T21:05:39.370Z,1697058339.370 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-10-11T21:05:39.371Z,1697058339.371 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0 2023-10-11T21:05:39.372Z,1697058339.372 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8224 2023-10-11T21:05:39.372Z,1697058339.372 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-10-11T21:05:39.373Z,1697058339.373 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-10-11T21:05:39.482Z,1697058339.482 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-10-11T21:05:39.483Z,1697058339.483 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-10-11T21:05:39.497Z,1697058339.497 [NavChart] Loaded 2023-10-11T21:05:39.497Z,1697058339.497 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-10-11T21:05:39.502Z,1697058339.502 [UniversalFixResidualReporter] Loaded 2023-10-11T21:05:39.503Z,1697058339.503 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-10-11T21:05:39.503Z,1697058339.503 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-10-11T21:05:39.504Z,1697058339.504 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-10-11T21:05:39.638Z,1697058339.638 [SBIT](DEBUG): Construct Startup Built In Test. 2023-10-11T21:05:39.647Z,1697058339.647 [SBIT] Loaded 2023-10-11T21:05:39.647Z,1697058339.647 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-10-11T21:05:39.650Z,1697058339.650 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-10-11T21:05:39.663Z,1697058339.663 [IBIT] Loaded 2023-10-11T21:05:39.663Z,1697058339.663 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-10-11T21:05:39.669Z,1697058339.669 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-10-11T21:05:39.774Z,1697058339.774 [CBIT] Loaded 2023-10-11T21:05:39.774Z,1697058339.774 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-10-11T21:05:39.774Z,1697058339.774 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-10-11T21:05:39.775Z,1697058339.775 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-10-11T21:05:39.902Z,1697058339.902 [BuoyancyServo] Loaded 2023-10-11T21:05:39.903Z,1697058339.903 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-10-11T21:05:39.923Z,1697058339.923 [ElevatorServo] Loaded 2023-10-11T21:05:39.923Z,1697058339.923 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-10-11T21:05:39.943Z,1697058339.943 [MassServo] Loaded 2023-10-11T21:05:39.943Z,1697058339.943 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-10-11T21:05:39.962Z,1697058339.962 [RudderServo] Loaded 2023-10-11T21:05:39.962Z,1697058339.962 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-10-11T21:05:39.979Z,1697058339.979 [ThrusterServo] Loaded 2023-10-11T21:05:39.979Z,1697058339.979 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2023-10-11T21:05:39.980Z,1697058339.980 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-10-11T21:05:39.980Z,1697058339.980 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-10-11T21:05:40.003Z,1697058340.003 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-10-11T21:05:40.003Z,1697058340.003 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-10-11T21:05:40.414Z,1697058340.414 [CTD_Seabird] Loaded 2023-10-11T21:05:40.414Z,1697058340.414 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-10-11T21:05:40.415Z,1697058340.415 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9A4E0 2023-10-11T21:05:40.416Z,1697058340.416 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8225 2023-10-11T21:05:40.453Z,1697058340.453 [PAR_Licor] Loaded 2023-10-11T21:05:40.453Z,1697058340.453 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-10-11T21:05:40.524Z,1697058340.524 [WetLabsBB2FL] Loaded 2023-10-11T21:05:40.524Z,1697058340.524 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-10-11T21:05:40.537Z,1697058340.537 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0 2023-10-11T21:05:40.538Z,1697058340.538 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8226 2023-10-11T21:05:40.557Z,1697058340.557 [WetLabsUBAT] Loaded 2023-10-11T21:05:40.558Z,1697058340.558 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2023-10-11T21:05:40.559Z,1697058340.559 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BFA4E0 2023-10-11T21:05:40.559Z,1697058340.559 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 8227 2023-10-11T21:05:40.560Z,1697058340.560 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-10-11T21:05:40.731Z,1697058340.731 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-10-11T21:05:40.734Z,1697058340.734 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-10-11T21:05:40.746Z,1697058340.746 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-10-11T21:05:40.747Z,1697058340.747 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C2A4E0 2023-10-11T21:05:40.747Z,1697058340.747 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8228 2023-10-11T21:05:40.752Z,1697058340.752 [Supervisor](INFO): Main Thread ID is 8105 2023-10-11T21:05:40.752Z,1697058340.752 [Supervisor](DEBUG): Running supervisor. 2023-10-11T21:05:40.752Z,1697058340.752 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8229 2023-10-11T21:05:40.753Z,1697058340.753 [CommandExec](INFO): Initializing the command executive. 2023-10-11T21:05:40.754Z,1697058340.754 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8230 2023-10-11T21:05:40.757Z,1697058340.757 [controlThread ThreadHandler](INFO): Handler Thread ID is 8231 2023-10-11T21:05:40.757Z,1697058340.757 [controlThread](DEBUG): Initializing ControlThread 2023-10-11T21:05:40.758Z,1697058340.758 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-10-11T21:05:40.760Z,1697058340.760 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-10-11T21:05:40.760Z,1697058340.760 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-10-11T21:05:40.761Z,1697058340.761 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-10-11T21:05:40.761Z,1697058340.761 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-10-11T21:05:40.762Z,1697058340.762 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-10-11T21:05:40.762Z,1697058340.762 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-10-11T21:05:40.762Z,1697058340.762 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-10-11T21:05:40.763Z,1697058340.763 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-10-11T21:05:40.771Z,1697058340.771 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-10-11T21:05:40.771Z,1697058340.771 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-10-11T21:05:40.771Z,1697058340.771 [SBIT](INFO): Initialize SBIT Component. 2023-10-11T21:05:40.772Z,1697058340.772 [SBIT](IMPORTANT): git: 2023-08-02-8-ga2b2183f7 2023-10-11T21:05:40.772Z,1697058340.772 [SBIT](INFO): git hash: a2b2183f71e07b707bfeaaf571c74c3561721119 2023-10-11T21:05:40.772Z,1697058340.772 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-10-11T21:05:40.773Z,1697058340.773 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-10-11T21:05:40.775Z,1697058340.775 [SBIT](INFO): Beginning SBIT in 33.000000 seconds. 2023-10-11T21:05:40.775Z,1697058340.775 [IBIT](INFO): Initialize IBIT Component. 2023-10-11T21:05:40.776Z,1697058340.776 [CBIT](DEBUG): Initialize CBIT Component. 2023-10-11T21:05:40.777Z,1697058340.777 [logger ThreadHandler](INFO): Handler Thread ID is 8232 2023-10-11T21:05:40.788Z,1697058340.788 [CBIT](DEBUG): Initialized mux pins. 2023-10-11T21:05:40.788Z,1697058340.788 [CBIT](DEBUG): Initializing the watchdog timer. 2023-10-11T21:05:40.796Z,1697058340.796 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8233 2023-10-11T21:05:40.808Z,1697058340.808 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8234 2023-10-11T21:05:40.809Z,1697058340.809 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-10-11T21:05:40.812Z,1697058340.812 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-10-11T21:05:40.813Z,1697058340.813 [CBIT](DEBUG): Initializing heartbeat. 2023-10-11T21:05:40.820Z,1697058340.820 [Onboard ThreadHandler](INFO): Handler Thread ID is 8235 2023-10-11T21:05:40.844Z,1697058340.844 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8236 2023-10-11T21:05:40.868Z,1697058340.868 [DAT ThreadHandler](INFO): Handler Thread ID is 8237 2023-10-11T21:05:40.869Z,1697058340.869 [DAT](INFO): Powering up 2023-10-11T21:05:40.869Z,1697058340.869 [DAT](DEBUG): Initializing DAT. 2023-10-11T21:05:40.873Z,1697058340.873 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8239 2023-10-11T21:05:40.874Z,1697058340.874 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-10-11T21:05:40.877Z,1697058340.877 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8241 2023-10-11T21:05:40.880Z,1697058340.880 [WetLabsBB2FL](INFO): Powering up 2023-10-11T21:05:40.881Z,1697058340.881 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 8243 2023-10-11T21:05:40.884Z,1697058340.884 [CBIT](DEBUG): Deactivating GF circuits. 2023-10-11T21:05:40.884Z,1697058340.884 [CBIT](DEBUG): Deactivating emergency mode. 2023-10-11T21:05:40.885Z,1697058340.885 [WetLabsUBAT](INFO): Powering up 2023-10-11T21:05:40.886Z,1697058340.886 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8245 2023-10-11T21:05:40.890Z,1697058340.890 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-10-11T21:05:40.890Z,1697058340.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-10-11T21:05:40.890Z,1697058340.890 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-10-11T21:05:40.890Z,1697058340.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-10-11T21:05:40.891Z,1697058340.891 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-10-11T21:05:40.891Z,1697058340.891 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-10-11T21:05:40.891Z,1697058340.891 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-10-11T21:05:40.891Z,1697058340.891 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-10-11T21:05:40.924Z,1697058340.924 [CBIT](DEBUG): Backplane powered. 2023-10-11T21:05:40.929Z,1697058340.929 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-10-11T21:05:40.958Z,1697058340.958 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-11T21:05:41.014Z,1697058341.014 [MissionManager](DEBUG): 2023-10-11T21:05:41.015Z,1697058341.015 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-10-11T21:05:41.128Z,1697058341.128 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-10-11T21:05:41.129Z,1697058341.129 [Default:A.Wait](DEBUG): Construct Wait. 2023-10-11T21:05:41.132Z,1697058341.132 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-11T21:05:41.186Z,1697058341.186 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-10-11T21:05:41.189Z,1697058341.189 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-10-11T21:05:41.207Z,1697058341.207 [Default:E.Execute](DEBUG): Construct Execute. 2023-10-11T21:05:41.229Z,1697058341.229 [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-10-11T21:05:41.233Z,1697058341.233 [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,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-10-11T21:05:41.240Z,1697058341.240 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-10-11T21:05:41.248Z,1697058341.248 [Radio_Surface](INFO): Powering up 2023-10-11T21:05:41.313Z,1697058341.313 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m 2023-10-11T21:05:41.315Z,1697058341.315 [PowerOnly](INFO): Powering up loadControl 2023-10-11T21:05:41.332Z,1697058341.332 [Power24vConverter](INFO): Powering up. 2023-10-11T21:05:41.342Z,1697058341.342 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-10-11T21:05:41.388Z,1697058341.388 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-10-11T21:05:41.396Z,1697058341.396 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-10-11T21:05:41.397Z,1697058341.397 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-10-11T21:05:41.404Z,1697058341.404 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-10-11T21:05:41.405Z,1697058341.405 [MassServo](DEBUG): Initializing EZServoServo. 2023-10-11T21:05:41.412Z,1697058341.412 [MassServo](DEBUG): Initializing MassServo. 2023-10-11T21:05:41.413Z,1697058341.413 [RudderServo](DEBUG): Initializing EZServoServo. 2023-10-11T21:05:41.420Z,1697058341.420 [RudderServo](DEBUG): Initializing RudderServo. 2023-10-11T21:05:41.421Z,1697058341.421 [ThrusterServo](DEBUG): Initializing EZServoServo. 2023-10-11T21:05:41.428Z,1697058341.428 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2023-10-11T21:05:43.492Z,1697058343.492 [WetLabsBB2FL](INFO): Powering down 2023-10-11T21:05:50.160Z,1697058350.160 [WetLabsUBAT](INFO): Powering down 2023-10-11T21:05:53.216Z,1697058353.216 [DAT](INFO): DAT read: 2023-10-11T21:05:53.218Z,1697058353.218 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-10-11T21:05:54.729Z,1697058354.729 [DAT](INFO): DAT read: MF Frequency Band 2023-10-11T21:05:54.730Z,1697058354.730 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-10-11T21:05:54.730Z,1697058354.730 [DAT](INFO): DAT read: Oct 11 2023 20:59:22 2023-10-11T21:05:55.174Z,1697058355.174 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004743 2023-10-11T21:05:55.989Z,1697058355.989 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-10-11T21:05:55.990Z,1697058355.990 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-10-11T21:05:55.990Z,1697058355.990 [DAT](INFO): commRate: 800 2023-10-11T21:05:58.056Z,1697058358.056 [DAT](INFO): entering command mode 2023-10-11T21:05:58.256Z,1697058358.256 [DAT](INFO): DAT read: 2023-10-11T21:05:58.257Z,1697058358.257 [DAT](INFO): DAT read: user:1> 2023-10-11T21:05:58.257Z,1697058358.257 [DAT](INFO): setting verbose to 3 2023-10-11T21:05:58.508Z,1697058358.508 [DAT](INFO): DAT read: user:1> 2023-10-11T21:05:58.509Z,1697058358.509 [DAT](INFO): DAT read: Verbose | 3 2023-10-11T21:05:58.510Z,1697058358.510 [DAT](INFO): set verbose to 3 2023-10-11T21:05:58.510Z,1697058358.510 [DAT](INFO): setting DatVerbose to 27440 2023-10-11T21:05:58.760Z,1697058358.760 [DAT](INFO): DAT read: user:2> 2023-10-11T21:05:58.761Z,1697058358.761 [DAT](INFO): DAT read: DatVerbose | 27440 2023-10-11T21:05:58.762Z,1697058358.762 [DAT](INFO): set DatVerbose to 27440 2023-10-11T21:05:58.762Z,1697058358.762 [DAT](INFO): setting transmit power to 8 2023-10-11T21:05:59.012Z,1697058359.012 [DAT](INFO): DAT read: user:3> 2023-10-11T21:05:59.013Z,1697058359.013 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-10-11T21:05:59.014Z,1697058359.014 [DAT](INFO): set transmit power to 8 2023-10-11T21:05:59.014Z,1697058359.014 [DAT](INFO): setting local address to 10 2023-10-11T21:05:59.264Z,1697058359.264 [DAT](INFO): DAT read: user:4> 2023-10-11T21:05:59.265Z,1697058359.265 [DAT](INFO): DAT read: LocalAddr | 10 2023-10-11T21:05:59.266Z,1697058359.266 [DAT](INFO): set local address to 10 2023-10-11T21:05:59.267Z,1697058359.267 [DAT](INFO): Setting time to: 21:5:59 And date to:10/11/2023 2023-10-11T21:05:59.516Z,1697058359.516 [DAT](INFO): DAT read: user:5> 2023-10-11T21:05:59.517Z,1697058359.517 [DAT](INFO): DAT read: Wed Oct 11, 2023 21:05:59 2023-10-11T21:05:59.518Z,1697058359.518 [DAT](INFO): Local DAT time set to Wed Oct 11, 2023 21:05:59 2023-10-11T21:06:09.545Z,1697058369.545 [NAL9602](INFO): Powering up NAL9602 2023-10-11T21:06:14.436Z,1697058374.436 [SBIT](IMPORTANT): Beginning Startup BIT 2023-10-11T21:06:14.444Z,1697058374.444 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-11T21:06:20.453Z,1697058380.453 [NAL9602](INFO): NAL9602 initialized 2023-10-11T21:06:25.377Z,1697058385.377 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002897 CHAN A1 (24V): 0.000540 CHAN A2 (12V): -0.007035 CHAN A3 (5V): -0.002845 CHAN B0 (3.3V): 0.000097 CHAN B1 (3.15aV): 0.000684 CHAN B2 (3.15bV): 0.000475 CHAN B3 (GND): 0.001657 OPEN: 0.115291 Full Scale: +/- 1 mA 2023-10-11T21:06:29.457Z,1697058389.457 [CommandExec](IMPORTANT): got command strobe off 2023-10-11T21:06:29.457Z,1697058389.457 [CommandExec](IMPORTANT): Deactivating strobe 2023-10-11T21:06:47.488Z,1697058407.488 [CommandExec](IMPORTANT): got command failComponent 2023-10-11T21:06:47.489Z,1697058407.489 [CommandExec](IMPORTANT): Failed components: 2023-10-11T21:06:47.489Z,1697058407.489 [CommandExec](IMPORTANT): No failed Components. 2023-10-11T21:07:08.225Z,1697058428.225 [SBIT](IMPORTANT): SBIT PASSED 2023-10-11T21:07:08.226Z,1697058428.226 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-10-11T21:07:08.227Z,1697058428.227 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2023-10-11T21:07:08.227Z,1697058428.227 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere; 2023-10-11T21:07:08.227Z,1697058428.227 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-10-11T21:07:08.227Z,1697058428.227 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere; 2023-10-11T21:07:08.227Z,1697058428.227 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2023-10-11T21:07:08.227Z,1697058428.227 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour; 2023-10-11T21:07:08.227Z,1697058428.227 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2023-10-11T21:07:08.228Z,1697058428.228 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool; 2023-10-11T21:07:08.228Z,1697058428.228 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=228.230545 cubic_centimeter; 2023-10-11T21:07:08.228Z,1697058428.228 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=0 second; 2023-10-11T21:07:08.229Z,1697058428.229 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=0.0015 reciprocal_second; 2023-10-11T21:07:08.229Z,1697058428.229 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=0.01 none; 2023-10-11T21:07:08.229Z,1697058428.229 [SBIT](IMPORTANT): VerticalControl.massDeadband=0.1 millimeter; 2023-10-11T21:07:08.229Z,1697058428.229 [SBIT](IMPORTANT): VerticalControl.massDefault=3.329263 millimeter; 2023-10-11T21:07:08.229Z,1697058428.229 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2023-10-11T21:07:08.566Z,1697058428.566 [MissionManager](IMPORTANT): Started mission Startup 2023-10-11T21:07:08.567Z,1697058428.567 [Startup] Running Loop=1 2023-10-11T21:07:08.567Z,1697058428.567 [Startup](DEBUG): Aggregate::initialize Startup 2023-10-11T21:07:08.567Z,1697058428.567 [Startup:A.GoToSurface] Running Loop=1 2023-10-11T21:07:08.567Z,1697058428.567 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-11T21:07:08.592Z,1697058428.592 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-11T21:07:08.592Z,1697058428.592 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-11T21:07:08.592Z,1697058428.592 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-11T21:07:08.593Z,1697058428.593 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-11T21:07:08.593Z,1697058428.593 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-11T21:07:08.595Z,1697058428.595 [Startup:StartupSatComms] Running Loop=1 2023-10-11T21:07:08.595Z,1697058428.595 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-10-11T21:07:08.595Z,1697058428.595 [Startup:StartupSatComms:A] Running Loop=1 2023-10-11T21:07:08.975Z,1697058428.975 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-10-11T21:07:23.917Z,1697058443.917 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2023-10-11T21:07:23.918Z,1697058443.918 [CommandExec](IMPORTANT): Onboard.Pressure 7.894428 psi 2023-10-11T21:07:44.429Z,1697058464.429 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2023-10-11T21:07:44.430Z,1697058464.430 [CommandExec](IMPORTANT): Onboard.Humidity 24.618484 % 2023-10-11T21:07:56.913Z,1697058476.913 [CommandExec](IMPORTANT): got command gfscan 2023-10-11T21:07:57.040Z,1697058477.040 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-11T21:08:07.966Z,1697058487.966 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000003 CHAN A1 (24V): -0.000217 CHAN A2 (12V): -0.007371 CHAN A3 (5V): -0.002149 CHAN B0 (3.3V): -0.000086 CHAN B1 (3.15aV): -0.000357 CHAN B2 (3.15bV): -0.000020 CHAN B3 (GND): 0.002886 OPEN: 0.004891 Full Scale: +/- 1 mA 2023-10-11T21:08:08.756Z,1697058488.756 [Startup:StartupSatComms:A](INFO): Timed out from 2023-10-11T21:07:08.6Z 2023-10-11T21:08:08.756Z,1697058488.756 [Startup:StartupSatComms:A] Stopped 2023-10-11T21:08:08.756Z,1697058488.756 [Startup:StartupSatComms:B] Running Loop=1 2023-10-11T21:08:09.154Z,1697058489.154 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-11T21:08:17.370Z,1697058497.370 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231004T212333/Courier0090.lzma 2023-10-11T21:08:18.370Z,1697058498.370 [DataOverHttps](INFO): Moved sent file to Logs/20231004T212333/Courier0090.lzma.bak 2023-10-11T21:08:18.370Z,1697058498.370 [DataOverHttps](INFO): SBD MOMSN=18987921 2023-10-11T21:08:33.892Z,1697058513.892 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20231011T204020/Courier0000.lzma 2023-10-11T21:08:34.894Z,1697058514.894 [DataOverHttps](INFO): Moved sent file to Logs/20231011T204020/Courier0000.lzma.bak 2023-10-11T21:08:34.894Z,1697058514.894 [DataOverHttps](INFO): SBD MOMSN=18987923 2023-10-11T21:08:41.171Z,1697058521.171 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-10-11T21:08:41.171Z,1697058521.171 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-11T21:08:41.193Z,1697058521.193 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-11T21:08:41.492Z,1697058521.492 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-11T21:08:41.493Z,1697058521.493 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-10-11T21:08:50.615Z,1697058530.615 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231011T204020/Courier0004.lzma 2023-10-11T21:08:51.617Z,1697058531.617 [DataOverHttps](INFO): Moved sent file to Logs/20231011T204020/Courier0004.lzma.bak 2023-10-11T21:08:51.618Z,1697058531.618 [DataOverHttps](INFO): SBD MOMSN=18987928 2023-10-11T21:09:07.276Z,1697058547.276 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20231011T205622/Courier0000.lzma 2023-10-11T21:09:08.277Z,1697058548.277 [DataOverHttps](INFO): Moved sent file to Logs/20231011T205622/Courier0000.lzma.bak 2023-10-11T21:09:08.278Z,1697058548.278 [DataOverHttps](INFO): SBD MOMSN=18987930 2023-10-11T21:09:08.946Z,1697058548.946 [Startup:StartupSatComms:B](INFO): Timed out from 2023-10-11T21:08:08.8Z 2023-10-11T21:09:08.946Z,1697058548.946 [Startup:StartupSatComms:B] Stopped 2023-10-11T21:09:08.946Z,1697058548.946 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-10-11T21:09:08.946Z,1697058548.946 [Startup:StartupSatComms] Stopped 2023-10-11T21:09:08.946Z,1697058548.946 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-10-11T21:09:08.947Z,1697058548.947 [Startup](INFO): Completed Startup 2023-10-11T21:09:08.947Z,1697058548.947 [MissionManager](INFO): Startup is completed. 2023-10-11T21:09:08.947Z,1697058548.947 [MissionManager](INFO): Uninitializing Mission Startup 2023-10-11T21:09:08.947Z,1697058548.947 [Startup] Stopped 2023-10-11T21:09:08.948Z,1697058548.948 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-10-11T21:09:08.948Z,1697058548.948 [Startup:A.GoToSurface] Stopped 2023-10-11T21:09:08.948Z,1697058548.948 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-11T21:09:09.408Z,1697058549.408 [MissionManager](IMPORTANT): Started mission Default 2023-10-11T21:09:09.408Z,1697058549.408 [Default] Running Loop=1 2023-10-11T21:09:09.408Z,1697058549.408 [Default](DEBUG): Aggregate::initialize Default 2023-10-11T21:09:09.408Z,1697058549.408 [Default:B.GoToSurface] Running Loop=1 2023-10-11T21:09:09.408Z,1697058549.408 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-11T21:09:09.409Z,1697058549.409 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-11T21:09:09.409Z,1697058549.409 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-11T21:09:09.409Z,1697058549.409 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-11T21:09:09.410Z,1697058549.410 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-11T21:09:09.410Z,1697058549.410 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-11T21:09:09.410Z,1697058549.410 [Default:A.Wait] Running Loop=1 2023-10-11T21:09:09.410Z,1697058549.410 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:09:22.740Z,1697058562.740 [Default:A.Wait](INFO): Done Waiting. 2023-10-11T21:09:22.740Z,1697058562.740 [Default:A.Wait] Stopped 2023-10-11T21:09:22.740Z,1697058562.740 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:09:23.081Z,1697058563.081 [Default:CheckIn] Running Loop=1 2023-10-11T21:09:23.082Z,1697058563.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T21:09:23.082Z,1697058563.082 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T21:09:23.483Z,1697058563.483 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-10-11T21:11:23.875Z,1697058683.875 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-11T21:11:42.051Z,1697058702.051 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-10-11T21:11:42.051Z,1697058702.051 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-11T21:11:42.061Z,1697058702.061 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-11T21:11:42.479Z,1697058702.479 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-11T21:11:42.479Z,1697058702.479 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-10-11T21:12:01.220Z,1697058721.220 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T21:12:03.232Z,1697058723.232 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259205 2023-10-11T21:12:36.364Z,1697058756.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T21:13:11.512Z,1697058791.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T21:13:44.453Z,1697058824.453 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-10-11T21:13:44.457Z,1697058824.457 [BPC1](INFO): Received data from all battery sticks. 2023-10-11T21:13:46.656Z,1697058826.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T21:14:21.796Z,1697058861.796 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T21:14:23.245Z,1697058863.245 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-11T21:09:23.1Z 2023-10-11T21:14:23.246Z,1697058863.246 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T21:14:23.246Z,1697058863.246 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T21:14:23.656Z,1697058863.656 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-11T21:14:43.050Z,1697058883.050 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-10-11T21:14:43.050Z,1697058883.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-11T21:14:43.060Z,1697058883.060 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-11T21:14:43.472Z,1697058883.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-11T21:14:43.472Z,1697058883.472 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-10-11T21:14:53.138Z,1697058893.138 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2023-10-11T21:14:53.212Z,1697058893.212 [NAL9602](ERROR): received: +CSQ:0 OK 2023-10-11T21:14:55.313Z,1697058895.313 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003184 2023-10-11T21:15:02.595Z,1697058902.595 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231011T210530/Courier0000.lzma 2023-10-11T21:15:03.597Z,1697058903.597 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0000.lzma.bak 2023-10-11T21:15:03.598Z,1697058903.598 [DataOverHttps](INFO): SBD MOMSN=18987948 2023-10-11T21:15:19.164Z,1697058919.164 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20231011T210530/Courier0004.lzma 2023-10-11T21:15:20.165Z,1697058920.165 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0004.lzma.bak 2023-10-11T21:15:20.166Z,1697058920.166 [DataOverHttps](INFO): SBD MOMSN=18987951 2023-10-11T21:15:35.981Z,1697058935.981 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20231004T212333/Express0091.lzma 2023-10-11T21:15:36.982Z,1697058936.982 [DataOverHttps](INFO): Moved sent file to Logs/20231004T212333/Express0091.lzma.bak 2023-10-11T21:15:36.982Z,1697058936.982 [DataOverHttps](INFO): SBD MOMSN=18987953 2023-10-11T21:15:52.780Z,1697058952.780 [DataOverHttps](INFO): Sending 764 bytes from file Logs/20231011T204020/Express0001.lzma 2023-10-11T21:15:53.781Z,1697058953.781 [DataOverHttps](INFO): Moved sent file to Logs/20231011T204020/Express0001.lzma.bak 2023-10-11T21:15:53.782Z,1697058953.782 [DataOverHttps](INFO): SBD MOMSN=18987957 2023-10-11T21:16:10.347Z,1697058970.347 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20231011T204020/Express0005.lzma 2023-10-11T21:16:11.361Z,1697058971.361 [DataOverHttps](INFO): Moved sent file to Logs/20231011T204020/Express0005.lzma.bak 2023-10-11T21:16:11.363Z,1697058971.363 [DataOverHttps](INFO): SBD MOMSN=18987974 2023-10-11T21:16:21.646Z,1697058981.646 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-11T21:16:21.646Z,1697058981.646 [NAL9602] Data Fault, FailCount= 1 2023-10-11T21:16:21.646Z,1697058981.646 [NAL9602](ERROR): Data Fault 2023-10-11T21:16:21.699Z,1697058981.699 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-11T21:16:22.033Z,1697058982.033 [NAL9602](INFO): Powering down 2023-10-11T21:16:22.860Z,1697058982.860 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-11T21:16:22.860Z,1697058982.860 [NAL9602] No Fault, FailCount= 1 2023-10-11T21:16:29.621Z,1697058989.621 [DataOverHttps](INFO): Sending 692 bytes from file Logs/20231011T205622/Express0001.lzma 2023-10-11T21:16:30.621Z,1697058990.621 [DataOverHttps](INFO): Moved sent file to Logs/20231011T205622/Express0001.lzma.bak 2023-10-11T21:16:30.622Z,1697058990.622 [DataOverHttps](INFO): SBD MOMSN=18987977 2023-10-11T21:16:46.113Z,1697059006.113 [DataOverHttps](INFO): Sending 1271 bytes from file Logs/20231011T210530/Express0001.lzma 2023-10-11T21:16:47.113Z,1697059007.113 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0001.lzma.bak 2023-10-11T21:16:47.113Z,1697059007.113 [DataOverHttps](INFO): SBD MOMSN=18987992 2023-10-11T21:16:52.329Z,1697059012.329 [NAL9602](INFO): Powering up NAL9602 2023-10-11T21:17:03.112Z,1697059023.112 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20231011T210530/Express0005.lzma 2023-10-11T21:17:03.238Z,1697059023.238 [NAL9602](INFO): NAL9602 initialized 2023-10-11T21:17:04.114Z,1697059024.114 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0005.lzma.bak 2023-10-11T21:17:04.114Z,1697059024.114 [DataOverHttps](INFO): SBD MOMSN=18988030 2023-10-11T21:17:05.682Z,1697059025.682 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T21:17:05.682Z,1697059025.682 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T21:17:05.682Z,1697059025.682 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:17:44.068Z,1697059064.068 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-10-11T21:17:44.069Z,1697059064.069 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-11T21:17:44.116Z,1697059064.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-11T21:17:44.483Z,1697059064.483 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-11T21:17:44.483Z,1697059064.483 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-10-11T21:20:45.051Z,1697059245.051 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-10-11T21:20:45.052Z,1697059245.052 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-11T21:20:45.062Z,1697059245.062 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-11T21:20:45.491Z,1697059245.491 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-11T21:20:45.491Z,1697059245.491 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-10-11T21:22:03.825Z,1697059323.825 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212202.00,A,3648.16540,N,12147.28751,W,1.322,0.00,111023,,,D*7D 2023-10-11T21:22:03.842Z,1697059323.842 [NAL9602](INFO): GPS fix at 20231011T212202: (36.802757, -121.788125) 2023-10-11T21:22:06.283Z,1697059326.283 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-11T21:22:06.283Z,1697059326.283 [Default:CheckIn:C.Wait] Stopped 2023-10-11T21:22:06.284Z,1697059326.284 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:22:06.284Z,1697059326.284 [Default:CheckIn:D] Running Loop=1 2023-10-11T21:22:06.708Z,1697059326.708 [Default:CheckIn:D] Stopped 2023-10-11T21:22:06.708Z,1697059326.708 [Default:CheckIn:E] Running Loop=1 2023-10-11T21:22:07.069Z,1697059327.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.954991 min 2023-10-11T21:22:07.069Z,1697059327.069 [Default:CheckIn:E] Stopped 2023-10-11T21:22:07.069Z,1697059327.069 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-11T21:22:07.069Z,1697059327.069 [Default:CheckIn] Stopped 2023-10-11T21:22:07.070Z,1697059327.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T21:22:07.070Z,1697059327.070 [Default:CheckIn](INFO): Running loop #2 2023-10-11T21:22:07.070Z,1697059327.070 [Default:CheckIn] Running Loop=2 2023-10-11T21:22:07.070Z,1697059327.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T21:22:07.070Z,1697059327.070 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T21:22:09.071Z,1697059329.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212207.00,A,3648.16510,N,12147.28817,W,0.389,0.00,111023,,,D*70 2023-10-11T21:22:09.073Z,1697059329.073 [NAL9602](INFO): GPS fix at 20231011T212207: (36.802752, -121.788136) 2023-10-11T21:22:09.106Z,1697059329.106 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T21:22:09.106Z,1697059329.106 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T21:22:21.036Z,1697059341.036 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20231011T210530/Courier0007.lzma 2023-10-11T21:22:22.038Z,1697059342.038 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0007.lzma.bak 2023-10-11T21:22:22.038Z,1697059342.038 [DataOverHttps](INFO): SBD MOMSN=18988041 2023-10-11T21:22:38.717Z,1697059358.717 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20231011T210530/Express0008.lzma 2023-10-11T21:22:39.717Z,1697059359.717 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0008.lzma.bak 2023-10-11T21:22:39.718Z,1697059359.718 [DataOverHttps](INFO): SBD MOMSN=18988044 2023-10-11T21:22:41.096Z,1697059361.096 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T21:22:41.096Z,1697059361.096 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T21:22:41.096Z,1697059361.096 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:22:41.477Z,1697059361.477 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T21:27:41.733Z,1697059661.733 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-11T21:27:41.734Z,1697059661.734 [Default:CheckIn:C.Wait] Stopped 2023-10-11T21:27:41.734Z,1697059661.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:27:41.734Z,1697059661.734 [Default:CheckIn:D] Running Loop=1 2023-10-11T21:27:42.141Z,1697059662.141 [Default:CheckIn:D] Stopped 2023-10-11T21:27:42.141Z,1697059662.141 [Default:CheckIn:E] Running Loop=1 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.545542 min 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn:E] Stopped 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn] Stopped 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn](INFO): Running loop #3 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn] Running Loop=3 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T21:27:42.558Z,1697059662.558 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T21:27:44.563Z,1697059664.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212742.00,A,3648.16721,N,12147.28663,W,0.175,0.00,111023,,,D*78 2023-10-11T21:27:44.566Z,1697059664.566 [NAL9602](INFO): GPS fix at 20231011T212742: (36.802787, -121.788111) 2023-10-11T21:27:44.597Z,1697059664.597 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T21:27:44.597Z,1697059664.597 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T21:27:52.479Z,1697059672.479 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231011T210530/Courier0010.lzma 2023-10-11T21:27:53.481Z,1697059673.481 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0010.lzma.bak 2023-10-11T21:27:53.482Z,1697059673.482 [DataOverHttps](INFO): SBD MOMSN=18988056 2023-10-11T21:28:09.479Z,1697059689.479 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20231011T210530/Express0011.lzma 2023-10-11T21:28:10.482Z,1697059690.482 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0011.lzma.bak 2023-10-11T21:28:10.482Z,1697059690.482 [DataOverHttps](INFO): SBD MOMSN=18988059 2023-10-11T21:28:12.050Z,1697059692.050 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T21:28:12.050Z,1697059692.050 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T21:28:12.050Z,1697059692.050 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:31:36.887Z,1697059896.887 [NAL9602](INFO): SBD MO Status=0, MOMSN=31841, MT Status=0, MTMSN=0 2023-10-11T21:31:36.888Z,1697059896.888 [NAL9602](INFO): No messages in MT queue 2023-10-11T21:32:07.589Z,1697059927.589 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T21:33:12.648Z,1697059992.648 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-11T21:33:12.649Z,1697059992.649 [Default:CheckIn:C.Wait] Stopped 2023-10-11T21:33:12.649Z,1697059992.649 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:33:12.649Z,1697059992.649 [Default:CheckIn:D] Running Loop=1 2023-10-11T21:33:13.067Z,1697059993.067 [Default:CheckIn:D] Stopped 2023-10-11T21:33:13.068Z,1697059993.068 [Default:CheckIn:E] Running Loop=1 2023-10-11T21:33:13.451Z,1697059993.451 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.060960 min 2023-10-11T21:33:13.451Z,1697059993.451 [Default:CheckIn:E] Stopped 2023-10-11T21:33:13.452Z,1697059993.452 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-11T21:33:13.452Z,1697059993.452 [Default:CheckIn] Stopped 2023-10-11T21:33:13.452Z,1697059993.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T21:33:13.452Z,1697059993.452 [Default:CheckIn](INFO): Running loop #4 2023-10-11T21:33:13.452Z,1697059993.452 [Default:CheckIn] Running Loop=4 2023-10-11T21:33:13.452Z,1697059993.452 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T21:33:13.452Z,1697059993.452 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T21:33:15.467Z,1697059995.467 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213314.00,A,3648.16383,N,12147.28106,W,0.350,256.02,111023,,,D*70 2023-10-11T21:33:15.469Z,1697059995.469 [NAL9602](INFO): GPS fix at 20231011T213314: (36.802731, -121.788018) 2023-10-11T21:33:15.480Z,1697059995.480 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T21:33:15.480Z,1697059995.480 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T21:33:22.755Z,1697060002.755 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231011T210530/Courier0013.lzma 2023-10-11T21:33:23.757Z,1697060003.757 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0013.lzma.bak 2023-10-11T21:33:23.758Z,1697060003.758 [DataOverHttps](INFO): SBD MOMSN=18988068 2023-10-11T21:33:34.855Z,1697060014.855 [NAL9602](INFO): SBD MO Status=2, MOMSN=31842, MT Status=2, MTMSN=0 2023-10-11T21:33:34.855Z,1697060014.855 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-11T21:33:39.851Z,1697060019.851 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20231011T210530/Express0014.lzma 2023-10-11T21:33:40.854Z,1697060020.854 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0014.lzma.bak 2023-10-11T21:33:40.854Z,1697060020.854 [DataOverHttps](INFO): SBD MOMSN=18988071 2023-10-11T21:33:42.141Z,1697060022.141 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T21:33:42.141Z,1697060022.141 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T21:33:42.141Z,1697060022.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:33:53.439Z,1697060033.439 [NAL9602](INFO): SBD MO Status=2, MOMSN=31842, MT Status=2, MTMSN=0 2023-10-11T21:33:53.439Z,1697060033.439 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-11T21:34:35.492Z,1697060075.492 [NAL9602](INFO): SBD MO Status=0, MOMSN=31842, MT Status=0, MTMSN=0 2023-10-11T21:34:35.492Z,1697060075.492 [NAL9602](INFO): No messages in MT queue 2023-10-11T21:35:06.193Z,1697060106.193 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T21:37:58.269Z,1697060278.269 [DataOverHttps](IMPORTANT): SBD MTMSN=20231011T213757 2023-10-11T21:38:05.654Z,1697060285.654 [DataOverHttps](INFO): Received command: strobe off 2023-10-11T21:38:05.685Z,1697060285.685 [CommandExec](IMPORTANT): got command strobe off 2023-10-11T21:38:05.685Z,1697060285.685 [CommandExec](IMPORTANT): Deactivating strobe 2023-10-11T21:38:42.786Z,1697060322.786 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-11T21:38:42.786Z,1697060322.786 [Default:CheckIn:C.Wait] Stopped 2023-10-11T21:38:42.786Z,1697060322.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:38:42.786Z,1697060322.786 [Default:CheckIn:D] Running Loop=1 2023-10-11T21:38:43.182Z,1697060323.182 [Default:CheckIn:D] Stopped 2023-10-11T21:38:43.182Z,1697060323.182 [Default:CheckIn:E] Running Loop=1 2023-10-11T21:38:43.596Z,1697060323.596 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.562893 min 2023-10-11T21:38:43.596Z,1697060323.596 [Default:CheckIn:E] Stopped 2023-10-11T21:38:43.596Z,1697060323.596 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-11T21:38:43.596Z,1697060323.596 [Default:CheckIn] Stopped 2023-10-11T21:38:43.597Z,1697060323.597 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T21:38:43.597Z,1697060323.597 [Default:CheckIn](INFO): Running loop #5 2023-10-11T21:38:43.597Z,1697060323.597 [Default:CheckIn] Running Loop=5 2023-10-11T21:38:43.597Z,1697060323.597 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T21:38:43.597Z,1697060323.597 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T21:38:45.587Z,1697060325.587 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213844.00,A,3648.16523,N,12147.28320,W,0.739,116.66,111023,,,D*7A 2023-10-11T21:38:45.589Z,1697060325.589 [NAL9602](INFO): GPS fix at 20231011T213844: (36.802754, -121.788053) 2023-10-11T21:38:45.601Z,1697060325.601 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T21:38:45.601Z,1697060325.601 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T21:38:52.627Z,1697060332.627 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231011T210530/Courier0016.lzma 2023-10-11T21:38:53.630Z,1697060333.630 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0016.lzma.bak 2023-10-11T21:38:53.630Z,1697060333.630 [DataOverHttps](INFO): SBD MOMSN=18988086 2023-10-11T21:39:09.587Z,1697060349.587 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20231011T210530/Express0017.lzma 2023-10-11T21:39:10.590Z,1697060350.590 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0017.lzma.bak 2023-10-11T21:39:10.590Z,1697060350.590 [DataOverHttps](INFO): SBD MOMSN=18988092 2023-10-11T21:39:26.531Z,1697060366.531 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20231011T210530/Express0018.lzma 2023-10-11T21:39:27.533Z,1697060367.533 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0018.lzma.bak 2023-10-11T21:39:27.534Z,1697060367.534 [DataOverHttps](INFO): SBD MOMSN=18988103 2023-10-11T21:39:28.838Z,1697060368.838 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T21:39:28.838Z,1697060368.838 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T21:39:28.839Z,1697060368.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:41:09.814Z,1697060469.814 [NAL9602](INFO): SBD MO Status=0, MOMSN=31843, MT Status=0, MTMSN=0 2023-10-11T21:41:09.814Z,1697060469.814 [NAL9602](INFO): No messages in MT queue 2023-10-11T21:41:40.517Z,1697060500.517 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T21:44:29.465Z,1697060669.465 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-11T21:44:29.465Z,1697060669.465 [Default:CheckIn:C.Wait] Stopped 2023-10-11T21:44:29.465Z,1697060669.465 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:44:29.465Z,1697060669.465 [Default:CheckIn:D] Running Loop=1 2023-10-11T21:44:29.868Z,1697060669.868 [Default:CheckIn:D] Stopped 2023-10-11T21:44:29.869Z,1697060669.869 [Default:CheckIn:E] Running Loop=1 2023-10-11T21:44:30.285Z,1697060670.285 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.341003 min 2023-10-11T21:44:30.285Z,1697060670.285 [Default:CheckIn:E] Stopped 2023-10-11T21:44:30.285Z,1697060670.285 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-11T21:44:30.285Z,1697060670.285 [Default:CheckIn] Stopped 2023-10-11T21:44:30.285Z,1697060670.285 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T21:44:30.286Z,1697060670.286 [Default:CheckIn](INFO): Running loop #6 2023-10-11T21:44:30.286Z,1697060670.286 [Default:CheckIn] Running Loop=6 2023-10-11T21:44:30.286Z,1697060670.286 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T21:44:30.286Z,1697060670.286 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T21:44:32.315Z,1697060672.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214431.00,A,3648.16551,N,12147.28293,W,0.641,32.52,111023,,,D*41 2023-10-11T21:44:32.337Z,1697060672.337 [NAL9602](INFO): GPS fix at 20231011T214431: (36.802759, -121.788049) 2023-10-11T21:44:32.392Z,1697060672.392 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T21:44:32.392Z,1697060672.392 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T21:44:40.363Z,1697060680.363 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231011T210530/Courier0021.lzma 2023-10-11T21:44:41.365Z,1697060681.365 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0021.lzma.bak 2023-10-11T21:44:41.366Z,1697060681.366 [DataOverHttps](INFO): SBD MOMSN=18988138 2023-10-11T21:44:53.695Z,1697060693.695 [NAL9602](INFO): SBD MO Status=2, MOMSN=31844, MT Status=2, MTMSN=0 2023-10-11T21:44:53.695Z,1697060693.695 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-11T21:44:57.400Z,1697060697.400 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20231011T210530/Express0023.lzma 2023-10-11T21:44:58.402Z,1697060698.402 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0023.lzma.bak 2023-10-11T21:44:58.402Z,1697060698.402 [DataOverHttps](INFO): SBD MOMSN=18988141 2023-10-11T21:44:59.784Z,1697060699.784 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T21:44:59.784Z,1697060699.784 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T21:44:59.785Z,1697060699.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:45:24.402Z,1697060724.402 [NAL9602](INFO): SBD MO Status=2, MOMSN=31844, MT Status=2, MTMSN=0 2023-10-11T21:45:24.402Z,1697060724.402 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-11T21:45:42.585Z,1697060742.585 [DataOverHttps](IMPORTANT): SBD MTMSN=20231011T214541 2023-10-11T21:45:43.387Z,1697060743.387 [NAL9602](INFO): SBD MO Status=2, MOMSN=31844, MT Status=2, MTMSN=0 2023-10-11T21:45:43.387Z,1697060743.387 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-11T21:45:49.988Z,1697060749.988 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.tl 2023-10-11T21:45:50.035Z,1697060750.035 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-10-11T21:45:50.036Z,1697060750.036 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-10-11T21:45:50.036Z,1697060750.036 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx 2023-10-11T21:45:50.107Z,1697060750.107 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min 2023-10-11T21:45:50.109Z,1697060750.109 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool 2023-10-11T21:45:50.112Z,1697060750.112 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m 2023-10-11T21:45:50.115Z,1697060750.115 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2023-10-11T21:45:50.118Z,1697060750.118 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m 2023-10-11T21:45:50.121Z,1697060750.121 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m 2023-10-11T21:45:50.124Z,1697060750.124 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min 2023-10-11T21:45:50.127Z,1697060750.127 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2023-10-11T21:45:50.130Z,1697060750.130 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2023-10-11T21:45:50.132Z,1697060750.132 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2023-10-11T21:45:50.135Z,1697060750.135 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm 2023-10-11T21:45:50.138Z,1697060750.138 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc 2023-10-11T21:45:50.141Z,1697060750.141 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 % 2023-10-11T21:45:50.172Z,1697060750.172 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min 2023-10-11T21:45:50.173Z,1697060750.173 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2023-10-11T21:45:50.173Z,1697060750.173 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx 2023-10-11T21:45:50.610Z,1697060750.610 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2023-10-11T21:45:50.613Z,1697060750.613 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2023-10-11T21:45:50.616Z,1697060750.616 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2023-10-11T21:45:50.619Z,1697060750.619 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool 2023-10-11T21:45:50.622Z,1697060750.622 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool 2023-10-11T21:45:50.625Z,1697060750.625 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2023-10-11T21:45:50.627Z,1697060750.627 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool 2023-10-11T21:45:50.630Z,1697060750.630 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2023-10-11T21:45:50.681Z,1697060750.681 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2023-10-11T21:45:50.684Z,1697060750.684 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2023-10-11T21:45:50.687Z,1697060750.687 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2023-10-11T21:45:50.698Z,1697060750.698 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2023-10-11T21:45:50.701Z,1697060750.701 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2023-10-11T21:45:50.717Z,1697060750.717 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool 2023-10-11T21:45:50.724Z,1697060750.724 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2023-10-11T21:45:50.727Z,1697060750.727 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool 2023-10-11T21:45:50.742Z,1697060750.742 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool 2023-10-11T21:45:50.754Z,1697060750.754 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool 2023-10-11T21:45:50.775Z,1697060750.775 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool 2023-10-11T21:45:50.785Z,1697060750.785 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2023-10-11T21:45:50.790Z,1697060750.790 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 1 bool 2023-10-11T21:45:50.801Z,1697060750.801 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2023-10-11T21:45:50.808Z,1697060750.808 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count 2023-10-11T21:45:50.811Z,1697060750.811 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m 2023-10-11T21:45:50.818Z,1697060750.818 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m 2023-10-11T21:45:50.826Z,1697060750.826 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2023-10-11T21:45:50.837Z,1697060750.837 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool 2023-10-11T21:45:50.840Z,1697060750.840 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l 2023-10-11T21:45:50.843Z,1697060750.843 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l 2023-10-11T21:45:50.850Z,1697060750.850 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count 2023-10-11T21:45:50.864Z,1697060750.864 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l 2023-10-11T21:45:50.867Z,1697060750.867 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m 2023-10-11T21:45:50.875Z,1697060750.875 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC 2023-10-11T21:45:50.892Z,1697060750.892 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2023-10-11T21:45:50.895Z,1697060750.895 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2023-10-11T21:45:50.898Z,1697060750.898 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l 2023-10-11T21:45:50.902Z,1697060750.902 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m 2023-10-11T21:45:50.913Z,1697060750.913 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2023-10-11T21:45:50.920Z,1697060750.920 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2023-10-11T21:45:50.923Z,1697060750.923 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3 2023-10-11T21:45:50.930Z,1697060750.930 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m 2023-10-11T21:45:50.941Z,1697060750.941 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2023-10-11T21:45:50.944Z,1697060750.944 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2023-10-11T21:45:50.952Z,1697060750.952 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb 2023-10-11T21:45:50.955Z,1697060750.955 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m 2023-10-11T21:45:50.965Z,1697060750.965 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC 2023-10-11T21:45:50.969Z,1697060750.969 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2023-10-11T21:45:50.976Z,1697060750.976 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2023-10-11T21:45:50.979Z,1697060750.979 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m 2023-10-11T21:45:50.987Z,1697060750.987 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu 2023-10-11T21:45:50.998Z,1697060750.998 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m 2023-10-11T21:45:51.001Z,1697060751.001 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC 2023-10-11T21:45:51.012Z,1697060751.012 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg 2023-10-11T21:45:51.015Z,1697060751.015 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg 2023-10-11T21:45:51.023Z,1697060751.023 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l 2023-10-11T21:45:51.030Z,1697060751.030 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m 2023-10-11T21:45:51.035Z,1697060751.035 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2023-10-11T21:45:51.074Z,1697060751.074 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2023-10-11T21:45:51.087Z,1697060751.087 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2023-10-11T21:45:51.102Z,1697060751.102 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu 2023-10-11T21:45:51.110Z,1697060751.110 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m 2023-10-11T21:45:51.125Z,1697060751.125 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg 2023-10-11T21:45:51.132Z,1697060751.132 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg 2023-10-11T21:45:51.135Z,1697060751.135 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m 2023-10-11T21:45:51.143Z,1697060751.143 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu 2023-10-11T21:45:51.150Z,1697060751.150 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m 2023-10-11T21:45:51.153Z,1697060751.153 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg 2023-10-11T21:45:51.167Z,1697060751.167 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg 2023-10-11T21:45:51.175Z,1697060751.175 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m 2023-10-11T21:45:51.179Z,1697060751.179 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool 2023-10-11T21:45:51.190Z,1697060751.190 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l 2023-10-11T21:45:51.262Z,1697060751.262 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-11T21:45:51.296Z,1697060751.296 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-10-11T21:45:51.327Z,1697060751.327 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-11T21:45:51.358Z,1697060751.358 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-11T21:45:51.383Z,1697060751.383 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-10-11T21:45:51.416Z,1697060751.416 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-11T21:45:51.440Z,1697060751.440 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-10-11T21:45:51.505Z,1697060751.505 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-10-11T21:45:51.540Z,1697060751.540 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2023-10-11T21:45:51.555Z,1697060751.555 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2023-10-11T21:45:51.561Z,1697060751.561 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx 2023-10-11T21:45:51.592Z,1697060751.592 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool 2023-10-11T21:45:51.595Z,1697060751.595 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2023-10-11T21:45:51.607Z,1697060751.607 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2023-10-11T21:45:51.607Z,1697060751.607 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx 2023-10-11T21:45:51.821Z,1697060751.821 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m 2023-10-11T21:45:51.826Z,1697060751.826 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2023-10-11T21:45:51.837Z,1697060751.837 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2023-10-11T21:45:51.845Z,1697060751.845 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2023-10-11T21:45:51.880Z,1697060751.880 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2023-10-11T21:45:51.883Z,1697060751.883 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2023-10-11T21:45:51.895Z,1697060751.895 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min 2023-10-11T21:45:51.906Z,1697060751.906 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min 2023-10-11T21:45:51.917Z,1697060751.917 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min 2023-10-11T21:45:51.936Z,1697060751.936 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2023-10-11T21:45:51.944Z,1697060751.944 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2023-10-11T21:45:51.947Z,1697060751.947 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 % 2023-10-11T21:45:51.961Z,1697060751.961 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2023-10-11T21:45:51.972Z,1697060751.972 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2023-10-11T21:45:52.005Z,1697060752.005 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2023-10-11T21:45:52.028Z,1697060752.028 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2023-10-11T21:45:52.035Z,1697060752.035 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2023-10-11T21:45:52.053Z,1697060752.053 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2023-10-11T21:45:52.061Z,1697060752.061 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2023-10-11T21:45:52.068Z,1697060752.068 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2023-10-11T21:45:52.086Z,1697060752.086 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2023-10-11T21:45:52.094Z,1697060752.094 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2023-10-11T21:45:52.103Z,1697060752.103 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2023-10-11T21:45:52.118Z,1697060752.118 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2023-10-11T21:45:52.130Z,1697060752.130 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim. 2023-10-11T21:45:52.187Z,1697060752.187 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband 2023-10-11T21:45:52.197Z,1697060752.197 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface 2023-10-11T21:45:52.199Z,1697060752.199 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2023-10-11T21:45:52.205Z,1697060752.205 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2023-10-11T21:45:52.207Z,1697060752.207 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2023-10-11T21:45:52.213Z,1697060752.213 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2023-10-11T21:45:52.215Z,1697060752.215 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2023-10-11T21:45:52.219Z,1697060752.219 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2023-10-11T21:45:52.243Z,1697060752.243 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2023-10-11T21:45:52.287Z,1697060752.287 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait. 2023-10-11T21:45:52.367Z,1697060752.367 [MissionManager](DEBUG): mission ballast_and_trim { arguments { MissionTimeout = 120 minute """ Maximum duration of mission """ SkipComms = false """ Skip communications at start of mission. """ Depth1 = 7 meter Depth2 = NaN meter DepthDeadband = 0.1 meter """ How much vertical drift from the specified depth is allowed durnig the mission. """ SurfaceThreshold = 2.5 meter """ Surface threshold depth. """ MaxWaitOnSurface = 20 minute """ Maximum wait time for the vehicle to go under the surface (i.e., reach the surface threshold depth). """ SettleTime = 10 minute """ How long to wait after reaching target depth before starting ballast and trim. """ EstimationTimeout = 45 minute """ Timeout for ballast and trim estimation. """ MinEstimationTime = 10 minute """ Minimum time to run ballast and trim estimation before determining convergence (must be shorter than timeout). """ MassEstimationErrorBound = 0.25 millimeter """ Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ BuoyEstimationErrorBound = 15 cubic_centimeter """ Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ EstimationConfidence = 99.9 percent """ Confidence level that the estimator has reached the desired error range (larger values take longer to converge). """ WaitToFloat = 5 minute } timeout duration=MissionTimeout insert Insert/Science.tl insert Insert/BackseatDriver.tl aggregate RunBallastAndTrim { run in sequence insert Insert/BallastAndTrim.tl { redefineArg InsertDepthDeadband = DepthDeadband redefineArg InsertApproachDepthTimeout = MaxWaitOnSurface redefineArg InsertSettleTime = SettleTime redefineArg EstTimeout = EstimationTimeout redefineArg MinEstTime = MinEstimationTime redefineArg MassEstErrorBound = MassEstimationErrorBound redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound redefineArg EstConfidence = EstimationConfidence } aggregate Depth1 { run in sequence break if ( isNaN ( Depth1 ) ) assign in sequence BallastAndTrim:InsertDepth = Depth1 call refId="BallastAndTrim" } aggregate Depth2 { run in sequence break if ( isNaN ( Depth2 ) ) assign in sequence BallastAndTrim:InsertDepth = Depth1 call refId="BallastAndTrim" } } aggregate Float_Up { """ Float to surface instead of driving. """ run in sequence break if ( Universal:depth < Control:VerticalControl.surfaceThreshold ) behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyDefault } behavior Guidance:Wait { run in sequence set duration = WaitToFloat } } } 2023-10-11T21:45:52.375Z,1697060752.375 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.tl 2023-10-11T21:45:52.699Z,1697060752.699 [Default] Stopped 2023-10-11T21:45:52.699Z,1697060752.699 [Default](DEBUG): Aggregate::uninitialize Default 2023-10-11T21:45:52.699Z,1697060752.699 [Default:B.GoToSurface] Stopped 2023-10-11T21:45:52.699Z,1697060752.699 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-11T21:45:52.699Z,1697060752.699 [Default:CheckIn] Stopped 2023-10-11T21:45:52.700Z,1697060752.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T21:45:52.700Z,1697060752.700 [Default:CheckIn:C.Wait] Stopped 2023-10-11T21:45:52.700Z,1697060752.700 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:45:52.700Z,1697060752.700 [MissionManager](IMPORTANT): Started mission ballast_and_trim 2023-10-11T21:45:52.700Z,1697060752.700 [ballast_and_trim] Running Loop=1 2023-10-11T21:45:52.700Z,1697060752.700 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim 2023-10-11T21:45:52.700Z,1697060752.700 [ballast_and_trim:Science] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:A] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:B] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:C] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:D] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:E] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:F] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2023-10-11T21:45:52.701Z,1697060752.701 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-11T21:45:52.708Z,1697060752.708 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1 2023-10-11T21:45:52.708Z,1697060752.708 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport 2023-10-11T21:45:52.708Z,1697060752.708 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-10-11T21:45:52.708Z,1697060752.708 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-10-11T21:45:52.709Z,1697060752.709 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1 2023-10-11T21:45:52.709Z,1697060752.709 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3 2023-10-11T21:45:52.709Z,1697060752.709 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2023-10-11T21:45:52.709Z,1697060752.709 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-11T21:45:52.711Z,1697060752.711 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1 2023-10-11T21:45:52.711Z,1697060752.711 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil 2023-10-11T21:45:52.711Z,1697060752.711 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2023-10-11T21:45:52.711Z,1697060752.711 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-11T21:45:52.717Z,1697060752.717 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1 2023-10-11T21:45:52.717Z,1697060752.717 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport 2023-10-11T21:45:52.717Z,1697060752.717 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-10-11T21:45:52.718Z,1697060752.718 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-10-11T21:45:52.718Z,1697060752.718 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1 2023-10-11T21:45:52.718Z,1697060752.718 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM 2023-10-11T21:45:52.718Z,1697060752.718 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2023-10-11T21:45:52.718Z,1697060752.718 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-11T21:45:52.724Z,1697060752.724 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1 2023-10-11T21:45:52.724Z,1697060752.724 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity 2023-10-11T21:45:52.724Z,1697060752.724 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2023-10-11T21:45:52.724Z,1697060752.724 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-10-11T21:45:52.726Z,1697060752.726 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1 2023-10-11T21:45:52.726Z,1697060752.726 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport 2023-10-11T21:45:52.726Z,1697060752.726 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-10-11T21:45:52.727Z,1697060752.727 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-10-11T21:45:52.727Z,1697060752.727 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1 2023-10-11T21:45:52.727Z,1697060752.727 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger 2023-10-11T21:45:52.727Z,1697060752.727 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2023-10-11T21:45:52.727Z,1697060752.727 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2023-10-11T21:45:52.732Z,1697060752.732 [ballast_and_trim:Science:OceanCurrent] Running Loop=1 2023-10-11T21:45:52.732Z,1697060752.732 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent 2023-10-11T21:45:52.732Z,1697060752.732 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2023-10-11T21:45:52.733Z,1697060752.733 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2023-10-11T21:45:52.733Z,1697060752.733 [ballast_and_trim:BackseatDriver] Running Loop=1 2023-10-11T21:45:52.733Z,1697060752.733 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver 2023-10-11T21:45:52.733Z,1697060752.733 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1 2023-10-11T21:45:52.733Z,1697060752.733 [ballast_and_trim:RunBallastAndTrim] Running Loop=1 2023-10-11T21:45:52.733Z,1697060752.733 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim 2023-10-11T21:45:52.733Z,1697060752.733 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1 2023-10-11T21:45:52.733Z,1697060752.733 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1 2023-10-11T21:45:52.734Z,1697060752.734 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1 2023-10-11T21:45:52.734Z,1697060752.734 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped 2023-10-11T21:45:52.734Z,1697060752.734 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1 2023-10-11T21:45:52.734Z,1697060752.734 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2023-10-11T21:45:52.734Z,1697060752.734 [ballast_and_trim:BackseatDriver] Running Loop=1 2023-10-11T21:45:52.735Z,1697060752.735 [ballast_and_trim:Science] Running Loop=1 2023-10-11T21:45:52.740Z,1697060752.740 [ballast_and_trim:Science:ChlThreshTrigger] Stopped 2023-10-11T21:45:52.740Z,1697060752.740 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger 2023-10-11T21:45:52.740Z,1697060752.740 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2023-10-11T21:45:52.740Z,1697060752.740 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2023-10-11T21:45:52.740Z,1697060752.740 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:PeakDetectSalinity] Stopped 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:PeakDetectFDOM] Stopped 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:HighestOilPeakReport] Stopped 2023-10-11T21:45:52.741Z,1697060752.741 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport 2023-10-11T21:45:52.742Z,1697060752.742 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2023-10-11T21:45:52.742Z,1697060752.742 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-10-11T21:45:52.742Z,1697060752.742 [ballast_and_trim:Science:PeakDetectOil] Stopped 2023-10-11T21:45:52.742Z,1697060752.742 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil 2023-10-11T21:45:52.742Z,1697060752.742 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2023-10-11T21:45:52.742Z,1697060752.742 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-11T21:45:52.742Z,1697060752.742 [ballast_and_trim:Science:PeakDetectNO3] Stopped 2023-10-11T21:45:52.742Z,1697060752.742 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3 2023-10-11T21:45:52.743Z,1697060752.743 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2023-10-11T21:45:52.743Z,1697060752.743 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-11T21:45:52.748Z,1697060752.748 [ballast_and_trim:Science:HighestChlPeakReport] Stopped 2023-10-11T21:45:52.748Z,1697060752.748 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport 2023-10-11T21:45:52.748Z,1697060752.748 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2023-10-11T21:45:52.748Z,1697060752.748 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-10-11T21:45:52.748Z,1697060752.748 [ballast_and_trim:Science:PeakDetectChl] Stopped 2023-10-11T21:45:52.748Z,1697060752.748 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl 2023-10-11T21:45:52.749Z,1697060752.749 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2023-10-11T21:45:52.749Z,1697060752.749 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-10-11T21:45:52.749Z,1697060752.749 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2023-10-11T21:45:52.749Z,1697060752.749 [ballast_and_trim:Science:Read_Oil] Stopped 2023-10-11T21:45:52.749Z,1697060752.749 [ballast_and_trim:Science:F] Running Loop=1 2023-10-11T21:45:52.750Z,1697060752.750 [ballast_and_trim:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence 2023-10-11T21:45:52.750Z,1697060752.750 [ballast_and_trim:Science:E] Running Loop=1 2023-10-11T21:45:52.751Z,1697060752.751 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2023-10-11T21:45:52.751Z,1697060752.751 [ballast_and_trim:Science:D] Stopped 2023-10-11T21:45:52.751Z,1697060752.751 [ballast_and_trim:Science:C] Running Loop=1 2023-10-11T21:45:52.751Z,1697060752.751 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2023-10-11T21:45:52.753Z,1697060752.753 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2023-10-11T21:45:52.753Z,1697060752.753 [ballast_and_trim:Science:B] Running Loop=1 2023-10-11T21:45:52.754Z,1697060752.754 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2023-10-11T21:45:52.754Z,1697060752.754 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2023-10-11T21:45:52.754Z,1697060752.754 [ballast_and_trim:Science:A] Stopped 2023-10-11T21:45:52.894Z,1697060752.894 [WetLabsBB2FL](INFO): Powering up 2023-10-11T21:45:53.101Z,1697060753.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1 2023-10-11T21:45:53.101Z,1697060753.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2023-10-11T21:45:53.101Z,1697060753.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2023-10-11T21:45:53.101Z,1697060753.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2023-10-11T21:45:53.101Z,1697060753.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2023-10-11T21:45:53.101Z,1697060753.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2023-10-11T21:45:53.101Z,1697060753.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2023-10-11T21:45:53.101Z,1697060753.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2023-10-11T21:45:53.103Z,1697060753.103 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2023-10-11T21:45:53.108Z,1697060753.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-10-11T21:45:53.109Z,1697060753.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2023-10-11T21:45:53.109Z,1697060753.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2023-10-11T21:45:53.109Z,1697060753.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2023-10-11T21:45:53.109Z,1697060753.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-10-11T21:45:53.109Z,1697060753.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2023-10-11T21:45:53.109Z,1697060753.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2023-10-11T21:45:53.110Z,1697060753.110 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2023-10-11T21:45:53.110Z,1697060753.110 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2023-10-11T21:45:53.110Z,1697060753.110 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2023-10-11T21:45:53.110Z,1697060753.110 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2023-10-11T21:45:53.393Z,1697060753.393 [WetLabsUBAT](INFO): Powering up 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2023-10-11T21:45:53.510Z,1697060753.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-10-11T21:45:53.910Z,1697060753.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2023-10-11T21:45:53.911Z,1697060753.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2023-10-11T21:45:53.911Z,1697060753.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2023-10-11T21:45:54.327Z,1697060754.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2023-10-11T21:45:54.328Z,1697060754.328 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 2023-10-11T21:45:54.328Z,1697060754.328 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2023-10-11T21:45:54.329Z,1697060754.329 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2023-10-11T21:45:54.329Z,1697060754.329 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2023-10-11T21:45:56.089Z,1697060756.089 [CTD_Seabird](ERROR): Failed to parse device response: 2023-10-11T21:45:58.098Z,1697060758.098 [CTD_Seabird](ERROR): Salinity reading out of range: 0.528215 psu 2023-10-11T21:45:58.101Z,1697060758.101 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:45:59.102Z,1697060759.102 [CTD_Seabird](ERROR): Salinity reading out of range: 0.527878 psu 2023-10-11T21:45:59.103Z,1697060759.103 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:45:59.550Z,1697060759.550 [NAL9602](INFO): SBD MO Status=2, MOMSN=31844, MT Status=2, MTMSN=0 2023-10-11T21:45:59.551Z,1697060759.551 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-11T21:46:00.106Z,1697060760.106 [CTD_Seabird](ERROR): Salinity reading out of range: 0.528040 psu 2023-10-11T21:46:00.107Z,1697060760.107 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:01.110Z,1697060761.110 [CTD_Seabird](ERROR): Salinity reading out of range: 0.528615 psu 2023-10-11T21:46:01.111Z,1697060761.111 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:02.114Z,1697060762.114 [CTD_Seabird](ERROR): Salinity reading out of range: 0.524830 psu 2023-10-11T21:46:02.115Z,1697060762.115 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:03.118Z,1697060763.118 [CTD_Seabird](ERROR): Salinity reading out of range: 0.529108 psu 2023-10-11T21:46:03.119Z,1697060763.119 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:03.582Z,1697060763.582 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s. 2023-10-11T21:46:04.122Z,1697060764.122 [CTD_Seabird](ERROR): Salinity reading out of range: 0.528713 psu 2023-10-11T21:46:04.123Z,1697060764.123 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:05.126Z,1697060765.126 [CTD_Seabird](ERROR): Salinity reading out of range: 0.554122 psu 2023-10-11T21:46:05.127Z,1697060765.127 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:06.130Z,1697060766.130 [CTD_Seabird](ERROR): Salinity reading out of range: 0.564342 psu 2023-10-11T21:46:06.131Z,1697060766.131 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:07.134Z,1697060767.134 [CTD_Seabird](ERROR): Salinity reading out of range: 0.565537 psu 2023-10-11T21:46:07.135Z,1697060767.135 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:08.138Z,1697060768.138 [CTD_Seabird](ERROR): Salinity reading out of range: 0.564410 psu 2023-10-11T21:46:08.140Z,1697060768.140 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:09.142Z,1697060769.142 [CTD_Seabird](ERROR): Salinity reading out of range: 0.563567 psu 2023-10-11T21:46:09.143Z,1697060769.143 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:10.146Z,1697060770.146 [CTD_Seabird](ERROR): Salinity reading out of range: 0.559383 psu 2023-10-11T21:46:10.147Z,1697060770.147 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:11.150Z,1697060771.150 [CTD_Seabird](ERROR): Salinity reading out of range: 0.553331 psu 2023-10-11T21:46:11.151Z,1697060771.151 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:12.154Z,1697060772.154 [CTD_Seabird](ERROR): Salinity reading out of range: 0.551354 psu 2023-10-11T21:46:12.170Z,1697060772.170 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:13.158Z,1697060773.158 [CTD_Seabird](ERROR): Salinity reading out of range: 0.548358 psu 2023-10-11T21:46:13.159Z,1697060773.159 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:14.163Z,1697060774.163 [CTD_Seabird](ERROR): Salinity reading out of range: 0.553594 psu 2023-10-11T21:46:14.165Z,1697060774.165 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:15.166Z,1697060775.166 [CTD_Seabird](ERROR): Salinity reading out of range: 0.556982 psu 2023-10-11T21:46:15.167Z,1697060775.167 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:16.174Z,1697060776.174 [CTD_Seabird](ERROR): Salinity reading out of range: 0.559124 psu 2023-10-11T21:46:16.175Z,1697060776.175 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:17.178Z,1697060777.178 [CTD_Seabird](ERROR): Salinity reading out of range: 0.560320 psu 2023-10-11T21:46:17.179Z,1697060777.179 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:18.199Z,1697060778.199 [CTD_Seabird](ERROR): Salinity reading out of range: 0.557163 psu 2023-10-11T21:46:18.208Z,1697060778.208 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:19.204Z,1697060779.204 [CTD_Seabird](ERROR): Salinity reading out of range: 0.555585 psu 2023-10-11T21:46:19.205Z,1697060779.205 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:20.206Z,1697060780.206 [CTD_Seabird](ERROR): Salinity reading out of range: 0.551522 psu 2023-10-11T21:46:20.208Z,1697060780.208 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:21.214Z,1697060781.214 [CTD_Seabird](ERROR): Salinity reading out of range: 0.550444 psu 2023-10-11T21:46:21.215Z,1697060781.215 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:22.222Z,1697060782.222 [CTD_Seabird](ERROR): Salinity reading out of range: 0.551904 psu 2023-10-11T21:46:22.223Z,1697060782.223 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:23.230Z,1697060783.230 [CTD_Seabird](ERROR): Salinity reading out of range: 0.547337 psu 2023-10-11T21:46:23.231Z,1697060783.231 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:24.238Z,1697060784.238 [CTD_Seabird](ERROR): Salinity reading out of range: 0.543505 psu 2023-10-11T21:46:24.239Z,1697060784.239 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:25.246Z,1697060785.246 [CTD_Seabird](ERROR): Salinity reading out of range: 0.538046 psu 2023-10-11T21:46:25.247Z,1697060785.247 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:26.250Z,1697060786.250 [CTD_Seabird](ERROR): Salinity reading out of range: 0.533546 psu 2023-10-11T21:46:26.251Z,1697060786.251 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:27.254Z,1697060787.254 [CTD_Seabird](ERROR): Salinity reading out of range: 0.531359 psu 2023-10-11T21:46:27.255Z,1697060787.255 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:28.258Z,1697060788.258 [CTD_Seabird](ERROR): Salinity reading out of range: 0.529047 psu 2023-10-11T21:46:28.259Z,1697060788.259 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:29.262Z,1697060789.262 [CTD_Seabird](ERROR): Salinity reading out of range: 0.524725 psu 2023-10-11T21:46:29.263Z,1697060789.263 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:30.266Z,1697060790.266 [CTD_Seabird](ERROR): Salinity reading out of range: 0.523945 psu 2023-10-11T21:46:30.267Z,1697060790.267 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:31.271Z,1697060791.271 [CTD_Seabird](ERROR): Salinity reading out of range: 0.521233 psu 2023-10-11T21:46:31.272Z,1697060791.272 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:32.274Z,1697060792.274 [CTD_Seabird](ERROR): Salinity reading out of range: 0.519505 psu 2023-10-11T21:46:32.277Z,1697060792.277 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:33.278Z,1697060793.278 [CTD_Seabird](ERROR): Salinity reading out of range: 0.521287 psu 2023-10-11T21:46:33.279Z,1697060793.279 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:34.282Z,1697060794.282 [CTD_Seabird](ERROR): Salinity reading out of range: 0.525176 psu 2023-10-11T21:46:34.283Z,1697060794.283 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:35.288Z,1697060795.288 [CTD_Seabird](ERROR): Salinity reading out of range: 0.921676 psu 2023-10-11T21:46:35.290Z,1697060795.290 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:36.290Z,1697060796.290 [CTD_Seabird](ERROR): Salinity reading out of range: 1.175474 psu 2023-10-11T21:46:36.291Z,1697060796.291 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:37.294Z,1697060797.294 [CTD_Seabird](ERROR): Salinity reading out of range: 1.050647 psu 2023-10-11T21:46:37.295Z,1697060797.295 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:38.298Z,1697060798.298 [CTD_Seabird](ERROR): Salinity reading out of range: 0.734744 psu 2023-10-11T21:46:38.299Z,1697060798.299 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:39.192Z,1697060799.192 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2023-10-11T21:46:39.302Z,1697060799.302 [CTD_Seabird](ERROR): Salinity reading out of range: 0.567072 psu 2023-10-11T21:46:39.303Z,1697060799.303 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:40.311Z,1697060800.311 [CTD_Seabird](ERROR): Salinity reading out of range: 0.405704 psu 2023-10-11T21:46:40.313Z,1697060800.313 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:41.315Z,1697060801.315 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339857 psu 2023-10-11T21:46:41.317Z,1697060801.317 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:42.314Z,1697060802.314 [CTD_Seabird](ERROR): Salinity reading out of range: 0.678313 psu 2023-10-11T21:46:42.315Z,1697060802.315 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:43.320Z,1697060803.320 [CTD_Seabird](ERROR): Salinity reading out of range: 1.578581 psu 2023-10-11T21:46:43.321Z,1697060803.321 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:46:44.322Z,1697060804.322 [CTD_Seabird](ERROR): Salinity reading out of range: 12.530517 psu 2023-10-11T21:46:44.323Z,1697060804.323 [CTD_Seabird](INFO): some bad data, not updating bins 2023-10-11T21:47:24.408Z,1697060844.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T21:47:31.436Z,1697060851.436 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243500 2023-10-11T21:48:02.208Z,1697060882.208 [Radio_Surface](INFO): Powering down 2023-10-11T21:48:02.592Z,1697060882.592 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-10-11T21:49:08.325Z,1697060948.325 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2023-10-11T21:49:08.326Z,1697060948.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-10-11T21:49:08.326Z,1697060948.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2023-10-11T21:49:08.326Z,1697060948.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2023-10-11T21:49:08.326Z,1697060948.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2023-10-11T21:49:08.326Z,1697060948.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2023-10-11T21:49:08.327Z,1697060948.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2023-10-11T21:49:08.328Z,1697060948.328 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2023-10-11T21:49:08.722Z,1697060948.722 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.025993 m 2023-10-11T21:49:08.722Z,1697060948.722 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2023-10-11T21:49:08.722Z,1697060948.722 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2023-10-11T21:49:08.722Z,1697060948.722 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2023-10-11T21:49:08.722Z,1697060948.722 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2023-10-11T21:49:08.723Z,1697060948.723 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2023-10-11T21:49:34.545Z,1697060974.545 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-11T21:50:05.243Z,1697061005.243 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T21:51:20.859Z,1697061080.859 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2023-10-11T21:59:09.468Z,1697061549.468 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting. 2023-10-11T21:59:09.469Z,1697061549.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2023-10-11T21:59:09.469Z,1697061549.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T21:59:09.469Z,1697061549.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1 2023-10-11T21:59:09.894Z,1697061549.894 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 7.251865 m 2023-10-11T21:59:09.894Z,1697061549.894 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped 2023-10-11T21:59:09.894Z,1697061549.894 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1 2023-10-11T21:59:09.895Z,1697061549.895 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim. 2023-10-11T22:06:50.325Z,1697062010.325 [CTD_Seabird](ERROR): Failed to parse device response: 0,4.72960,2238.06 2023-10-11T22:10:25.811Z,1697062225.811 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 11.26 minutes. 2023-10-11T22:10:25.812Z,1697062225.812 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 235.728337 +/- 14.997484 cc (conf. level 99.90%, sigma: 18.887184 cc). 2023-10-11T22:10:25.813Z,1697062225.813 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: 6.637162 +/- 0.229151 mm (conf. level 99.90%, sigma: 0.288583 mm). 2023-10-11T22:10:25.814Z,1697062225.814 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 235.728337 cc buoyancy neutral and 6.637162 mm mass default 2023-10-11T22:10:25.820Z,1697062225.820 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-10-11T22:10:25.820Z,1697062225.820 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2023-10-11T22:10:25.821Z,1697062225.821 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CBIT.gf24Offset=143 microampere; 2023-10-11T22:10:25.821Z,1697062225.821 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-10-11T22:10:25.821Z,1697062225.821 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere; 2023-10-11T22:10:25.821Z,1697062225.821 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2023-10-11T22:10:25.821Z,1697062225.821 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour; 2023-10-11T22:10:25.821Z,1697062225.821 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2023-10-11T22:10:25.821Z,1697062225.821 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool; 2023-10-11T22:10:25.821Z,1697062225.821 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.buoyancyNeutral=235.728337 cubic_centimeter; 2023-10-11T22:10:25.822Z,1697062225.822 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kdPitchMass=0 second; 2023-10-11T22:10:25.822Z,1697062225.822 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kiPitchMass=0.0015 reciprocal_second; 2023-10-11T22:10:25.822Z,1697062225.822 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kpPitchMass=0.01 none; 2023-10-11T22:10:25.822Z,1697062225.822 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDeadband=0.1 millimeter; 2023-10-11T22:10:25.822Z,1697062225.822 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDefault=6.637162 millimeter; 2023-10-11T22:10:25.822Z,1697062225.822 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2023-10-11T22:10:25.823Z,1697062225.823 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped 2023-10-11T22:10:25.823Z,1697062225.823 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim. 2023-10-11T22:10:25.824Z,1697062225.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2023-10-11T22:10:25.824Z,1697062225.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2023-10-11T22:10:25.824Z,1697062225.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2023-10-11T22:10:25.824Z,1697062225.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2023-10-11T22:10:25.824Z,1697062225.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2023-10-11T22:10:25.824Z,1697062225.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2023-10-11T22:10:25.825Z,1697062225.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2023-10-11T22:10:25.825Z,1697062225.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped 2023-10-11T22:10:25.825Z,1697062225.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2023-10-11T22:10:25.826Z,1697062225.826 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped 2023-10-11T22:10:25.826Z,1697062225.826 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped 2023-10-11T22:10:25.826Z,1697062225.826 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped 2023-10-11T22:10:25.826Z,1697062225.826 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped 2023-10-11T22:10:25.826Z,1697062225.826 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped 2023-10-11T22:10:26.208Z,1697062226.208 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B 2023-10-11T22:10:26.208Z,1697062226.208 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped 2023-10-11T22:10:26.208Z,1697062226.208 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2023-10-11T22:10:26.208Z,1697062226.208 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1 2023-10-11T22:10:26.208Z,1697062226.208 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped 2023-10-11T22:10:26.208Z,1697062226.208 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1 2023-10-11T22:10:26.209Z,1697062226.209 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1 2023-10-11T22:10:26.209Z,1697062226.209 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2 2023-10-11T22:10:26.209Z,1697062226.209 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1 2023-10-11T22:10:26.606Z,1697062226.606 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped 2023-10-11T22:10:26.606Z,1697062226.606 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2 2023-10-11T22:10:26.606Z,1697062226.606 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped 2023-10-11T22:10:26.606Z,1697062226.606 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim 2023-10-11T22:10:26.606Z,1697062226.606 [ballast_and_trim:RunBallastAndTrim] Stopped 2023-10-11T22:10:26.606Z,1697062226.606 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2023-10-11T22:10:26.606Z,1697062226.606 [ballast_and_trim:Float_Up] Running Loop=1 2023-10-11T22:10:26.607Z,1697062226.607 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up 2023-10-11T22:10:26.607Z,1697062226.607 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1 2023-10-11T22:10:26.607Z,1697062226.607 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2023-10-11T22:10:26.607Z,1697062226.607 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1 2023-10-11T22:10:26.607Z,1697062226.607 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component. 2023-10-11T22:10:27.021Z,1697062227.021 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1 2023-10-11T22:12:31.856Z,1697062351.856 [ballast_and_trim:Float_Up] Stopped 2023-10-11T22:12:31.856Z,1697062351.856 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up 2023-10-11T22:12:31.857Z,1697062351.857 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped 2023-10-11T22:12:31.857Z,1697062351.857 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2023-10-11T22:12:31.857Z,1697062351.857 [ballast_and_trim:Float_Up:B.Wait] Stopped 2023-10-11T22:12:31.857Z,1697062351.857 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T22:12:31.858Z,1697062351.858 [ballast_and_trim](INFO): Completed ballast_and_trim 2023-10-11T22:12:31.859Z,1697062351.859 [MissionManager](INFO): ballast_and_trim is completed. 2023-10-11T22:12:31.859Z,1697062351.859 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2023-10-11T22:12:31.859Z,1697062351.859 [ballast_and_trim] Stopped 2023-10-11T22:12:31.859Z,1697062351.859 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2023-10-11T22:12:31.859Z,1697062351.859 [ballast_and_trim:Science] Stopped 2023-10-11T22:12:31.859Z,1697062351.859 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2023-10-11T22:12:31.859Z,1697062351.859 [ballast_and_trim:Science:B] Stopped 2023-10-11T22:12:31.859Z,1697062351.859 [ballast_and_trim:Science:C] Stopped 2023-10-11T22:12:31.859Z,1697062351.859 [ballast_and_trim:Science:E] Stopped 2023-10-11T22:12:31.860Z,1697062351.860 [ballast_and_trim:Science:F] Stopped 2023-10-11T22:12:31.860Z,1697062351.860 [ballast_and_trim:Science:OceanCurrent] Stopped 2023-10-11T22:12:31.860Z,1697062351.860 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent 2023-10-11T22:12:31.860Z,1697062351.860 [ballast_and_trim:Science:OceanCurrent:A.] Stopped 2023-10-11T22:12:31.860Z,1697062351.860 [ballast_and_trim:BackseatDriver] Stopped 2023-10-11T22:12:31.860Z,1697062351.860 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver 2023-10-11T22:12:31.860Z,1697062351.860 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped 2023-10-11T22:12:32.224Z,1697062352.224 [WetLabsBB2FL](INFO): Powering down 2023-10-11T22:12:32.248Z,1697062352.248 [WetLabsUBAT](INFO): Powering down 2023-10-11T22:12:32.287Z,1697062352.287 [MissionManager](IMPORTANT): Started mission Default 2023-10-11T22:12:32.292Z,1697062352.292 [Default] Running Loop=1 2023-10-11T22:12:32.292Z,1697062352.292 [Default](DEBUG): Aggregate::initialize Default 2023-10-11T22:12:32.292Z,1697062352.292 [Default:B.GoToSurface] Running Loop=1 2023-10-11T22:12:32.292Z,1697062352.292 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-11T22:12:32.292Z,1697062352.292 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-11T22:12:32.293Z,1697062352.293 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-11T22:12:32.293Z,1697062352.293 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-11T22:12:32.293Z,1697062352.293 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-11T22:12:32.294Z,1697062352.294 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-11T22:12:32.295Z,1697062352.295 [Default:A.Wait] Running Loop=1 2023-10-11T22:12:32.295Z,1697062352.295 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-10-11T22:12:32.436Z,1697062352.436 [Radio_Surface](INFO): Powering up 2023-10-11T22:12:38.664Z,1697062358.664 [DataOverHttps](INFO): Radio surface powered ON. 2023-10-11T22:12:45.609Z,1697062365.609 [Default:A.Wait](INFO): Done Waiting. 2023-10-11T22:12:45.609Z,1697062365.609 [Default:A.Wait] Stopped 2023-10-11T22:12:45.609Z,1697062365.609 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T22:12:45.997Z,1697062365.997 [Default:CheckIn] Running Loop=1 2023-10-11T22:12:45.997Z,1697062365.997 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T22:12:45.997Z,1697062365.997 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T22:13:08.792Z,1697062388.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:13:18.709Z,1697062398.709 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-11T22:13:18.788Z,1697062398.788 [NAL9602](ERROR): received: +CSQ:0 OK844, 2, 0, 0, 0 OK 2023-10-11T22:13:43.936Z,1697062423.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:14:19.076Z,1697062459.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:14:54.216Z,1697062494.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:15:29.357Z,1697062529.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:16:02.208Z,1697062562.208 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002961 2023-10-11T22:17:46.207Z,1697062666.207 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-11T22:12:45.0Z 2023-10-11T22:17:46.207Z,1697062666.207 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T22:17:46.207Z,1697062666.207 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T22:17:50.217Z,1697062670.217 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-11T22:17:54.139Z,1697062674.139 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231011T210530/Courier0025.lzma 2023-10-11T22:17:55.142Z,1697062675.142 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0025.lzma.bak 2023-10-11T22:17:55.142Z,1697062675.142 [DataOverHttps](INFO): SBD MOMSN=18988216 2023-10-11T22:18:11.298Z,1697062691.298 [DataOverHttps](INFO): Sending 1441 bytes from file Logs/20231011T210530/Express0026.lzma 2023-10-11T22:18:12.298Z,1697062692.298 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0026.lzma.bak 2023-10-11T22:18:12.298Z,1697062692.298 [DataOverHttps](INFO): SBD MOMSN=18988218 2023-10-11T22:18:20.977Z,1697062700.977 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T22:18:32.035Z,1697062712.035 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20231011T210530/Express0029.lzma 2023-10-11T22:18:33.038Z,1697062713.038 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0029.lzma.bak 2023-10-11T22:18:33.038Z,1697062713.038 [DataOverHttps](INFO): SBD MOMSN=18988251 2023-10-11T22:18:34.336Z,1697062714.336 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T22:18:34.336Z,1697062714.336 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T22:18:34.337Z,1697062714.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T22:21:11.073Z,1697062871.073 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-10-11T22:21:11.080Z,1697062871.080 [BPC1](INFO): Received data from all battery sticks. 2023-10-11T22:23:34.895Z,1697063014.895 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-11T22:23:34.895Z,1697063014.895 [Default:CheckIn:C.Wait] Stopped 2023-10-11T22:23:34.895Z,1697063014.895 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T22:23:34.896Z,1697063014.896 [Default:CheckIn:D] Running Loop=1 2023-10-11T22:23:35.303Z,1697063015.303 [Default:CheckIn:D] Stopped 2023-10-11T22:23:35.303Z,1697063015.303 [Default:CheckIn:E] Running Loop=1 2023-10-11T22:23:35.704Z,1697063015.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.050185 min 2023-10-11T22:23:35.704Z,1697063015.704 [Default:CheckIn:E] Stopped 2023-10-11T22:23:35.704Z,1697063015.704 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-11T22:23:35.704Z,1697063015.704 [Default:CheckIn] Stopped 2023-10-11T22:23:35.704Z,1697063015.704 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T22:23:35.705Z,1697063015.705 [Default:CheckIn](INFO): Running loop #2 2023-10-11T22:23:35.705Z,1697063015.705 [Default:CheckIn] Running Loop=2 2023-10-11T22:23:35.705Z,1697063015.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T22:23:35.705Z,1697063015.705 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T22:23:37.317Z,1697063017.317 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-11T22:23:37.317Z,1697063017.317 [NAL9602] Data Fault, FailCount= 1 2023-10-11T22:23:37.317Z,1697063017.317 [NAL9602](ERROR): Data Fault 2023-10-11T22:23:37.339Z,1697063017.339 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-11T22:23:37.713Z,1697063017.713 [NAL9602](INFO): Powering down 2023-10-11T22:23:38.541Z,1697063018.541 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-11T22:23:38.541Z,1697063018.541 [NAL9602] No Fault, FailCount= 1 2023-10-11T22:24:08.017Z,1697063048.017 [NAL9602](INFO): Powering up NAL9602 2023-10-11T22:24:18.921Z,1697063058.921 [NAL9602](INFO): NAL9602 initialized 2023-10-11T22:28:35.888Z,1697063315.888 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-11T22:23:35.7Z 2023-10-11T22:28:35.888Z,1697063315.888 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T22:28:35.889Z,1697063315.889 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T22:28:43.175Z,1697063323.175 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20231011T210530/Courier0031.lzma 2023-10-11T22:28:44.178Z,1697063324.178 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0031.lzma.bak 2023-10-11T22:28:44.178Z,1697063324.178 [DataOverHttps](IMPORTANT): SBD MOMSN=18988280, MTMSN=20231011T222843 2023-10-11T22:28:51.631Z,1697063331.631 [DataOverHttps](INFO): Received command: gfscan 2023-10-11T22:28:51.641Z,1697063331.641 [CommandExec](IMPORTANT): got command gfscan 2023-10-11T22:28:51.663Z,1697063331.663 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-11T22:29:00.548Z,1697063340.548 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20231011T210530/Express0032.lzma 2023-10-11T22:29:01.550Z,1697063341.550 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0032.lzma.bak 2023-10-11T22:29:01.550Z,1697063341.550 [DataOverHttps](INFO): SBD MOMSN=18988283 2023-10-11T22:29:02.639Z,1697063342.639 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.010929 CHAN A1 (24V): 0.000081 CHAN A2 (12V): -0.007274 CHAN A3 (5V): -0.001654 CHAN B0 (3.3V): 0.000154 CHAN B1 (3.15aV): -0.000416 CHAN B2 (3.15bV): 0.000311 CHAN B3 (GND): 0.002204 OPEN: 0.005089 Full Scale: +/- 1 mA 2023-10-11T22:29:17.571Z,1697063357.571 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231011T210530/Courier0034.lzma 2023-10-11T22:29:18.574Z,1697063358.574 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0034.lzma.bak 2023-10-11T22:29:18.574Z,1697063358.574 [DataOverHttps](INFO): SBD MOMSN=18988288 2023-10-11T22:29:22.337Z,1697063362.337 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-11T22:29:34.443Z,1697063374.443 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20231011T210530/Express0035.lzma 2023-10-11T22:29:35.446Z,1697063375.446 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0035.lzma.bak 2023-10-11T22:29:35.446Z,1697063375.446 [DataOverHttps](INFO): SBD MOMSN=18988290 2023-10-11T22:29:36.903Z,1697063376.903 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T22:29:36.904Z,1697063376.904 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T22:29:36.904Z,1697063376.904 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T22:29:53.049Z,1697063393.049 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T22:34:37.572Z,1697063677.572 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-11T22:34:37.572Z,1697063677.572 [Default:CheckIn:C.Wait] Stopped 2023-10-11T22:34:37.572Z,1697063677.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T22:34:37.572Z,1697063677.572 [Default:CheckIn:D] Running Loop=1 2023-10-11T22:34:37.930Z,1697063677.930 [Default:CheckIn:D] Stopped 2023-10-11T22:34:37.930Z,1697063677.930 [Default:CheckIn:E] Running Loop=1 2023-10-11T22:34:38.323Z,1697063678.323 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.093968 min 2023-10-11T22:34:38.324Z,1697063678.324 [Default:CheckIn:E] Stopped 2023-10-11T22:34:38.324Z,1697063678.324 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-11T22:34:38.324Z,1697063678.324 [Default:CheckIn] Stopped 2023-10-11T22:34:38.324Z,1697063678.324 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T22:34:38.324Z,1697063678.324 [Default:CheckIn](INFO): Running loop #3 2023-10-11T22:34:38.324Z,1697063678.324 [Default:CheckIn] Running Loop=3 2023-10-11T22:34:38.324Z,1697063678.324 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T22:34:38.324Z,1697063678.324 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T22:34:39.933Z,1697063679.933 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-11T22:34:39.933Z,1697063679.933 [NAL9602] Data Fault, FailCount= 2 2023-10-11T22:34:39.933Z,1697063679.933 [NAL9602](ERROR): Data Fault 2023-10-11T22:34:39.973Z,1697063679.973 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-11T22:34:40.340Z,1697063680.340 [NAL9602](INFO): Powering down 2023-10-11T22:34:41.178Z,1697063681.178 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-11T22:34:41.178Z,1697063681.178 [NAL9602] No Fault, FailCount= 2 2023-10-11T22:35:10.641Z,1697063710.641 [NAL9602](INFO): Powering up NAL9602 2023-10-11T22:35:21.549Z,1697063721.549 [NAL9602](INFO): NAL9602 initialized 2023-10-11T22:39:19.677Z,1697063959.677 [CommandExec](IMPORTANT): got command gfscan 2023-10-11T22:39:19.945Z,1697063959.945 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-11T22:39:30.845Z,1697063970.845 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.010173 CHAN A1 (24V): 0.000328 CHAN A2 (12V): -0.007754 CHAN A3 (5V): -0.002877 CHAN B0 (3.3V): 0.000075 CHAN B1 (3.15aV): 0.000169 CHAN B2 (3.15bV): 0.000352 CHAN B3 (GND): 0.002483 OPEN: 0.004894 Full Scale: +/- 1 mA 2023-10-11T22:39:38.527Z,1697063978.527 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-11T22:34:38.3Z 2023-10-11T22:39:38.527Z,1697063978.527 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T22:39:38.527Z,1697063978.527 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T22:39:46.187Z,1697063986.187 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231011T210530/Courier0037.lzma 2023-10-11T22:39:47.195Z,1697063987.195 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0037.lzma.bak 2023-10-11T22:39:47.195Z,1697063987.195 [DataOverHttps](INFO): SBD MOMSN=18988318 2023-10-11T22:40:03.192Z,1697064003.192 [DataOverHttps](INFO): Sending 477 bytes from file Logs/20231011T210530/Express0038.lzma 2023-10-11T22:40:04.194Z,1697064004.194 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0038.lzma.bak 2023-10-11T22:40:04.194Z,1697064004.194 [DataOverHttps](INFO): SBD MOMSN=18988320 2023-10-11T22:40:05.612Z,1697064005.612 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T22:40:05.613Z,1697064005.613 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T22:40:05.613Z,1697064005.613 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T22:40:24.962Z,1697064024.962 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-11T22:40:55.669Z,1697064055.669 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T22:43:24.417Z,1697064204.417 [CommandExec](IMPORTANT): got command gfscan 2023-10-11T22:43:24.790Z,1697064204.790 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-11T22:43:35.673Z,1697064215.673 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.010302 CHAN A1 (24V): 0.000389 CHAN A2 (12V): -0.007185 CHAN A3 (5V): -0.002042 CHAN B0 (3.3V): -0.000408 CHAN B1 (3.15aV): -0.000614 CHAN B2 (3.15bV): -0.000405 CHAN B3 (GND): 0.002027 OPEN: 0.005002 Full Scale: +/- 1 mA 2023-10-11T22:45:06.235Z,1697064306.235 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-11T22:45:06.235Z,1697064306.235 [Default:CheckIn:C.Wait] Stopped 2023-10-11T22:45:06.235Z,1697064306.235 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T22:45:06.235Z,1697064306.235 [Default:CheckIn:D] Running Loop=1 2023-10-11T22:45:06.637Z,1697064306.637 [Default:CheckIn:D] Stopped 2023-10-11T22:45:06.637Z,1697064306.637 [Default:CheckIn:E] Running Loop=1 2023-10-11T22:45:07.036Z,1697064307.036 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.572414 min 2023-10-11T22:45:07.036Z,1697064307.036 [Default:CheckIn:E] Stopped 2023-10-11T22:45:07.036Z,1697064307.036 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-11T22:45:07.036Z,1697064307.036 [Default:CheckIn] Stopped 2023-10-11T22:45:07.036Z,1697064307.036 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T22:45:07.037Z,1697064307.037 [Default:CheckIn](INFO): Running loop #4 2023-10-11T22:45:07.037Z,1697064307.037 [Default:CheckIn] Running Loop=4 2023-10-11T22:45:07.037Z,1697064307.037 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-11T22:45:07.037Z,1697064307.037 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-11T22:45:39.742Z,1697064339.742 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-11T22:45:39.824Z,1697064339.824 [NAL9602](ERROR): received: +CSQ:0 OK844, 2, 0, 0, 0 OK 2023-10-11T22:46:58.648Z,1697064418.648 [CBIT](CRITICAL): Run-time exception #7 2023-10-11T22:46:58.648Z,1697064418.648 [CBIT] Software Fault, FailCount= 1 2023-10-11T22:46:58.648Z,1697064418.648 [CBIT](ERROR): Software Fault 2023-10-11T22:46:58.651Z,1697064418.651 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-10-11T22:46:58.652Z,1697064418.652 [SyncHandler](INFO): Protected caller Thread ID is 8720 2023-10-11T22:46:58.975Z,1697064418.975 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-10-11T22:46:58.975Z,1697064418.975 [CBIT](DEBUG): Powering off loads. 2023-10-11T22:46:58.988Z,1697064418.988 [CBIT](DEBUG): Disabling WDT. 2023-10-11T22:46:58.000Z,1697064419.000 [CBIT](DEBUG): Opening all GF detection circuits. 2023-10-11T22:47:09.448Z,1697064429.448 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2023-10-11T22:47:09.448Z,1697064429.448 [AHRS_M2] Data Fault, FailCount= 1 2023-10-11T22:47:09.448Z,1697064429.448 [AHRS_M2](ERROR): Data Fault 2023-10-11T22:47:09.844Z,1697064429.844 [AHRS_M2](INFO): Powering down 2023-10-11T22:47:30.045Z,1697064450.045 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-11T22:47:30.568Z,1697064450.568 [NAL9602](ERROR): received: 2023-10-11T22:47:42.548Z,1697064462.548 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:47:44.556Z,1697064464.556 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.257874 2023-10-11T22:48:00.909Z,1697064480.909 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-11T22:48:01.432Z,1697064481.432 [NAL9602](ERROR): received: 2023-10-11T22:48:17.696Z,1697064497.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:48:31.829Z,1697064511.829 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-11T22:48:32.344Z,1697064512.344 [NAL9602](ERROR): received: 2023-10-11T22:48:52.844Z,1697064532.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:49:02.669Z,1697064542.669 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-11T22:49:03.184Z,1697064543.184 [NAL9602](ERROR): received: 2023-10-11T22:49:09.299Z,1697064549.299 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-10-11T22:49:09.299Z,1697064549.299 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-10-11T22:49:09.299Z,1697064549.299 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-11T22:49:28.017Z,1697064568.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-11T22:49:33.518Z,1697064573.518 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-11T22:49:34.040Z,1697064574.040 [NAL9602](ERROR): received: 2023-10-11T22:49:40.733Z,1697064580.733 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003433 2023-10-11T22:50:04.400Z,1697064604.400 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-10-11T22:50:04.920Z,1697064604.920 [NAL9602](ERROR): received: 2023-10-11T22:50:07.393Z,1697064607.393 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-11T22:45:07.0Z 2023-10-11T22:50:07.393Z,1697064607.393 [Default:CheckIn:Read_GPS] Stopped 2023-10-11T22:50:07.393Z,1697064607.393 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-11T22:50:11.413Z,1697064611.413 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-11T22:50:14.635Z,1697064614.635 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20231011T210530/Courier0040.lzma 2023-10-11T22:50:15.637Z,1697064615.637 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Courier0040.lzma.bak 2023-10-11T22:50:15.638Z,1697064615.638 [DataOverHttps](INFO): SBD MOMSN=18988341 2023-10-11T22:50:31.761Z,1697064631.761 [DataOverHttps](INFO): Sending 558 bytes from file Logs/20231011T210530/Express0041.lzma 2023-10-11T22:50:32.762Z,1697064632.762 [DataOverHttps](INFO): Moved sent file to Logs/20231011T210530/Express0041.lzma.bak 2023-10-11T22:50:32.762Z,1697064632.762 [DataOverHttps](INFO): SBD MOMSN=18988344 2023-10-11T22:50:34.071Z,1697064634.071 [Default:CheckIn:Read_Iridium] Stopped 2023-10-11T22:50:34.071Z,1697064634.071 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-11T22:50:34.071Z,1697064634.071 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-11T22:50:42.117Z,1697064642.117 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-11T22:55:06.873Z,1697064906.873 [CommandExec](IMPORTANT): got command quit 2023-10-11T22:55:07.876Z,1697064907.876 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-10-11T22:55:07.876Z,1697064907.876 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:07.876Z,1697064907.876 [CommandExec](INFO): Uninitializing the command executive. 2023-10-11T22:55:07.876Z,1697064907.876 [CommandExec](INFO): Uninitializing the command scheduler. 2023-10-11T22:55:07.877Z,1697064907.877 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:07.932Z,1697064907.932 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-10-11T22:55:07.932Z,1697064907.932 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-10-11T22:55:07.932Z,1697064907.932 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:07.933Z,1697064907.933 [NavChartDb](INFO): Join timeout helper Thread ID is 1818 2023-10-11T22:55:08.280Z,1697064908.280 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:08.280Z,1697064908.280 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:08.284Z,1697064908.284 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2023-10-11T22:55:08.284Z,1697064908.284 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:08.284Z,1697064908.284 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1821 2023-10-11T22:55:08.672Z,1697064908.672 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:08.672Z,1697064908.672 [WetLabsUBAT](INFO): Powering down 2023-10-11T22:55:08.673Z,1697064908.673 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:08.676Z,1697064908.676 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-10-11T22:55:08.676Z,1697064908.676 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:08.676Z,1697064908.676 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1824 2023-10-11T22:55:09.060Z,1697064909.060 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:09.060Z,1697064909.060 [WetLabsBB2FL](INFO): Powering down 2023-10-11T22:55:09.061Z,1697064909.061 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:09.084Z,1697064909.084 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-10-11T22:55:09.084Z,1697064909.084 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:09.085Z,1697064909.085 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1828 2023-10-11T22:55:09.668Z,1697064909.668 [CTD_Seabird](INFO): Powering down 2023-10-11T22:55:09.680Z,1697064909.680 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:09.680Z,1697064909.680 [CTD_Seabird](INFO): Powering down 2023-10-11T22:55:09.692Z,1697064909.692 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:09.720Z,1697064909.720 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-10-11T22:55:09.720Z,1697064909.720 [DAT ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:09.720Z,1697064909.720 [DAT](INFO): Join timeout helper Thread ID is 1832 2023-10-11T22:55:09.868Z,1697064909.868 [DAT](INFO): Powering down 2023-10-11T22:55:09.940Z,1697064909.940 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:09.940Z,1697064909.940 [DAT](INFO): Powering down 2023-10-11T22:55:09.941Z,1697064909.941 [DAT ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:09.949Z,1697064909.949 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-10-11T22:55:09.949Z,1697064909.949 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:09.949Z,1697064909.949 [Radio_Surface](INFO): Join timeout helper Thread ID is 1835 2023-10-11T22:55:10.288Z,1697064910.288 [Radio_Surface](INFO): Powering down 2023-10-11T22:55:10.289Z,1697064910.289 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:10.289Z,1697064910.289 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:10.304Z,1697064910.304 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-10-11T22:55:10.304Z,1697064910.304 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:10.305Z,1697064910.305 [Onboard](INFO): Join timeout helper Thread ID is 1838 2023-10-11T22:55:11.024Z,1697064911.024 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-10-11T22:55:11.312Z,1697064911.312 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:11.312Z,1697064911.312 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:11.320Z,1697064911.320 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-10-11T22:55:11.320Z,1697064911.320 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:11.321Z,1697064911.321 [DataOverHttps](INFO): Join timeout helper Thread ID is 1845 2023-10-11T22:55:12.028Z,1697064912.028 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:12.028Z,1697064912.028 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.040Z,1697064912.040 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-10-11T22:55:12.041Z,1697064912.041 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.041Z,1697064912.041 [BackseatComponent](INFO): Join timeout helper Thread ID is 1850 2023-10-11T22:55:12.200Z,1697064912.200 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:12.200Z,1697064912.200 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.221Z,1697064912.221 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-10-11T22:55:12.221Z,1697064912.221 [logger ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.221Z,1697064912.221 [logger](INFO): Join timeout helper Thread ID is 1852 2023-10-11T22:55:12.259Z,1697064912.259 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:12.259Z,1697064912.259 [logger ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.277Z,1697064912.277 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-10-11T22:55:12.277Z,1697064912.277 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.277Z,1697064912.277 [CommandLine](INFO): Join timeout helper Thread ID is 1854 2023-10-11T22:55:12.376Z,1697064912.376 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:12.376Z,1697064912.376 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.396Z,1697064912.396 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-10-11T22:55:12.396Z,1697064912.396 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.397Z,1697064912.397 [CommandExec](INFO): Join timeout helper Thread ID is 1855 2023-10-11T22:55:12.398Z,1697064912.398 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-10-11T22:55:12.398Z,1697064912.398 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.398Z,1697064912.398 [controlThread](INFO): Join timeout helper Thread ID is 1856 2023-10-11T22:55:12.464Z,1697064912.464 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-11T22:55:12.464Z,1697064912.464 [controlThread](DEBUG): Uninitializing ControlThread 2023-10-11T22:55:12.465Z,1697064912.465 [AHRS_M2](INFO): Powering down 2023-10-11T22:55:12.466Z,1697064912.466 [NAL9602](INFO): Powering down 2023-10-11T22:55:12.468Z,1697064912.468 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-10-11T22:55:12.469Z,1697064912.469 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-10-11T22:55:12.469Z,1697064912.469 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-10-11T22:55:12.469Z,1697064912.469 [MissionManager](INFO): Uninitializing Mission Default 2023-10-11T22:55:12.469Z,1697064912.469 [Default] Stopped 2023-10-11T22:55:12.469Z,1697064912.469 [Default](DEBUG): Aggregate::uninitialize Default 2023-10-11T22:55:12.470Z,1697064912.470 [Default:B.GoToSurface] Stopped 2023-10-11T22:55:12.470Z,1697064912.470 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-11T22:55:12.470Z,1697064912.470 [Default:CheckIn] Stopped 2023-10-11T22:55:12.470Z,1697064912.470 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-11T22:55:12.470Z,1697064912.470 [Default:CheckIn:C.Wait] Stopped 2023-10-11T22:55:12.470Z,1697064912.470 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-11T22:55:12.473Z,1697064912.473 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-10-11T22:55:12.473Z,1697064912.473 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-10-11T22:55:12.473Z,1697064912.473 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-10-11T22:55:12.473Z,1697064912.473 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-10-11T22:55:12.474Z,1697064912.474 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-10-11T22:55:12.474Z,1697064912.474 [BuoyancyServo](INFO): Powering down 2023-10-11T22:55:12.488Z,1697064912.488 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-10-11T22:55:12.488Z,1697064912.488 [ElevatorServo](INFO): Powering down 2023-10-11T22:55:12.489Z,1697064912.489 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-10-11T22:55:12.489Z,1697064912.489 [MassServo](INFO): Powering down 2023-10-11T22:55:12.490Z,1697064912.490 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-10-11T22:55:12.490Z,1697064912.490 [RudderServo](INFO): Powering down 2023-10-11T22:55:12.491Z,1697064912.491 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-10-11T22:55:12.491Z,1697064912.491 [ThrusterServo](INFO): Powering down 2023-10-11T22:55:12.492Z,1697064912.492 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-10-11T22:55:12.492Z,1697064912.492 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-10-11T22:55:12.492Z,1697064912.492 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-10-11T22:55:12.493Z,1697064912.493 [CBIT](DEBUG): Powering off loads. 2023-10-11T22:55:12.504Z,1697064912.504 [CBIT](DEBUG): Disabling WDT. 2023-10-11T22:55:12.516Z,1697064912.516 [CBIT](DEBUG): Opening all GF detection circuits. 2023-10-11T22:55:12.517Z,1697064912.517 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.599Z,1697064912.599 [DAT ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.605Z,1697064912.605 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.614Z,1697064912.614 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.632Z,1697064912.632 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.676Z,1697064912.676 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.734Z,1697064912.734 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.736Z,1697064912.736 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.740Z,1697064912.740 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.747Z,1697064912.747 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-11T22:55:12.822Z,1697064912.822 [logger ThreadHandler](INFO): Thread cancelled.