2024-06-24T19:48:21.566Z,1719258501.566 [Supervisor](DEBUG): Initializing supervisor.
2024-06-24T19:48:21.572Z,1719258501.572 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-06-24T19:48:21.572Z,1719258501.572 [SyncHandler](INFO): Protected caller Thread ID is 3288
2024-06-24T19:48:21.573Z,1719258501.573 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-06-24T19:48:21.574Z,1719258501.574 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-06-24T19:48:21.575Z,1719258501.575 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3289
2024-06-24T19:48:21.579Z,1719258501.579 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-06-24T19:48:21.597Z,1719258501.597 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-06-24T19:48:21.598Z,1719258501.598 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-06-24T19:48:21.598Z,1719258501.598 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3290
2024-06-24T19:48:21.602Z,1719258501.602 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-06-24T19:48:21.603Z,1719258501.603 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-06-24T19:48:21.604Z,1719258501.604 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3291
2024-06-24T19:48:21.606Z,1719258501.606 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-06-24T19:48:21.607Z,1719258501.607 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-06-24T19:48:21.607Z,1719258501.607 [logger ThreadHandler](INFO): Protected caller Thread ID is 3292
2024-06-24T19:48:21.611Z,1719258501.611 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-06-24T19:48:21.611Z,1719258501.611 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-06-24T19:48:21.613Z,1719258501.613 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-06-24T19:48:21.728Z,1719258501.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-06-24T19:48:21.729Z,1719258501.729 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-06-24T19:48:22.552Z,1719258502.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-06-24T19:48:22.552Z,1719258502.552 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-06-24T19:48:22.810Z,1719258502.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-06-24T19:48:22.811Z,1719258502.811 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-06-24T19:48:22.904Z,1719258502.904 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-06-24T19:48:23.052Z,1719258503.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-06-24T19:48:23.052Z,1719258503.052 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-06-24T19:48:23.148Z,1719258503.148 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-06-24T19:48:23.271Z,1719258503.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-06-24T19:48:23.272Z,1719258503.272 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-06-24T19:48:23.704Z,1719258503.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-06-24T19:48:23.704Z,1719258503.704 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-06-24T19:48:24.285Z,1719258504.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-06-24T19:48:24.286Z,1719258504.286 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-06-24T19:48:24.529Z,1719258504.529 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-06-24T19:48:24.530Z,1719258504.530 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-06-24T19:48:24.742Z,1719258504.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-06-24T19:48:24.742Z,1719258504.742 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-06-24T19:48:25.949Z,1719258505.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-06-24T19:48:25.950Z,1719258505.950 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-06-24T19:48:26.907Z,1719258506.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-06-24T19:48:26.908Z,1719258506.908 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-06-24T19:48:27.297Z,1719258507.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-06-24T19:48:27.298Z,1719258507.298 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-06-24T19:48:27.707Z,1719258507.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-06-24T19:48:27.707Z,1719258507.707 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-06-24T19:48:28.090Z,1719258508.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-06-24T19:48:28.092Z,1719258508.092 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2024-06-24T19:48:28.093Z,1719258508.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2024-06-24T19:48:28.192Z,1719258508.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2024-06-24T19:48:28.381Z,1719258508.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2024-06-24T19:48:28.491Z,1719258508.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2024-06-24T19:48:28.616Z,1719258508.616 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2024-06-24T19:48:28.716Z,1719258508.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2024-06-24T19:48:28.827Z,1719258508.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2024-06-24T19:48:28.956Z,1719258508.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2024-06-24T19:48:29.240Z,1719258509.240 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-06-24T19:48:29.240Z,1719258509.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2024-06-24T19:48:29.350Z,1719258509.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2024-06-24T19:48:29.458Z,1719258509.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2024-06-24T19:48:29.619Z,1719258509.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2024-06-24T19:48:29.735Z,1719258509.735 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2024-06-24T19:48:29.735Z,1719258509.735 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-06-24T19:48:29.751Z,1719258509.751 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-06-24T19:48:29.868Z,1719258509.868 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-06-24T19:48:29.868Z,1719258509.868 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-06-24T19:48:29.923Z,1719258509.923 [VerticalControl](DEBUG): Construct VerticalControl.
2024-06-24T19:48:29.982Z,1719258509.982 [VerticalControl] Loaded
2024-06-24T19:48:29.982Z,1719258509.982 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-06-24T19:48:29.985Z,1719258509.985 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-06-24T19:48:30.031Z,1719258510.031 [HorizontalControl] Loaded
2024-06-24T19:48:30.032Z,1719258510.032 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-06-24T19:48:30.034Z,1719258510.034 [SpeedControl](DEBUG): Construct SpeedControl.
2024-06-24T19:48:30.037Z,1719258510.037 [SpeedControl] Loaded
2024-06-24T19:48:30.037Z,1719258510.037 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-06-24T19:48:30.040Z,1719258510.040 [LoopControl](DEBUG): Construct LoopControl.
2024-06-24T19:48:30.040Z,1719258510.040 [LoopControl] Loaded
2024-06-24T19:48:30.040Z,1719258510.040 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-06-24T19:48:30.041Z,1719258510.041 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-06-24T19:48:30.041Z,1719258510.041 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-06-24T19:48:30.092Z,1719258510.092 [DepthRateCalculator] Loaded
2024-06-24T19:48:30.092Z,1719258510.092 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-06-24T19:48:30.097Z,1719258510.097 [PitchRateCalculator] Loaded
2024-06-24T19:48:30.097Z,1719258510.097 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-06-24T19:48:30.107Z,1719258510.107 [SpeedCalculator] Loaded
2024-06-24T19:48:30.107Z,1719258510.107 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-06-24T19:48:30.112Z,1719258510.112 [YawRateCalculator] Loaded
2024-06-24T19:48:30.112Z,1719258510.112 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-06-24T19:48:30.130Z,1719258510.130 [ElevatorOffsetCalculator] Loaded
2024-06-24T19:48:30.130Z,1719258510.130 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-06-24T19:48:30.131Z,1719258510.131 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-06-24T19:48:30.131Z,1719258510.131 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-06-24T19:48:30.159Z,1719258510.159 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-06-24T19:48:30.159Z,1719258510.159 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-06-24T19:48:30.215Z,1719258510.215 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-06-24T19:48:30.216Z,1719258510.216 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-06-24T19:48:30.562Z,1719258510.562 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-06-24T19:48:30.562Z,1719258510.562 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-06-24T19:48:30.739Z,1719258510.739 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-06-24T19:48:30.739Z,1719258510.739 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-06-24T19:48:31.212Z,1719258511.212 [AHRS_M2] Loaded
2024-06-24T19:48:31.212Z,1719258511.212 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-06-24T19:48:31.251Z,1719258511.251 [BackseatComponent] Loaded
2024-06-24T19:48:31.252Z,1719258511.252 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-06-24T19:48:31.253Z,1719258511.253 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C54E0
2024-06-24T19:48:31.253Z,1719258511.253 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3377
2024-06-24T19:48:31.256Z,1719258511.256 [LcmUniversalReporter] Loaded
2024-06-24T19:48:31.256Z,1719258511.256 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-06-24T19:48:32.622Z,1719258512.622 [BPC1] Loaded
2024-06-24T19:48:32.622Z,1719258512.622 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-06-24T19:48:32.695Z,1719258512.695 [DataOverHttps] Loaded
2024-06-24T19:48:32.695Z,1719258512.695 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-06-24T19:48:32.696Z,1719258512.696 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F54E0
2024-06-24T19:48:32.697Z,1719258512.697 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3378
2024-06-24T19:48:32.720Z,1719258512.720 [Depth_Keller] Loaded
2024-06-24T19:48:32.720Z,1719258512.720 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-06-24T19:48:32.725Z,1719258512.725 [DropWeight] Loaded
2024-06-24T19:48:32.725Z,1719258512.725 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-06-24T19:48:32.789Z,1719258512.789 [NAL9602] Loaded
2024-06-24T19:48:32.790Z,1719258512.790 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-06-24T19:48:32.820Z,1719258512.820 [Onboard] Loaded
2024-06-24T19:48:32.820Z,1719258512.820 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-06-24T19:48:32.821Z,1719258512.821 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A254E0
2024-06-24T19:48:32.821Z,1719258512.821 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3379
2024-06-24T19:48:32.835Z,1719258512.835 [Power24vConverter] Loaded
2024-06-24T19:48:32.836Z,1719258512.836 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-06-24T19:48:32.852Z,1719258512.852 [Radio_Surface] Loaded
2024-06-24T19:48:32.852Z,1719258512.852 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-06-24T19:48:32.853Z,1719258512.853 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0
2024-06-24T19:48:32.853Z,1719258512.853 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3380
2024-06-24T19:48:32.901Z,1719258512.901 [RDI_Pathfinder] Loaded
2024-06-24T19:48:32.901Z,1719258512.901 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2024-06-24T19:48:32.916Z,1719258512.916 [Sonardyne_Nano] Loaded
2024-06-24T19:48:32.916Z,1719258512.916 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-06-24T19:48:33.012Z,1719258513.012 [DAT] Loaded
2024-06-24T19:48:33.012Z,1719258513.012 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-06-24T19:48:33.013Z,1719258513.013 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2024-06-24T19:48:33.013Z,1719258513.013 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3381
2024-06-24T19:48:33.014Z,1719258513.014 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-06-24T19:48:33.015Z,1719258513.015 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-06-24T19:48:33.167Z,1719258513.167 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-06-24T19:48:33.167Z,1719258513.167 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-06-24T19:48:33.180Z,1719258513.180 [NavChart] Loaded
2024-06-24T19:48:33.181Z,1719258513.181 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-06-24T19:48:33.186Z,1719258513.186 [UniversalFixResidualReporter] Loaded
2024-06-24T19:48:33.186Z,1719258513.186 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-06-24T19:48:33.187Z,1719258513.187 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-06-24T19:48:33.187Z,1719258513.187 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-06-24T19:48:33.331Z,1719258513.331 [SBIT](DEBUG): Construct Startup Built In Test.
2024-06-24T19:48:33.340Z,1719258513.340 [SBIT] Loaded
2024-06-24T19:48:33.340Z,1719258513.340 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-06-24T19:48:33.343Z,1719258513.343 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-06-24T19:48:33.356Z,1719258513.356 [IBIT] Loaded
2024-06-24T19:48:33.356Z,1719258513.356 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-06-24T19:48:33.362Z,1719258513.362 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-06-24T19:48:33.467Z,1719258513.467 [CBIT] Loaded
2024-06-24T19:48:33.467Z,1719258513.467 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-06-24T19:48:33.468Z,1719258513.468 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-06-24T19:48:33.468Z,1719258513.468 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-06-24T19:48:33.604Z,1719258513.604 [BuoyancyServo] Loaded
2024-06-24T19:48:33.604Z,1719258513.604 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-06-24T19:48:33.627Z,1719258513.627 [ElevatorServo] Loaded
2024-06-24T19:48:33.628Z,1719258513.628 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-06-24T19:48:33.657Z,1719258513.657 [DockingStepper] Loaded
2024-06-24T19:48:33.658Z,1719258513.658 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2024-06-24T19:48:33.679Z,1719258513.679 [MassServo] Loaded
2024-06-24T19:48:33.680Z,1719258513.680 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-06-24T19:48:33.700Z,1719258513.700 [RudderServo] Loaded
2024-06-24T19:48:33.700Z,1719258513.700 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-06-24T19:48:33.719Z,1719258513.719 [ThrusterServo] Loaded
2024-06-24T19:48:33.719Z,1719258513.719 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2024-06-24T19:48:33.720Z,1719258513.720 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-06-24T19:48:33.720Z,1719258513.720 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-06-24T19:48:33.731Z,1719258513.731 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-06-24T19:48:33.731Z,1719258513.731 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-06-24T19:48:33.907Z,1719258513.907 [CTD_Seabird] Loaded
2024-06-24T19:48:33.908Z,1719258513.908 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-06-24T19:48:33.909Z,1719258513.909 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BDA4E0
2024-06-24T19:48:33.909Z,1719258513.909 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3382
2024-06-24T19:48:33.932Z,1719258513.932 [PAR_Licor] Loaded
2024-06-24T19:48:33.932Z,1719258513.932 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-06-24T19:48:33.976Z,1719258513.976 [WetLabsBB2FL] Loaded
2024-06-24T19:48:33.977Z,1719258513.977 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-06-24T19:48:33.978Z,1719258513.978 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C0A4E0
2024-06-24T19:48:33.978Z,1719258513.978 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3383
2024-06-24T19:48:33.000Z,1719258514.000 [WetLabsUBAT] Loaded
2024-06-24T19:48:33.000Z,1719258514.000 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2024-06-24T19:48:34.001Z,1719258514.001 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C3A4E0
2024-06-24T19:48:34.001Z,1719258514.001 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 3384
2024-06-24T19:48:34.002Z,1719258514.002 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-06-24T19:48:34.008Z,1719258514.008 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-06-24T19:48:34.011Z,1719258514.011 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-06-24T19:48:34.023Z,1719258514.023 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-06-24T19:48:34.024Z,1719258514.024 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6A4E0
2024-06-24T19:48:34.024Z,1719258514.024 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3385
2024-06-24T19:48:34.029Z,1719258514.029 [Supervisor](INFO): Main Thread ID is 2131
2024-06-24T19:48:34.029Z,1719258514.029 [Supervisor](DEBUG): Running supervisor.
2024-06-24T19:48:34.029Z,1719258514.029 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3386
2024-06-24T19:48:34.030Z,1719258514.030 [CommandExec](INFO): Initializing the command executive.
2024-06-24T19:48:34.031Z,1719258514.031 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3387
2024-06-24T19:48:34.034Z,1719258514.034 [controlThread ThreadHandler](INFO): Handler Thread ID is 3388
2024-06-24T19:48:34.034Z,1719258514.034 [controlThread](DEBUG): Initializing ControlThread
2024-06-24T19:48:34.035Z,1719258514.035 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-06-24T19:48:34.037Z,1719258514.037 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-06-24T19:48:34.037Z,1719258514.037 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-06-24T19:48:34.038Z,1719258514.038 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-06-24T19:48:34.038Z,1719258514.038 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-06-24T19:48:34.038Z,1719258514.038 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-06-24T19:48:34.039Z,1719258514.039 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-06-24T19:48:34.039Z,1719258514.039 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-06-24T19:48:34.039Z,1719258514.039 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-06-24T19:48:34.045Z,1719258514.045 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-06-24T19:48:34.045Z,1719258514.045 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-06-24T19:48:34.045Z,1719258514.045 [SBIT](INFO): Initialize SBIT Component.
2024-06-24T19:48:34.046Z,1719258514.046 [SBIT](IMPORTANT): git: 2024-06-14_A
2024-06-24T19:48:34.046Z,1719258514.046 [SBIT](INFO): git hash: 90384bcf3876045306bf8b12c7409f70f96e52e4
2024-06-24T19:48:34.046Z,1719258514.046 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-06-24T19:48:34.047Z,1719258514.047 [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-06-24T19:48:34.048Z,1719258514.048 [SBIT](INFO): Beginning SBIT in 107.000000 seconds.
2024-06-24T19:48:34.049Z,1719258514.049 [IBIT](INFO): Initialize IBIT Component.
2024-06-24T19:48:34.050Z,1719258514.050 [CBIT](DEBUG): Initialize CBIT Component.
2024-06-24T19:48:34.051Z,1719258514.051 [logger ThreadHandler](INFO): Handler Thread ID is 3389
2024-06-24T19:48:34.062Z,1719258514.062 [CBIT](DEBUG): Initialized mux pins.
2024-06-24T19:48:34.062Z,1719258514.062 [CBIT](DEBUG): Initializing the watchdog timer.
2024-06-24T19:48:34.070Z,1719258514.070 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3390
2024-06-24T19:48:34.082Z,1719258514.082 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3391
2024-06-24T19:48:34.084Z,1719258514.084 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-06-24T19:48:34.086Z,1719258514.086 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-06-24T19:48:34.086Z,1719258514.086 [CBIT](DEBUG): Initializing heartbeat.
2024-06-24T19:48:34.094Z,1719258514.094 [Onboard ThreadHandler](INFO): Handler Thread ID is 3392
2024-06-24T19:48:34.118Z,1719258514.118 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3393
2024-06-24T19:48:34.135Z,1719258514.135 [DAT ThreadHandler](INFO): Handler Thread ID is 3394
2024-06-24T19:48:34.135Z,1719258514.135 [DAT](INFO): Powering up
2024-06-24T19:48:34.136Z,1719258514.136 [DAT](DEBUG): Initializing DAT.
2024-06-24T19:48:34.139Z,1719258514.139 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3396
2024-06-24T19:48:34.140Z,1719258514.140 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-06-24T19:48:34.143Z,1719258514.143 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3398
2024-06-24T19:48:34.147Z,1719258514.147 [WetLabsBB2FL](INFO): Powering up
2024-06-24T19:48:34.148Z,1719258514.148 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 3400
2024-06-24T19:48:34.151Z,1719258514.151 [WetLabsUBAT](INFO): Powering up
2024-06-24T19:48:34.152Z,1719258514.152 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3402
2024-06-24T19:48:34.156Z,1719258514.156 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-06-24T19:48:34.156Z,1719258514.156 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-06-24T19:48:34.156Z,1719258514.156 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-06-24T19:48:34.156Z,1719258514.156 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-06-24T19:48:34.156Z,1719258514.156 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-06-24T19:48:34.157Z,1719258514.157 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-06-24T19:48:34.157Z,1719258514.157 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-06-24T19:48:34.157Z,1719258514.157 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-06-24T19:48:34.158Z,1719258514.158 [CBIT](DEBUG): Deactivating GF circuits.
2024-06-24T19:48:34.158Z,1719258514.158 [CBIT](DEBUG): Deactivating emergency mode.
2024-06-24T19:48:34.198Z,1719258514.198 [CBIT](DEBUG): Backplane powered.
2024-06-24T19:48:34.203Z,1719258514.203 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-06-24T19:48:34.203Z,1719258514.203 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-06-24T19:48:34.204Z,1719258514.204 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-06-24T19:48:34.205Z,1719258514.205 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-06-24T19:48:34.270Z,1719258514.270 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-06-24T19:48:34.291Z,1719258514.291 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-24T19:48:34.315Z,1719258514.315 [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 Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-06-24T19:48:34.315Z,1719258514.315 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-06-24T19:48:34.316Z,1719258514.316 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-06-24T19:48:34.317Z,1719258514.317 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-06-24T19:48:34.522Z,1719258514.522 [Radio_Surface](INFO): Powering up
2024-06-24T19:48:34.630Z,1719258514.630 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-06-24T19:48:34.636Z,1719258514.636 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-06-24T19:48:34.651Z,1719258514.651 [Default:A.Wait](DEBUG): Construct Wait.
2024-06-24T19:48:34.653Z,1719258514.653 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-24T19:48:34.702Z,1719258514.702 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-06-24T19:48:34.705Z,1719258514.705 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-06-24T19:48:34.723Z,1719258514.723 [Default:E.Execute](DEBUG): Construct Execute.
2024-06-24T19:48:34.746Z,1719258514.746 [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 Default {
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
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
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-06-24T19:48:34.751Z,1719258514.751 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,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-06-24T19:48:34.758Z,1719258514.758 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-06-24T19:48:34.836Z,1719258514.836 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m
2024-06-24T19:48:34.837Z,1719258514.837 [Power24vConverter](INFO): Powering up.
2024-06-24T19:48:34.838Z,1719258514.838 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2024-06-24T19:48:34.883Z,1719258514.883 [Sonardyne_Nano](INFO): Initializing.
2024-06-24T19:48:34.930Z,1719258514.930 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-06-24T19:48:34.956Z,1719258514.956 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:48:34.962Z,1719258514.962 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-06-24T19:48:34.963Z,1719258514.963 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:48:34.970Z,1719258514.970 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-24T19:48:34.971Z,1719258514.971 [DockingStepper](INFO): Start
2024-06-24T19:48:34.971Z,1719258514.971 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-24T19:48:34.978Z,1719258514.978 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-24T19:48:34.979Z,1719258514.979 [MassServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:48:34.986Z,1719258514.986 [MassServo](DEBUG): Initializing MassServo.
2024-06-24T19:48:34.987Z,1719258514.987 [RudderServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:48:34.994Z,1719258514.994 [RudderServo](DEBUG): Initializing RudderServo.
2024-06-24T19:48:34.995Z,1719258514.995 [ThrusterServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:48:35.002Z,1719258515.002 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2024-06-24T19:48:35.195Z,1719258515.195 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-06-24T19:48:35.195Z,1719258515.195 [DropWeight] Hardware Fault, FailCount= 1
2024-06-24T19:48:35.195Z,1719258515.195 [DropWeight](ERROR): Hardware Fault
2024-06-24T19:48:35.207Z,1719258515.207 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-06-24T19:48:35.222Z,1719258515.222 [DockingStepper](INFO): Starting
2024-06-24T19:48:35.224Z,1719258515.224 [CommandExec](FAULT): Scheduling is paused
2024-06-24T19:48:35.224Z,1719258515.224 [CBIT](INFO): Critical error at 20240624T194835
2024-06-24T19:48:35.225Z,1719258515.225 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-06-24T19:48:35.235Z,1719258515.235 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-06-24T19:48:35.235Z,1719258515.235 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-06-24T19:48:35.585Z,1719258515.585 [DockingStepper](INFO): Starting
2024-06-24T19:48:35.591Z,1719258515.591 [CBIT](INFO): Critical error at 20240624T194835
2024-06-24T19:48:36.111Z,1719258516.111 [DockingStepper](INFO): Starting
2024-06-24T19:48:36.163Z,1719258516.163 [DockingStepper](INFO): Init string: /1z7632aE13561N3m50
2024-06-24T19:48:36.402Z,1719258516.402 [DockingStepper](INFO): Starting
2024-06-24T19:48:36.758Z,1719258516.758 [WetLabsBB2FL](INFO): Powering down
2024-06-24T19:48:36.818Z,1719258516.818 [DockingStepper](INFO): Starting
2024-06-24T19:48:37.185Z,1719258517.185 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-06-24T19:48:37.250Z,1719258517.250 [DockingStepper](INFO): Starting
2024-06-24T19:48:37.654Z,1719258517.654 [DockingStepper](INFO): Starting
2024-06-24T19:48:37.679Z,1719258517.679 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-24T19:48:38.191Z,1719258518.191 [DockingStepper](INFO): Starting
2024-06-24T19:48:38.435Z,1719258518.435 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103
2024-06-24T19:48:38.550Z,1719258518.550 [DockingStepper](INFO): Commanding positive:54599
2024-06-24T19:48:38.655Z,1719258518.655 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:39.039Z,1719258519.039 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:39.559Z,1719258519.559 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:39.778Z,1719258519.778 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2024-06-24T19:48:39.779Z,1719258519.779 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2024-06-24T19:48:39.959Z,1719258519.959 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:40.435Z,1719258520.435 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:40.791Z,1719258520.791 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:41.239Z,1719258521.239 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:41.611Z,1719258521.611 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:42.123Z,1719258522.123 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:42.443Z,1719258522.443 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:42.899Z,1719258522.899 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:43.296Z,1719258523.296 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:43.411Z,1719258523.411 [WetLabsUBAT](INFO): Powering down
2024-06-24T19:48:43.663Z,1719258523.663 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:1
2024-06-24T19:48:44.071Z,1719258524.071 [DockingStepper](INFO): isNeeded returning:0 cmdArmValue:16500 Mode:1
2024-06-24T19:48:44.265Z,1719258524.265 [DockingStepper](INFO): Pause
2024-06-24T19:48:46.738Z,1719258526.738 [DAT](INFO): DAT read:
2024-06-24T19:48:46.740Z,1719258526.740 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-06-24T19:48:48.503Z,1719258528.503 [DAT](INFO): DAT read: MF Frequency Band
2024-06-24T19:48:48.504Z,1719258528.504 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2024-06-24T19:48:48.505Z,1719258528.505 [DAT](INFO): DAT read: Jun 24 2024 19:48:42
2024-06-24T19:48:49.513Z,1719258529.513 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-06-24T19:48:49.763Z,1719258529.763 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-06-24T19:48:49.764Z,1719258529.764 [DAT](INFO): commRate: 800
2024-06-24T19:48:51.830Z,1719258531.830 [DAT](INFO): entering command mode
2024-06-24T19:48:52.031Z,1719258532.031 [DAT](INFO): DAT read:
user:1>
2024-06-24T19:48:52.031Z,1719258532.031 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:52.282Z,1719258532.282 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:52.534Z,1719258532.534 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:52.786Z,1719258532.786 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:53.038Z,1719258533.038 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:53.290Z,1719258533.290 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:53.543Z,1719258533.543 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:53.794Z,1719258533.794 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:54.046Z,1719258534.046 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:54.298Z,1719258534.298 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:54.550Z,1719258534.550 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:54.802Z,1719258534.802 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:55.054Z,1719258535.054 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:55.306Z,1719258535.306 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:55.559Z,1719258535.559 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:55.810Z,1719258535.810 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:56.062Z,1719258536.062 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:56.314Z,1719258536.314 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:56.566Z,1719258536.566 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:56.819Z,1719258536.819 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:57.071Z,1719258537.071 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:57.323Z,1719258537.323 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:57.574Z,1719258537.574 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:57.826Z,1719258537.826 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:58.078Z,1719258538.078 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:58.330Z,1719258538.330 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:58.582Z,1719258538.582 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:58.834Z,1719258538.834 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:59.086Z,1719258539.086 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:59.338Z,1719258539.338 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:59.590Z,1719258539.590 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:48:59.842Z,1719258539.842 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:00.094Z,1719258540.094 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:00.348Z,1719258540.348 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:00.599Z,1719258540.599 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:00.851Z,1719258540.851 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:01.102Z,1719258541.102 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:01.354Z,1719258541.354 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:01.606Z,1719258541.606 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:01.858Z,1719258541.858 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:02.110Z,1719258542.110 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:02.364Z,1719258542.364 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:02.614Z,1719258542.614 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:02.866Z,1719258542.866 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:03.118Z,1719258543.118 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:03.183Z,1719258543.183 [NAL9602](INFO): Powering up NAL9602
2024-06-24T19:49:03.319Z,1719258543.319 [CommandExec](IMPORTANT): got command docking arm
2024-06-24T19:49:03.319Z,1719258543.319 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-06-24T19:49:03.371Z,1719258543.371 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:03.615Z,1719258543.615 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:03.639Z,1719258543.639 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:03.890Z,1719258543.890 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:04.016Z,1719258544.016 [DockingStepper](INFO): Resume
2024-06-24T19:49:04.144Z,1719258544.144 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:04.396Z,1719258544.396 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:04.531Z,1719258544.531 [DockingStepper](INFO): Resuming
2024-06-24T19:49:04.646Z,1719258544.646 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:04.844Z,1719258544.844 [DockingStepper](INFO): Start
2024-06-24T19:49:04.844Z,1719258544.844 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-24T19:49:04.898Z,1719258544.898 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:04.959Z,1719258544.959 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-24T19:49:05.150Z,1719258545.150 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:05.206Z,1719258545.206 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:49:05.268Z,1719258545.268 [DockingStepper](INFO): Starting
2024-06-24T19:49:05.403Z,1719258545.403 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:05.654Z,1719258545.654 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:05.673Z,1719258545.673 [DockingStepper](INFO): Starting
2024-06-24T19:49:05.906Z,1719258545.906 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:06.062Z,1719258546.062 [DockingStepper](INFO): Starting
2024-06-24T19:49:06.114Z,1719258546.114 [DockingStepper](INFO): Init string: /1z16366aE13561N3m50
2024-06-24T19:49:06.158Z,1719258546.158 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:06.412Z,1719258546.412 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:06.516Z,1719258546.516 [DockingStepper](INFO): Starting
2024-06-24T19:49:06.662Z,1719258546.662 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:06.865Z,1719258546.865 [DockingStepper](INFO): Starting
2024-06-24T19:49:06.915Z,1719258546.915 [DAT](DEBUG): checking for command mode acknowledgment
2024-06-24T19:49:06.915Z,1719258546.915 [DAT](FAULT): failed to enter command mode
2024-06-24T19:49:07.221Z,1719258547.221 [DAT](INFO): entering command mode
2024-06-24T19:49:07.260Z,1719258547.260 [DockingStepper](INFO): Starting
2024-06-24T19:49:07.419Z,1719258547.419 [DAT](INFO): DAT read: user:1>
2024-06-24T19:49:07.420Z,1719258547.420 [DAT](INFO): DAT read: Command '+++' not found
2024-06-24T19:49:07.420Z,1719258547.420 [DAT](INFO): DAT read: Error
2024-06-24T19:49:07.420Z,1719258547.420 [DAT](INFO): setting verbose to 3
2024-06-24T19:49:07.664Z,1719258547.664 [DockingStepper](INFO): Starting
2024-06-24T19:49:07.672Z,1719258547.672 [DAT](INFO): DAT read: user:2>
2024-06-24T19:49:07.673Z,1719258547.673 [DAT](INFO): DAT read: Verbose | 3
2024-06-24T19:49:07.673Z,1719258547.673 [DAT](INFO): set verbose to 3
2024-06-24T19:49:07.674Z,1719258547.674 [DAT](INFO): setting DatVerbose to 27440
2024-06-24T19:49:07.699Z,1719258547.699 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-24T19:49:07.923Z,1719258547.923 [DAT](INFO): DAT read: user:3>
2024-06-24T19:49:07.924Z,1719258547.924 [DAT](INFO): DAT read: DatVerbose | 27440
2024-06-24T19:49:07.924Z,1719258547.924 [DAT](INFO): set DatVerbose to 27440
2024-06-24T19:49:07.924Z,1719258547.924 [DAT](INFO): setting transmit power to 8
2024-06-24T19:49:08.067Z,1719258548.067 [DockingStepper](INFO): Starting
2024-06-24T19:49:08.175Z,1719258548.175 [DAT](INFO): DAT read: user:4>
2024-06-24T19:49:08.176Z,1719258548.176 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-06-24T19:49:08.176Z,1719258548.176 [DAT](INFO): set transmit power to 8
2024-06-24T19:49:08.176Z,1719258548.176 [DAT](INFO): setting local address to 10
2024-06-24T19:49:08.427Z,1719258548.427 [DAT](INFO): DAT read: user:5>
2024-06-24T19:49:08.428Z,1719258548.428 [DAT](INFO): DAT read: LocalAddr | 10
2024-06-24T19:49:08.428Z,1719258548.428 [DAT](INFO): set local address to 10
2024-06-24T19:49:08.429Z,1719258548.429 [DAT](INFO): Setting time to: 19:49:8 And date to:6/24/2024
2024-06-24T19:49:08.539Z,1719258548.539 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:08.540Z,1719258548.540 [DockingStepper](INFO): Changing to mode: 2
2024-06-24T19:49:08.540Z,1719258548.540 [DockingStepper](INFO): Armed mode.
2024-06-24T19:49:08.602Z,1719258548.602 [DockingStepper](INFO): Commanding negative:54494
2024-06-24T19:49:08.679Z,1719258548.679 [DAT](INFO): DAT read: user:6>
2024-06-24T19:49:08.680Z,1719258548.680 [DAT](INFO): DAT read: Mon Jun 24, 2024 19:49:08
2024-06-24T19:49:08.681Z,1719258548.681 [DAT](INFO): Local DAT time set to Mon Jun 24, 2024 19:49:08
2024-06-24T19:49:08.707Z,1719258548.707 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:08.707Z,1719258548.707 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:08.901Z,1719258548.901 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:09.086Z,1719258549.086 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:09.087Z,1719258549.087 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:09.281Z,1719258549.281 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:09.470Z,1719258549.470 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:09.471Z,1719258549.471 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:09.692Z,1719258549.692 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:09.887Z,1719258549.887 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:09.887Z,1719258549.887 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:10.100Z,1719258550.100 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:10.291Z,1719258550.291 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:10.291Z,1719258550.291 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:10.585Z,1719258550.585 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:10.770Z,1719258550.770 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:10.771Z,1719258550.771 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:10.892Z,1719258550.892 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:11.082Z,1719258551.082 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:11.083Z,1719258551.083 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:11.306Z,1719258551.306 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:11.494Z,1719258551.494 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:11.495Z,1719258551.495 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:11.711Z,1719258551.711 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:12.126Z,1719258552.126 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:12.127Z,1719258552.127 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:12.238Z,1719258552.238 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:12.426Z,1719258552.426 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:12.427Z,1719258552.427 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:12.627Z,1719258552.627 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:12.763Z,1719258552.763 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:12.763Z,1719258552.763 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:13.023Z,1719258553.023 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:13.151Z,1719258553.151 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:13.151Z,1719258553.151 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:13.428Z,1719258553.428 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:13.559Z,1719258553.559 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:13.559Z,1719258553.559 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:13.824Z,1719258553.824 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:13.959Z,1719258553.959 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:13.959Z,1719258553.959 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:14.196Z,1719258554.196 [NAL9602](INFO): NAL9602 initialized
2024-06-24T19:49:14.236Z,1719258554.236 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:14.369Z,1719258554.369 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:14.372Z,1719258554.372 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:14.664Z,1719258554.664 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:14.795Z,1719258554.795 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:14.795Z,1719258554.795 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:15.064Z,1719258555.064 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:15.187Z,1719258555.187 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:15.187Z,1719258555.187 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:15.452Z,1719258555.452 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:15.583Z,1719258555.583 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:15.583Z,1719258555.583 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:15.860Z,1719258555.860 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:15.991Z,1719258555.991 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:15.991Z,1719258555.991 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:16.232Z,1719258556.232 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:16.364Z,1719258556.364 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:16.366Z,1719258556.366 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:16.679Z,1719258556.679 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:16.807Z,1719258556.807 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:16.807Z,1719258556.807 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:17.053Z,1719258557.053 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:17.187Z,1719258557.187 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:17.187Z,1719258557.187 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:17.476Z,1719258557.476 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:17.607Z,1719258557.607 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:17.607Z,1719258557.607 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:17.872Z,1719258557.872 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:17.999Z,1719258557.999 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:17.999Z,1719258557.999 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:18.263Z,1719258558.263 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:18.394Z,1719258558.394 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:18.396Z,1719258558.396 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:18.732Z,1719258558.732 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:18.863Z,1719258558.863 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:18.863Z,1719258558.863 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:19.077Z,1719258559.077 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:19.271Z,1719258559.271 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:19.271Z,1719258559.271 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:19.493Z,1719258559.493 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:19.627Z,1719258559.627 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:19.627Z,1719258559.627 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:19.907Z,1719258559.907 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:20.039Z,1719258560.039 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:20.039Z,1719258560.039 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:20.285Z,1719258560.285 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:20.420Z,1719258560.420 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:20.422Z,1719258560.422 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:20.713Z,1719258560.713 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:20.866Z,1719258560.866 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:20.867Z,1719258560.867 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:21.096Z,1719258561.096 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:21.227Z,1719258561.227 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:21.227Z,1719258561.227 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:21.492Z,1719258561.492 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:21.619Z,1719258561.619 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:21.619Z,1719258561.619 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:21.775Z,1719258561.775 [CommandExec](IMPORTANT): got command docking unrequest
2024-06-24T19:49:21.776Z,1719258561.776 [CommandExec](IMPORTANT): Setting data request false.
2024-06-24T19:49:21.899Z,1719258561.899 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:21.899Z,1719258561.899 [DockingStepper](INFO): Changing to mode: 1
2024-06-24T19:49:21.899Z,1719258561.899 [DockingStepper](INFO): Standby mode.
2024-06-24T19:49:21.966Z,1719258561.966 [DockingStepper](INFO): Commanding positive:54506
2024-06-24T19:49:22.071Z,1719258562.071 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:22.308Z,1719258562.308 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:22.447Z,1719258562.447 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:22.730Z,1719258562.730 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:22.863Z,1719258562.863 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:23.124Z,1719258563.124 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:23.259Z,1719258563.259 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:23.513Z,1719258563.513 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:23.699Z,1719258563.699 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:23.959Z,1719258563.959 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:24.135Z,1719258564.135 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:24.350Z,1719258564.350 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:24.771Z,1719258564.771 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:24.912Z,1719258564.912 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:25.096Z,1719258565.096 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:25.267Z,1719258565.267 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:25.459Z,1719258565.459 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:25.664Z,1719258565.664 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:25.847Z,1719258565.847 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:26.072Z,1719258566.072 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:26.267Z,1719258566.267 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:26.527Z,1719258566.527 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:26.719Z,1719258566.719 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:26.886Z,1719258566.886 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:27.076Z,1719258567.076 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:27.268Z,1719258567.268 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:27.463Z,1719258567.463 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:49:27.676Z,1719258567.676 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:49:27.807Z,1719258567.807 [DockingStepper](INFO): isNeeded returning:0 cmdArmValue:16500 Mode:1
2024-06-24T19:49:28.099Z,1719258568.099 [DockingStepper](INFO): Pause
2024-06-24T19:49:36.331Z,1719258576.331 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:49:42.595Z,1719258582.595 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:49:46.219Z,1719258586.219 [CommandExec](IMPORTANT): got command docking arm
2024-06-24T19:49:46.219Z,1719258586.219 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-06-24T19:49:46.252Z,1719258586.252 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:46.675Z,1719258586.675 [DockingStepper](INFO): Resume
2024-06-24T19:49:47.066Z,1719258587.066 [DockingStepper](INFO): Resuming
2024-06-24T19:49:47.460Z,1719258587.460 [DockingStepper](INFO): Start
2024-06-24T19:49:47.460Z,1719258587.460 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-24T19:49:47.579Z,1719258587.579 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-24T19:49:47.868Z,1719258587.868 [DockingStepper](INFO): Starting
2024-06-24T19:49:48.269Z,1719258588.269 [DockingStepper](INFO): Starting
2024-06-24T19:49:48.715Z,1719258588.715 [DockingStepper](INFO): Starting
2024-06-24T19:49:48.766Z,1719258588.766 [DockingStepper](INFO): Init string: /1z16352aE13561N3m50
2024-06-24T19:49:49.103Z,1719258589.103 [DockingStepper](INFO): Starting
2024-06-24T19:49:49.484Z,1719258589.484 [DockingStepper](INFO): Starting
2024-06-24T19:49:49.888Z,1719258589.888 [DockingStepper](INFO): Starting
2024-06-24T19:49:50.299Z,1719258590.299 [DockingStepper](INFO): Starting
2024-06-24T19:49:50.326Z,1719258590.326 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-24T19:49:50.720Z,1719258590.720 [DockingStepper](INFO): Starting
2024-06-24T19:49:51.100Z,1719258591.100 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:51.100Z,1719258591.100 [DockingStepper](INFO): Changing to mode: 2
2024-06-24T19:49:51.100Z,1719258591.100 [DockingStepper](INFO): Armed mode.
2024-06-24T19:49:51.166Z,1719258591.166 [DockingStepper](INFO): Commanding negative:54488
2024-06-24T19:49:51.331Z,1719258591.331 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:51.331Z,1719258591.331 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:51.515Z,1719258591.515 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:51.697Z,1719258591.697 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:51.699Z,1719258591.699 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:51.928Z,1719258591.928 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:52.111Z,1719258592.111 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:52.111Z,1719258592.111 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:52.346Z,1719258592.346 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:52.762Z,1719258592.762 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:52.763Z,1719258592.763 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:52.837Z,1719258592.837 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:53.026Z,1719258593.026 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:53.027Z,1719258593.027 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:53.200Z,1719258593.200 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:53.394Z,1719258593.394 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:53.395Z,1719258593.395 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:53.604Z,1719258593.604 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:53.790Z,1719258593.790 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:53.791Z,1719258593.791 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:54.012Z,1719258594.012 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:54.430Z,1719258594.430 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:54.431Z,1719258594.431 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:54.527Z,1719258594.527 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:54.950Z,1719258594.950 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:54.951Z,1719258594.951 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:55.100Z,1719258595.100 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:55.294Z,1719258595.294 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:55.295Z,1719258595.295 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:55.391Z,1719258595.391 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:55.518Z,1719258595.518 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:55.519Z,1719258595.519 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:55.804Z,1719258595.804 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:55.938Z,1719258595.938 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:55.939Z,1719258595.939 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:56.212Z,1719258596.212 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:56.342Z,1719258596.342 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:56.343Z,1719258596.343 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:49:56.628Z,1719258596.628 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:56.762Z,1719258596.762 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:56.763Z,1719258596.763 [DockingStepper](INFO): isNeeded returning:0 cmdArmValue:7500 Mode:2
2024-06-24T19:49:57.004Z,1719258597.004 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:57.138Z,1719258597.138 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:57.139Z,1719258597.139 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:57.409Z,1719258597.409 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:57.534Z,1719258597.534 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:57.535Z,1719258597.535 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:57.854Z,1719258597.854 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:57.990Z,1719258597.990 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:57.991Z,1719258597.991 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:58.195Z,1719258598.195 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:49:58.249Z,1719258598.249 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:58.384Z,1719258598.384 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:58.385Z,1719258598.385 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:58.659Z,1719258598.659 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:58.790Z,1719258598.790 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:58.791Z,1719258598.791 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:59.037Z,1719258599.037 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:59.172Z,1719258599.172 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:59.172Z,1719258599.172 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:59.429Z,1719258599.429 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:59.558Z,1719258599.558 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:59.559Z,1719258599.559 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:49:59.840Z,1719258599.840 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:49:59.962Z,1719258599.962 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:49:59.963Z,1719258599.963 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:00.236Z,1719258600.236 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:00.360Z,1719258600.360 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:00.361Z,1719258600.361 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:00.691Z,1719258600.691 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:00.814Z,1719258600.814 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:00.815Z,1719258600.815 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:01.064Z,1719258601.064 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:01.190Z,1719258601.190 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:01.191Z,1719258601.191 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:01.464Z,1719258601.464 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:01.594Z,1719258601.594 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:01.595Z,1719258601.595 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:01.852Z,1719258601.852 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:01.974Z,1719258601.974 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:01.975Z,1719258601.975 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:02.256Z,1719258602.256 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:02.392Z,1719258602.392 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:02.393Z,1719258602.393 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:02.679Z,1719258602.679 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:02.806Z,1719258602.806 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:02.807Z,1719258602.807 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:03.070Z,1719258603.070 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:03.211Z,1719258603.211 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:03.211Z,1719258603.211 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:03.496Z,1719258603.496 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:03.627Z,1719258603.627 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:03.627Z,1719258603.627 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:03.884Z,1719258603.884 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:04.006Z,1719258604.006 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:04.007Z,1719258604.007 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:04.277Z,1719258604.277 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:04.412Z,1719258604.412 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:04.413Z,1719258604.413 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:04.704Z,1719258604.704 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:04.830Z,1719258604.830 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:04.831Z,1719258604.831 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:05.084Z,1719258605.084 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:05.206Z,1719258605.206 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:05.207Z,1719258605.207 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:05.485Z,1719258605.485 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:05.611Z,1719258605.611 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:05.611Z,1719258605.611 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:05.901Z,1719258605.901 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:06.030Z,1719258606.030 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:06.031Z,1719258606.031 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:06.311Z,1719258606.311 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:06.722Z,1719258606.722 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:06.723Z,1719258606.723 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:06.806Z,1719258606.806 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:06.934Z,1719258606.934 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:06.935Z,1719258606.935 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:07.161Z,1719258607.161 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:07.290Z,1719258607.290 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:07.291Z,1719258607.291 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:07.454Z,1719258607.454 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:50:07.573Z,1719258607.573 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:07.698Z,1719258607.698 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:07.699Z,1719258607.699 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:07.994Z,1719258607.994 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:50:08.122Z,1719258608.122 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:50:08.123Z,1719258608.123 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:50:08.296Z,1719258608.296 [CommandExec](IMPORTANT): got command docking unrequest
2024-06-24T19:50:08.296Z,1719258608.296 [CommandExec](IMPORTANT): Setting data request false.
2024-06-24T19:50:08.515Z,1719258608.515 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:08.515Z,1719258608.515 [DockingStepper](INFO): Changing to mode: 1
2024-06-24T19:50:08.515Z,1719258608.515 [DockingStepper](INFO): Standby mode.
2024-06-24T19:50:08.578Z,1719258608.578 [DockingStepper](INFO): Commanding positive:54482
2024-06-24T19:50:08.683Z,1719258608.683 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:08.780Z,1719258608.780 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:08.915Z,1719258608.915 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:09.213Z,1719258609.213 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:09.367Z,1719258609.367 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:09.604Z,1719258609.604 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:09.739Z,1719258609.739 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:09.992Z,1719258609.992 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:10.183Z,1719258610.183 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:10.433Z,1719258610.433 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:10.627Z,1719258610.627 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:10.820Z,1719258610.820 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:10.999Z,1719258610.999 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:11.211Z,1719258611.211 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:11.407Z,1719258611.407 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:11.604Z,1719258611.604 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:11.799Z,1719258611.799 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:12.045Z,1719258612.045 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:12.235Z,1719258612.235 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:12.470Z,1719258612.470 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:12.611Z,1719258612.611 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:12.860Z,1719258612.860 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:13.051Z,1719258613.051 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:13.203Z,1719258613.203 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:50:13.244Z,1719258613.244 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:13.439Z,1719258613.439 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:13.664Z,1719258613.664 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:14.087Z,1719258614.087 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:16500 Mode:2
2024-06-24T19:50:14.157Z,1719258614.157 [DockingStepper](INFO): modeCmdReader Active:1
2024-06-24T19:50:14.299Z,1719258614.299 [DockingStepper](INFO): isNeeded returning:0 cmdArmValue:16500 Mode:1
2024-06-24T19:50:14.561Z,1719258614.561 [DockingStepper](INFO): Pause
2024-06-24T19:50:21.797Z,1719258621.797 [SBIT](IMPORTANT): Beginning Startup BIT
2024-06-24T19:50:21.802Z,1719258621.802 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-24T19:50:28.643Z,1719258628.643 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:50:32.770Z,1719258632.770 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000242
CHAN A1 (24V): 0.016198
CHAN A2 (12V): -0.000559
CHAN A3 (5V): -0.001065
CHAN B0 (3.3V): 0.001065
CHAN B1 (3.15aV): 0.000417
CHAN B2 (3.15bV): 0.001363
CHAN B3 (GND): 0.000839
OPEN: 0.004999
Full Scale: +/- 1 mA
2024-06-24T19:50:38.582Z,1719258638.582 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:50:43.995Z,1719258643.995 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:50:59.355Z,1719258659.355 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:51:07.843Z,1719258667.843 [NAL9602](INFO): SBD MO Status=2, MOMSN=38121, MT Status=2, MTMSN=0
2024-06-24T19:51:07.844Z,1719258667.844 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-24T19:51:09.710Z,1719258669.710 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:51:15.249Z,1719258675.249 [SBIT](IMPORTANT): SBIT PASSED
2024-06-24T19:51:15.249Z,1719258675.249 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-06-24T19:51:15.262Z,1719258675.262 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2024-06-24T19:51:15.262Z,1719258675.262 [SBIT](IMPORTANT): Dock.lat=36.9033 degree;
2024-06-24T19:51:15.262Z,1719258675.262 [SBIT](IMPORTANT): Dock.lon=-122.119 degree;
2024-06-24T19:51:15.262Z,1719258675.262 [SBIT](IMPORTANT): Dock.transponderCode=50 count;
2024-06-24T19:51:15.262Z,1719258675.262 [SBIT](IMPORTANT): Docked.verbose=1 bool;
2024-06-24T19:51:15.263Z,1719258675.263 [SBIT](IMPORTANT): DockingStepper.slideValue=9700 count;
2024-06-24T19:51:15.263Z,1719258675.263 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;
2024-06-24T19:51:15.263Z,1719258675.263 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=25 ampere_hour;
2024-06-24T19:51:15.263Z,1719258675.263 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-06-24T19:51:15.263Z,1719258675.263 [SBIT](IMPORTANT): LineCapture.kiHeadingFinalApproach=0.004 reciprocal_second;
2024-06-24T19:51:15.263Z,1719258675.263 [SBIT](IMPORTANT): LineCapture.kiHeadingTerminalGuidance=0.004 reciprocal_second;
2024-06-24T19:51:15.263Z,1719258675.263 [SBIT](IMPORTANT): LineCapture.navigationGain=3 none;
2024-06-24T19:51:15.263Z,1719258675.263 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0 meter_per_second;
2024-06-24T19:51:15.264Z,1719258675.264 [SBIT](IMPORTANT): LineCapture.rolloutTimeout=10 minute;
2024-06-24T19:51:15.264Z,1719258675.264 [SBIT](IMPORTANT): LineCapture.searchTimeout=10 minute;
2024-06-24T19:51:15.264Z,1719258675.264 [SBIT](IMPORTANT): LineCapture.shortFinalRange=10 meter;
2024-06-24T19:51:15.264Z,1719258675.264 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;
2024-06-24T19:51:15.264Z,1719258675.264 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=439.628257 cubic_centimeter;
2024-06-24T19:51:15.264Z,1719258675.264 [SBIT](IMPORTANT): VerticalControl.massDefault=21.880764 millimeter;
2024-06-24T19:51:15.648Z,1719258675.648 [MissionManager](IMPORTANT): Started mission Startup
2024-06-24T19:51:15.649Z,1719258675.649 [Startup] Running Loop=1
2024-06-24T19:51:15.649Z,1719258675.649 [Startup](DEBUG): Aggregate::initialize Startup
2024-06-24T19:51:15.649Z,1719258675.649 [Startup:A.GoToSurface] Running Loop=1
2024-06-24T19:51:15.649Z,1719258675.649 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-24T19:51:15.649Z,1719258675.649 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-24T19:51:15.670Z,1719258675.670 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-24T19:51:15.670Z,1719258675.670 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-24T19:51:15.671Z,1719258675.671 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-24T19:51:15.671Z,1719258675.671 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-24T19:51:15.671Z,1719258675.671 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-24T19:51:15.673Z,1719258675.673 [Startup:StartupSatComms] Running Loop=1
2024-06-24T19:51:15.673Z,1719258675.673 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-06-24T19:51:15.673Z,1719258675.673 [Startup:StartupSatComms:A] Running Loop=1
2024-06-24T19:51:16.161Z,1719258676.161 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-06-24T19:51:20.760Z,1719258680.760 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004038
2024-06-24T19:51:21.758Z,1719258681.758 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:51:34.183Z,1719258694.183 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-06-24T19:51:34.184Z,1719258694.184 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-24T19:51:34.221Z,1719258694.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-24T19:51:34.636Z,1719258694.636 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-24T19:51:34.636Z,1719258694.636 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-06-24T19:51:35.726Z,1719258695.726 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:51:44.224Z,1719258704.224 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:51:52.748Z,1719258712.748 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:51:55.180Z,1719258715.180 [NAL9602](INFO): SBD MO Status=2, MOMSN=38121, MT Status=2, MTMSN=0
2024-06-24T19:51:55.181Z,1719258715.181 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-06-24T19:52:01.266Z,1719258721.266 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:52:09.730Z,1719258729.730 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:52:15.796Z,1719258735.796 [Startup:StartupSatComms:A](INFO): Timed out from 2024-06-24T19:51:15.7Z
2024-06-24T19:52:15.796Z,1719258735.796 [Startup:StartupSatComms:A] Stopped
2024-06-24T19:52:15.796Z,1719258735.796 [Startup:StartupSatComms:B] Running Loop=1
2024-06-24T19:52:16.200Z,1719258736.200 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-24T19:52:16.629Z,1719258736.629 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240624T193802/Courier0007.lzma
2024-06-24T19:52:17.276Z,1719258737.276 [DataOverHttps](INFO): Moved sent file to Logs/20240624T193802/Courier0007.lzma.bak
2024-06-24T19:52:17.276Z,1719258737.276 [DataOverHttps](INFO): SBD MOMSN=21268531
2024-06-24T19:52:33.238Z,1719258753.238 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20240624T194821/Courier0000.lzma
2024-06-24T19:52:34.240Z,1719258754.240 [DataOverHttps](INFO): Moved sent file to Logs/20240624T194821/Courier0000.lzma.bak
2024-06-24T19:52:34.240Z,1719258754.240 [DataOverHttps](INFO): SBD MOMSN=21268533
2024-06-24T19:52:50.210Z,1719258770.210 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20240624T193802/Express0005.lzma
2024-06-24T19:52:51.212Z,1719258771.212 [DataOverHttps](INFO): Moved sent file to Logs/20240624T193802/Express0005.lzma.bak
2024-06-24T19:52:51.212Z,1719258771.212 [DataOverHttps](INFO): SBD MOMSN=21268538
2024-06-24T19:53:07.205Z,1719258787.205 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20240624T193802/Express0008.lzma
2024-06-24T19:53:08.208Z,1719258788.208 [DataOverHttps](INFO): Moved sent file to Logs/20240624T193802/Express0008.lzma.bak
2024-06-24T19:53:08.208Z,1719258788.208 [DataOverHttps](INFO): SBD MOMSN=21268554
2024-06-24T19:53:16.116Z,1719258796.116 [Startup:StartupSatComms:B](INFO): Timed out from 2024-06-24T19:52:15.8Z
2024-06-24T19:53:16.117Z,1719258796.117 [Startup:StartupSatComms:B] Stopped
2024-06-24T19:53:16.117Z,1719258796.117 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-06-24T19:53:16.117Z,1719258796.117 [Startup:StartupSatComms] Stopped
2024-06-24T19:53:16.117Z,1719258796.117 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-06-24T19:53:16.118Z,1719258796.118 [Startup](INFO): Completed Startup
2024-06-24T19:53:16.118Z,1719258796.118 [MissionManager](INFO): Startup is completed.
2024-06-24T19:53:16.118Z,1719258796.118 [MissionManager](INFO): Uninitializing Mission Startup
2024-06-24T19:53:16.118Z,1719258796.118 [Startup] Stopped
2024-06-24T19:53:16.119Z,1719258796.119 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-06-24T19:53:16.119Z,1719258796.119 [Startup:A.GoToSurface] Stopped
2024-06-24T19:53:16.119Z,1719258796.119 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-24T19:53:16.545Z,1719258796.545 [MissionManager](IMPORTANT): Started mission Default
2024-06-24T19:53:16.545Z,1719258796.545 [Default] Running Loop=1
2024-06-24T19:53:16.545Z,1719258796.545 [Default](DEBUG): Aggregate::initialize Default
2024-06-24T19:53:16.545Z,1719258796.545 [Default:B.GoToSurface] Running Loop=1
2024-06-24T19:53:16.545Z,1719258796.545 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-24T19:53:16.546Z,1719258796.546 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-24T19:53:16.547Z,1719258796.547 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-24T19:53:16.547Z,1719258796.547 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-24T19:53:16.547Z,1719258796.547 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-24T19:53:16.547Z,1719258796.547 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-24T19:53:16.548Z,1719258796.548 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-24T19:53:16.548Z,1719258796.548 [Default:A.Wait] Running Loop=1
2024-06-24T19:53:16.548Z,1719258796.548 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-06-24T19:53:24.287Z,1719258804.287 [DataOverHttps](INFO): Sending 1299 bytes from file Logs/20240624T194821/Express0001.lzma
2024-06-24T19:53:25.288Z,1719258805.288 [DataOverHttps](INFO): Moved sent file to Logs/20240624T194821/Express0001.lzma.bak
2024-06-24T19:53:25.288Z,1719258805.288 [DataOverHttps](INFO): SBD MOMSN=21268557
2024-06-24T19:53:26.291Z,1719258806.291 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:53:29.868Z,1719258809.868 [Default:A.Wait](INFO): Done Waiting.
2024-06-24T19:53:29.868Z,1719258809.868 [Default:A.Wait] Stopped
2024-06-24T19:53:29.868Z,1719258809.868 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-06-24T19:53:30.406Z,1719258810.406 [Default:CheckIn] Running Loop=1
2024-06-24T19:53:30.415Z,1719258810.415 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-24T19:53:30.415Z,1719258810.415 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-24T19:53:30.643Z,1719258810.643 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:53:30.739Z,1719258810.739 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-06-24T19:53:34.834Z,1719258814.834 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:53:43.572Z,1719258823.572 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:53:43.942Z,1719258823.942 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:53:52.579Z,1719258832.579 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:53:56.505Z,1719258836.505 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:54:01.022Z,1719258841.022 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:54:09.427Z,1719258849.427 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:54:09.438Z,1719258849.438 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:54:17.515Z,1719258857.515 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-06-24T19:54:17.990Z,1719258857.990 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:54:22.365Z,1719258862.365 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:54:26.514Z,1719258866.514 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:54:34.878Z,1719258874.878 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:54:34.886Z,1719258874.886 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:54:35.317Z,1719258875.317 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-06-24T19:54:35.317Z,1719258875.317 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-24T19:54:35.328Z,1719258875.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-24T19:54:35.741Z,1719258875.741 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-24T19:54:35.741Z,1719258875.741 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-06-24T19:54:43.306Z,1719258883.306 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:54:47.403Z,1719258887.403 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:54:51.854Z,1719258891.854 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:55:00.331Z,1719258900.331 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:55:00.430Z,1719258900.430 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:55:08.830Z,1719258908.830 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:55:12.451Z,1719258912.451 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:55:17.259Z,1719258917.259 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:55:24.999Z,1719258924.999 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:55:27.494Z,1719258927.494 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:55:29.904Z,1719258929.904 [CommandExec](IMPORTANT): got command docking arm
2024-06-24T19:55:29.905Z,1719258929.905 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-06-24T19:55:29.972Z,1719258929.972 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:30.337Z,1719258930.337 [DockingStepper](INFO): Resume
2024-06-24T19:55:30.801Z,1719258930.801 [DockingStepper](INFO): Resuming
2024-06-24T19:55:31.177Z,1719258931.177 [DockingStepper](INFO): Start
2024-06-24T19:55:31.177Z,1719258931.177 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-24T19:55:31.291Z,1719258931.291 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-24T19:55:31.526Z,1719258931.526 [DockingStepper](INFO): Starting
2024-06-24T19:55:31.913Z,1719258931.913 [DockingStepper](INFO): Starting
2024-06-24T19:55:32.356Z,1719258932.356 [DockingStepper](INFO): Starting
2024-06-24T19:55:32.419Z,1719258932.419 [DockingStepper](INFO): Init string: /1z16367aE13561N3m50
2024-06-24T19:55:32.752Z,1719258932.752 [DockingStepper](INFO): Starting
2024-06-24T19:55:33.157Z,1719258933.157 [DockingStepper](INFO): Starting
2024-06-24T19:55:33.533Z,1719258933.533 [DockingStepper](INFO): Starting
2024-06-24T19:55:33.957Z,1719258933.957 [DockingStepper](INFO): Starting
2024-06-24T19:55:33.999Z,1719258933.999 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-24T19:55:34.360Z,1719258934.360 [DockingStepper](INFO): Starting
2024-06-24T19:55:34.849Z,1719258934.849 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:34.870Z,1719258934.870 [DockingStepper](INFO): Changing to mode: 2
2024-06-24T19:55:34.870Z,1719258934.870 [DockingStepper](INFO): Armed mode.
2024-06-24T19:55:34.938Z,1719258934.938 [DockingStepper](INFO): Commanding negative:54593
2024-06-24T19:55:35.107Z,1719258935.107 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:35.107Z,1719258935.107 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:35.403Z,1719258935.403 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:35.579Z,1719258935.579 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:35.579Z,1719258935.579 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:35.725Z,1719258935.725 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:35.915Z,1719258935.915 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:35.915Z,1719258935.915 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:36.080Z,1719258936.080 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:36.495Z,1719258936.495 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:36.495Z,1719258936.495 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:36.724Z,1719258936.724 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:36.867Z,1719258936.867 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:36.867Z,1719258936.867 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:37.017Z,1719258937.017 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:37.203Z,1719258937.203 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:37.203Z,1719258937.203 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:37.409Z,1719258937.409 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:37.595Z,1719258937.595 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:37.595Z,1719258937.595 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:37.995Z,1719258937.995 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:38.187Z,1719258938.187 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:38.187Z,1719258938.187 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:38.220Z,1719258938.220 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-24T19:55:38.460Z,1719258938.460 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:38.643Z,1719258938.643 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:38.643Z,1719258938.643 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:38.778Z,1719258938.778 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:38.962Z,1719258938.962 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:38.963Z,1719258938.963 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:39.164Z,1719258939.164 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:39.295Z,1719258939.295 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:39.296Z,1719258939.296 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:39.529Z,1719258939.529 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:39.683Z,1719258939.683 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:39.683Z,1719258939.683 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:39.907Z,1719258939.907 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:40.035Z,1719258940.035 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:40.035Z,1719258940.035 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:1
2024-06-24T19:55:40.357Z,1719258940.357 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:40.511Z,1719258940.511 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:40.511Z,1719258940.511 [DockingStepper](INFO): isNeeded returning:0 cmdArmValue:7500 Mode:2
2024-06-24T19:55:40.763Z,1719258940.763 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:40.897Z,1719258940.897 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:40.897Z,1719258940.897 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:41.147Z,1719258941.147 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:41.271Z,1719258941.271 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:41.272Z,1719258941.272 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:41.532Z,1719258941.532 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:41.656Z,1719258941.656 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:41.657Z,1719258941.657 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:41.953Z,1719258941.953 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:42.083Z,1719258942.083 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:42.083Z,1719258942.083 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:42.365Z,1719258942.365 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:42.372Z,1719258942.372 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:55:42.531Z,1719258942.531 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:42.531Z,1719258942.531 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:42.794Z,1719258942.794 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:42.923Z,1719258942.923 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:42.923Z,1719258942.923 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:43.138Z,1719258943.138 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:43.291Z,1719258943.291 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:43.291Z,1719258943.291 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:43.587Z,1719258943.587 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:43.715Z,1719258943.715 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:43.716Z,1719258943.716 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:43.962Z,1719258943.962 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:44.087Z,1719258944.087 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:44.087Z,1719258944.087 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:44.453Z,1719258944.453 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:44.591Z,1719258944.591 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:44.591Z,1719258944.591 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:44.842Z,1719258944.842 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:44.975Z,1719258944.975 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:44.975Z,1719258944.975 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:45.173Z,1719258945.173 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:45.303Z,1719258945.303 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:45.303Z,1719258945.303 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:45.583Z,1719258945.583 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:45.711Z,1719258945.711 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:45.711Z,1719258945.711 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:45.981Z,1719258945.981 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:46.111Z,1719258946.111 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:46.111Z,1719258946.111 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:46.461Z,1719258946.461 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:46.595Z,1719258946.595 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:46.595Z,1719258946.595 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:46.929Z,1719258946.929 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:47.059Z,1719258947.059 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:47.059Z,1719258947.059 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:47.213Z,1719258947.213 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:47.363Z,1719258947.363 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:47.363Z,1719258947.363 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:47.585Z,1719258947.585 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:47.743Z,1719258947.743 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:47.744Z,1719258947.744 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:48.144Z,1719258948.144 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:48.271Z,1719258948.271 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:48.271Z,1719258948.271 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:48.572Z,1719258948.572 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:48.699Z,1719258948.699 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:48.699Z,1719258948.699 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:48.868Z,1719258948.868 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:48.991Z,1719258948.991 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:48.991Z,1719258948.991 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:49.223Z,1719258949.223 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:49.351Z,1719258949.351 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:49.351Z,1719258949.351 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:49.645Z,1719258949.645 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:49.783Z,1719258949.783 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:49.783Z,1719258949.783 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:50.045Z,1719258950.045 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:50.267Z,1719258950.267 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:50.267Z,1719258950.267 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:50.708Z,1719258950.708 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:50.835Z,1719258950.835 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:50.835Z,1719258950.835 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:50.991Z,1719258950.991 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:51.127Z,1719258951.127 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:51.127Z,1719258951.127 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:51.295Z,1719258951.295 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:51.419Z,1719258951.419 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:51.419Z,1719258951.419 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:51.717Z,1719258951.717 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:51.847Z,1719258951.847 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:51.847Z,1719258951.847 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:52.114Z,1719258952.114 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:52.239Z,1719258952.239 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:52.239Z,1719258952.239 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:52.551Z,1719258952.551 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:52.683Z,1719258952.683 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:52.683Z,1719258952.683 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:52.921Z,1719258952.921 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:53.054Z,1719258953.054 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:53.055Z,1719258953.055 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:53.314Z,1719258953.314 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:53.450Z,1719258953.450 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:53.451Z,1719258953.451 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:53.697Z,1719258953.697 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:53.831Z,1719258953.831 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:53.831Z,1719258953.831 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:54.099Z,1719258954.099 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:54.234Z,1719258954.234 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:54.235Z,1719258954.235 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:54.573Z,1719258954.573 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:54.703Z,1719258954.703 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:54.703Z,1719258954.703 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:54.905Z,1719258954.905 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 4.
2024-06-24T19:55:54.909Z,1719258954.909 [BPC1](INFO): Received data from all battery sticks.
2024-06-24T19:55:54.963Z,1719258954.963 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:55.094Z,1719258955.094 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:55.095Z,1719258955.095 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:55.295Z,1719258955.295 [CommandExec](IMPORTANT): got command restart application
2024-06-24T19:55:55.350Z,1719258955.350 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:55.478Z,1719258955.478 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:55.479Z,1719258955.479 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:55.743Z,1719258955.743 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:55.874Z,1719258955.874 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:55.875Z,1719258955.875 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:56.050Z,1719258956.050 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:55:56.137Z,1719258956.137 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:56.267Z,1719258956.267 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:56.267Z,1719258956.267 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:56.298Z,1719258956.298 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:55:56.298Z,1719258956.298 [CommandExec](INFO): Uninitializing the command executive.
2024-06-24T19:55:56.298Z,1719258956.298 [CommandExec](INFO): Uninitializing the command scheduler.
2024-06-24T19:55:56.299Z,1719258956.299 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:56.466Z,1719258956.466 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-06-24T19:55:56.466Z,1719258956.466 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-06-24T19:55:56.466Z,1719258956.466 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:56.467Z,1719258956.467 [NavChartDb](INFO): Join timeout helper Thread ID is 3441
2024-06-24T19:55:56.560Z,1719258956.560 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:56.690Z,1719258956.690 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:56.691Z,1719258956.691 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:56.714Z,1719258956.714 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:55:56.714Z,1719258956.714 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:56.734Z,1719258956.734 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2024-06-24T19:55:56.734Z,1719258956.734 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:56.734Z,1719258956.734 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 3442
2024-06-24T19:55:56.941Z,1719258956.941 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:57.067Z,1719258957.067 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:57.067Z,1719258957.067 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:57.090Z,1719258957.090 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:55:57.090Z,1719258957.090 [WetLabsUBAT](INFO): Powering down
2024-06-24T19:55:57.091Z,1719258957.091 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:57.094Z,1719258957.094 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-06-24T19:55:57.094Z,1719258957.094 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:57.094Z,1719258957.094 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3443
2024-06-24T19:55:57.334Z,1719258957.334 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:55:57.334Z,1719258957.334 [WetLabsBB2FL](INFO): Powering down
2024-06-24T19:55:57.335Z,1719258957.335 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:57.338Z,1719258957.338 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-06-24T19:55:57.338Z,1719258957.338 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:57.339Z,1719258957.339 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3444
2024-06-24T19:55:57.384Z,1719258957.384 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:57.518Z,1719258957.518 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:57.519Z,1719258957.519 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:57.735Z,1719258957.735 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:57.859Z,1719258957.859 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:57.859Z,1719258957.859 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:58.014Z,1719258958.014 [CTD_Seabird](INFO): Powering down
2024-06-24T19:55:58.026Z,1719258958.026 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:55:58.026Z,1719258958.026 [CTD_Seabird](INFO): Powering down
2024-06-24T19:55:58.038Z,1719258958.038 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:58.038Z,1719258958.038 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-06-24T19:55:58.038Z,1719258958.038 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:58.039Z,1719258958.039 [DAT](INFO): Join timeout helper Thread ID is 3445
2024-06-24T19:55:58.126Z,1719258958.126 [DAT](INFO): Powering down
2024-06-24T19:55:58.193Z,1719258958.193 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:58.199Z,1719258958.199 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:55:58.199Z,1719258958.199 [DAT](INFO): Powering down
2024-06-24T19:55:58.200Z,1719258958.200 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:58.207Z,1719258958.207 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-06-24T19:55:58.207Z,1719258958.207 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:58.208Z,1719258958.208 [Radio_Surface](INFO): Join timeout helper Thread ID is 3446
2024-06-24T19:55:58.298Z,1719258958.298 [Radio_Surface](INFO): Powering down
2024-06-24T19:55:58.299Z,1719258958.299 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:55:58.300Z,1719258958.300 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:58.315Z,1719258958.315 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-06-24T19:55:58.315Z,1719258958.315 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:58.315Z,1719258958.315 [Onboard](INFO): Join timeout helper Thread ID is 3447
2024-06-24T19:55:58.331Z,1719258958.331 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:58.331Z,1719258958.331 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:58.639Z,1719258958.639 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:58.771Z,1719258958.771 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:58.771Z,1719258958.771 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:58.989Z,1719258958.989 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:59.115Z,1719258959.115 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:59.115Z,1719258959.115 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:59.378Z,1719258959.378 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:59.462Z,1719258959.462 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:55:59.463Z,1719258959.463 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:59.479Z,1719258959.479 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-06-24T19:55:59.479Z,1719258959.479 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:55:59.479Z,1719258959.479 [DataOverHttps](INFO): Join timeout helper Thread ID is 3448
2024-06-24T19:55:59.499Z,1719258959.499 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:59.499Z,1719258959.499 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:55:59.781Z,1719258959.781 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:55:59.919Z,1719258959.919 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:55:59.919Z,1719258959.919 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:56:00.196Z,1719258960.196 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:56:00.323Z,1719258960.323 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:56:00.323Z,1719258960.323 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:56:00.624Z,1719258960.624 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:56:00.747Z,1719258960.747 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:56:00.747Z,1719258960.747 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:56:01.005Z,1719258961.005 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:56:01.139Z,1719258961.139 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:56:01.139Z,1719258961.139 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:56:01.373Z,1719258961.373 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:56:01.507Z,1719258961.507 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:56:01.507Z,1719258961.507 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:56:01.793Z,1719258961.793 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:56:01.927Z,1719258961.927 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:56:01.927Z,1719258961.927 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:56:02.198Z,1719258962.198 [DockingStepper](INFO): modeCmdReader Active:2
2024-06-24T19:56:02.323Z,1719258962.323 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:56:02.323Z,1719258962.323 [DockingStepper](INFO): isNeeded returning:1 cmdArmValue:7500 Mode:2
2024-06-24T19:56:02.341Z,1719258962.341 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:56:02.344Z,1719258962.344 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.351Z,1719258962.351 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-06-24T19:56:02.352Z,1719258962.352 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.353Z,1719258962.353 [BackseatComponent](INFO): Join timeout helper Thread ID is 3449
2024-06-24T19:56:02.403Z,1719258962.403 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:56:02.404Z,1719258962.404 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.416Z,1719258962.416 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-06-24T19:56:02.417Z,1719258962.417 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.418Z,1719258962.418 [logger](INFO): Join timeout helper Thread ID is 3450
2024-06-24T19:56:02.434Z,1719258962.434 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:56:02.435Z,1719258962.435 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.436Z,1719258962.436 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-06-24T19:56:02.436Z,1719258962.436 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.437Z,1719258962.437 [CommandLine](INFO): Join timeout helper Thread ID is 3451
2024-06-24T19:56:02.494Z,1719258962.494 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:56:02.494Z,1719258962.494 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.499Z,1719258962.499 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-06-24T19:56:02.499Z,1719258962.499 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.499Z,1719258962.499 [CommandExec](INFO): Join timeout helper Thread ID is 3452
2024-06-24T19:56:02.500Z,1719258962.500 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-06-24T19:56:02.500Z,1719258962.500 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.501Z,1719258962.501 [controlThread](INFO): Join timeout helper Thread ID is 3453
2024-06-24T19:56:02.554Z,1719258962.554 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:56:02.554Z,1719258962.554 [controlThread](DEBUG): Uninitializing ControlThread
2024-06-24T19:56:02.555Z,1719258962.555 [AHRS_M2](INFO): Powering down
2024-06-24T19:56:02.627Z,1719258962.627 [NAL9602](INFO): Powering down
2024-06-24T19:56:02.698Z,1719258962.698 [RDI_Pathfinder](INFO): Powering down
2024-06-24T19:56:02.699Z,1719258962.699 [Sonardyne_Nano](INFO): Powering down
2024-06-24T19:56:02.819Z,1719258962.819 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-06-24T19:56:02.820Z,1719258962.820 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-06-24T19:56:02.820Z,1719258962.820 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-06-24T19:56:02.821Z,1719258962.821 [MissionManager](INFO): Uninitializing Mission Default
2024-06-24T19:56:02.821Z,1719258962.821 [Default] Stopped
2024-06-24T19:56:02.821Z,1719258962.821 [Default](DEBUG): Aggregate::uninitialize Default
2024-06-24T19:56:02.821Z,1719258962.821 [Default:B.GoToSurface] Stopped
2024-06-24T19:56:02.821Z,1719258962.821 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-24T19:56:02.821Z,1719258962.821 [Default:CheckIn] Stopped
2024-06-24T19:56:02.821Z,1719258962.821 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-24T19:56:02.821Z,1719258962.821 [Default:CheckIn:Read_GPS] Stopped
2024-06-24T19:56:02.824Z,1719258962.824 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-06-24T19:56:02.824Z,1719258962.824 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-06-24T19:56:02.824Z,1719258962.824 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-06-24T19:56:02.825Z,1719258962.825 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-06-24T19:56:02.825Z,1719258962.825 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-06-24T19:56:02.825Z,1719258962.825 [BuoyancyServo](INFO): Powering down
2024-06-24T19:56:02.838Z,1719258962.838 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-24T19:56:02.838Z,1719258962.838 [ElevatorServo](INFO): Powering down
2024-06-24T19:56:02.839Z,1719258962.839 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-06-24T19:56:02.839Z,1719258962.839 [DockingStepper](INFO): Powering down
2024-06-24T19:56:02.840Z,1719258962.840 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-06-24T19:56:02.840Z,1719258962.840 [MassServo](INFO): Powering down
2024-06-24T19:56:02.841Z,1719258962.841 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-06-24T19:56:02.841Z,1719258962.841 [RudderServo](INFO): Powering down
2024-06-24T19:56:02.842Z,1719258962.842 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2024-06-24T19:56:02.842Z,1719258962.842 [ThrusterServo](INFO): Powering down
2024-06-24T19:56:02.843Z,1719258962.843 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-06-24T19:56:02.843Z,1719258962.843 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-06-24T19:56:02.843Z,1719258962.843 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-06-24T19:56:02.843Z,1719258962.843 [CBIT](DEBUG): Powering off loads.
2024-06-24T19:56:02.854Z,1719258962.854 [CBIT](DEBUG): Disabling WDT.
2024-06-24T19:56:02.866Z,1719258962.866 [CBIT](DEBUG): Opening all GF detection circuits.
2024-06-24T19:56:02.867Z,1719258962.867 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.924Z,1719258962.924 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.932Z,1719258962.932 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.934Z,1719258962.934 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.940Z,1719258962.940 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:02.987Z,1719258962.987 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:03.164Z,1719258963.164 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:03.166Z,1719258963.166 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:03.171Z,1719258963.171 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:03.179Z,1719258963.179 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:56:03.247Z,1719258963.247 [logger ThreadHandler](INFO): Thread cancelled.