2024-05-22T21:42:23.932Z,1716414143.932 [Supervisor](DEBUG): Initializing supervisor. 2024-05-22T21:42:23.942Z,1716414143.942 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-05-22T21:42:23.943Z,1716414143.943 [SyncHandler](INFO): Protected caller Thread ID is 9556 2024-05-22T21:42:23.943Z,1716414143.943 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-05-22T21:42:23.944Z,1716414143.944 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-05-22T21:42:23.944Z,1716414143.944 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9557 2024-05-22T21:42:23.948Z,1716414143.948 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-05-22T21:42:23.975Z,1716414143.975 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-05-22T21:42:23.976Z,1716414143.976 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-05-22T21:42:23.976Z,1716414143.976 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9558 2024-05-22T21:42:23.980Z,1716414143.980 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-05-22T21:42:23.981Z,1716414143.981 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-05-22T21:42:23.982Z,1716414143.982 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9559 2024-05-22T21:42:23.992Z,1716414143.992 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-05-22T21:42:23.993Z,1716414143.993 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-05-22T21:42:23.993Z,1716414143.993 [logger ThreadHandler](INFO): Protected caller Thread ID is 9560 2024-05-22T21:42:23.997Z,1716414143.997 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-05-22T21:42:23.997Z,1716414143.997 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-05-22T21:42:23.999Z,1716414143.999 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-05-22T21:42:24.165Z,1716414144.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-05-22T21:42:24.165Z,1716414144.165 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-05-22T21:42:25.484Z,1716414145.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-05-22T21:42:25.484Z,1716414145.484 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-05-22T21:42:26.061Z,1716414146.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-05-22T21:42:26.062Z,1716414146.062 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-05-22T21:42:26.201Z,1716414146.201 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-05-22T21:42:26.416Z,1716414146.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-05-22T21:42:26.417Z,1716414146.417 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-05-22T21:42:26.559Z,1716414146.559 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-05-22T21:42:26.733Z,1716414146.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-05-22T21:42:26.733Z,1716414146.733 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-05-22T21:42:27.386Z,1716414147.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-05-22T21:42:27.387Z,1716414147.387 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-05-22T21:42:28.270Z,1716414148.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-05-22T21:42:28.270Z,1716414148.270 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-05-22T21:42:28.633Z,1716414148.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-05-22T21:42:28.634Z,1716414148.634 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-05-22T21:42:28.958Z,1716414148.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-05-22T21:42:28.959Z,1716414148.959 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-05-22T21:42:29.842Z,1716414149.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-05-22T21:42:29.843Z,1716414149.843 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-05-22T21:42:30.324Z,1716414150.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-05-22T21:42:30.325Z,1716414150.325 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-05-22T21:42:30.759Z,1716414150.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-05-22T21:42:30.759Z,1716414150.759 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-05-22T21:42:31.697Z,1716414151.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-05-22T21:42:31.697Z,1716414151.697 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-05-22T21:42:32.140Z,1716414152.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-05-22T21:42:32.142Z,1716414152.142 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2024-05-22T21:42:32.143Z,1716414152.143 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2024-05-22T21:42:32.241Z,1716414152.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2024-05-22T21:42:32.428Z,1716414152.428 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2024-05-22T21:42:32.538Z,1716414152.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2024-05-22T21:42:32.661Z,1716414152.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2024-05-22T21:42:32.760Z,1716414152.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2024-05-22T21:42:32.870Z,1716414152.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2024-05-22T21:42:32.998Z,1716414152.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2024-05-22T21:42:33.507Z,1716414153.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-05-22T21:42:33.508Z,1716414153.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2024-05-22T21:42:33.686Z,1716414153.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2024-05-22T21:42:33.866Z,1716414153.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2024-05-22T21:42:34.475Z,1716414154.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2024-05-22T21:42:34.752Z,1716414154.752 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2024-05-22T21:42:34.752Z,1716414154.752 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-05-22T21:42:34.770Z,1716414154.770 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-05-22T21:42:34.965Z,1716414154.965 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-05-22T21:42:34.966Z,1716414154.966 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-05-22T21:42:35.142Z,1716414155.142 [VerticalControl](DEBUG): Construct VerticalControl. 2024-05-22T21:42:35.317Z,1716414155.317 [VerticalControl] Loaded 2024-05-22T21:42:35.317Z,1716414155.317 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-05-22T21:42:35.356Z,1716414155.356 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-05-22T21:42:35.556Z,1716414155.556 [HorizontalControl] Loaded 2024-05-22T21:42:35.556Z,1716414155.556 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-05-22T21:42:35.569Z,1716414155.569 [SpeedControl](DEBUG): Construct SpeedControl. 2024-05-22T21:42:35.604Z,1716414155.604 [SpeedControl] Loaded 2024-05-22T21:42:35.605Z,1716414155.605 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-05-22T21:42:35.607Z,1716414155.607 [LoopControl](DEBUG): Construct LoopControl. 2024-05-22T21:42:35.608Z,1716414155.608 [LoopControl] Loaded 2024-05-22T21:42:35.608Z,1716414155.608 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-05-22T21:42:35.608Z,1716414155.608 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-05-22T21:42:35.609Z,1716414155.609 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-05-22T21:42:35.825Z,1716414155.825 [DepthRateCalculator] Loaded 2024-05-22T21:42:35.827Z,1716414155.827 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-05-22T21:42:35.842Z,1716414155.842 [PitchRateCalculator] Loaded 2024-05-22T21:42:35.842Z,1716414155.842 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-05-22T21:42:35.918Z,1716414155.918 [SpeedCalculator] Loaded 2024-05-22T21:42:35.918Z,1716414155.918 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-05-22T21:42:35.931Z,1716414155.931 [YawRateCalculator] Loaded 2024-05-22T21:42:35.931Z,1716414155.931 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-05-22T21:42:36.022Z,1716414156.022 [ElevatorOffsetCalculator] Loaded 2024-05-22T21:42:36.023Z,1716414156.023 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-05-22T21:42:36.023Z,1716414156.023 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-05-22T21:42:36.024Z,1716414156.024 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-05-22T21:42:36.136Z,1716414156.136 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-05-22T21:42:36.137Z,1716414156.137 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-05-22T21:42:36.276Z,1716414156.276 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-05-22T21:42:36.277Z,1716414156.277 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-05-22T21:42:37.330Z,1716414157.330 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-05-22T21:42:37.331Z,1716414157.331 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-05-22T21:42:37.639Z,1716414157.639 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-05-22T21:42:37.640Z,1716414157.640 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-05-22T21:42:38.112Z,1716414158.112 [AHRS_M2] Loaded 2024-05-22T21:42:38.112Z,1716414158.112 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-05-22T21:42:38.148Z,1716414158.148 [BackseatComponent] Loaded 2024-05-22T21:42:38.148Z,1716414158.148 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-05-22T21:42:38.149Z,1716414158.149 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C44E0 2024-05-22T21:42:38.149Z,1716414158.149 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9645 2024-05-22T21:42:38.152Z,1716414158.152 [LcmUniversalReporter] Loaded 2024-05-22T21:42:38.153Z,1716414158.153 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-05-22T21:42:38.975Z,1716414158.975 [BPC1] Loaded 2024-05-22T21:42:38.976Z,1716414158.976 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-05-22T21:42:39.058Z,1716414159.058 [DataOverHttps] Loaded 2024-05-22T21:42:39.058Z,1716414159.058 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-05-22T21:42:39.059Z,1716414159.059 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F44E0 2024-05-22T21:42:39.060Z,1716414159.060 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9646 2024-05-22T21:42:39.080Z,1716414159.080 [Depth_Keller] Loaded 2024-05-22T21:42:39.081Z,1716414159.081 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-05-22T21:42:39.144Z,1716414159.144 [NAL9602] Loaded 2024-05-22T21:42:39.144Z,1716414159.144 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-05-22T21:42:39.174Z,1716414159.174 [Onboard] Loaded 2024-05-22T21:42:39.175Z,1716414159.175 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-05-22T21:42:39.176Z,1716414159.176 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A244E0 2024-05-22T21:42:39.176Z,1716414159.176 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9647 2024-05-22T21:42:39.188Z,1716414159.188 [Power24vConverter] Loaded 2024-05-22T21:42:39.189Z,1716414159.189 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-05-22T21:42:39.203Z,1716414159.203 [Radio_Surface] Loaded 2024-05-22T21:42:39.204Z,1716414159.204 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-05-22T21:42:39.205Z,1716414159.205 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A544E0 2024-05-22T21:42:39.205Z,1716414159.205 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9648 2024-05-22T21:42:39.251Z,1716414159.251 [RDI_Pathfinder] Loaded 2024-05-22T21:42:39.251Z,1716414159.251 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2024-05-22T21:42:39.345Z,1716414159.345 [DAT] Loaded 2024-05-22T21:42:39.345Z,1716414159.345 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-05-22T21:42:39.346Z,1716414159.346 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A844E0 2024-05-22T21:42:39.347Z,1716414159.347 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9649 2024-05-22T21:42:39.347Z,1716414159.347 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-05-22T21:42:39.348Z,1716414159.348 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-05-22T21:42:39.461Z,1716414159.461 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-05-22T21:42:39.462Z,1716414159.462 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-05-22T21:42:39.476Z,1716414159.476 [NavChart] Loaded 2024-05-22T21:42:39.476Z,1716414159.476 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-05-22T21:42:39.482Z,1716414159.482 [UniversalFixResidualReporter] Loaded 2024-05-22T21:42:39.482Z,1716414159.482 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-05-22T21:42:39.483Z,1716414159.483 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-05-22T21:42:39.483Z,1716414159.483 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-05-22T21:42:39.629Z,1716414159.629 [SBIT](DEBUG): Construct Startup Built In Test. 2024-05-22T21:42:39.638Z,1716414159.638 [SBIT] Loaded 2024-05-22T21:42:39.639Z,1716414159.639 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-05-22T21:42:39.642Z,1716414159.642 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-05-22T21:42:39.655Z,1716414159.655 [IBIT] Loaded 2024-05-22T21:42:39.655Z,1716414159.655 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-05-22T21:42:39.661Z,1716414159.661 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-05-22T21:42:39.769Z,1716414159.769 [CBIT] Loaded 2024-05-22T21:42:39.769Z,1716414159.769 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-05-22T21:42:39.770Z,1716414159.770 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-05-22T21:42:39.770Z,1716414159.770 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-05-22T21:42:39.906Z,1716414159.906 [BuoyancyServo] Loaded 2024-05-22T21:42:39.906Z,1716414159.906 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-05-22T21:42:39.927Z,1716414159.927 [ElevatorServo] Loaded 2024-05-22T21:42:39.928Z,1716414159.928 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-05-22T21:42:39.956Z,1716414159.956 [DockingStepper] Loaded 2024-05-22T21:42:39.956Z,1716414159.956 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2024-05-22T21:42:39.976Z,1716414159.976 [MassServo] Loaded 2024-05-22T21:42:39.976Z,1716414159.976 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-05-22T21:42:39.996Z,1716414159.996 [RudderServo] Loaded 2024-05-22T21:42:39.996Z,1716414159.996 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-05-22T21:42:40.013Z,1716414160.013 [ThrusterServo] Loaded 2024-05-22T21:42:40.013Z,1716414160.013 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2024-05-22T21:42:40.014Z,1716414160.014 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-05-22T21:42:40.015Z,1716414160.015 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-05-22T21:42:40.025Z,1716414160.025 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-05-22T21:42:40.026Z,1716414160.026 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-05-22T21:42:40.205Z,1716414160.205 [CTD_Seabird] Loaded 2024-05-22T21:42:40.205Z,1716414160.205 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-05-22T21:42:40.206Z,1716414160.206 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD84E0 2024-05-22T21:42:40.206Z,1716414160.206 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9650 2024-05-22T21:42:40.240Z,1716414160.240 [ESPComponent] Loaded 2024-05-22T21:42:40.240Z,1716414160.240 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-05-22T21:42:40.261Z,1716414160.261 [PAR_Licor] Loaded 2024-05-22T21:42:40.262Z,1716414160.262 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-05-22T21:42:40.305Z,1716414160.305 [WetLabsBB2FL] Loaded 2024-05-22T21:42:40.306Z,1716414160.306 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-05-22T21:42:40.307Z,1716414160.307 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C084E0 2024-05-22T21:42:40.307Z,1716414160.307 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9651 2024-05-22T21:42:40.327Z,1716414160.327 [WetLabsUBAT] Loaded 2024-05-22T21:42:40.328Z,1716414160.328 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2024-05-22T21:42:40.329Z,1716414160.329 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C384E0 2024-05-22T21:42:40.329Z,1716414160.329 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 9652 2024-05-22T21:42:40.330Z,1716414160.330 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-05-22T21:42:40.336Z,1716414160.336 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-05-22T21:42:40.339Z,1716414160.339 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-05-22T21:42:40.351Z,1716414160.351 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-05-22T21:42:40.351Z,1716414160.351 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C684E0 2024-05-22T21:42:40.352Z,1716414160.352 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9653 2024-05-22T21:42:40.356Z,1716414160.356 [Supervisor](INFO): Main Thread ID is 831 2024-05-22T21:42:40.357Z,1716414160.357 [Supervisor](DEBUG): Running supervisor. 2024-05-22T21:42:40.357Z,1716414160.357 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9654 2024-05-22T21:42:40.358Z,1716414160.358 [CommandExec](INFO): Initializing the command executive. 2024-05-22T21:42:40.359Z,1716414160.359 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9655 2024-05-22T21:42:40.362Z,1716414160.362 [controlThread ThreadHandler](INFO): Handler Thread ID is 9656 2024-05-22T21:42:40.363Z,1716414160.363 [controlThread](DEBUG): Initializing ControlThread 2024-05-22T21:42:40.364Z,1716414160.364 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-05-22T21:42:40.365Z,1716414160.365 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-05-22T21:42:40.366Z,1716414160.366 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-05-22T21:42:40.366Z,1716414160.366 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-05-22T21:42:40.367Z,1716414160.367 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-05-22T21:42:40.367Z,1716414160.367 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-05-22T21:42:40.367Z,1716414160.367 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-05-22T21:42:40.368Z,1716414160.368 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-05-22T21:42:40.368Z,1716414160.368 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-05-22T21:42:40.371Z,1716414160.371 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-05-22T21:42:40.372Z,1716414160.372 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-05-22T21:42:40.372Z,1716414160.372 [SBIT](INFO): Initialize SBIT Component. 2024-05-22T21:42:40.372Z,1716414160.372 [SBIT](IMPORTANT): git: 2024-05-10-4-gbb504d4a1 2024-05-22T21:42:40.372Z,1716414160.372 [SBIT](INFO): git hash: bb504d4a1c64b9083deca52c3c8cef871ae8bac1 2024-05-22T21:42:40.373Z,1716414160.373 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-05-22T21:42:40.374Z,1716414160.374 [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 2024-05-22T21:42:40.375Z,1716414160.375 [SBIT](INFO): Beginning SBIT in 94.000000 seconds. 2024-05-22T21:42:40.375Z,1716414160.375 [IBIT](INFO): Initialize IBIT Component. 2024-05-22T21:42:40.376Z,1716414160.376 [CBIT](DEBUG): Initialize CBIT Component. 2024-05-22T21:42:40.377Z,1716414160.377 [logger ThreadHandler](INFO): Handler Thread ID is 9657 2024-05-22T21:42:40.390Z,1716414160.390 [CBIT](DEBUG): Initialized mux pins. 2024-05-22T21:42:40.390Z,1716414160.390 [CBIT](DEBUG): Initializing the watchdog timer. 2024-05-22T21:42:40.398Z,1716414160.398 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9658 2024-05-22T21:42:40.410Z,1716414160.410 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9659 2024-05-22T21:42:40.412Z,1716414160.412 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-05-22T21:42:40.414Z,1716414160.414 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-05-22T21:42:40.415Z,1716414160.415 [CBIT](FAULT): Found backtrace file: 1716413960.164225 [BackseatComponent]: SIGSEGV: address not mapped to object 1716413960.164225 [BackseatComponent]: Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b3734] 2024-05-22T21:42:40.418Z,1716414160.418 [CBIT](FAULT): Renaming backtrace file to Data/backtrace15 2024-05-22T21:42:40.419Z,1716414160.419 [CBIT](DEBUG): Initializing heartbeat. 2024-05-22T21:42:40.429Z,1716414160.429 [Onboard ThreadHandler](INFO): Handler Thread ID is 9660 2024-05-22T21:42:40.441Z,1716414160.441 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9661 2024-05-22T21:42:40.474Z,1716414160.474 [DAT ThreadHandler](INFO): Handler Thread ID is 9662 2024-05-22T21:42:40.474Z,1716414160.474 [DAT](INFO): Powering up 2024-05-22T21:42:40.475Z,1716414160.475 [DAT](DEBUG): Initializing DAT. 2024-05-22T21:42:40.476Z,1716414160.476 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9663 2024-05-22T21:42:40.477Z,1716414160.477 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-05-22T21:42:40.487Z,1716414160.487 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9665 2024-05-22T21:42:40.490Z,1716414160.490 [CBIT](DEBUG): Deactivating GF circuits. 2024-05-22T21:42:40.490Z,1716414160.490 [CBIT](DEBUG): Deactivating emergency mode. 2024-05-22T21:42:40.491Z,1716414160.491 [WetLabsBB2FL](INFO): Powering up 2024-05-22T21:42:40.492Z,1716414160.492 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 9667 2024-05-22T21:42:40.494Z,1716414160.494 [WetLabsUBAT](INFO): Powering up 2024-05-22T21:42:40.495Z,1716414160.495 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9669 2024-05-22T21:42:40.498Z,1716414160.498 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-05-22T21:42:40.498Z,1716414160.498 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-05-22T21:42:40.499Z,1716414160.499 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-05-22T21:42:40.499Z,1716414160.499 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-05-22T21:42:40.499Z,1716414160.499 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-05-22T21:42:40.499Z,1716414160.499 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-05-22T21:42:40.499Z,1716414160.499 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-05-22T21:42:40.499Z,1716414160.499 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-05-22T21:42:40.530Z,1716414160.530 [CBIT](DEBUG): Backplane powered. 2024-05-22T21:42:40.534Z,1716414160.534 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-05-22T21:42:40.535Z,1716414160.535 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2024-05-22T21:42:40.535Z,1716414160.535 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2024-05-22T21:42:40.536Z,1716414160.536 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2024-05-22T21:42:40.550Z,1716414160.550 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2024-05-22T21:42:40.550Z,1716414160.550 [MissionManager](ERROR): Top node has no child nodes: Mission 2024-05-22T21:42:40.551Z,1716414160.551 [MissionManager](ERROR): Could not load startup mission. 2024-05-22T21:42:40.551Z,1716414160.551 [MissionManager](INFO): Loading Mission from file: Missions/Engineering/DefaultTankUndock.tl 2024-05-22T21:42:40.551Z,1716414160.551 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Engineering/DefaultTankUndock.tl 2024-05-22T21:42:40.552Z,1716414160.552 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Engineering/DefaultTankUndock.tl 2024-05-22T21:42:40.843Z,1716414160.843 [MissionManager](DEBUG): TethyslAPI: Missions/Engineering/DefaultTankUndock.tl translated into: 0 13 dock arm 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-05-22T21:42:40.850Z,1716414160.850 [Radio_Surface](INFO): Powering up 2024-05-22T21:42:40.881Z,1716414160.881 [MissionManager](INFO): DefineArg DefaultTankUndock.ElapsedSinceDefaultStarted = 0.000000 min 2024-05-22T21:42:40.883Z,1716414160.883 [DefaultTankUndock:A.Wait](DEBUG): Construct Wait. 2024-05-22T21:42:40.903Z,1716414160.903 [DefaultTankUndock:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-22T21:42:40.921Z,1716414160.921 [DefaultTankUndock:C.Execute](DEBUG): Construct Execute. 2024-05-22T21:42:40.925Z,1716414160.925 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-05-22T21:42:40.955Z,1716414160.955 [DefaultTankUndock:F.Execute](DEBUG): Construct Execute. 2024-05-22T21:42:40.982Z,1716414160.982 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission DefaultTankUndock { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } behavior Guidance:Execute { run in sequence set command = "dock arm" } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-05-22T21:42:40.987Z,1716414160.987 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-05-22T21:42:41.010Z,1716414161.010 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-05-22T21:42:41.067Z,1716414161.067 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m 2024-05-22T21:42:41.068Z,1716414161.068 [Power24vConverter](INFO): Powering up. 2024-05-22T21:42:41.069Z,1716414161.069 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-05-22T21:42:41.134Z,1716414161.134 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-05-22T21:42:41.160Z,1716414161.160 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-05-22T21:42:41.166Z,1716414161.166 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-05-22T21:42:41.167Z,1716414161.167 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-05-22T21:42:41.174Z,1716414161.174 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-05-22T21:42:41.175Z,1716414161.175 [DockingStepper](INFO): Start 2024-05-22T21:42:41.175Z,1716414161.175 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:42:41.176Z,1716414161.176 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:42:41.176Z,1716414161.176 [MassServo](DEBUG): Initializing EZServoServo. 2024-05-22T21:42:41.182Z,1716414161.182 [MassServo](DEBUG): Initializing MassServo. 2024-05-22T21:42:41.183Z,1716414161.183 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-22T21:42:41.190Z,1716414161.190 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-22T21:42:41.191Z,1716414161.191 [ThrusterServo](DEBUG): Initializing EZServoServo. 2024-05-22T21:42:41.199Z,1716414161.199 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2024-05-22T21:42:41.457Z,1716414161.457 [DockingStepper](INFO): Starting 2024-05-22T21:42:41.822Z,1716414161.822 [DockingStepper](INFO): Starting 2024-05-22T21:42:42.350Z,1716414162.350 [DockingStepper](INFO): Starting 2024-05-22T21:42:42.399Z,1716414162.399 [DockingStepper](INFO): Init string: /1z7664aE13561N3m50 2024-05-22T21:42:42.645Z,1716414162.645 [DockingStepper](INFO): Starting 2024-05-22T21:42:43.033Z,1716414163.033 [DockingStepper](INFO): Starting 2024-05-22T21:42:43.118Z,1716414163.118 [WetLabsBB2FL](INFO): Powering down 2024-05-22T21:42:43.456Z,1716414163.456 [DockingStepper](INFO): Starting 2024-05-22T21:42:43.890Z,1716414163.890 [DockingStepper](INFO): Starting 2024-05-22T21:42:43.914Z,1716414163.914 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:42:44.346Z,1716414164.346 [DockingStepper](INFO): Starting 2024-05-22T21:42:44.802Z,1716414164.802 [DockingStepper](INFO): Commanding positive:44526 2024-05-22T21:42:45.147Z,1716414165.147 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0O 2024-05-22T21:42:45.147Z,1716414165.147 [DockingStepper](FAULT): Command Overflow Error 2024-05-22T21:42:45.147Z,1716414165.147 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:42:45.147Z,1716414165.147 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:42:45.147Z,1716414165.147 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:42:45.275Z,1716414165.275 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:42:45.675Z,1716414165.675 [DockingStepper](INFO): Stop 2024-05-22T21:42:45.675Z,1716414165.675 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:42:45.675Z,1716414165.675 [DockingStepper](INFO): Powering down 2024-05-22T21:42:45.967Z,1716414165.967 [DockingStepper](INFO): Stopping 2024-05-22T21:42:46.102Z,1716414166.102 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2024-05-22T21:42:46.103Z,1716414166.103 [CommandExec](INFO): Found local IP address 10.89.10.2 for eth0 2024-05-22T21:42:46.366Z,1716414166.366 [DockingStepper](INFO): Stopped 2024-05-22T21:42:46.446Z,1716414166.446 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:42:46.446Z,1716414166.446 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:42:46.754Z,1716414166.754 [DockingStepper](INFO): Start 2024-05-22T21:42:46.755Z,1716414166.755 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:42:46.755Z,1716414166.755 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:42:47.187Z,1716414167.187 [DockingStepper](INFO): Starting 2024-05-22T21:42:47.506Z,1716414167.506 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-05-22T21:42:47.555Z,1716414167.555 [DockingStepper](INFO): Starting 2024-05-22T21:42:48.019Z,1716414168.019 [DockingStepper](INFO): Starting 2024-05-22T21:42:48.070Z,1716414168.070 [DockingStepper](INFO): Init string: /1z9119aE13561N3m50 2024-05-22T21:42:48.399Z,1716414168.399 [DockingStepper](INFO): Starting 2024-05-22T21:42:48.799Z,1716414168.799 [DockingStepper](INFO): Starting 2024-05-22T21:42:49.188Z,1716414169.188 [DockingStepper](INFO): Starting 2024-05-22T21:42:49.591Z,1716414169.591 [DockingStepper](INFO): Starting 2024-05-22T21:42:49.618Z,1716414169.618 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:42:49.742Z,1716414169.742 [WetLabsUBAT](INFO): Powering down 2024-05-22T21:42:49.999Z,1716414169.999 [DockingStepper](INFO): Starting 2024-05-22T21:42:50.502Z,1716414170.502 [DockingStepper](INFO): Commanding positive:35586 2024-05-22T21:42:53.074Z,1716414173.074 [DAT](INFO): DAT read: 2024-05-22T21:42:53.076Z,1716414173.076 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-05-22T21:42:54.371Z,1716414174.371 [DockingStepper](INFO): Pause 2024-05-22T21:42:54.839Z,1716414174.839 [DAT](INFO): DAT read: MF Frequency Band 2024-05-22T21:42:54.840Z,1716414174.840 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2024-05-22T21:42:54.841Z,1716414174.841 [DAT](INFO): DAT read: May 22 2024 21:42:48 2024-05-22T21:42:55.847Z,1716414175.847 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-05-22T21:42:55.848Z,1716414175.848 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-05-22T21:42:55.849Z,1716414175.849 [DAT](INFO): commRate: 800 2024-05-22T21:42:57.914Z,1716414177.914 [DAT](INFO): entering command mode 2024-05-22T21:42:58.114Z,1716414178.114 [DAT](INFO): DAT read: 2024-05-22T21:42:58.115Z,1716414178.115 [DAT](INFO): DAT read: user:1> 2024-05-22T21:42:58.115Z,1716414178.115 [DAT](INFO): setting verbose to 3 2024-05-22T21:42:58.367Z,1716414178.367 [DAT](INFO): DAT read: user:1> 2024-05-22T21:42:58.368Z,1716414178.368 [DAT](INFO): DAT read: Verbose | 3 2024-05-22T21:42:58.368Z,1716414178.368 [DAT](INFO): set verbose to 3 2024-05-22T21:42:58.368Z,1716414178.368 [DAT](INFO): setting DatVerbose to 27440 2024-05-22T21:42:58.619Z,1716414178.619 [DAT](INFO): DAT read: user:2> 2024-05-22T21:42:58.620Z,1716414178.620 [DAT](INFO): DAT read: DatVerbose | 27440 2024-05-22T21:42:58.620Z,1716414178.620 [DAT](INFO): set DatVerbose to 27440 2024-05-22T21:42:58.620Z,1716414178.620 [DAT](INFO): setting transmit power to 8 2024-05-22T21:42:58.871Z,1716414178.871 [DAT](INFO): DAT read: user:3> 2024-05-22T21:42:58.872Z,1716414178.872 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-05-22T21:42:58.872Z,1716414178.872 [DAT](INFO): set transmit power to 8 2024-05-22T21:42:58.873Z,1716414178.873 [DAT](INFO): setting local address to 10 2024-05-22T21:42:59.123Z,1716414179.123 [DAT](INFO): DAT read: user:4> 2024-05-22T21:42:59.123Z,1716414179.123 [DAT](INFO): DAT read: LocalAddr | 10 2024-05-22T21:42:59.124Z,1716414179.124 [DAT](INFO): set local address to 10 2024-05-22T21:42:59.135Z,1716414179.135 [DAT](INFO): Setting time to: 21:42:59 And date to:5/22/2024 2024-05-22T21:42:59.376Z,1716414179.376 [DAT](INFO): DAT read: user:5> 2024-05-22T21:42:59.380Z,1716414179.380 [DAT](INFO): DAT read: Wed May 22, 2024 21:42:59 2024-05-22T21:42:59.380Z,1716414179.380 [DAT](INFO): Local DAT time set to Wed May 22, 2024 21:42:59 2024-05-22T21:43:09.295Z,1716414189.295 [NAL9602](INFO): Powering up NAL9602 2024-05-22T21:43:11.542Z,1716414191.542 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:43:20.203Z,1716414200.203 [NAL9602](INFO): NAL9602 initialized 2024-05-22T21:43:42.666Z,1716414222.666 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:43:44.044Z,1716414224.044 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-05-22T21:43:49.307Z,1716414229.307 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-05-22T21:44:00.623Z,1716414240.623 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-05-22T21:44:06.263Z,1716414246.263 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-05-22T21:44:13.810Z,1716414253.810 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:44:15.229Z,1716414255.229 [SBIT](IMPORTANT): Beginning Startup BIT 2024-05-22T21:44:15.253Z,1716414255.253 [CBIT](IMPORTANT): Beginning ground fault scan 2024-05-22T21:44:21.611Z,1716414261.611 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:44:26.138Z,1716414266.138 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002953 CHAN A1 (24V): 0.011890 CHAN A2 (12V): -0.002277 CHAN A3 (5V): -0.001269 CHAN B0 (3.3V): 0.001399 CHAN B1 (3.15aV): 0.001466 CHAN B2 (3.15bV): 0.001591 CHAN B3 (GND): 0.001685 OPEN: 0.005571 Full Scale: +/- 1 mA 2024-05-22T21:44:36.963Z,1716414276.963 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:44:44.946Z,1716414284.946 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:44:52.719Z,1716414292.719 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:45:08.884Z,1716414308.884 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:45:08.925Z,1716414308.925 [SBIT](IMPORTANT): SBIT PASSED 2024-05-22T21:45:08.925Z,1716414308.925 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-05-22T21:45:08.926Z,1716414308.926 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2024-05-22T21:45:08.926Z,1716414308.926 [SBIT](IMPORTANT): Docked.detachTimeout=8 hour; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): DockingStepper.closedValue=16500 count; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=1 bool; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): DockingStepper.openValue=7500 count; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): DockingStepper.simulateHardware=0 bool; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): DockingStepper.slideValue=12000 count; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2024-05-22T21:45:08.927Z,1716414308.927 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=1 ampere_hour; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=486.306689 cubic_centimeter; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): VerticalControl.kpDepth=0.09 radian_per_meter; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): VerticalControl.kpPitchElevator=0.5 none; 2024-05-22T21:45:08.928Z,1716414308.928 [SBIT](IMPORTANT): VerticalControl.massDefault=18.506968 millimeter; 2024-05-22T21:45:08.929Z,1716414308.929 [SBIT](IMPORTANT): VerticalControl.stopDepthExcursion=20 meter; 2024-05-22T21:45:09.389Z,1716414309.389 [MissionManager](IMPORTANT): Started mission DefaultTankUndock 2024-05-22T21:45:09.389Z,1716414309.389 [DefaultTankUndock] Running Loop=1 2024-05-22T21:45:09.389Z,1716414309.389 [DefaultTankUndock](DEBUG): Aggregate::initialize DefaultTankUndock 2024-05-22T21:45:09.389Z,1716414309.389 [DefaultTankUndock:B.GoToSurface] Running Loop=1 2024-05-22T21:45:09.389Z,1716414309.389 [DefaultTankUndock:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-22T21:45:09.390Z,1716414309.390 [DefaultTankUndock:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-22T21:45:09.390Z,1716414309.390 [DefaultTankUndock:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-22T21:45:09.391Z,1716414309.391 [DefaultTankUndock:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-22T21:45:09.391Z,1716414309.391 [DefaultTankUndock:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-22T21:45:09.391Z,1716414309.391 [DefaultTankUndock:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-22T21:45:09.392Z,1716414309.392 [DefaultTankUndock:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-22T21:45:09.392Z,1716414309.392 [DefaultTankUndock:A.Wait] Running Loop=1 2024-05-22T21:45:09.392Z,1716414309.392 [DefaultTankUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-05-22T21:45:16.074Z,1716414316.074 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:45:22.632Z,1716414322.632 [DefaultTankUndock:A.Wait](INFO): Done Waiting. 2024-05-22T21:45:22.632Z,1716414322.632 [DefaultTankUndock:A.Wait] Stopped 2024-05-22T21:45:22.632Z,1716414322.632 [DefaultTankUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T21:45:23.121Z,1716414323.121 [DefaultTankUndock:C.Execute] Running Loop=1 2024-05-22T21:45:23.448Z,1716414323.448 [DefaultTankUndock:C.Execute](INFO): Executing command dock arm 2024-05-22T21:45:23.448Z,1716414323.448 [DefaultTankUndock:C.Execute] Stopped 2024-05-22T21:45:23.448Z,1716414323.448 [DefaultTankUndock:CheckIn] Running Loop=1 2024-05-22T21:45:23.448Z,1716414323.448 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::initialize DefaultTankUndock:CheckIn 2024-05-22T21:45:23.448Z,1716414323.448 [DefaultTankUndock:CheckIn:Read_GPS] Running Loop=1 2024-05-22T21:45:23.479Z,1716414323.479 [CommandExec](IMPORTANT): got command docking arm 2024-05-22T21:45:23.479Z,1716414323.479 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-22T21:45:23.883Z,1716414323.883 [DefaultTankUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-05-22T21:45:24.261Z,1716414324.261 [DockingStepper](INFO): Resume 2024-05-22T21:45:24.656Z,1716414324.656 [DockingStepper](INFO): Resuming 2024-05-22T21:45:25.084Z,1716414325.084 [DockingStepper](INFO): Start 2024-05-22T21:45:25.085Z,1716414325.085 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:45:25.085Z,1716414325.085 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:45:25.479Z,1716414325.479 [DockingStepper](INFO): Starting 2024-05-22T21:45:25.895Z,1716414325.895 [DockingStepper](INFO): Starting 2024-05-22T21:45:26.276Z,1716414326.276 [DockingStepper](INFO): Starting 2024-05-22T21:45:26.330Z,1716414326.330 [DockingStepper](INFO): Init string: /1z14815aE13561N3m50 2024-05-22T21:45:26.681Z,1716414326.681 [DockingStepper](INFO): Starting 2024-05-22T21:45:27.100Z,1716414327.100 [DockingStepper](INFO): Starting 2024-05-22T21:45:27.484Z,1716414327.484 [DockingStepper](INFO): Starting 2024-05-22T21:45:27.901Z,1716414327.901 [DockingStepper](INFO): Starting 2024-05-22T21:45:27.942Z,1716414327.942 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:45:28.293Z,1716414328.293 [DockingStepper](INFO): Starting 2024-05-22T21:45:28.701Z,1716414328.701 [DockingStepper](INFO): Changing to mode: 2 2024-05-22T21:45:28.701Z,1716414328.701 [DockingStepper](INFO): Armed mode. 2024-05-22T21:45:28.759Z,1716414328.759 [DockingStepper](INFO): Commanding negative:45025 2024-05-22T21:45:40.406Z,1716414340.406 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-05-22T21:45:40.406Z,1716414340.406 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-05-22T21:45:40.562Z,1716414340.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-05-22T21:45:40.975Z,1716414340.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-05-22T21:45:40.975Z,1716414340.975 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-05-22T21:45:47.199Z,1716414347.199 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:46:18.326Z,1716414378.326 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:46:29.280Z,1716414389.280 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004154 2024-05-22T21:46:30.278Z,1716414390.278 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:46:33.819Z,1716414393.819 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.00, +0.00, , +8,A 2024-05-22T21:46:38.139Z,1716414398.139 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T214637 2024-05-22T21:46:46.031Z,1716414406.031 [DataOverHttps](INFO): Received command: strobe off 2024-05-22T21:46:46.123Z,1716414406.123 [CommandExec](IMPORTANT): got command strobe off 2024-05-22T21:46:46.123Z,1716414406.123 [CommandExec](IMPORTANT): Deactivating strobe 2024-05-22T21:46:48.034Z,1716414408.034 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:46:56.923Z,1716414416.923 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:47:05.762Z,1716414425.762 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:47:14.714Z,1716414434.714 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:47:23.570Z,1716414443.570 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:47:32.378Z,1716414452.378 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:47:36.376Z,1716414456.376 [NAL9602](INFO): SBD MO Status=0, MOMSN=37799, MT Status=0, MTMSN=0 2024-05-22T21:47:36.376Z,1716414456.376 [NAL9602](INFO): No messages in MT queue 2024-05-22T21:47:37.179Z,1716414457.179 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:47:37.601Z,1716414457.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214735.00,A,3648.16457,N,12147.28353,W,0.253,146.90,220524,,,A*71 2024-05-22T21:47:37.616Z,1716414457.616 [NAL9602](INFO): GPS fix at 20240522T214735: (36.802743, -121.788059) 2024-05-22T21:47:37.639Z,1716414457.639 [DefaultTankUndock:CheckIn:Read_GPS] Stopped 2024-05-22T21:47:37.639Z,1716414457.639 [DefaultTankUndock:CheckIn:Read_Iridium] Running Loop=1 2024-05-22T21:47:38.072Z,1716414458.072 [DefaultTankUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-05-22T21:47:41.022Z,1716414461.022 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20240522T213122/Courier0003.lzma 2024-05-22T21:47:42.028Z,1716414462.028 [DataOverHttps](INFO): Moved sent file to Logs/20240522T213122/Courier0003.lzma.bak 2024-05-22T21:47:42.028Z,1716414462.028 [DataOverHttps](INFO): SBD MOMSN=21071057 2024-05-22T21:47:49.776Z,1716414469.776 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:47:57.172Z,1716414477.172 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:47:57.173Z,1716414477.173 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:47:57.173Z,1716414477.173 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:47:57.174Z,1716414477.174 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:47:57.179Z,1716414477.179 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:47:57.495Z,1716414477.495 [DockingStepper](INFO): Stop 2024-05-22T21:47:57.495Z,1716414477.495 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:47:57.495Z,1716414477.495 [DockingStepper](INFO): Powering down 2024-05-22T21:47:57.911Z,1716414477.911 [DockingStepper](INFO): Stopping 2024-05-22T21:47:58.336Z,1716414478.336 [DockingStepper](INFO): Stopped 2024-05-22T21:47:58.356Z,1716414478.356 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:47:58.356Z,1716414478.356 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:47:58.687Z,1716414478.687 [DockingStepper](INFO): Start 2024-05-22T21:47:58.688Z,1716414478.688 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:47:58.688Z,1716414478.688 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:47:59.154Z,1716414479.154 [DockingStepper](INFO): Starting 2024-05-22T21:47:59.558Z,1716414479.558 [DockingStepper](INFO): Starting 2024-05-22T21:47:59.964Z,1716414479.964 [DockingStepper](INFO): Starting 2024-05-22T21:48:00.028Z,1716414480.028 [DockingStepper](INFO): Init string: /1z7551aE13561N3m50 2024-05-22T21:48:00.372Z,1716414480.372 [DockingStepper](INFO): Starting 2024-05-22T21:48:00.791Z,1716414480.791 [DockingStepper](INFO): Starting 2024-05-22T21:48:01.177Z,1716414481.177 [DockingStepper](INFO): Starting 2024-05-22T21:48:01.587Z,1716414481.587 [DockingStepper](INFO): Starting 2024-05-22T21:48:01.628Z,1716414481.628 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:48:01.987Z,1716414481.987 [DockingStepper](INFO): Starting 2024-05-22T21:48:03.120Z,1716414483.120 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:48:05.481Z,1716414485.481 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240522T213122/Courier0006.lzma 2024-05-22T21:48:06.484Z,1716414486.484 [DataOverHttps](INFO): Moved sent file to Logs/20240522T213122/Courier0006.lzma.bak 2024-05-22T21:48:06.484Z,1716414486.484 [DataOverHttps](IMPORTANT): SBD MOMSN=21071067, MTMSN=20240522T214805 2024-05-22T21:48:09.577Z,1716414489.577 [NAL9602](INFO): Not Powering down - fast GPS 2024-05-22T21:48:10.813Z,1716414490.813 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:48:10.838Z,1716414490.838 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:48:10.838Z,1716414490.838 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:48:10.838Z,1716414490.838 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:48:10.848Z,1716414490.848 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:48:11.359Z,1716414491.359 [DockingStepper](INFO): Stop 2024-05-22T21:48:11.360Z,1716414491.360 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:48:11.360Z,1716414491.360 [DockingStepper](INFO): Powering down 2024-05-22T21:48:11.663Z,1716414491.663 [DockingStepper](INFO): Stopping 2024-05-22T21:48:12.037Z,1716414492.037 [DockingStepper](INFO): Stopped 2024-05-22T21:48:12.053Z,1716414492.053 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:48:12.053Z,1716414492.053 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:48:12.466Z,1716414492.466 [DockingStepper](INFO): Start 2024-05-22T21:48:12.466Z,1716414492.466 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:48:12.467Z,1716414492.467 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:48:12.880Z,1716414492.880 [DockingStepper](INFO): Starting 2024-05-22T21:48:13.366Z,1716414493.366 [DockingStepper](INFO): Starting 2024-05-22T21:48:13.673Z,1716414493.673 [DockingStepper](INFO): Starting 2024-05-22T21:48:13.722Z,1716414493.722 [DockingStepper](INFO): Init string: /1z7519aE13561N3m50 2024-05-22T21:48:14.094Z,1716414494.094 [DockingStepper](INFO): Starting 2024-05-22T21:48:14.520Z,1716414494.520 [DockingStepper](INFO): Starting 2024-05-22T21:48:14.892Z,1716414494.892 [DockingStepper](INFO): Starting 2024-05-22T21:48:15.331Z,1716414495.331 [DockingStepper](INFO): Starting 2024-05-22T21:48:15.358Z,1716414495.358 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:48:15.759Z,1716414495.759 [DockingStepper](INFO): Starting 2024-05-22T21:48:15.973Z,1716414495.973 [DataOverHttps](INFO): Received command: dock slide 2024-05-22T21:48:16.007Z,1716414496.007 [CommandExec](IMPORTANT): got command docking slide 2024-05-22T21:48:16.007Z,1716414496.007 [CommandExec](IMPORTANT): Switching docking module to SLIDE. 2024-05-22T21:48:16.037Z,1716414496.037 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:48:16.122Z,1716414496.122 [DockingStepper](INFO): Changing to mode: 4 2024-05-22T21:48:16.122Z,1716414496.122 [DockingStepper](INFO): Slide mode. 2024-05-22T21:48:16.170Z,1716414496.170 [DockingStepper](INFO): Commanding positive:27576 2024-05-22T21:48:28.237Z,1716414508.237 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:48:31.011Z,1716414511.011 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20240522T213122/Courier0009.lzma 2024-05-22T21:48:32.012Z,1716414512.012 [DataOverHttps](INFO): Moved sent file to Logs/20240522T213122/Courier0009.lzma.bak 2024-05-22T21:48:32.012Z,1716414512.012 [DataOverHttps](INFO): SBD MOMSN=21071071 2024-05-22T21:48:41.184Z,1716414521.184 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:48:43.155Z,1716414523.155 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T214842 2024-05-22T21:48:51.111Z,1716414531.111 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240522T214223/Courier0000.lzma 2024-05-22T21:48:51.115Z,1716414531.115 [DataOverHttps](INFO): Received command: dock standby 2024-05-22T21:48:51.203Z,1716414531.203 [CommandExec](IMPORTANT): got command docking standby 2024-05-22T21:48:51.203Z,1716414531.203 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-22T21:48:51.329Z,1716414531.329 [DockingStepper](INFO): Changing to mode: 1 2024-05-22T21:48:51.330Z,1716414531.330 [DockingStepper](INFO): Standby mode. 2024-05-22T21:48:51.387Z,1716414531.387 [DockingStepper](INFO): Commanding positive:27909 2024-05-22T21:48:52.388Z,1716414532.388 [DataOverHttps](INFO): Moved sent file to Logs/20240522T214223/Courier0000.lzma.bak 2024-05-22T21:48:52.389Z,1716414532.389 [DataOverHttps](IMPORTANT): SBD MOMSN=21071076, MTMSN=20240522T214851 2024-05-22T21:48:54.338Z,1716414534.338 [DockingStepper](INFO): Commanding positive:5541 2024-05-22T21:48:54.516Z,1716414534.516 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:48:55.038Z,1716414535.038 [DockingStepper](INFO): Commanding positive:4857 2024-05-22T21:48:55.835Z,1716414535.835 [DockingStepper](INFO): Commanding positive:3971 2024-05-22T21:48:56.614Z,1716414536.614 [DockingStepper](INFO): Commanding positive:3183 2024-05-22T21:49:03.902Z,1716414543.902 [DataOverHttps](INFO): Received command: schedule resume 2024-05-22T21:49:03.932Z,1716414543.932 [CommandExec](IMPORTANT): got command schedule resume 2024-05-22T21:49:05.221Z,1716414545.221 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T214903 2024-05-22T21:49:07.848Z,1716414547.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:49:12.921Z,1716414552.921 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T214223/Courier0006.lzma 2024-05-22T21:49:12.924Z,1716414552.924 [DataOverHttps](INFO): Received command: load Maintenance/tank_ballast_and_trim.tl;set ballast_and_trim.MissionTimeout 8 min;set ballast_and_trim.Depth1 1.25 m;set ballast_and_trim.DepthDeadband 0.25 m;set ballast_and_trim.SettleTime 20 min;run 2024-05-22T21:49:13.028Z,1716414553.028 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.tl 2024-05-22T21:49:13.028Z,1716414553.028 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl 2024-05-22T21:49:13.028Z,1716414553.028 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Maintenance/tank_ballast_and_trim.tl 2024-05-22T21:49:13.031Z,1716414553.031 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Maintenance/tank_ballast_and_trim.tl 2024-05-22T21:49:14.479Z,1716414554.479 [DataOverHttps](INFO): Moved sent file to Logs/20240522T214223/Courier0006.lzma.bak 2024-05-22T21:49:14.479Z,1716414554.479 [DataOverHttps](IMPORTANT): SBD MOMSN=21071084, MTMSN=20240522T214912 2024-05-22T21:49:16.940Z,1716414556.940 [MissionManager](DEBUG): TethyslAPI: ./Missions/Maintenance/tank_ballast_and_trim.tl translated into: 120 7 NaN 0.1 2.5 20 10 45 10 0.25 15 99.9 5 2024-05-22T21:49:16.976Z,1716414556.976 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min 2024-05-22T21:49:16.983Z,1716414556.983 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m 2024-05-22T21:49:16.990Z,1716414556.990 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2024-05-22T21:49:16.993Z,1716414556.993 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m 2024-05-22T21:49:17.000Z,1716414557.000 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m 2024-05-22T21:49:17.007Z,1716414557.007 [MissionManager](INFO): DefineArg ballast_and_trim.TargetDepthTimeout = 20.000000 min 2024-05-22T21:49:17.048Z,1716414557.048 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2024-05-22T21:49:17.055Z,1716414557.055 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2024-05-22T21:49:17.067Z,1716414557.067 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2024-05-22T21:49:17.103Z,1716414557.103 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm 2024-05-22T21:49:17.152Z,1716414557.152 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc 2024-05-22T21:49:17.167Z,1716414557.167 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 % 2024-05-22T21:49:17.182Z,1716414557.182 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min 2024-05-22T21:49:17.183Z,1716414557.183 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2024-05-22T21:49:17.183Z,1716414557.183 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl 2024-05-22T21:49:17.185Z,1716414557.185 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl 2024-05-22T21:49:19.527Z,1716414559.527 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into: NaN 3 100 80 20 5 NaN NaN 2.0 NaN NaN 5 NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN Triggered. PeakChl values on consecutive yo-yo profiles fall in [ ] (NaN bound is ignored). 2024-05-22T21:49:19.548Z,1716414559.548 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2024-05-22T21:49:19.551Z,1716414559.551 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2024-05-22T21:49:19.554Z,1716414559.554 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2024-05-22T21:49:19.556Z,1716414559.556 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool 2024-05-22T21:49:19.559Z,1716414559.559 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool 2024-05-22T21:49:19.562Z,1716414559.562 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2024-05-22T21:49:19.565Z,1716414559.565 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2024-05-22T21:49:19.568Z,1716414559.568 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2024-05-22T21:49:19.571Z,1716414559.571 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2024-05-22T21:49:19.573Z,1716414559.573 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2024-05-22T21:49:19.576Z,1716414559.576 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2024-05-22T21:49:19.583Z,1716414559.583 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2024-05-22T21:49:19.654Z,1716414559.654 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool 2024-05-22T21:49:19.657Z,1716414559.657 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2024-05-22T21:49:19.665Z,1716414559.665 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool 2024-05-22T21:49:19.698Z,1716414559.698 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool 2024-05-22T21:49:19.714Z,1716414559.714 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool 2024-05-22T21:49:19.717Z,1716414559.717 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool 2024-05-22T21:49:19.725Z,1716414559.725 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2024-05-22T21:49:19.737Z,1716414559.737 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 1 bool 2024-05-22T21:49:19.765Z,1716414559.765 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2024-05-22T21:49:19.772Z,1716414559.772 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count 2024-05-22T21:49:19.783Z,1716414559.783 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m 2024-05-22T21:49:19.806Z,1716414559.806 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m 2024-05-22T21:49:19.809Z,1716414559.809 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2024-05-22T21:49:19.816Z,1716414559.816 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool 2024-05-22T21:49:19.840Z,1716414559.840 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l 2024-05-22T21:49:19.851Z,1716414559.851 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l 2024-05-22T21:49:19.879Z,1716414559.879 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count 2024-05-22T21:49:19.894Z,1716414559.894 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l 2024-05-22T21:49:19.897Z,1716414559.897 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m 2024-05-22T21:49:19.913Z,1716414559.913 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC 2024-05-22T21:49:19.938Z,1716414559.938 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2024-05-22T21:49:19.958Z,1716414559.958 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2024-05-22T21:49:19.961Z,1716414559.961 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l 2024-05-22T21:49:19.969Z,1716414559.969 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m 2024-05-22T21:49:19.985Z,1716414559.985 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2024-05-22T21:49:20.004Z,1716414560.004 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2024-05-22T21:49:20.012Z,1716414560.012 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3 2024-05-22T21:49:20.035Z,1716414560.035 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m 2024-05-22T21:49:20.043Z,1716414560.043 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2024-05-22T21:49:20.067Z,1716414560.067 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2024-05-22T21:49:20.071Z,1716414560.071 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb 2024-05-22T21:49:20.098Z,1716414560.098 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m 2024-05-22T21:49:20.101Z,1716414560.101 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC 2024-05-22T21:49:20.109Z,1716414560.109 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2024-05-22T21:49:20.112Z,1716414560.112 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:49:20.132Z,1716414560.132 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2024-05-22T21:49:20.140Z,1716414560.140 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu 2024-05-22T21:49:20.166Z,1716414560.166 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m 2024-05-22T21:49:20.169Z,1716414560.169 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC 2024-05-22T21:49:20.185Z,1716414560.185 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg 2024-05-22T21:49:20.216Z,1716414560.216 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg 2024-05-22T21:49:20.245Z,1716414560.245 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l 2024-05-22T21:49:20.273Z,1716414560.273 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m 2024-05-22T21:49:20.284Z,1716414560.284 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2024-05-22T21:49:20.320Z,1716414560.320 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2024-05-22T21:49:20.328Z,1716414560.328 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2024-05-22T21:49:20.352Z,1716414560.352 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu 2024-05-22T21:49:20.394Z,1716414560.394 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m 2024-05-22T21:49:20.397Z,1716414560.397 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg 2024-05-22T21:49:20.408Z,1716414560.408 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg 2024-05-22T21:49:20.416Z,1716414560.416 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m 2024-05-22T21:49:20.447Z,1716414560.447 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu 2024-05-22T21:49:20.454Z,1716414560.454 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m 2024-05-22T21:49:20.457Z,1716414560.457 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg 2024-05-22T21:49:20.473Z,1716414560.473 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg 2024-05-22T21:49:20.523Z,1716414560.523 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m 2024-05-22T21:49:20.538Z,1716414560.538 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool 2024-05-22T21:49:20.541Z,1716414560.541 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l 2024-05-22T21:49:20.693Z,1716414560.693 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T21:49:20.741Z,1716414560.741 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-22T21:49:20.835Z,1716414560.835 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T21:49:20.869Z,1716414560.869 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T21:49:20.936Z,1716414560.936 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-22T21:49:20.993Z,1716414560.993 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T21:49:21.048Z,1716414561.048 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2024-05-22T21:49:21.130Z,1716414561.130 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2024-05-22T21:49:21.189Z,1716414561.189 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2024-05-22T21:49:21.221Z,1716414561.221 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-05-22T21:49:21.221Z,1716414561.221 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-05-22T21:49:21.227Z,1716414561.227 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-05-22T21:49:21.372Z,1716414561.372 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-05-22T21:49:21.392Z,1716414561.392 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool 2024-05-22T21:49:21.407Z,1716414561.407 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-05-22T21:49:21.409Z,1716414561.409 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-05-22T21:49:21.414Z,1716414561.414 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-05-22T21:49:21.416Z,1716414561.416 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-05-22T21:49:21.907Z,1716414561.907 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-05-22T21:49:21.936Z,1716414561.936 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleAll = 0 bool 2024-05-22T21:49:21.947Z,1716414561.947 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad1 = 0 bool 2024-05-22T21:49:21.960Z,1716414561.960 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad2 = 0 bool 2024-05-22T21:49:21.967Z,1716414561.967 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.SampleLoad3 = 0 bool 2024-05-22T21:49:21.988Z,1716414561.988 [MissionManager](INFO): DefineArg ballast_and_trim:PowerOnly.EnabledPowerOnly = 0 bool 2024-05-22T21:49:22.024Z,1716414562.024 [ballast_and_trim:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-05-22T21:49:22.025Z,1716414562.025 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2024-05-22T21:49:22.034Z,1716414562.034 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BallastAndTrim.tl 2024-05-22T21:49:22.036Z,1716414562.036 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BallastAndTrim.tl 2024-05-22T21:49:23.844Z,1716414563.844 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BallastAndTrim.tl translated into: 25 0 0.4 20 0 20 10 45 10 0.25 15 99.9 1 0.25 0.005 0.00075 0 Going to target depth. Speed set to 0 13 0 Waiting for while pumping down to neutral. Moving to Timed out trying to reach the target depth. Stopping mission at current depth of stop 0 Waiting for the vehicle to settle. Depth = gfscan Running ballast and trim. Depth = 2024-05-22T21:49:23.942Z,1716414563.942 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m 2024-05-22T21:49:23.945Z,1716414563.945 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2024-05-22T21:49:23.968Z,1716414563.968 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2024-05-22T21:49:23.980Z,1716414563.980 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2024-05-22T21:49:24.011Z,1716414564.011 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2024-05-22T21:49:24.026Z,1716414564.026 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2024-05-22T21:49:24.029Z,1716414564.029 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min 2024-05-22T21:49:24.041Z,1716414564.041 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min 2024-05-22T21:49:24.048Z,1716414564.048 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min 2024-05-22T21:49:24.072Z,1716414564.072 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2024-05-22T21:49:24.079Z,1716414564.079 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2024-05-22T21:49:24.096Z,1716414564.096 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 % 2024-05-22T21:49:24.108Z,1716414564.108 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2024-05-22T21:49:24.136Z,1716414564.136 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2024-05-22T21:49:24.201Z,1716414564.201 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2024-05-22T21:49:24.221Z,1716414564.221 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2024-05-22T21:49:24.247Z,1716414564.247 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2024-05-22T21:49:24.324Z,1716414564.324 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2024-05-22T21:49:24.352Z,1716414564.352 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2024-05-22T21:49:24.363Z,1716414564.363 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2024-05-22T21:49:24.403Z,1716414564.403 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2024-05-22T21:49:24.411Z,1716414564.411 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2024-05-22T21:49:24.422Z,1716414564.422 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2024-05-22T21:49:24.433Z,1716414564.433 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2024-05-22T21:49:24.447Z,1716414564.447 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute. 2024-05-22T21:49:24.474Z,1716414564.474 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2024-05-22T21:49:24.519Z,1716414564.519 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband 2024-05-22T21:49:24.521Z,1716414564.521 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout 2024-05-22T21:49:24.547Z,1716414564.547 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2024-05-22T21:49:24.549Z,1716414564.549 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2024-05-22T21:49:24.570Z,1716414564.570 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2024-05-22T21:49:24.572Z,1716414564.572 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2024-05-22T21:49:24.582Z,1716414564.582 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2024-05-22T21:49:24.584Z,1716414564.584 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2024-05-22T21:49:24.628Z,1716414564.628 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2024-05-22T21:49:24.648Z,1716414564.648 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait. 2024-05-22T21:49:24.791Z,1716414564.791 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission ballast_and_trim { arguments { MissionTimeout = 120 minute """ Maximum duration of mission. """ Depth1 = 7 meter """ Target depth for ballast and trim. """ Depth2 = NaN meter """ Target depth to run a second ballast and trim, skipped if NaN. """ DepthDeadband = 0.1 meter """ How much vertical drift from the specified depth is allowed during the mission. """ SurfaceThreshold = 2.5 meter """ Surface threshold depth, relevant only to the ascent. """ TargetDepthTimeout = 20 minute """ Maximum wait time for the vehicle to reach the target ballast and trim 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 for reaching surface threshold on float ascent, mission will end when reached. """ } timeout duration=MissionTimeout insert Insert/Science.tl insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl aggregate RunBallastAndTrim { run in sequence insert Insert/BallastAndTrim.tl { redefineArg InsertDepthDeadband = DepthDeadband redefineArg InsertApproachDepthTimeout = TargetDepthTimeout 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 = Depth2 call refId="BallastAndTrim" } } aggregate Float_Up { """ Float to surface instead of driving. """ run in sequence break if ( Universal:depth < SurfaceThreshold ) behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyDefault } behavior Guidance:Wait { run in sequence set duration = WaitToFloat } } } 2024-05-22T21:49:24.792Z,1716414564.792 [CommandExec](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.tl id=ballast_and_trim 2024-05-22T21:49:31.979Z,1716414571.979 [DataOverHttps](INFO): Received command: sched "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout 8 h;set OnDock.RangeTimeout 8 h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m;set OnDock.MinOffshore 0.000001 km" 3e1xu 1 2 2024-05-22T21:49:33.104Z,1716414573.104 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:49:33.487Z,1716414573.487 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T214932 2024-05-22T21:49:42.563Z,1716414582.563 [CommandExec](IMPORTANT): got command set ballast_and_trim.MissionTimeout 8 minute 2024-05-22T21:49:42.564Z,1716414582.564 [CommandExec](IMPORTANT): got command set ballast_and_trim.Depth1 1.25 meter 2024-05-22T21:49:42.565Z,1716414582.565 [CommandExec](IMPORTANT): got command set ballast_and_trim.DepthDeadband 0.25 meter 2024-05-22T21:49:42.570Z,1716414582.570 [CommandExec](IMPORTANT): got command set ballast_and_trim.SettleTime 20 minute 2024-05-22T21:49:42.571Z,1716414582.571 [CommandExec](IMPORTANT): got command run 2024-05-22T21:49:42.578Z,1716414582.578 [CommandExec](IMPORTANT): Running 2024-05-22T21:49:42.660Z,1716414582.660 [CommandExec](IMPORTANT): got command schedule "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout 8 h;set OnDock.RangeTimeout 8 h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m;set OnDock.MinOffshore 0.000001 km" 3e1xu 1 2.000000 2024-05-22T21:49:42.660Z,1716414582.660 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3e1xu 2024-05-22T21:49:42.661Z,1716414582.661 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='3e1xu'): "load Engineering/OnDock.tl;set OnDock.DockedTime 45 min;set OnDock.DetachedTimeout 8 h;set OnDock.RangeTimeout 8 h;set OnDock.MaxDepth 5 m;set OnDock.MinAltitude 0.00001 m;set OnDock.MinOffshore 0.000001 km", AFTER MISSION 2024-05-22T21:49:42.842Z,1716414582.842 [DefaultTankUndock] Stopped 2024-05-22T21:49:42.842Z,1716414582.842 [DefaultTankUndock](DEBUG): Aggregate::uninitialize DefaultTankUndock 2024-05-22T21:49:42.843Z,1716414582.843 [DefaultTankUndock:B.GoToSurface] Stopped 2024-05-22T21:49:42.843Z,1716414582.843 [DefaultTankUndock:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-22T21:49:42.843Z,1716414582.843 [DefaultTankUndock:CheckIn] Stopped 2024-05-22T21:49:42.843Z,1716414582.843 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultTankUndock:CheckIn 2024-05-22T21:49:42.843Z,1716414582.843 [DefaultTankUndock:CheckIn:Read_Iridium] Stopped 2024-05-22T21:49:42.843Z,1716414582.843 [MissionManager](IMPORTANT): Started mission ballast_and_trim 2024-05-22T21:49:42.843Z,1716414582.843 [ballast_and_trim] Running Loop=1 2024-05-22T21:49:42.843Z,1716414582.843 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim 2024-05-22T21:49:42.843Z,1716414582.843 [ballast_and_trim:Science] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:A] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:B] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:C] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:D] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:E] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:F] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T21:49:42.844Z,1716414582.844 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T21:49:42.851Z,1716414582.851 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1 2024-05-22T21:49:42.851Z,1716414582.851 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport 2024-05-22T21:49:42.851Z,1716414582.851 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-22T21:49:42.851Z,1716414582.851 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-22T21:49:42.851Z,1716414582.851 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1 2024-05-22T21:49:42.852Z,1716414582.852 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3 2024-05-22T21:49:42.852Z,1716414582.852 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T21:49:42.852Z,1716414582.852 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T21:49:42.878Z,1716414582.878 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1 2024-05-22T21:49:42.878Z,1716414582.878 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil 2024-05-22T21:49:42.878Z,1716414582.878 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T21:49:42.878Z,1716414582.878 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T21:49:42.880Z,1716414582.880 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1 2024-05-22T21:49:42.880Z,1716414582.880 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport 2024-05-22T21:49:42.880Z,1716414582.880 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-22T21:49:42.880Z,1716414582.880 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-22T21:49:42.881Z,1716414582.881 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1 2024-05-22T21:49:42.881Z,1716414582.881 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM 2024-05-22T21:49:42.881Z,1716414582.881 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T21:49:42.881Z,1716414582.881 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T21:49:42.888Z,1716414582.888 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1 2024-05-22T21:49:42.888Z,1716414582.888 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity 2024-05-22T21:49:42.889Z,1716414582.889 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2024-05-22T21:49:42.889Z,1716414582.889 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2024-05-22T21:49:42.906Z,1716414582.906 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1 2024-05-22T21:49:42.906Z,1716414582.906 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport 2024-05-22T21:49:42.907Z,1716414582.907 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2024-05-22T21:49:42.907Z,1716414582.907 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2024-05-22T21:49:42.907Z,1716414582.907 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1 2024-05-22T21:49:42.907Z,1716414582.907 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger 2024-05-22T21:49:42.907Z,1716414582.907 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2024-05-22T21:49:42.907Z,1716414582.907 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2024-05-22T21:49:42.908Z,1716414582.908 [ballast_and_trim:Science:OceanCurrent] Running Loop=1 2024-05-22T21:49:42.908Z,1716414582.908 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent 2024-05-22T21:49:42.908Z,1716414582.908 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2024-05-22T21:49:42.908Z,1716414582.908 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2024-05-22T21:49:42.908Z,1716414582.908 [ballast_and_trim:BackseatDriver] Running Loop=1 2024-05-22T21:49:42.908Z,1716414582.908 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver 2024-05-22T21:49:42.908Z,1716414582.908 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:PowerOnly] Running Loop=1 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:PowerOnly](DEBUG): Aggregate::initialize ballast_and_trim:PowerOnly 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:PowerOnly:A] Running Loop=1 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:PowerOnly:B] Running Loop=1 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:PowerOnly:C] Running Loop=1 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:PowerOnly:D] Running Loop=1 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:PowerOnly:E.Wait] Running Loop=1 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:RunBallastAndTrim] Running Loop=1 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim 2024-05-22T21:49:42.909Z,1716414582.909 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1 2024-05-22T21:49:42.914Z,1716414582.914 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1 2024-05-22T21:49:42.914Z,1716414582.914 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:PowerOnly] Running Loop=1 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:PowerOnly:D] Stopped 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:PowerOnly:C] Stopped 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:PowerOnly:B] Stopped 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:PowerOnly:A] Stopped 2024-05-22T21:49:42.915Z,1716414582.915 [ballast_and_trim:BackseatDriver] Running Loop=1 2024-05-22T21:49:42.916Z,1716414582.916 [ballast_and_trim:Science] Running Loop=1 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:ChlThreshTrigger] Stopped 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:PeakDetectSalinity] Stopped 2024-05-22T21:49:42.917Z,1716414582.917 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:PeakDetectFDOM] Stopped 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:HighestOilPeakReport] Stopped 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport 2024-05-22T21:49:42.926Z,1716414582.926 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:PeakDetectOil] Stopped 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:PeakDetectNO3] Stopped 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:HighestChlPeakReport] Stopped 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2024-05-22T21:49:42.927Z,1716414582.927 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2024-05-22T21:49:42.928Z,1716414582.928 [ballast_and_trim:Science:PeakDetectChl] Stopped 2024-05-22T21:49:42.928Z,1716414582.928 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl 2024-05-22T21:49:42.928Z,1716414582.928 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2024-05-22T21:49:42.928Z,1716414582.928 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2024-05-22T21:49:42.928Z,1716414582.928 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2024-05-22T21:49:42.928Z,1716414582.928 [ballast_and_trim:Science:Read_Oil] Stopped 2024-05-22T21:49:42.928Z,1716414582.928 [ballast_and_trim:Science:F] Running Loop=1 2024-05-22T21:49:42.929Z,1716414582.929 [ballast_and_trim:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence 2024-05-22T21:49:42.929Z,1716414582.929 [ballast_and_trim:Science:E] Running Loop=1 2024-05-22T21:49:42.943Z,1716414582.943 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2024-05-22T21:49:42.943Z,1716414582.943 [ballast_and_trim:Science:D] Stopped 2024-05-22T21:49:42.943Z,1716414582.943 [ballast_and_trim:Science:C] Running Loop=1 2024-05-22T21:49:42.944Z,1716414582.944 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2024-05-22T21:49:42.944Z,1716414582.944 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2024-05-22T21:49:42.944Z,1716414582.944 [ballast_and_trim:Science:B] Running Loop=1 2024-05-22T21:49:42.945Z,1716414582.945 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2024-05-22T21:49:42.958Z,1716414582.958 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2024-05-22T21:49:42.958Z,1716414582.958 [ballast_and_trim:Science:A] Stopped 2024-05-22T21:49:43.124Z,1716414583.124 [WetLabsBB2FL](INFO): Powering up 2024-05-22T21:49:43.264Z,1716414583.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1 2024-05-22T21:49:43.264Z,1716414583.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2024-05-22T21:49:43.264Z,1716414583.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2024-05-22T21:49:43.264Z,1716414583.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2024-05-22T21:49:43.264Z,1716414583.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2024-05-22T21:49:43.264Z,1716414583.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2024-05-22T21:49:43.264Z,1716414583.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2024-05-22T21:49:43.264Z,1716414583.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2024-05-22T21:49:43.282Z,1716414583.282 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-05-22T21:49:43.283Z,1716414583.283 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2024-05-22T21:49:43.284Z,1716414583.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2024-05-22T21:49:43.284Z,1716414583.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2024-05-22T21:49:43.284Z,1716414583.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-22T21:49:43.284Z,1716414583.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2024-05-22T21:49:43.284Z,1716414583.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2024-05-22T21:49:43.284Z,1716414583.284 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2024-05-22T21:49:43.285Z,1716414583.285 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2024-05-22T21:49:43.285Z,1716414583.285 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2024-05-22T21:49:43.285Z,1716414583.285 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2024-05-22T21:49:43.574Z,1716414583.574 [WetLabsUBAT](INFO): Powering up 2024-05-22T21:49:43.756Z,1716414583.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2024-05-22T21:49:43.756Z,1716414583.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2024-05-22T21:49:43.756Z,1716414583.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2024-05-22T21:49:43.757Z,1716414583.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2024-05-22T21:49:43.757Z,1716414583.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2024-05-22T21:49:43.757Z,1716414583.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2024-05-22T21:49:43.757Z,1716414583.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2024-05-22T21:49:43.757Z,1716414583.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2024-05-22T21:49:43.757Z,1716414583.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2024-05-22T21:49:43.757Z,1716414583.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2024-05-22T21:49:43.757Z,1716414583.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2024-05-22T21:49:44.064Z,1716414584.064 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2024-05-22T21:49:44.064Z,1716414584.064 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2024-05-22T21:49:44.064Z,1716414584.064 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T21:49:44.064Z,1716414584.064 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-22T21:49:44.064Z,1716414584.064 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2024-05-22T21:49:44.065Z,1716414584.065 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2024-05-22T21:49:44.065Z,1716414584.065 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2024-05-22T21:49:44.065Z,1716414584.065 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-05-22T21:49:44.065Z,1716414584.065 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-05-22T21:49:44.065Z,1716414584.065 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2024-05-22T21:49:44.065Z,1716414584.065 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2024-05-22T21:49:44.574Z,1716414584.574 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2024-05-22T21:49:44.575Z,1716414584.575 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 1.250000 m 2024-05-22T21:49:44.575Z,1716414584.575 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2024-05-22T21:49:44.575Z,1716414584.575 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2024-05-22T21:49:44.575Z,1716414584.575 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2024-05-22T21:49:46.242Z,1716414586.242 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:49:46.329Z,1716414586.329 [CTD_Seabird](ERROR): Failed to parse device response: 2024-05-22T21:49:46.889Z,1716414586.889 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T214223/Courier0010.lzma 2024-05-22T21:49:46.892Z,1716414586.892 [DataOverHttps](INFO): Received command: sched "set OnDock.IfconfigInterval 5 min;run" 3e1xu 2 2 2024-05-22T21:49:46.958Z,1716414586.958 [CommandExec](IMPORTANT): got command schedule "set OnDock.IfconfigInterval 5 min;run" 3e1xu 2 2.000000 2024-05-22T21:49:46.959Z,1716414586.959 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3e1xu 2024-05-22T21:49:46.960Z,1716414586.960 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='3e1xu'): "set OnDock.IfconfigInterval 5 min;run", AFTER MISSION 2024-05-22T21:49:47.892Z,1716414587.892 [DataOverHttps](INFO): Moved sent file to Logs/20240522T214223/Courier0010.lzma.bak 2024-05-22T21:49:47.892Z,1716414587.892 [DataOverHttps](INFO): SBD MOMSN=21071088 2024-05-22T21:49:53.765Z,1716414593.765 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s. 2024-05-22T21:49:58.994Z,1716414598.994 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:50:13.693Z,1716414613.693 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:50:16.187Z,1716414616.187 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:50:16.188Z,1716414616.188 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:50:16.188Z,1716414616.188 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:50:16.188Z,1716414616.188 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:50:16.265Z,1716414616.265 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:50:16.575Z,1716414616.575 [DockingStepper](INFO): Stop 2024-05-22T21:50:16.575Z,1716414616.575 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:50:16.575Z,1716414616.575 [DockingStepper](INFO): Powering down 2024-05-22T21:50:16.921Z,1716414616.921 [DockingStepper](INFO): Stopping 2024-05-22T21:50:17.495Z,1716414617.495 [DockingStepper](INFO): Stopped 2024-05-22T21:50:17.499Z,1716414617.499 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:50:17.499Z,1716414617.499 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:50:18.015Z,1716414618.015 [DockingStepper](INFO): Start 2024-05-22T21:50:18.016Z,1716414618.016 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:50:18.016Z,1716414618.016 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:50:18.204Z,1716414618.204 [DockingStepper](INFO): Starting 2024-05-22T21:50:18.611Z,1716414618.611 [DockingStepper](INFO): Starting 2024-05-22T21:50:19.003Z,1716414619.003 [DockingStepper](INFO): Starting 2024-05-22T21:50:19.376Z,1716414619.376 [DockingStepper](INFO): Starting 2024-05-22T21:50:19.442Z,1716414619.442 [DockingStepper](INFO): Init string: /1z16064aE13561N3m50 2024-05-22T21:50:19.823Z,1716414619.823 [DockingStepper](INFO): Starting 2024-05-22T21:50:20.215Z,1716414620.215 [DockingStepper](INFO): Starting 2024-05-22T21:50:20.555Z,1716414620.555 [DockingStepper](INFO): Starting 2024-05-22T21:50:20.987Z,1716414620.987 [DockingStepper](INFO): Starting 2024-05-22T21:50:21.014Z,1716414621.014 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:50:21.427Z,1716414621.427 [DockingStepper](INFO): Starting 2024-05-22T21:50:26.127Z,1716414626.127 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:50:36.586Z,1716414636.586 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:50:46.699Z,1716414646.699 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:50:51.068Z,1716414651.068 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS3521454,35.0, +19.7, 0.0,1520.7, 0 2024-05-22T21:50:56.875Z,1716414656.875 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:51:06.968Z,1716414666.968 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:51:17.015Z,1716414677.015 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:51:26.290Z,1716414686.290 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-05-22T21:51:27.194Z,1716414687.194 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:51:27.614Z,1716414687.614 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:51:27.615Z,1716414687.615 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:51:27.615Z,1716414687.615 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:51:27.615Z,1716414687.615 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:51:27.621Z,1716414687.621 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:51:28.026Z,1716414688.026 [DockingStepper](INFO): Stop 2024-05-22T21:51:28.026Z,1716414688.026 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:51:28.026Z,1716414688.026 [DockingStepper](INFO): Powering down 2024-05-22T21:51:28.586Z,1716414688.586 [DockingStepper](INFO): Stopping 2024-05-22T21:51:28.824Z,1716414688.824 [DockingStepper](INFO): Stopped 2024-05-22T21:51:28.887Z,1716414688.887 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:51:28.887Z,1716414688.887 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:51:29.319Z,1716414689.319 [DockingStepper](INFO): Start 2024-05-22T21:51:29.319Z,1716414689.319 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:51:29.320Z,1716414689.320 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:51:29.668Z,1716414689.668 [DockingStepper](INFO): Starting 2024-05-22T21:51:30.305Z,1716414690.305 [DockingStepper](INFO): Starting 2024-05-22T21:51:30.559Z,1716414690.559 [DockingStepper](INFO): Starting 2024-05-22T21:51:30.622Z,1716414690.622 [DockingStepper](INFO): Init string: /1z16063aE13561N3m50 2024-05-22T21:51:30.951Z,1716414690.951 [DockingStepper](INFO): Starting 2024-05-22T21:51:31.380Z,1716414691.380 [DockingStepper](INFO): Starting 2024-05-22T21:51:31.690Z,1716414691.690 [DockingStepper](INFO): Starting 2024-05-22T21:51:32.152Z,1716414692.152 [DockingStepper](INFO): Starting 2024-05-22T21:51:32.175Z,1716414692.175 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:51:32.553Z,1716414692.553 [DockingStepper](INFO): Starting 2024-05-22T21:51:35.768Z,1716414695.768 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:51:35.768Z,1716414695.768 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:51:35.768Z,1716414695.768 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:51:35.768Z,1716414695.768 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:51:35.777Z,1716414695.777 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:51:36.207Z,1716414696.207 [DockingStepper](INFO): Stop 2024-05-22T21:51:36.207Z,1716414696.207 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:51:36.207Z,1716414696.207 [DockingStepper](INFO): Powering down 2024-05-22T21:51:36.507Z,1716414696.507 [DockingStepper](INFO): Stopping 2024-05-22T21:51:36.933Z,1716414696.933 [DockingStepper](INFO): Stopped 2024-05-22T21:51:36.966Z,1716414696.966 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:51:36.966Z,1716414696.966 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:51:37.158Z,1716414697.158 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:51:37.406Z,1716414697.406 [DockingStepper](INFO): Start 2024-05-22T21:51:37.406Z,1716414697.406 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:51:37.407Z,1716414697.407 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:51:37.716Z,1716414697.716 [DockingStepper](INFO): Starting 2024-05-22T21:51:38.255Z,1716414698.255 [DockingStepper](INFO): Starting 2024-05-22T21:51:38.534Z,1716414698.534 [DockingStepper](INFO): Starting 2024-05-22T21:51:38.586Z,1716414698.586 [DockingStepper](INFO): Init string: /1z16063aE13561N3m50 2024-05-22T21:51:38.925Z,1716414698.925 [DockingStepper](INFO): Starting 2024-05-22T21:51:39.445Z,1716414699.445 [DockingStepper](INFO): Starting 2024-05-22T21:51:39.772Z,1716414699.772 [DockingStepper](INFO): Starting 2024-05-22T21:51:40.233Z,1716414700.233 [DockingStepper](INFO): Starting 2024-05-22T21:51:40.258Z,1716414700.258 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:51:40.640Z,1716414700.640 [DockingStepper](INFO): Starting 2024-05-22T21:51:47.319Z,1716414707.319 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:51:57.130Z,1716414717.130 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:52:02.898Z,1716414722.898 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-05-22T21:52:09.998Z,1716414729.998 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:52:24.170Z,1716414744.170 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:52:26.400Z,1716414746.400 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:52:34.310Z,1716414754.310 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:52:39.013Z,1716414759.013 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:52:44.306Z,1716414764.306 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:52:51.544Z,1716414771.544 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:52:54.794Z,1716414774.794 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:53:04.465Z,1716414784.465 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:53:04.902Z,1716414784.902 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:53:14.944Z,1716414794.944 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:53:17.397Z,1716414797.397 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:53:25.120Z,1716414805.120 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:53:30.321Z,1716414810.321 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:53:35.323Z,1716414815.323 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:53:43.369Z,1716414823.369 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:53:45.782Z,1716414825.782 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:53:47.956Z,1716414827.956 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:53:47.956Z,1716414827.956 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:53:47.956Z,1716414827.956 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:53:47.956Z,1716414827.956 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:53:47.963Z,1716414827.963 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:53:48.395Z,1716414828.395 [DockingStepper](INFO): Stop 2024-05-22T21:53:48.395Z,1716414828.395 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:53:48.395Z,1716414828.395 [DockingStepper](INFO): Powering down 2024-05-22T21:53:48.781Z,1716414828.781 [DockingStepper](INFO): Stopping 2024-05-22T21:53:49.139Z,1716414829.139 [DockingStepper](INFO): Stopped 2024-05-22T21:53:49.156Z,1716414829.156 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:53:49.156Z,1716414829.156 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:53:49.519Z,1716414829.519 [DockingStepper](INFO): Start 2024-05-22T21:53:49.520Z,1716414829.520 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:53:49.521Z,1716414829.521 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:53:49.963Z,1716414829.963 [DockingStepper](INFO): Starting 2024-05-22T21:53:50.403Z,1716414830.403 [DockingStepper](INFO): Starting 2024-05-22T21:53:50.760Z,1716414830.760 [DockingStepper](INFO): Starting 2024-05-22T21:53:50.812Z,1716414830.812 [DockingStepper](INFO): Init string: /1z16064aE13561N3m50 2024-05-22T21:53:51.181Z,1716414831.181 [DockingStepper](INFO): Starting 2024-05-22T21:53:51.611Z,1716414831.611 [DockingStepper](INFO): Starting 2024-05-22T21:53:51.973Z,1716414831.973 [DockingStepper](INFO): Starting 2024-05-22T21:53:52.487Z,1716414832.487 [DockingStepper](INFO): Starting 2024-05-22T21:53:52.514Z,1716414832.514 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:53:52.790Z,1716414832.790 [DockingStepper](INFO): Starting 2024-05-22T21:53:55.948Z,1716414835.948 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:53:57.110Z,1716414837.110 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:54:06.035Z,1716414846.035 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:54:10.284Z,1716414850.284 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:54:12.026Z,1716414852.026 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-05-22T21:54:16.094Z,1716414856.094 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-05-22T21:54:16.762Z,1716414856.762 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:54:24.309Z,1716414864.309 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:54:31.528Z,1716414871.528 [DataOverHttps](IMPORTANT): SBD MTMSN=20240522T215430 2024-05-22T21:54:37.341Z,1716414877.341 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-22T21:54:41.018Z,1716414881.018 [DataOverHttps](INFO): Received command: get platform_buoyancy_position 2024-05-22T21:54:42.460Z,1716414882.460 [CommandExec](IMPORTANT): got command get platform_buoyancy_position 2024-05-22T21:54:42.461Z,1716414882.461 [CommandExec](IMPORTANT): platform_buoyancy_position 315.712036 cc 2024-05-22T21:54:43.022Z,1716414883.022 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:54:53.067Z,1716414893.067 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:55:03.015Z,1716414903.015 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:55:13.223Z,1716414913.223 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:55:22.342Z,1716414922.342 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:55:22.343Z,1716414922.343 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:55:22.343Z,1716414922.343 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:55:22.344Z,1716414922.344 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:55:22.382Z,1716414922.382 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:55:22.759Z,1716414922.759 [DockingStepper](INFO): Stop 2024-05-22T21:55:22.759Z,1716414922.759 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:55:22.759Z,1716414922.759 [DockingStepper](INFO): Powering down 2024-05-22T21:55:23.168Z,1716414923.168 [DockingStepper](INFO): Stopping 2024-05-22T21:55:23.563Z,1716414923.563 [DockingStepper](INFO): Stopped 2024-05-22T21:55:23.575Z,1716414923.575 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:55:23.575Z,1716414923.575 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:55:23.963Z,1716414923.963 [DockingStepper](INFO): Start 2024-05-22T21:55:23.963Z,1716414923.963 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:55:23.963Z,1716414923.963 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:55:24.419Z,1716414924.419 [DockingStepper](INFO): Starting 2024-05-22T21:55:24.780Z,1716414924.780 [DockingStepper](INFO): Starting 2024-05-22T21:55:25.163Z,1716414925.163 [DockingStepper](INFO): Starting 2024-05-22T21:55:25.212Z,1716414925.212 [DockingStepper](INFO): Init string: /1z16048aE13561N3m50 2024-05-22T21:55:25.578Z,1716414925.578 [DockingStepper](INFO): Starting 2024-05-22T21:55:25.984Z,1716414925.984 [DockingStepper](INFO): Starting 2024-05-22T21:55:26.483Z,1716414926.483 [DockingStepper](INFO): Starting 2024-05-22T21:55:26.787Z,1716414926.787 [DockingStepper](INFO): Starting 2024-05-22T21:55:26.791Z,1716414926.791 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:55:27.149Z,1716414927.149 [DockingStepper](INFO): Starting 2024-05-22T21:55:27.282Z,1716414927.282 [WetLabsUBAT](ERROR): Failed to parse incomplete device message. 2024-05-22T21:55:27.572Z,1716414927.572 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:55:27.572Z,1716414927.572 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:55:27.573Z,1716414927.573 [DockingStepper] Communications Fault, FailCount= 2 2024-05-22T21:55:27.573Z,1716414927.573 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:55:27.577Z,1716414927.577 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:55:27.969Z,1716414927.969 [DockingStepper](INFO): Stop 2024-05-22T21:55:27.969Z,1716414927.969 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:55:27.969Z,1716414927.969 [DockingStepper](INFO): Powering down 2024-05-22T21:55:28.368Z,1716414928.368 [DockingStepper](INFO): Stopping 2024-05-22T21:55:28.898Z,1716414928.898 [DockingStepper](INFO): Stopped 2024-05-22T21:55:28.930Z,1716414928.930 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:55:28.930Z,1716414928.930 [DockingStepper] No Fault, FailCount= 2 2024-05-22T21:55:29.179Z,1716414929.179 [DockingStepper](INFO): Start 2024-05-22T21:55:29.180Z,1716414929.180 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:55:29.180Z,1716414929.180 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:55:29.595Z,1716414929.595 [DockingStepper](INFO): Starting 2024-05-22T21:55:30.027Z,1716414930.027 [DockingStepper](INFO): Starting 2024-05-22T21:55:30.404Z,1716414930.404 [DockingStepper](INFO): Starting 2024-05-22T21:55:30.458Z,1716414930.458 [DockingStepper](INFO): Init string: /1z16048aE13561N3m50 2024-05-22T21:55:30.911Z,1716414930.911 [DockingStepper](INFO): Starting 2024-05-22T21:55:31.241Z,1716414931.241 [DockingStepper](INFO): Starting 2024-05-22T21:55:31.631Z,1716414931.631 [DockingStepper](INFO): Starting 2024-05-22T21:55:32.003Z,1716414932.003 [DockingStepper](INFO): Starting 2024-05-22T21:55:32.034Z,1716414932.034 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:55:32.394Z,1716414932.394 [DockingStepper](INFO): Starting 2024-05-22T21:55:44.254Z,1716414944.254 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:55:44.256Z,1716414944.256 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:55:44.257Z,1716414944.257 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:55:44.257Z,1716414944.257 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:55:44.265Z,1716414944.265 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:55:44.624Z,1716414944.624 [DockingStepper](INFO): Stop 2024-05-22T21:55:44.624Z,1716414944.624 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:55:44.625Z,1716414944.625 [DockingStepper](INFO): Powering down 2024-05-22T21:55:44.887Z,1716414944.887 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:55:44.989Z,1716414944.989 [DockingStepper](INFO): Stopping 2024-05-22T21:55:45.431Z,1716414945.431 [DockingStepper](INFO): Stopped 2024-05-22T21:55:45.469Z,1716414945.469 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:55:45.469Z,1716414945.469 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:55:45.831Z,1716414945.831 [DockingStepper](INFO): Start 2024-05-22T21:55:45.831Z,1716414945.831 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:55:45.831Z,1716414945.831 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:55:46.211Z,1716414946.211 [DockingStepper](INFO): Starting 2024-05-22T21:55:46.631Z,1716414946.631 [DockingStepper](INFO): Starting 2024-05-22T21:55:47.011Z,1716414947.011 [DockingStepper](INFO): Starting 2024-05-22T21:55:47.069Z,1716414947.069 [DockingStepper](INFO): Init string: /1z16048aE13561N3m50 2024-05-22T21:55:47.354Z,1716414947.354 [DockingStepper](INFO): Starting 2024-05-22T21:55:47.759Z,1716414947.759 [DockingStepper](INFO): Starting 2024-05-22T21:55:48.144Z,1716414948.144 [DockingStepper](INFO): Starting 2024-05-22T21:55:48.614Z,1716414948.614 [DockingStepper](INFO): Starting 2024-05-22T21:55:48.658Z,1716414948.658 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:55:49.099Z,1716414949.099 [DockingStepper](INFO): Starting 2024-05-22T21:56:02.340Z,1716414962.340 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:56:02.340Z,1716414962.340 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:56:02.340Z,1716414962.340 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:56:02.340Z,1716414962.340 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:56:02.348Z,1716414962.348 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:56:02.763Z,1716414962.763 [DockingStepper](INFO): Stop 2024-05-22T21:56:02.763Z,1716414962.763 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:56:02.763Z,1716414962.763 [DockingStepper](INFO): Powering down 2024-05-22T21:56:03.155Z,1716414963.155 [DockingStepper](INFO): Stopping 2024-05-22T21:56:03.619Z,1716414963.619 [DockingStepper](INFO): Stopped 2024-05-22T21:56:03.658Z,1716414963.658 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:56:03.659Z,1716414963.659 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:56:03.987Z,1716414963.987 [DockingStepper](INFO): Start 2024-05-22T21:56:03.987Z,1716414963.987 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:56:03.987Z,1716414963.987 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:56:04.359Z,1716414964.359 [DockingStepper](INFO): Starting 2024-05-22T21:56:04.779Z,1716414964.779 [DockingStepper](INFO): Starting 2024-05-22T21:56:05.160Z,1716414965.160 [DockingStepper](INFO): Starting 2024-05-22T21:56:05.211Z,1716414965.211 [DockingStepper](INFO): Init string: /1z16064aE13561N3m50 2024-05-22T21:56:05.639Z,1716414965.639 [DockingStepper](INFO): Starting 2024-05-22T21:56:05.972Z,1716414965.972 [DockingStepper](INFO): Starting 2024-05-22T21:56:06.343Z,1716414966.343 [DockingStepper](INFO): Starting 2024-05-22T21:56:06.757Z,1716414966.757 [DockingStepper](INFO): Starting 2024-05-22T21:56:06.790Z,1716414966.790 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:56:07.164Z,1716414967.164 [DockingStepper](INFO): Starting 2024-05-22T21:56:13.011Z,1716414973.011 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.790909 2024-05-22T21:56:14.014Z,1716414974.014 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:56:35.912Z,1716414995.912 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2024-05-22T21:56:35.912Z,1716414995.912 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-05-22T21:56:35.912Z,1716414995.912 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2024-05-22T21:56:35.912Z,1716414995.912 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2024-05-22T21:56:35.912Z,1716414995.912 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2024-05-22T21:56:35.913Z,1716414995.913 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2024-05-22T21:56:35.913Z,1716414995.913 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2024-05-22T21:56:35.913Z,1716414995.913 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped 2024-05-22T21:56:35.913Z,1716414995.913 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2024-05-22T21:56:35.913Z,1716414995.913 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped 2024-05-22T21:56:35.913Z,1716414995.913 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2024-05-22T21:56:35.913Z,1716414995.913 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2024-05-22T21:56:35.913Z,1716414995.913 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2024-05-22T21:56:35.914Z,1716414995.914 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2024-05-22T21:56:35.914Z,1716414995.914 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2024-05-22T21:56:35.914Z,1716414995.914 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2024-05-22T21:56:35.914Z,1716414995.914 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2024-05-22T21:56:36.338Z,1716414996.338 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 1.249759 m 2024-05-22T21:56:36.338Z,1716414996.338 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2024-05-22T21:56:36.339Z,1716414996.339 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2024-05-22T21:56:36.339Z,1716414996.339 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2024-05-22T21:56:36.339Z,1716414996.339 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2024-05-22T21:56:36.339Z,1716414996.339 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2024-05-22T21:56:44.136Z,1716415004.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-22T21:56:45.141Z,1716415005.141 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:57:06.461Z,1716415026.461 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:57:09.298Z,1716415029.298 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@ 2024-05-22T21:57:09.298Z,1716415029.298 [DockingStepper](FAULT): Docking arm not reporting position 2024-05-22T21:57:09.302Z,1716415029.302 [DockingStepper] Communications Fault, FailCount= 1 2024-05-22T21:57:09.302Z,1716415029.302 [DockingStepper](ERROR): Communications Fault 2024-05-22T21:57:09.308Z,1716415029.308 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-22T21:57:09.692Z,1716415029.692 [DockingStepper](INFO): Stop 2024-05-22T21:57:09.692Z,1716415029.692 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-22T21:57:09.693Z,1716415029.693 [DockingStepper](INFO): Powering down 2024-05-22T21:57:10.096Z,1716415030.096 [DockingStepper](INFO): Stopping 2024-05-22T21:57:10.554Z,1716415030.554 [DockingStepper](INFO): Stopped 2024-05-22T21:57:10.560Z,1716415030.560 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-22T21:57:10.560Z,1716415030.560 [DockingStepper] No Fault, FailCount= 1 2024-05-22T21:57:10.907Z,1716415030.907 [DockingStepper](INFO): Start 2024-05-22T21:57:10.907Z,1716415030.907 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-22T21:57:10.908Z,1716415030.908 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-22T21:57:11.311Z,1716415031.311 [DockingStepper](INFO): Starting 2024-05-22T21:57:11.752Z,1716415031.752 [DockingStepper](INFO): Starting 2024-05-22T21:57:12.118Z,1716415032.118 [DockingStepper](INFO): Starting 2024-05-22T21:57:12.167Z,1716415032.167 [DockingStepper](INFO): Init string: /1z16048aE13561N3m50 2024-05-22T21:57:12.484Z,1716415032.484 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:57:12.592Z,1716415032.592 [DockingStepper](INFO): Starting 2024-05-22T21:57:12.966Z,1716415032.966 [DockingStepper](INFO): Starting 2024-05-22T21:57:13.344Z,1716415033.344 [DockingStepper](INFO): Starting 2024-05-22T21:57:13.756Z,1716415033.756 [DockingStepper](INFO): Starting 2024-05-22T21:57:13.787Z,1716415033.787 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-22T21:57:14.113Z,1716415034.113 [DockingStepper](INFO): Starting 2024-05-22T21:57:14.150Z,1716415034.150 [Radio_Surface](INFO): Powering down 2024-05-22T21:57:15.258Z,1716415035.258 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-22T21:57:16.263Z,1716415036.263 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-05-22T21:57:16.263Z,1716415036.263 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:57:25.805Z,1716415045.805 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:57:36.010Z,1716415056.010 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2024-05-22T21:57:36.010Z,1716415056.010 [RDI_Pathfinder] Communications Fault, FailCount= 1 2024-05-22T21:57:36.010Z,1716415056.010 [RDI_Pathfinder](ERROR): Communications Fault 2024-05-22T21:57:36.271Z,1716415056.271 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2024-05-22T21:57:36.385Z,1716415056.385 [RDI_Pathfinder](INFO): Powering down 2024-05-22T21:57:37.407Z,1716415057.407 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2024-05-22T21:57:37.407Z,1716415057.407 [RDI_Pathfinder] No Fault, FailCount= 1 2024-05-22T21:57:37.589Z,1716415057.589 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-05-22T21:57:38.407Z,1716415058.407 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:57:42.862Z,1716415062.862 [ballast_and_trim](INFO): Timed out from 2024-05-22T21:49:42.8Z 2024-05-22T21:57:42.863Z,1716415062.863 [MissionManager](INFO): ballast_and_trim is completed. 2024-05-22T21:57:42.863Z,1716415062.863 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim] Stopped 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim:Science] Stopped 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim:Science:B] Stopped 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim:Science:C] Stopped 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim:Science:E] Stopped 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim:Science:F] Stopped 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim:Science:OceanCurrent] Stopped 2024-05-22T21:57:42.863Z,1716415062.863 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:Science:OceanCurrent:A.] Stopped 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:BackseatDriver] Stopped 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:PowerOnly] Stopped 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:PowerOnly](DEBUG): Aggregate::uninitialize ballast_and_trim:PowerOnly 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:PowerOnly:E.Wait] Stopped 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:RunBallastAndTrim] Stopped 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2024-05-22T21:57:42.864Z,1716415062.864 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped 2024-05-22T21:57:42.865Z,1716415062.865 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B 2024-05-22T21:57:42.982Z,1716415062.982 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/OnDock.tl 2024-05-22T21:57:42.983Z,1716415062.983 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl 2024-05-22T21:57:42.983Z,1716415062.983 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl 2024-05-22T21:57:42.985Z,1716415062.985 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl 2024-05-22T21:57:43.038Z,1716415063.038 [WetLabsUBAT](INFO): Powering down 2024-05-22T21:57:43.346Z,1716415063.346 [WetLabsBB2FL](INFO): Powering down 2024-05-22T21:57:43.834Z,1716415063.834 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/OnDock.tl translated into: 8 2 1 5 2 30 5 Skipping DHCP restart to test autonetwork Restarting logs restart logs ! ifconfig Mission ending, breaking off. Range: . 2024-05-22T21:57:43.857Z,1716415063.857 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h 2024-05-22T21:57:43.861Z,1716415063.861 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 28800.000000 s 2024-05-22T21:57:43.867Z,1716415063.867 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s 2024-05-22T21:57:43.871Z,1716415063.871 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min 2024-05-22T21:57:43.874Z,1716415063.874 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count 2024-05-22T21:57:43.878Z,1716415063.878 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 201 count 2024-05-22T21:57:43.882Z,1716415063.882 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m 2024-05-22T21:57:43.886Z,1716415063.886 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m 2024-05-22T21:57:43.889Z,1716415063.889 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km 2024-05-22T21:57:43.892Z,1716415063.892 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool 2024-05-22T21:57:43.896Z,1716415063.896 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 30.000000 min 2024-05-22T21:57:43.931Z,1716415063.931 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h 2024-05-22T21:57:43.932Z,1716415063.932 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-05-22T21:57:43.932Z,1716415063.932 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-05-22T21:57:43.933Z,1716415063.933 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-05-22T21:57:44.177Z,1716415064.177 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-05-22T21:57:44.200Z,1716415064.200 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m 2024-05-22T21:57:44.218Z,1716415064.218 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-05-22T21:57:44.227Z,1716415064.227 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m 2024-05-22T21:57:44.236Z,1716415064.236 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-05-22T21:57:44.243Z,1716415064.243 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-05-22T21:57:44.279Z,1716415064.279 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-05-22T21:57:44.297Z,1716415064.297 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-05-22T21:57:44.337Z,1716415064.337 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-05-22T21:57:44.337Z,1716415064.337 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-05-22T21:57:44.354Z,1716415064.354 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-05-22T21:57:44.400Z,1716415064.400 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-05-22T21:57:44.405Z,1716415064.405 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool 2024-05-22T21:57:44.407Z,1716415064.407 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-05-22T21:57:44.410Z,1716415064.410 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-05-22T21:57:44.410Z,1716415064.410 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-05-22T21:57:44.411Z,1716415064.411 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-05-22T21:57:44.682Z,1716415064.681 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-05-22T21:57:44.696Z,1716415064.696 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool 2024-05-22T21:57:44.712Z,1716415064.712 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool 2024-05-22T21:57:44.723Z,1716415064.723 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool 2024-05-22T21:57:44.727Z,1716415064.727 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool 2024-05-22T21:57:44.732Z,1716415064.732 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool 2024-05-22T21:57:44.755Z,1716415064.755 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-05-22T21:57:44.903Z,1716415064.903 [OnDock:L.Execute](DEBUG): Construct Execute. 2024-05-22T21:57:44.919Z,1716415064.919 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute. 2024-05-22T21:57:44.921Z,1716415064.921 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait. 2024-05-22T21:57:44.928Z,1716415064.928 [OnDock:O.Docked](DEBUG): Construct. 2024-05-22T21:57:44.928Z,1716415064.928 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values. 2024-05-22T21:57:45.014Z,1716415065.014 [OnDock:Detach:B.Undock](DEBUG): Construct. 2024-05-22T21:57:45.014Z,1716415065.014 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2024-05-22T21:57:45.071Z,1716415065.071 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission { """ Wait on dock, communicating when possible """ arguments { DockedTime = 8 hour """ Time to stay on dock. Mission timeout will be five minutes longer. """ DetachedTimeout = Dock:Docked.detachTimeout """ Time duration limit for determining if the vehicle is detached from the dock. """ RangeTimeout = Dock:Docked.rangeTimeout """ Maximum allowed time without acoustic range. """ TrackingUpdatePeriod = 2 minute """ How long to wait between acoustic queries. """ NumberOfPings = 1 count """ Number of pings requested each time. """ TransponderCode = Dock:Dock.transponderCode """ Transponder Address. """ MaxDepth = BIT:CBIT.stopDepth """ Maximum depth while docked. """ MinAltitude = 5 meter """ Minimum altitude """ MinOffshore = 2 km """ Minimum offshore """ StayOnDock = false """ If true, don't detach from dock at end of mission """ IfconfigInterval = 30 minute """ How long to wait between ifconfig runs """ # Mission variables (do not change) MissionTimeout = DockedTime """ Mission timeout (local variable, do not change) """ } timeout duration=MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore # End docking 5 minutes before end of mission to allow clean undock assign in sequence MissionTimeout = DockedTime + 5 minute insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl # Send data when connection exists readData { while ( Sensor:DataOverHttps.connectionStatus == true ) Universal:platform_communications } behavior Estimation:TrackAcousticContact { run in parallel set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } aggregate restartDHCP { run when ( called ) syslog important "Skipping DHCP restart to test autonetwork" # behavior Guidance:Execute { # run in sequence # set command = "! /mnt/mmc/LRAUV/Tools/dhcp.sh" # } } syslog important "Restarting logs" behavior Guidance:Execute { run in sequence set command = "restart logs" } # Kick DHCP again, in case it took us a while to close the arm in Docked call refId="restartDHCP" aggregate PeriodicCheck { run in parallel aggregate CheckIP { run in sequence behavior Guidance:Execute { run in sequence set command = "! ifconfig" } behavior Guidance:Wait { run in sequence set duration = IfconfigInterval } } } behavior Dock:Docked { run in sequence timeout duration=DockedTime set detachTimeout = DetachedTimeout set rangeTimeout = RangeTimeout } # Undock cleanly, instead of relying on DefaultWithUndock to come next aggregate Detach { run in sequence break if ( StayOnDock == true ) syslog important "Mission ending, breaking off. Range: " + Estimation:TrackAcousticContact.range_to_contact~meter + "." behavior Dock:Undock { run in sequence } # Get our 10.89 IP back once we're on the surface call refId="restartDHCP" } } 2024-05-22T21:57:45.072Z,1716415065.072 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/OnDock.tl id=OnDock 2024-05-22T21:57:45.297Z,1716415065.297 [MissionManager](IMPORTANT): Started mission DefaultTankUndock 2024-05-22T21:57:45.297Z,1716415065.297 [DefaultTankUndock] Running Loop=1 2024-05-22T21:57:45.306Z,1716415065.306 [DefaultTankUndock](DEBUG): Aggregate::initialize DefaultTankUndock 2024-05-22T21:57:45.306Z,1716415065.306 [DefaultTankUndock:B.GoToSurface] Running Loop=1 2024-05-22T21:57:45.306Z,1716415065.306 [DefaultTankUndock:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-22T21:57:45.306Z,1716415065.306 [DefaultTankUndock:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-22T21:57:45.307Z,1716415065.307 [DefaultTankUndock:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-22T21:57:45.307Z,1716415065.307 [DefaultTankUndock:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-22T21:57:45.307Z,1716415065.307 [DefaultTankUndock:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-22T21:57:45.307Z,1716415065.307 [DefaultTankUndock:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-22T21:57:45.308Z,1716415065.308 [DefaultTankUndock:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-22T21:57:45.308Z,1716415065.308 [DefaultTankUndock:A.Wait] Running Loop=1 2024-05-22T21:57:45.308Z,1716415065.308 [DefaultTankUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-05-22T21:57:45.818Z,1716415065.818 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -25.00 mm (1 active estimators). 2024-05-22T21:57:46.078Z,1716415066.078 [Radio_Surface](INFO): Powering up 2024-05-22T21:57:46.389Z,1716415066.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-05-22T21:57:47.390Z,1716415067.390 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-05-22T21:57:51.331Z,1716415071.331 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2024-05-22T21:57:51.331Z,1716415071.331 [CommandExec](INFO): Found local IP address 10.89.10.2 for eth0 2024-05-22T21:57:51.573Z,1716415071.573 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-22T21:57:57.946Z,1716415077.946 [CommandExec](IMPORTANT): got command set OnDock.DockedTime 45 minute 2024-05-22T21:57:57.947Z,1716415077.947 [CommandExec](IMPORTANT): got command set OnDock.DetachedTimeout 8 hour 2024-05-22T21:57:57.948Z,1716415077.948 [CommandExec](IMPORTANT): got command set OnDock.RangeTimeout 8 hour 2024-05-22T21:57:57.948Z,1716415077.948 [CommandExec](IMPORTANT): got command set OnDock.MaxDepth 5 meter 2024-05-22T21:57:57.949Z,1716415077.949 [CommandExec](IMPORTANT): got command set OnDock.MinAltitude 0.00001 meter 2024-05-22T21:57:57.951Z,1716415077.951 [CommandExec](IMPORTANT): got command set OnDock.MinOffshore 0.000001 kilometer 2024-05-22T21:57:58.533Z,1716415078.533 [DefaultTankUndock:A.Wait](INFO): Done Waiting. 2024-05-22T21:57:58.533Z,1716415078.533 [DefaultTankUndock:A.Wait] Stopped 2024-05-22T21:57:58.538Z,1716415078.538 [DefaultTankUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-22T21:58:00.705Z,1716415080.705 [CommandExec](IMPORTANT): got command set OnDock.IfconfigInterval 5 minute 2024-05-22T21:58:00.706Z,1716415080.706 [CommandExec](IMPORTANT): got command run 2024-05-22T21:58:00.708Z,1716415080.708 [CommandExec](IMPORTANT): Running 2024-05-22T21:58:00.974Z,1716415080.974 [DefaultTankUndock] Stopped 2024-05-22T21:58:00.974Z,1716415080.974 [DefaultTankUndock](DEBUG): Aggregate::uninitialize DefaultTankUndock 2024-05-22T21:58:00.974Z,1716415080.974 [DefaultTankUndock:B.GoToSurface] Stopped 2024-05-22T21:58:00.974Z,1716415080.974 [DefaultTankUndock:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-22T21:58:00.975Z,1716415080.975 [MissionManager](IMPORTANT): Started mission OnDock 2024-05-22T21:58:00.975Z,1716415080.975 [OnDock] Running Loop=1 2024-05-22T21:58:00.975Z,1716415080.975 [OnDock](DEBUG): Aggregate::initialize OnDock 2024-05-22T21:58:00.975Z,1716415080.975 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-22T21:58:00.975Z,1716415080.975 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes 2024-05-22T21:58:00.975Z,1716415080.975 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-22T21:58:00.975Z,1716415080.975 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:BackseatDriver] Running Loop=1 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:PowerOnly] Running Loop=1 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly 2024-05-22T21:58:00.976Z,1716415080.976 [OnDock:PowerOnly:A] Running Loop=1 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:PowerOnly:B] Running Loop=1 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:PowerOnly:C] Running Loop=1 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:PowerOnly:D] Running Loop=1 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:PowerOnly:E.Wait] Running Loop=1 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:H] Running Loop=1 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:I.] Running Loop=1 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:I.](INFO): Initializing TrackAcousticContact. 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:PeriodicCheck] Running Loop=1 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck 2024-05-22T21:58:00.977Z,1716415080.977 [OnDock:PeriodicCheck:CheckIP] Running Loop=1 2024-05-22T21:58:00.978Z,1716415080.978 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP 2024-05-22T21:58:00.978Z,1716415080.978 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1 2024-05-22T21:58:00.978Z,1716415080.978 [OnDock:B] Running Loop=1 2024-05-22T21:58:00.978Z,1716415080.978 [OnDock:PeriodicCheck] Running Loop=1 2024-05-22T21:58:00.979Z,1716415080.979 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig 2024-05-22T21:58:00.979Z,1716415080.979 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped 2024-05-22T21:58:00.979Z,1716415080.979 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1 2024-05-22T21:58:00.979Z,1716415080.979 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component. 2024-05-22T21:58:00.979Z,1716415080.979 [OnDock:I.] Running Loop=1 2024-05-22T21:58:00.980Z,1716415080.980 [OnDock:H] Stopped 2024-05-22T21:58:00.980Z,1716415080.980 [OnDock:PowerOnly] Running Loop=1 2024-05-22T21:58:00.980Z,1716415080.980 [OnDock:PowerOnly:D] Stopped 2024-05-22T21:58:00.980Z,1716415080.980 [OnDock:PowerOnly:C] Stopped 2024-05-22T21:58:00.980Z,1716415080.980 [OnDock:PowerOnly:B] Stopped 2024-05-22T21:58:00.980Z,1716415080.980 [OnDock:PowerOnly:A] Stopped 2024-05-22T21:58:00.980Z,1716415080.980 [OnDock:BackseatDriver] Running Loop=1 2024-05-22T21:58:00.981Z,1716415080.981 [OnDock:B] Stopped 2024-05-22T21:58:00.981Z,1716415080.981 [OnDock:C] Running Loop=1 2024-05-22T21:58:00.981Z,1716415080.981 [OnDock:StandardEnvelopes] Running Loop=1 2024-05-22T21:58:00.981Z,1716415080.981 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-05-22T21:58:00.981Z,1716415080.981 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE 2024-05-22T21:58:00.989Z,1716415080.989 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-05-22T21:58:00.990Z,1716415080.990 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-05-22T21:58:00.994Z,1716415080.994 [HorizontalControl](DEBUG): kpHeading = 0.400000 2024-05-22T21:58:00.995Z,1716415080.995 [HorizontalControl](DEBUG): kiHeading = 0.001000 2024-05-22T21:58:00.995Z,1716415080.995 [HorizontalControl](DEBUG): kdHeading = 0.050000 2024-05-22T21:58:01.019Z,1716415081.019 [CommandExec](IMPORTANT): got command ! ifconfig 2024-05-22T21:58:01.295Z,1716415081.295 [CommandExec](IMPORTANT): eth0 Link encap:Ethernet HWaddr 50:2D:F4:07:45:4B inet addr:10.89.10.2 Bcast:10.89.255.255 Mask:255.255.0.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:6822 errors:0 dropped:0 overruns:0 frame:0 TX packets:7378 errors:0 dropped:0 overruns:0 carrier:0 collisions:12 txqueuelen:1000 RX bytes:2459921 (2.3 Mb) TX bytes:2137355 (2.0 Mb) Interrupt:29 lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MULTICAST MTU:16436 Metric:1 RX packets:368 errors:0 dropped:0 overruns:0 frame:0 TX packets:368 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:29064 (28.3 Kb) TX bytes:29064 (28.3 Kb) 2024-05-22T21:58:01.457Z,1716415081.457 [OnDock:C] Stopped 2024-05-22T21:58:01.457Z,1716415081.457 [OnDock:D] Running Loop=1 2024-05-22T21:58:01.828Z,1716415081.828 [OnDock:D] Stopped 2024-05-22T21:58:01.828Z,1716415081.828 [OnDock:E] Running Loop=1 2024-05-22T21:58:02.232Z,1716415082.232 [OnDock:E] Stopped 2024-05-22T21:58:02.232Z,1716415082.232 [OnDock:K] Running Loop=1 2024-05-22T21:58:02.643Z,1716415082.643 [OnDock:K](IMPORTANT): Restarting logs 2024-05-22T21:58:02.644Z,1716415082.644 [OnDock:K] Stopped 2024-05-22T21:58:02.644Z,1716415082.644 [OnDock:L.Execute] Running Loop=1