2024-05-22T23:05:39.427Z,1716419139.427 [Supervisor](DEBUG): Initializing supervisor.
2024-05-22T23:05:39.431Z,1716419139.431 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-05-22T23:05:39.432Z,1716419139.432 [SyncHandler](INFO): Protected caller Thread ID is 1362
2024-05-22T23:05:39.432Z,1716419139.432 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-05-22T23:05:39.433Z,1716419139.433 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-05-22T23:05:39.434Z,1716419139.434 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1363
2024-05-22T23:05:39.438Z,1716419139.438 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-05-22T23:05:39.455Z,1716419139.455 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-05-22T23:05:39.456Z,1716419139.456 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-05-22T23:05:39.456Z,1716419139.456 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1364
2024-05-22T23:05:39.460Z,1716419139.460 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-05-22T23:05:39.461Z,1716419139.461 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-05-22T23:05:39.462Z,1716419139.462 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1365
2024-05-22T23:05:39.464Z,1716419139.464 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-05-22T23:05:39.465Z,1716419139.465 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-05-22T23:05:39.465Z,1716419139.465 [logger ThreadHandler](INFO): Protected caller Thread ID is 1366
2024-05-22T23:05:39.469Z,1716419139.469 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-05-22T23:05:39.469Z,1716419139.469 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-05-22T23:05:39.479Z,1716419139.479 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-05-22T23:05:39.777Z,1716419139.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-05-22T23:05:39.777Z,1716419139.777 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-05-22T23:05:40.775Z,1716419140.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-05-22T23:05:40.776Z,1716419140.776 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-05-22T23:05:41.026Z,1716419141.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-05-22T23:05:41.026Z,1716419141.026 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-05-22T23:05:41.117Z,1716419141.117 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-05-22T23:05:41.262Z,1716419141.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-05-22T23:05:41.263Z,1716419141.263 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-05-22T23:05:41.356Z,1716419141.356 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-05-22T23:05:41.477Z,1716419141.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-05-22T23:05:41.477Z,1716419141.477 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-05-22T23:05:41.894Z,1716419141.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-05-22T23:05:41.894Z,1716419141.894 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-05-22T23:05:42.453Z,1716419142.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-05-22T23:05:42.454Z,1716419142.454 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-05-22T23:05:42.688Z,1716419142.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-05-22T23:05:42.688Z,1716419142.688 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-05-22T23:05:42.890Z,1716419142.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-05-22T23:05:42.890Z,1716419142.890 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-05-22T23:05:43.446Z,1716419143.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-05-22T23:05:43.446Z,1716419143.446 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-05-22T23:05:43.753Z,1716419143.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-05-22T23:05:43.753Z,1716419143.753 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-05-22T23:05:43.999Z,1716419143.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-05-22T23:05:43.999Z,1716419143.999 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-05-22T23:05:44.392Z,1716419144.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-05-22T23:05:44.393Z,1716419144.393 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-05-22T23:05:44.775Z,1716419144.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-05-22T23:05:44.777Z,1716419144.777 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2024-05-22T23:05:44.778Z,1716419144.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2024-05-22T23:05:45.109Z,1716419145.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2024-05-22T23:05:45.521Z,1716419145.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2024-05-22T23:05:45.693Z,1716419145.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2024-05-22T23:05:45.816Z,1716419145.816 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2024-05-22T23:05:45.914Z,1716419145.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2024-05-22T23:05:46.021Z,1716419146.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2024-05-22T23:05:46.146Z,1716419146.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2024-05-22T23:05:46.420Z,1716419146.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-05-22T23:05:46.421Z,1716419146.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2024-05-22T23:05:46.527Z,1716419146.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2024-05-22T23:05:46.631Z,1716419146.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2024-05-22T23:05:46.786Z,1716419146.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2024-05-22T23:05:46.899Z,1716419146.899 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2024-05-22T23:05:46.899Z,1716419146.899 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-05-22T23:05:46.917Z,1716419146.917 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-05-22T23:05:47.035Z,1716419147.035 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-05-22T23:05:47.036Z,1716419147.036 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-05-22T23:05:47.088Z,1716419147.088 [VerticalControl](DEBUG): Construct VerticalControl.
2024-05-22T23:05:47.148Z,1716419147.148 [VerticalControl] Loaded
2024-05-22T23:05:47.148Z,1716419147.148 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-05-22T23:05:47.151Z,1716419147.151 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-05-22T23:05:47.198Z,1716419147.198 [HorizontalControl] Loaded
2024-05-22T23:05:47.198Z,1716419147.198 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-05-22T23:05:47.201Z,1716419147.201 [SpeedControl](DEBUG): Construct SpeedControl.
2024-05-22T23:05:47.203Z,1716419147.203 [SpeedControl] Loaded
2024-05-22T23:05:47.204Z,1716419147.204 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-05-22T23:05:47.206Z,1716419147.206 [LoopControl](DEBUG): Construct LoopControl.
2024-05-22T23:05:47.207Z,1716419147.207 [LoopControl] Loaded
2024-05-22T23:05:47.207Z,1716419147.207 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-05-22T23:05:47.207Z,1716419147.207 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-05-22T23:05:47.208Z,1716419147.208 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-05-22T23:05:47.257Z,1716419147.257 [DepthRateCalculator] Loaded
2024-05-22T23:05:47.257Z,1716419147.257 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-05-22T23:05:47.262Z,1716419147.262 [PitchRateCalculator] Loaded
2024-05-22T23:05:47.262Z,1716419147.262 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-05-22T23:05:47.272Z,1716419147.272 [SpeedCalculator] Loaded
2024-05-22T23:05:47.272Z,1716419147.272 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-05-22T23:05:47.276Z,1716419147.276 [YawRateCalculator] Loaded
2024-05-22T23:05:47.277Z,1716419147.277 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-05-22T23:05:47.295Z,1716419147.295 [ElevatorOffsetCalculator] Loaded
2024-05-22T23:05:47.295Z,1716419147.295 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-05-22T23:05:47.295Z,1716419147.295 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-05-22T23:05:47.296Z,1716419147.296 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-05-22T23:05:47.323Z,1716419147.323 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-05-22T23:05:47.323Z,1716419147.323 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-05-22T23:05:47.378Z,1716419147.378 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-05-22T23:05:47.378Z,1716419147.378 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-05-22T23:05:47.715Z,1716419147.715 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-05-22T23:05:47.716Z,1716419147.716 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-05-22T23:05:47.889Z,1716419147.889 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-05-22T23:05:47.889Z,1716419147.889 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-05-22T23:05:48.341Z,1716419148.341 [AHRS_M2] Loaded
2024-05-22T23:05:48.342Z,1716419148.342 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-05-22T23:05:48.376Z,1716419148.376 [BackseatComponent] Loaded
2024-05-22T23:05:48.377Z,1716419148.377 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-05-22T23:05:48.378Z,1716419148.378 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C44E0
2024-05-22T23:05:48.378Z,1716419148.378 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1451
2024-05-22T23:05:48.381Z,1716419148.381 [LcmUniversalReporter] Loaded
2024-05-22T23:05:48.381Z,1716419148.381 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-05-22T23:05:49.187Z,1716419149.187 [BPC1] Loaded
2024-05-22T23:05:49.187Z,1716419149.187 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-05-22T23:05:49.260Z,1716419149.260 [DataOverHttps] Loaded
2024-05-22T23:05:49.260Z,1716419149.260 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-05-22T23:05:49.261Z,1716419149.261 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F44E0
2024-05-22T23:05:49.261Z,1716419149.261 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1452
2024-05-22T23:05:49.282Z,1716419149.282 [Depth_Keller] Loaded
2024-05-22T23:05:49.282Z,1716419149.282 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-05-22T23:05:49.343Z,1716419149.343 [NAL9602] Loaded
2024-05-22T23:05:49.343Z,1716419149.343 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-05-22T23:05:49.373Z,1716419149.373 [Onboard] Loaded
2024-05-22T23:05:49.373Z,1716419149.373 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-05-22T23:05:49.374Z,1716419149.374 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A244E0
2024-05-22T23:05:49.374Z,1716419149.374 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1453
2024-05-22T23:05:49.386Z,1716419149.386 [Power24vConverter] Loaded
2024-05-22T23:05:49.387Z,1716419149.387 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-05-22T23:05:49.400Z,1716419149.400 [Radio_Surface] Loaded
2024-05-22T23:05:49.401Z,1716419149.401 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-05-22T23:05:49.402Z,1716419149.402 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A544E0
2024-05-22T23:05:49.402Z,1716419149.402 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1454
2024-05-22T23:05:49.446Z,1716419149.446 [RDI_Pathfinder] Loaded
2024-05-22T23:05:49.447Z,1716419149.447 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2024-05-22T23:05:49.538Z,1716419149.538 [DAT] Loaded
2024-05-22T23:05:49.538Z,1716419149.538 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-05-22T23:05:49.539Z,1716419149.539 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A844E0
2024-05-22T23:05:49.540Z,1716419149.540 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1455
2024-05-22T23:05:49.540Z,1716419149.540 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-05-22T23:05:49.541Z,1716419149.541 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-05-22T23:05:49.650Z,1716419149.650 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-05-22T23:05:49.650Z,1716419149.650 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-05-22T23:05:49.664Z,1716419149.664 [NavChart] Loaded
2024-05-22T23:05:49.664Z,1716419149.664 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-05-22T23:05:49.670Z,1716419149.670 [UniversalFixResidualReporter] Loaded
2024-05-22T23:05:49.670Z,1716419149.670 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-05-22T23:05:49.671Z,1716419149.671 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-05-22T23:05:49.671Z,1716419149.671 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-05-22T23:05:49.809Z,1716419149.809 [SBIT](DEBUG): Construct Startup Built In Test.
2024-05-22T23:05:49.819Z,1716419149.819 [SBIT] Loaded
2024-05-22T23:05:49.819Z,1716419149.819 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-05-22T23:05:49.822Z,1716419149.822 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-05-22T23:05:49.835Z,1716419149.835 [IBIT] Loaded
2024-05-22T23:05:49.835Z,1716419149.835 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-05-22T23:05:49.841Z,1716419149.841 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-05-22T23:05:49.947Z,1716419149.947 [CBIT] Loaded
2024-05-22T23:05:49.947Z,1716419149.947 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-05-22T23:05:49.947Z,1716419149.947 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-05-22T23:05:49.948Z,1716419149.948 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-05-22T23:05:50.323Z,1716419150.323 [BuoyancyServo] Loaded
2024-05-22T23:05:50.323Z,1716419150.323 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-05-22T23:05:50.344Z,1716419150.344 [ElevatorServo] Loaded
2024-05-22T23:05:50.344Z,1716419150.344 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-05-22T23:05:50.372Z,1716419150.372 [DockingStepper] Loaded
2024-05-22T23:05:50.372Z,1716419150.372 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2024-05-22T23:05:50.392Z,1716419150.392 [MassServo] Loaded
2024-05-22T23:05:50.392Z,1716419150.392 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-05-22T23:05:50.410Z,1716419150.410 [RudderServo] Loaded
2024-05-22T23:05:50.411Z,1716419150.411 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-05-22T23:05:50.504Z,1716419150.504 [ThrusterServo] Loaded
2024-05-22T23:05:50.504Z,1716419150.504 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2024-05-22T23:05:50.505Z,1716419150.505 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-05-22T23:05:50.505Z,1716419150.505 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-05-22T23:05:50.550Z,1716419150.550 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-05-22T23:05:50.551Z,1716419150.551 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-05-22T23:05:50.723Z,1716419150.723 [CTD_Seabird] Loaded
2024-05-22T23:05:50.723Z,1716419150.723 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-05-22T23:05:50.724Z,1716419150.724 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD84E0
2024-05-22T23:05:50.725Z,1716419150.725 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1456
2024-05-22T23:05:50.758Z,1716419150.758 [ESPComponent] Loaded
2024-05-22T23:05:50.758Z,1716419150.758 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-05-22T23:05:50.778Z,1716419150.778 [PAR_Licor] Loaded
2024-05-22T23:05:50.778Z,1716419150.778 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-05-22T23:05:50.821Z,1716419150.821 [WetLabsBB2FL] Loaded
2024-05-22T23:05:50.821Z,1716419150.821 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-05-22T23:05:50.822Z,1716419150.822 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C084E0
2024-05-22T23:05:50.823Z,1716419150.823 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1457
2024-05-22T23:05:50.843Z,1716419150.843 [WetLabsUBAT] Loaded
2024-05-22T23:05:50.843Z,1716419150.843 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2024-05-22T23:05:50.844Z,1716419150.844 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C384E0
2024-05-22T23:05:50.845Z,1716419150.845 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1458
2024-05-22T23:05:50.845Z,1716419150.845 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-05-22T23:05:50.851Z,1716419150.851 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-05-22T23:05:50.854Z,1716419150.854 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-05-22T23:05:50.866Z,1716419150.866 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-05-22T23:05:50.867Z,1716419150.867 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C684E0
2024-05-22T23:05:50.867Z,1716419150.867 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1459
2024-05-22T23:05:50.871Z,1716419150.871 [Supervisor](INFO): Main Thread ID is 831
2024-05-22T23:05:50.872Z,1716419150.872 [Supervisor](DEBUG): Running supervisor.
2024-05-22T23:05:50.872Z,1716419150.872 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1460
2024-05-22T23:05:50.873Z,1716419150.873 [CommandExec](INFO): Initializing the command executive.
2024-05-22T23:05:50.874Z,1716419150.874 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1461
2024-05-22T23:05:50.877Z,1716419150.877 [controlThread ThreadHandler](INFO): Handler Thread ID is 1462
2024-05-22T23:05:50.878Z,1716419150.878 [controlThread](DEBUG): Initializing ControlThread
2024-05-22T23:05:50.879Z,1716419150.879 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-05-22T23:05:50.880Z,1716419150.880 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-05-22T23:05:50.881Z,1716419150.881 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-05-22T23:05:50.881Z,1716419150.881 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-05-22T23:05:50.882Z,1716419150.882 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-05-22T23:05:50.882Z,1716419150.882 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-05-22T23:05:50.882Z,1716419150.882 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-05-22T23:05:50.883Z,1716419150.883 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-05-22T23:05:50.883Z,1716419150.883 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-05-22T23:05:50.889Z,1716419150.889 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-05-22T23:05:50.889Z,1716419150.889 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-05-22T23:05:50.890Z,1716419150.890 [SBIT](INFO): Initialize SBIT Component.
2024-05-22T23:05:50.890Z,1716419150.890 [SBIT](IMPORTANT): git: 2024-05-10-4-gbb504d4a1
2024-05-22T23:05:50.890Z,1716419150.890 [SBIT](INFO): git hash: bb504d4a1c64b9083deca52c3c8cef871ae8bac1
2024-05-22T23:05:50.891Z,1716419150.891 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-05-22T23:05:50.892Z,1716419150.892 [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-22T23:05:50.893Z,1716419150.893 [SBIT](INFO): Beginning SBIT in 94.000000 seconds.
2024-05-22T23:05:50.893Z,1716419150.893 [IBIT](INFO): Initialize IBIT Component.
2024-05-22T23:05:50.894Z,1716419150.894 [CBIT](DEBUG): Initialize CBIT Component.
2024-05-22T23:05:50.895Z,1716419150.895 [logger ThreadHandler](INFO): Handler Thread ID is 1463
2024-05-22T23:05:50.908Z,1716419150.908 [CBIT](DEBUG): Initialized mux pins.
2024-05-22T23:05:50.908Z,1716419150.908 [CBIT](DEBUG): Initializing the watchdog timer.
2024-05-22T23:05:50.912Z,1716419150.912 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1464
2024-05-22T23:05:50.920Z,1716419150.920 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1465
2024-05-22T23:05:50.922Z,1716419150.922 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-05-22T23:05:50.928Z,1716419150.928 [Onboard ThreadHandler](INFO): Handler Thread ID is 1466
2024-05-22T23:05:50.932Z,1716419150.932 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-05-22T23:05:50.933Z,1716419150.933 [CBIT](DEBUG): Initializing heartbeat.
2024-05-22T23:05:50.949Z,1716419150.949 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1467
2024-05-22T23:05:50.953Z,1716419150.953 [Radio_Surface](INFO): Stopped
2024-05-22T23:05:50.969Z,1716419150.969 [DAT ThreadHandler](INFO): Handler Thread ID is 1468
2024-05-22T23:05:50.969Z,1716419150.969 [DAT](INFO): Powering up
2024-05-22T23:05:50.969Z,1716419150.969 [DAT](DEBUG): Initializing DAT.
2024-05-22T23:05:50.977Z,1716419150.977 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1469
2024-05-22T23:05:50.977Z,1716419150.977 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-05-22T23:05:50.981Z,1716419150.981 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1471
2024-05-22T23:05:50.985Z,1716419150.985 [WetLabsBB2FL](INFO): Powering up
2024-05-22T23:05:50.986Z,1716419150.986 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1473
2024-05-22T23:05:50.988Z,1716419150.988 [WetLabsUBAT](INFO): Powering up
2024-05-22T23:05:50.989Z,1716419150.989 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1475
2024-05-22T23:05:50.993Z,1716419150.993 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-05-22T23:05:50.993Z,1716419150.993 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-05-22T23:05:50.993Z,1716419150.993 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-05-22T23:05:50.993Z,1716419150.993 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-05-22T23:05:50.993Z,1716419150.993 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-05-22T23:05:50.994Z,1716419150.994 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-05-22T23:05:50.994Z,1716419150.994 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-05-22T23:05:50.994Z,1716419150.994 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-05-22T23:05:51.004Z,1716419151.004 [CBIT](DEBUG): Deactivating GF circuits.
2024-05-22T23:05:51.004Z,1716419151.004 [CBIT](DEBUG): Deactivating emergency mode.
2024-05-22T23:05:51.044Z,1716419151.044 [CBIT](DEBUG): Backplane powered.
2024-05-22T23:05:51.048Z,1716419151.048 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-05-22T23:05:51.049Z,1716419151.049 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2024-05-22T23:05:51.049Z,1716419151.049 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2024-05-22T23:05:51.050Z,1716419151.050 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2024-05-22T23:05:51.064Z,1716419151.064 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
2024-05-22T23:05:51.064Z,1716419151.064 [MissionManager](ERROR): Top node has no child nodes: Mission
2024-05-22T23:05:51.064Z,1716419151.064 [MissionManager](ERROR): Could not load startup mission.
2024-05-22T23:05:51.065Z,1716419151.065 [MissionManager](INFO): Loading Mission from file: Missions/Engineering/DefaultTankUndock.tl
2024-05-22T23:05:51.065Z,1716419151.065 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Engineering/DefaultTankUndock.tl
2024-05-22T23:05:51.066Z,1716419151.066 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Engineering/DefaultTankUndock.tl
2024-05-22T23:05:51.352Z,1716419151.352 [Radio_Surface](INFO): Start
2024-05-22T23:05:51.352Z,1716419151.352 [Radio_Surface](INFO): Powering up
2024-05-22T23:05:51.359Z,1716419151.359 [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-22T23:05:51.381Z,1716419151.381 [MissionManager](INFO): DefineArg DefaultTankUndock.ElapsedSinceDefaultStarted = 0.000000 min
2024-05-22T23:05:51.382Z,1716419151.382 [DefaultTankUndock:A.Wait](DEBUG): Construct Wait.
2024-05-22T23:05:51.402Z,1716419151.402 [DefaultTankUndock:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-22T23:05:51.443Z,1716419151.443 [DefaultTankUndock:C.Execute](DEBUG): Construct Execute.
2024-05-22T23:05:51.464Z,1716419151.464 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-05-22T23:05:51.469Z,1716419151.469 [DefaultTankUndock:F.Execute](DEBUG): Construct Execute.
2024-05-22T23:05:51.485Z,1716419151.485 [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-22T23:05:51.501Z,1716419151.501 [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-22T23:05:51.507Z,1716419151.507 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-05-22T23:05:51.578Z,1716419151.578 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m
2024-05-22T23:05:51.578Z,1716419151.578 [Power24vConverter](INFO): Powering up.
2024-05-22T23:05:51.579Z,1716419151.579 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2024-05-22T23:05:51.587Z,1716419151.587 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-05-22T23:05:51.625Z,1716419151.625 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-05-22T23:05:51.625Z,1716419151.625 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-05-22T23:05:51.626Z,1716419151.626 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-05-22T23:05:51.632Z,1716419151.632 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-05-22T23:05:51.633Z,1716419151.633 [DockingStepper](INFO): Start
2024-05-22T23:05:51.633Z,1716419151.633 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-22T23:05:51.634Z,1716419151.634 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-22T23:05:51.634Z,1716419151.634 [MassServo](DEBUG): Initializing EZServoServo.
2024-05-22T23:05:51.640Z,1716419151.640 [MassServo](DEBUG): Initializing MassServo.
2024-05-22T23:05:51.641Z,1716419151.641 [RudderServo](DEBUG): Initializing EZServoServo.
2024-05-22T23:05:51.648Z,1716419151.648 [RudderServo](DEBUG): Initializing RudderServo.
2024-05-22T23:05:51.649Z,1716419151.649 [ThrusterServo](DEBUG): Initializing EZServoServo.
2024-05-22T23:05:51.649Z,1716419151.649 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2024-05-22T23:05:51.756Z,1716419151.756 [Radio_Surface](INFO): Starting
2024-05-22T23:05:51.958Z,1716419151.958 [DockingStepper](INFO): Starting
2024-05-22T23:05:52.160Z,1716419152.160 [Radio_Surface](INFO): Starting
2024-05-22T23:05:52.336Z,1716419152.336 [DockingStepper](INFO): Starting
2024-05-22T23:05:52.564Z,1716419152.564 [Radio_Surface](INFO): Starting
2024-05-22T23:05:52.860Z,1716419152.860 [DockingStepper](INFO): Starting
2024-05-22T23:05:52.909Z,1716419152.909 [DockingStepper](INFO): Init string: /1z7943aE13561N3m50
2024-05-22T23:05:52.971Z,1716419152.971 [Radio_Surface](INFO): Starting
2024-05-22T23:05:53.161Z,1716419153.161 [DockingStepper](INFO): Starting
2024-05-22T23:05:53.372Z,1716419153.372 [Radio_Surface](INFO): Starting
2024-05-22T23:05:53.547Z,1716419153.547 [DockingStepper](INFO): Starting
2024-05-22T23:05:53.596Z,1716419153.596 [WetLabsBB2FL](INFO): Powering down
2024-05-22T23:05:53.776Z,1716419153.776 [Radio_Surface](INFO): Starting
2024-05-22T23:05:53.966Z,1716419153.966 [DockingStepper](INFO): Starting
2024-05-22T23:05:54.180Z,1716419154.180 [Radio_Surface](INFO): Starting
2024-05-22T23:05:54.404Z,1716419154.404 [DockingStepper](INFO): Starting
2024-05-22T23:05:54.428Z,1716419154.428 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-22T23:05:54.584Z,1716419154.584 [Radio_Surface](INFO): Starting
2024-05-22T23:05:54.898Z,1716419154.898 [DockingStepper](INFO): Starting
2024-05-22T23:05:54.988Z,1716419154.988 [Radio_Surface](INFO): Starting
2024-05-22T23:05:55.317Z,1716419155.317 [DockingStepper](INFO): Commanding positive:42876
2024-05-22T23:05:55.392Z,1716419155.392 [Radio_Surface](INFO): Starting
2024-05-22T23:05:55.796Z,1716419155.796 [Radio_Surface](INFO): Starting
2024-05-22T23:05:56.200Z,1716419156.200 [Radio_Surface](INFO): Starting
2024-05-22T23:05:56.604Z,1716419156.604 [Radio_Surface](INFO): Starting
2024-05-22T23:05:56.604Z,1716419156.604 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2024-05-22T23:05:56.605Z,1716419156.605 [CommandExec](INFO): Found local IP address 10.89.10.2 for eth0
2024-05-22T23:06:00.109Z,1716419160.109 [DockingStepper](INFO): Pause
2024-05-22T23:06:00.236Z,1716419160.236 [WetLabsUBAT](INFO): Powering down
2024-05-22T23:06:03.572Z,1716419163.572 [DAT](INFO): DAT read:
2024-05-22T23:06:03.575Z,1716419163.575 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-05-22T23:06:05.337Z,1716419165.337 [DAT](INFO): DAT read: MF Frequency Band
2024-05-22T23:06:05.338Z,1716419165.338 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2024-05-22T23:06:05.339Z,1716419165.339 [DAT](INFO): DAT read: May 22 2024 23:05:59
2024-05-22T23:06:06.345Z,1716419166.345 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-05-22T23:06:06.346Z,1716419166.346 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-05-22T23:06:06.347Z,1716419166.347 [DAT](INFO): commRate: 800
2024-05-22T23:06:08.412Z,1716419168.412 [DAT](INFO): entering command mode
2024-05-22T23:06:08.613Z,1716419168.613 [DAT](INFO): DAT read:
2024-05-22T23:06:08.613Z,1716419168.613 [DAT](INFO): DAT read: user:1>
2024-05-22T23:06:08.614Z,1716419168.614 [DAT](INFO): setting verbose to 3
2024-05-22T23:06:08.865Z,1716419168.865 [DAT](INFO): DAT read: user:1>
2024-05-22T23:06:08.866Z,1716419168.866 [DAT](INFO): DAT read: Verbose | 3
2024-05-22T23:06:08.866Z,1716419168.866 [DAT](INFO): set verbose to 3
2024-05-22T23:06:08.866Z,1716419168.866 [DAT](INFO): setting DatVerbose to 27440
2024-05-22T23:06:09.117Z,1716419169.117 [DAT](INFO): DAT read: user:2>
2024-05-22T23:06:09.118Z,1716419169.118 [DAT](INFO): DAT read: DatVerbose | 27440
2024-05-22T23:06:09.118Z,1716419169.118 [DAT](INFO): set DatVerbose to 27440
2024-05-22T23:06:09.118Z,1716419169.118 [DAT](INFO): setting transmit power to 8
2024-05-22T23:06:09.369Z,1716419169.369 [DAT](INFO): DAT read: user:3>
2024-05-22T23:06:09.369Z,1716419169.369 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-05-22T23:06:09.370Z,1716419169.370 [DAT](INFO): set transmit power to 8
2024-05-22T23:06:09.370Z,1716419169.370 [DAT](INFO): setting local address to 10
2024-05-22T23:06:09.621Z,1716419169.621 [DAT](INFO): DAT read: user:4>
2024-05-22T23:06:09.622Z,1716419169.622 [DAT](INFO): DAT read: LocalAddr | 10
2024-05-22T23:06:09.622Z,1716419169.622 [DAT](INFO): set local address to 10
2024-05-22T23:06:09.623Z,1716419169.623 [DAT](INFO): Setting time to: 23:6:9 And date to:5/22/2024
2024-05-22T23:06:09.873Z,1716419169.873 [DAT](INFO): DAT read: user:5>
2024-05-22T23:06:09.874Z,1716419169.874 [DAT](INFO): DAT read: Wed May 22, 2024 23:06:09
2024-05-22T23:06:09.874Z,1716419169.874 [DAT](INFO): Local DAT time set to Wed May 22, 2024 23:06:09
2024-05-22T23:06:19.409Z,1716419179.409 [NAL9602](INFO): Powering up NAL9602
2024-05-22T23:06:22.057Z,1716419182.057 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:06:25.501Z,1716419185.501 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, 0.00
2024-05-22T23:06:30.330Z,1716419190.330 [NAL9602](INFO): NAL9602 initialized
2024-05-22T23:06:53.180Z,1716419213.180 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:07:23.239Z,1716419243.239 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:07:24.304Z,1716419244.304 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:07:25.690Z,1716419245.690 [SBIT](IMPORTANT): Beginning Startup BIT
2024-05-22T23:07:25.697Z,1716419245.697 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-22T23:07:36.165Z,1716419256.165 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:07:36.679Z,1716419256.679 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004968
CHAN A1 (24V): 0.007711
CHAN A2 (12V): -0.003968
CHAN A3 (5V): -0.001627
CHAN B0 (3.3V): 0.001138
CHAN B1 (3.15aV): 0.000207
CHAN B2 (3.15bV): 0.000564
CHAN B3 (GND): 0.001906
OPEN: 0.005044
Full Scale: +/- 1 mA
2024-05-22T23:07:49.520Z,1716419269.520 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:07:55.444Z,1716419275.444 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:08:02.425Z,1716419282.425 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:08:15.757Z,1716419295.757 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:08:19.442Z,1716419299.442 [SBIT](IMPORTANT): SBIT PASSED
2024-05-22T23:08:19.442Z,1716419299.442 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-05-22T23:08:19.443Z,1716419299.443 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;
2024-05-22T23:08:19.443Z,1716419299.443 [SBIT](IMPORTANT): Docked.detachTimeout=8 hour;
2024-05-22T23:08:19.443Z,1716419299.443 [SBIT](IMPORTANT): DockingStepper.closedValue=16500 count;
2024-05-22T23:08:19.443Z,1716419299.443 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=1 bool;
2024-05-22T23:08:19.443Z,1716419299.443 [SBIT](IMPORTANT): DockingStepper.openValue=7500 count;
2024-05-22T23:08:19.443Z,1716419299.443 [SBIT](IMPORTANT): DockingStepper.simulateHardware=0 bool;
2024-05-22T23:08:19.445Z,1716419299.445 [SBIT](IMPORTANT): DockingStepper.slideValue=12000 count;
2024-05-22T23:08:19.445Z,1716419299.445 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-05-22T23:08:19.445Z,1716419299.445 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2024-05-22T23:08:19.445Z,1716419299.445 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute;
2024-05-22T23:08:19.445Z,1716419299.445 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2024-05-22T23:08:19.445Z,1716419299.445 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=1 ampere_hour;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=486.306689 cubic_centimeter;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): VerticalControl.kpDepth=0.09 radian_per_meter;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): VerticalControl.kpPitchElevator=0.5 none;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): VerticalControl.massDefault=18.506968 millimeter;
2024-05-22T23:08:19.446Z,1716419299.446 [SBIT](IMPORTANT): VerticalControl.stopDepthExcursion=20 meter;
2024-05-22T23:08:19.823Z,1716419299.823 [MissionManager](IMPORTANT): Started mission DefaultTankUndock
2024-05-22T23:08:19.823Z,1716419299.823 [DefaultTankUndock] Running Loop=1
2024-05-22T23:08:19.823Z,1716419299.823 [DefaultTankUndock](DEBUG): Aggregate::initialize DefaultTankUndock
2024-05-22T23:08:19.823Z,1716419299.823 [DefaultTankUndock:B.GoToSurface] Running Loop=1
2024-05-22T23:08:19.823Z,1716419299.823 [DefaultTankUndock:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-22T23:08:19.824Z,1716419299.824 [DefaultTankUndock:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-22T23:08:19.824Z,1716419299.824 [DefaultTankUndock:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-22T23:08:19.825Z,1716419299.825 [DefaultTankUndock:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-22T23:08:19.825Z,1716419299.825 [DefaultTankUndock:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-22T23:08:19.825Z,1716419299.825 [DefaultTankUndock:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-05-22T23:08:19.826Z,1716419299.826 [DefaultTankUndock:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-22T23:08:19.826Z,1716419299.826 [DefaultTankUndock:A.Wait] Running Loop=1
2024-05-22T23:08:19.826Z,1716419299.826 [DefaultTankUndock:A.Wait](DEBUG): Initialize Wait Component.
2024-05-22T23:08:26.289Z,1716419306.289 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -5, +2,A
2024-05-22T23:08:26.568Z,1716419306.568 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:08:29.135Z,1716419309.135 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:08:33.203Z,1716419313.203 [DefaultTankUndock:A.Wait](INFO): Done Waiting.
2024-05-22T23:08:33.203Z,1716419313.203 [DefaultTankUndock:A.Wait] Stopped
2024-05-22T23:08:33.203Z,1716419313.203 [DefaultTankUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2024-05-22T23:08:33.583Z,1716419313.583 [DefaultTankUndock:C.Execute] Running Loop=1
2024-05-22T23:08:33.953Z,1716419313.953 [DefaultTankUndock:C.Execute](INFO): Executing command dock arm
2024-05-22T23:08:33.953Z,1716419313.953 [DefaultTankUndock:C.Execute] Stopped
2024-05-22T23:08:33.953Z,1716419313.953 [DefaultTankUndock:CheckIn] Running Loop=1
2024-05-22T23:08:33.954Z,1716419313.954 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::initialize DefaultTankUndock:CheckIn
2024-05-22T23:08:33.954Z,1716419313.954 [DefaultTankUndock:CheckIn:Read_GPS] Running Loop=1
2024-05-22T23:08:34.017Z,1716419314.017 [CommandExec](IMPORTANT): got command docking arm
2024-05-22T23:08:34.017Z,1716419314.017 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-05-22T23:08:34.372Z,1716419314.372 [DefaultTankUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-05-22T23:08:34.767Z,1716419314.767 [DockingStepper](INFO): Resume
2024-05-22T23:08:35.203Z,1716419315.203 [DockingStepper](INFO): Resuming
2024-05-22T23:08:35.606Z,1716419315.606 [DockingStepper](INFO): Start
2024-05-22T23:08:35.606Z,1716419315.606 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-22T23:08:35.606Z,1716419315.606 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-22T23:08:35.983Z,1716419315.983 [DockingStepper](INFO): Starting
2024-05-22T23:08:36.463Z,1716419316.463 [DockingStepper](INFO): Starting
2024-05-22T23:08:36.872Z,1716419316.872 [DockingStepper](INFO): Starting
2024-05-22T23:08:36.928Z,1716419316.928 [DockingStepper](INFO): Init string: /1z14831aE13561N3m50
2024-05-22T23:08:37.279Z,1716419317.279 [DockingStepper](INFO): Starting
2024-05-22T23:08:37.637Z,1716419317.637 [DockingStepper](INFO): Starting
2024-05-22T23:08:38.041Z,1716419318.041 [DockingStepper](INFO): Starting
2024-05-22T23:08:38.429Z,1716419318.429 [DockingStepper](INFO): Starting
2024-05-22T23:08:38.472Z,1716419318.472 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-22T23:08:38.807Z,1716419318.807 [DockingStepper](INFO): Starting
2024-05-22T23:08:39.211Z,1716419319.211 [DockingStepper](INFO): Changing to mode: 2
2024-05-22T23:08:39.211Z,1716419319.211 [DockingStepper](INFO): Armed mode.
2024-05-22T23:08:39.265Z,1716419319.265 [DockingStepper](INFO): Commanding negative:45043
2024-05-22T23:08:41.613Z,1716419321.613 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:08:50.932Z,1716419330.932 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-05-22T23:08:50.932Z,1716419330.932 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T23:08:51.095Z,1716419331.095 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T23:08:51.496Z,1716419331.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T23:08:51.496Z,1716419331.496 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-05-22T23:08:54.143Z,1716419334.143 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:08:57.696Z,1716419337.696 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:09:01.434Z,1716419341.434 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@
2024-05-22T23:09:01.435Z,1716419341.435 [DockingStepper](FAULT): Docking arm not reporting position
2024-05-22T23:09:01.435Z,1716419341.435 [DockingStepper] Communications Fault, FailCount= 1
2024-05-22T23:09:01.435Z,1716419341.435 [DockingStepper](ERROR): Communications Fault
2024-05-22T23:09:01.440Z,1716419341.440 [CBIT](ERROR): Communications Fault in component: DockingStepper
2024-05-22T23:09:01.854Z,1716419341.854 [DockingStepper](INFO): Stop
2024-05-22T23:09:01.854Z,1716419341.854 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-05-22T23:09:01.855Z,1716419341.855 [DockingStepper](INFO): Powering down
2024-05-22T23:09:02.239Z,1716419342.239 [DockingStepper](INFO): Stopping
2024-05-22T23:09:02.687Z,1716419342.687 [DockingStepper](INFO): Stopped
2024-05-22T23:09:02.691Z,1716419342.691 [CBIT](INFO): Clearing failed state for component DockingStepper
2024-05-22T23:09:02.692Z,1716419342.692 [DockingStepper] No Fault, FailCount= 1
2024-05-22T23:09:03.067Z,1716419343.067 [DockingStepper](INFO): Start
2024-05-22T23:09:03.067Z,1716419343.067 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-22T23:09:03.068Z,1716419343.068 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-22T23:09:03.527Z,1716419343.527 [DockingStepper](INFO): Starting
2024-05-22T23:09:03.917Z,1716419343.917 [DockingStepper](INFO): Starting
2024-05-22T23:09:04.271Z,1716419344.271 [DockingStepper](INFO): Starting
2024-05-22T23:09:04.325Z,1716419344.325 [DockingStepper](INFO): Init string: /1z7791aE13561N3m50
2024-05-22T23:09:04.714Z,1716419344.714 [DockingStepper](INFO): Starting
2024-05-22T23:09:05.076Z,1716419345.076 [DockingStepper](INFO): Starting
2024-05-22T23:09:05.471Z,1716419345.471 [DockingStepper](INFO): Starting
2024-05-22T23:09:05.911Z,1716419345.911 [DockingStepper](INFO): Starting
2024-05-22T23:09:05.950Z,1716419345.950 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-22T23:09:06.323Z,1716419346.323 [DockingStepper](INFO): Starting
2024-05-22T23:09:06.683Z,1716419346.683 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:09:19.590Z,1716419359.590 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:09:28.820Z,1716419368.820 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:09:32.921Z,1716419372.921 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-22T23:09:36.174Z,1716419376.174 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -5, +4,
2024-05-22T23:09:59.945Z,1716419399.945 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:10:12.058Z,1716419412.058 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004097
2024-05-22T23:10:13.056Z,1716419413.056 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:10:15.916Z,1716419415.916 [DockingStepper](ERROR): only read 0 of 4 ADC values. Response is: ÿ/0@
2024-05-22T23:10:15.916Z,1716419415.916 [DockingStepper](FAULT): Docking arm not reporting position
2024-05-22T23:10:15.916Z,1716419415.916 [DockingStepper] Communications Fault, FailCount= 1
2024-05-22T23:10:15.916Z,1716419415.916 [DockingStepper](ERROR): Communications Fault
2024-05-22T23:10:15.924Z,1716419415.924 [CBIT](ERROR): Communications Fault in component: DockingStepper
2024-05-22T23:10:16.291Z,1716419416.291 [DockingStepper](INFO): Stop
2024-05-22T23:10:16.291Z,1716419416.291 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-05-22T23:10:16.291Z,1716419416.291 [DockingStepper](INFO): Powering down
2024-05-22T23:10:16.743Z,1716419416.743 [DockingStepper](INFO): Stopping
2024-05-22T23:10:17.087Z,1716419417.087 [DockingStepper](INFO): Stopped
2024-05-22T23:10:17.116Z,1716419417.116 [CBIT](INFO): Clearing failed state for component DockingStepper
2024-05-22T23:10:17.116Z,1716419417.116 [DockingStepper] No Fault, FailCount= 1
2024-05-22T23:10:17.479Z,1716419417.479 [DockingStepper](INFO): Start
2024-05-22T23:10:17.484Z,1716419417.484 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-22T23:10:17.485Z,1716419417.485 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-22T23:10:17.947Z,1716419417.947 [DockingStepper](INFO): Starting
2024-05-22T23:10:18.223Z,1716419418.223 [CommandExec](IMPORTANT): got command schedule resume
2024-05-22T23:10:18.289Z,1716419418.289 [DockingStepper](INFO): Starting
2024-05-22T23:10:18.689Z,1716419418.689 [DockingStepper](INFO): Starting
2024-05-22T23:10:18.743Z,1716419418.743 [DockingStepper](INFO): Init string: /1z7775aE13561N3m50
2024-05-22T23:10:19.094Z,1716419419.094 [DockingStepper](INFO): Starting
2024-05-22T23:10:19.481Z,1716419419.481 [DockingStepper](INFO): Starting
2024-05-22T23:10:19.900Z,1716419419.900 [DockingStepper](INFO): Starting
2024-05-22T23:10:20.279Z,1716419420.279 [DockingStepper](INFO): Starting
2024-05-22T23:10:20.304Z,1716419420.304 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-22T23:10:20.704Z,1716419420.704 [DockingStepper](INFO): Starting
2024-05-22T23:10:22.483Z,1716419422.483 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:10:31.460Z,1716419431.460 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-05-22T23:10:37.817Z,1716419437.817 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/OnDock.tl
2024-05-22T23:10:37.817Z,1716419437.817 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl
2024-05-22T23:10:37.817Z,1716419437.817 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl
2024-05-22T23:10:37.819Z,1716419437.819 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl
2024-05-22T23:10:38.407Z,1716419438.407 [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-22T23:10:38.417Z,1716419438.417 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h
2024-05-22T23:10:38.421Z,1716419438.421 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 28800.000000 s
2024-05-22T23:10:38.425Z,1716419438.425 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s
2024-05-22T23:10:38.429Z,1716419438.429 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min
2024-05-22T23:10:38.433Z,1716419438.433 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count
2024-05-22T23:10:38.446Z,1716419438.446 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 201 count
2024-05-22T23:10:38.450Z,1716419438.450 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m
2024-05-22T23:10:38.453Z,1716419438.453 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m
2024-05-22T23:10:38.489Z,1716419438.489 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km
2024-05-22T23:10:38.501Z,1716419438.501 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool
2024-05-22T23:10:38.512Z,1716419438.512 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 30.000000 min
2024-05-22T23:10:38.515Z,1716419438.515 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h
2024-05-22T23:10:38.524Z,1716419438.524 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2024-05-22T23:10:38.524Z,1716419438.524 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2024-05-22T23:10:38.525Z,1716419438.525 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2024-05-22T23:10:38.733Z,1716419438.733 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2024-05-22T23:10:38.742Z,1716419438.742 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m
2024-05-22T23:10:38.750Z,1716419438.750 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2024-05-22T23:10:38.781Z,1716419438.781 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m
2024-05-22T23:10:38.789Z,1716419438.789 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m
2024-05-22T23:10:38.790Z,1716419438.790 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2024-05-22T23:10:38.807Z,1716419438.807 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2024-05-22T23:10:38.837Z,1716419438.837 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2024-05-22T23:10:38.924Z,1716419438.924 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-05-22T23:10:38.924Z,1716419438.924 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-05-22T23:10:38.925Z,1716419438.925 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-05-22T23:10:38.999Z,1716419438.999 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-05-22T23:10:39.060Z,1716419439.060 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool
2024-05-22T23:10:39.075Z,1716419439.075 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-05-22T23:10:39.082Z,1716419439.082 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2024-05-22T23:10:39.083Z,1716419439.083 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2024-05-22T23:10:39.088Z,1716419439.088 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2024-05-22T23:10:39.328Z,1716419439.328 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2024-05-22T23:10:39.334Z,1716419439.334 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool
2024-05-22T23:10:39.346Z,1716419439.346 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool
2024-05-22T23:10:39.362Z,1716419439.362 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool
2024-05-22T23:10:39.367Z,1716419439.367 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool
2024-05-22T23:10:39.395Z,1716419439.395 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool
2024-05-22T23:10:39.417Z,1716419439.417 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait.
2024-05-22T23:10:39.566Z,1716419439.566 [OnDock:L.Execute](DEBUG): Construct Execute.
2024-05-22T23:10:39.574Z,1716419439.574 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute.
2024-05-22T23:10:39.575Z,1716419439.575 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait.
2024-05-22T23:10:39.589Z,1716419439.589 [OnDock:O.Docked](DEBUG): Construct.
2024-05-22T23:10:39.590Z,1716419439.590 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values.
2024-05-22T23:10:39.682Z,1716419439.682 [OnDock:Detach:B.Undock](DEBUG): Construct.
2024-05-22T23:10:39.682Z,1716419439.682 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values.
2024-05-22T23:10:39.766Z,1716419439.766 [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-22T23:10:39.776Z,1716419439.776 [CommandExec](IMPORTANT): Running ./Missions/Engineering/OnDock.tl
2024-05-22T23:10:39.819Z,1716419439.819 [DefaultTankUndock] Stopped
2024-05-22T23:10:39.819Z,1716419439.819 [DefaultTankUndock](DEBUG): Aggregate::uninitialize DefaultTankUndock
2024-05-22T23:10:39.819Z,1716419439.819 [DefaultTankUndock:B.GoToSurface] Stopped
2024-05-22T23:10:39.819Z,1716419439.819 [DefaultTankUndock:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-22T23:10:39.819Z,1716419439.819 [DefaultTankUndock:CheckIn] Stopped
2024-05-22T23:10:39.819Z,1716419439.819 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultTankUndock:CheckIn
2024-05-22T23:10:39.819Z,1716419439.819 [DefaultTankUndock:CheckIn:Read_GPS] Stopped
2024-05-22T23:10:39.819Z,1716419439.819 [MissionManager](IMPORTANT): Started mission OnDock
2024-05-22T23:10:39.819Z,1716419439.819 [OnDock] Running Loop=1
2024-05-22T23:10:39.824Z,1716419439.824 [OnDock](DEBUG): Aggregate::initialize OnDock
2024-05-22T23:10:39.824Z,1716419439.824 [OnDock:StandardEnvelopes] Running Loop=1
2024-05-22T23:10:39.824Z,1716419439.824 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes
2024-05-22T23:10:39.824Z,1716419439.824 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-05-22T23:10:39.824Z,1716419439.824 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2024-05-22T23:10:39.824Z,1716419439.824 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-05-22T23:10:39.824Z,1716419439.824 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:BackseatDriver] Running Loop=1
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:PowerOnly] Running Loop=1
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:PowerOnly:A] Running Loop=1
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:PowerOnly:B] Running Loop=1
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:PowerOnly:C] Running Loop=1
2024-05-22T23:10:39.825Z,1716419439.825 [OnDock:PowerOnly:D] Running Loop=1
2024-05-22T23:10:39.826Z,1716419439.826 [OnDock:PowerOnly:E.Wait] Running Loop=1
2024-05-22T23:10:39.826Z,1716419439.826 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2024-05-22T23:10:39.826Z,1716419439.826 [OnDock:H] Running Loop=1
2024-05-22T23:10:39.826Z,1716419439.826 [OnDock:I.] Running Loop=1
2024-05-22T23:10:39.826Z,1716419439.826 [OnDock:I.](INFO): Initializing TrackAcousticContact.
2024-05-22T23:10:39.826Z,1716419439.826 [OnDock:PeriodicCheck] Running Loop=1
2024-05-22T23:10:39.826Z,1716419439.826 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck
2024-05-22T23:10:39.826Z,1716419439.826 [OnDock:PeriodicCheck:CheckIP] Running Loop=1
2024-05-22T23:10:39.827Z,1716419439.827 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP
2024-05-22T23:10:39.827Z,1716419439.827 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1
2024-05-22T23:10:39.827Z,1716419439.827 [OnDock:B] Running Loop=1
2024-05-22T23:10:39.827Z,1716419439.827 [OnDock:PeriodicCheck] Running Loop=1
2024-05-22T23:10:39.827Z,1716419439.827 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig
2024-05-22T23:10:39.827Z,1716419439.827 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped
2024-05-22T23:10:39.828Z,1716419439.828 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1
2024-05-22T23:10:39.832Z,1716419439.832 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component.
2024-05-22T23:10:39.832Z,1716419439.832 [OnDock:I.] Running Loop=1
2024-05-22T23:10:39.833Z,1716419439.833 [OnDock:H] Running Loop=1
2024-05-22T23:10:39.833Z,1716419439.833 [OnDock:H](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-22T23:10:39.833Z,1716419439.833 [OnDock:PowerOnly] Running Loop=1
2024-05-22T23:10:39.834Z,1716419439.834 [OnDock:PowerOnly:D] Stopped
2024-05-22T23:10:39.834Z,1716419439.834 [OnDock:PowerOnly:C] Stopped
2024-05-22T23:10:39.834Z,1716419439.834 [OnDock:PowerOnly:B] Stopped
2024-05-22T23:10:39.834Z,1716419439.834 [OnDock:PowerOnly:A] Stopped
2024-05-22T23:10:39.834Z,1716419439.834 [OnDock:BackseatDriver] Running Loop=1
2024-05-22T23:10:39.835Z,1716419439.835 [OnDock:B] Stopped
2024-05-22T23:10:39.835Z,1716419439.835 [OnDock:C] Running Loop=1
2024-05-22T23:10:39.835Z,1716419439.835 [OnDock:StandardEnvelopes] Running Loop=1
2024-05-22T23:10:39.835Z,1716419439.835 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-05-22T23:10:39.835Z,1716419439.835 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2024-05-22T23:10:39.835Z,1716419439.835 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-05-22T23:10:39.841Z,1716419439.841 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-05-22T23:10:39.881Z,1716419439.881 [CommandExec](IMPORTANT): got command ! ifconfig
2024-05-22T23:10:40.119Z,1716419440.119 [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:6661 errors:0 dropped:0 overruns:0 frame:0
TX packets:7177 errors:0 dropped:0 overruns:0 carrier:0
collisions:12 txqueuelen:1000
RX bytes:2723826 (2.5 Mb) TX bytes:1558114 (1.4 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:375 errors:0 dropped:0 overruns:0 frame:0
TX packets:375 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:30164 (29.4 Kb) TX bytes:30164 (29.4 Kb)
2024-05-22T23:10:40.789Z,1716419440.789 [OnDock:C] Stopped
2024-05-22T23:10:40.789Z,1716419440.789 [OnDock:D] Running Loop=1
2024-05-22T23:10:40.789Z,1716419440.789 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2024-05-22T23:10:41.200Z,1716419441.200 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, 11, -2,A
2024-05-22T23:10:41.239Z,1716419441.239 [OnDock:D] Stopped
2024-05-22T23:10:41.239Z,1716419441.239 [OnDock:E] Running Loop=1
2024-05-22T23:10:41.239Z,1716419441.239 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2024-05-22T23:10:41.594Z,1716419441.594 [OnDock:E] Stopped
2024-05-22T23:10:41.594Z,1716419441.594 [OnDock:K] Running Loop=1
2024-05-22T23:10:41.594Z,1716419441.594 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2024-05-22T23:10:41.982Z,1716419441.982 [OnDock:K](IMPORTANT): Restarting logs
2024-05-22T23:10:41.982Z,1716419441.982 [OnDock:K] Stopped
2024-05-22T23:10:41.982Z,1716419441.982 [OnDock:L.Execute] Running Loop=1
2024-05-22T23:10:41.983Z,1716419441.983 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.