2022-11-08T20:45:21.517Z,1667940321.517 [Supervisor](DEBUG): Initializing supervisor.
2022-11-08T20:45:21.521Z,1667940321.521 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-11-08T20:45:21.522Z,1667940321.522 [SyncHandler](INFO): Protected caller Thread ID is 834
2022-11-08T20:45:21.522Z,1667940321.522 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-11-08T20:45:21.523Z,1667940321.523 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-11-08T20:45:21.523Z,1667940321.523 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
2022-11-08T20:45:21.528Z,1667940321.528 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-11-08T20:45:21.546Z,1667940321.546 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-11-08T20:45:21.547Z,1667940321.547 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-11-08T20:45:21.547Z,1667940321.547 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
2022-11-08T20:45:21.551Z,1667940321.551 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-11-08T20:45:21.552Z,1667940321.552 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-11-08T20:45:21.553Z,1667940321.553 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2022-11-08T20:45:21.555Z,1667940321.555 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-11-08T20:45:21.556Z,1667940321.556 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-11-08T20:45:21.556Z,1667940321.556 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2022-11-08T20:45:21.560Z,1667940321.560 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-11-08T20:45:21.560Z,1667940321.560 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-11-08T20:45:21.565Z,1667940321.565 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-11-08T20:45:21.993Z,1667940321.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-11-08T20:45:21.995Z,1667940321.995 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-11-08T20:45:22.210Z,1667940322.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-11-08T20:45:22.211Z,1667940322.211 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-11-08T20:45:22.395Z,1667940322.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-11-08T20:45:22.396Z,1667940322.396 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-11-08T20:45:22.502Z,1667940322.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-11-08T20:45:22.503Z,1667940322.503 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-11-08T20:45:22.871Z,1667940322.871 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-11-08T20:45:22.873Z,1667940322.873 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-11-08T20:45:23.193Z,1667940323.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-11-08T20:45:23.195Z,1667940323.195 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-11-08T20:45:23.291Z,1667940323.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-11-08T20:45:23.293Z,1667940323.293 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-11-08T20:45:23.511Z,1667940323.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-11-08T20:45:23.512Z,1667940323.512 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-11-08T20:45:24.024Z,1667940324.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-11-08T20:45:24.025Z,1667940324.025 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-11-08T20:45:24.377Z,1667940324.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-11-08T20:45:24.377Z,1667940324.377 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-11-08T20:45:24.458Z,1667940324.458 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-11-08T20:45:24.667Z,1667940324.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-11-08T20:45:24.668Z,1667940324.668 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-11-08T20:45:24.806Z,1667940324.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-11-08T20:45:24.807Z,1667940324.807 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-11-08T20:45:25.862Z,1667940325.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-11-08T20:45:25.863Z,1667940325.863 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-11-08T20:45:25.942Z,1667940325.942 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-11-08T20:45:26.230Z,1667940326.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-11-08T20:45:26.232Z,1667940326.232 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2022-11-08T20:45:26.236Z,1667940326.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2022-11-08T20:45:26.414Z,1667940326.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2022-11-08T20:45:26.507Z,1667940326.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2022-11-08T20:45:26.619Z,1667940326.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2022-11-08T20:45:26.719Z,1667940326.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2022-11-08T20:45:26.804Z,1667940326.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2022-11-08T20:45:26.889Z,1667940326.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2022-11-08T20:45:27.017Z,1667940327.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2022-11-08T20:45:27.116Z,1667940327.116 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2022-11-08T20:45:27.270Z,1667940327.270 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2022-11-08T20:45:27.533Z,1667940327.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-11-08T20:45:27.533Z,1667940327.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2022-11-08T20:45:27.627Z,1667940327.627 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-11-08T20:45:27.646Z,1667940327.646 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-11-08T20:45:28.006Z,1667940328.006 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-11-08T20:45:28.007Z,1667940328.007 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-11-08T20:45:28.148Z,1667940328.148 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-11-08T20:45:28.148Z,1667940328.148 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-11-08T20:45:28.188Z,1667940328.188 [DeadReckonUsingSpeedCalculator] Loaded
2022-11-08T20:45:28.188Z,1667940328.188 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-11-08T20:45:28.202Z,1667940328.202 [NavChart] Loaded
2022-11-08T20:45:28.202Z,1667940328.202 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-11-08T20:45:28.208Z,1667940328.208 [UniversalFixResidualReporter] Loaded
2022-11-08T20:45:28.209Z,1667940328.209 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-11-08T20:45:28.209Z,1667940328.209 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-11-08T20:45:28.210Z,1667940328.210 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-11-08T20:45:28.226Z,1667940328.226 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-11-08T20:45:28.227Z,1667940328.227 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-11-08T20:45:28.432Z,1667940328.432 [PAR_Licor] Loaded
2022-11-08T20:45:28.432Z,1667940328.432 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-11-08T20:45:28.464Z,1667940328.464 [WetLabsBB2FL] Loaded
2022-11-08T20:45:28.464Z,1667940328.464 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-11-08T20:45:28.465Z,1667940328.465 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065A4E0
2022-11-08T20:45:28.466Z,1667940328.466 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 920
2022-11-08T20:45:28.466Z,1667940328.466 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-11-08T20:45:28.468Z,1667940328.468 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-11-08T20:45:28.537Z,1667940328.537 [VerticalControl](DEBUG): Construct VerticalControl.
2022-11-08T20:45:28.599Z,1667940328.599 [VerticalControl] Loaded
2022-11-08T20:45:28.599Z,1667940328.599 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-11-08T20:45:28.602Z,1667940328.602 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-11-08T20:45:28.644Z,1667940328.644 [HorizontalControl] Loaded
2022-11-08T20:45:28.644Z,1667940328.644 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-11-08T20:45:28.647Z,1667940328.647 [SpeedControl](DEBUG): Construct SpeedControl.
2022-11-08T20:45:28.650Z,1667940328.650 [SpeedControl] Loaded
2022-11-08T20:45:28.650Z,1667940328.650 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-11-08T20:45:28.653Z,1667940328.653 [LoopControl](DEBUG): Construct LoopControl.
2022-11-08T20:45:28.654Z,1667940328.654 [LoopControl] Loaded
2022-11-08T20:45:28.654Z,1667940328.654 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-11-08T20:45:28.654Z,1667940328.654 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-11-08T20:45:28.655Z,1667940328.655 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-11-08T20:45:28.725Z,1667940328.725 [DepthRateCalculator] Loaded
2022-11-08T20:45:28.725Z,1667940328.725 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-11-08T20:45:28.730Z,1667940328.730 [PitchRateCalculator] Loaded
2022-11-08T20:45:28.730Z,1667940328.730 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-11-08T20:45:28.740Z,1667940328.740 [SpeedCalculator] Loaded
2022-11-08T20:45:28.740Z,1667940328.740 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-11-08T20:45:28.744Z,1667940328.744 [YawRateCalculator] Loaded
2022-11-08T20:45:28.744Z,1667940328.744 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-11-08T20:45:28.763Z,1667940328.763 [ElevatorOffsetCalculator] Loaded
2022-11-08T20:45:28.763Z,1667940328.763 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-11-08T20:45:28.764Z,1667940328.764 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-11-08T20:45:28.764Z,1667940328.764 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-11-08T20:45:28.882Z,1667940328.882 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-11-08T20:45:28.883Z,1667940328.883 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-11-08T20:45:28.968Z,1667940328.968 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-11-08T20:45:28.968Z,1667940328.968 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-11-08T20:45:29.120Z,1667940329.120 [BuoyancyServo] Loaded
2022-11-08T20:45:29.120Z,1667940329.120 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-11-08T20:45:29.141Z,1667940329.141 [ElevatorServo] Loaded
2022-11-08T20:45:29.141Z,1667940329.141 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-11-08T20:45:29.160Z,1667940329.160 [MassServo] Loaded
2022-11-08T20:45:29.161Z,1667940329.161 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-11-08T20:45:29.179Z,1667940329.179 [RudderServo] Loaded
2022-11-08T20:45:29.180Z,1667940329.180 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-11-08T20:45:29.194Z,1667940329.194 [ThrusterHE] Loaded
2022-11-08T20:45:29.194Z,1667940329.194 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-11-08T20:45:29.195Z,1667940329.195 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-11-08T20:45:29.195Z,1667940329.195 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-11-08T20:45:29.385Z,1667940329.385 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-11-08T20:45:29.387Z,1667940329.387 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-11-08T20:45:30.105Z,1667940330.105 [AHRS_M2] Loaded
2022-11-08T20:45:30.106Z,1667940330.106 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-11-08T20:45:30.356Z,1667940330.356 [BackseatComponent] Loaded
2022-11-08T20:45:30.356Z,1667940330.356 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-11-08T20:45:30.357Z,1667940330.357 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0
2022-11-08T20:45:30.357Z,1667940330.357 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2022-11-08T20:45:30.360Z,1667940330.360 [LcmUniversalReporter] Loaded
2022-11-08T20:45:30.360Z,1667940330.360 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-11-08T20:45:31.476Z,1667940331.476 [BPC1] Loaded
2022-11-08T20:45:31.477Z,1667940331.477 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-11-08T20:45:31.619Z,1667940331.619 [DataOverHttps] Loaded
2022-11-08T20:45:31.619Z,1667940331.619 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-11-08T20:45:31.620Z,1667940331.620 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2022-11-08T20:45:31.620Z,1667940331.620 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2022-11-08T20:45:31.641Z,1667940331.641 [Depth_Keller] Loaded
2022-11-08T20:45:31.642Z,1667940331.642 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-11-08T20:45:31.647Z,1667940331.647 [DropWeight] Loaded
2022-11-08T20:45:31.647Z,1667940331.647 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-11-08T20:45:31.665Z,1667940331.665 [MultiRay] Loaded
2022-11-08T20:45:31.665Z,1667940331.665 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2022-11-08T20:45:31.727Z,1667940331.727 [NAL9602] Loaded
2022-11-08T20:45:31.728Z,1667940331.728 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-11-08T20:45:31.759Z,1667940331.759 [Onboard] Loaded
2022-11-08T20:45:31.759Z,1667940331.759 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-11-08T20:45:31.760Z,1667940331.760 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0
2022-11-08T20:45:31.760Z,1667940331.760 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2022-11-08T20:45:31.767Z,1667940331.767 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2022-11-08T20:45:31.778Z,1667940331.778 [PowerOnly] Loaded
2022-11-08T20:45:31.778Z,1667940331.778 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-11-08T20:45:31.790Z,1667940331.790 [Power24vConverter] Loaded
2022-11-08T20:45:31.791Z,1667940331.791 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-11-08T20:45:31.804Z,1667940331.804 [Radio_Surface] Loaded
2022-11-08T20:45:31.804Z,1667940331.804 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-11-08T20:45:31.805Z,1667940331.805 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0
2022-11-08T20:45:31.806Z,1667940331.806 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2022-11-08T20:45:31.899Z,1667940331.899 [DAT] Loaded
2022-11-08T20:45:31.900Z,1667940331.900 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-11-08T20:45:31.901Z,1667940331.901 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0
2022-11-08T20:45:31.901Z,1667940331.901 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2022-11-08T20:45:31.901Z,1667940331.901 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-11-08T20:45:31.902Z,1667940331.902 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-11-08T20:45:32.076Z,1667940332.076 [SBIT](DEBUG): Construct Startup Built In Test.
2022-11-08T20:45:32.085Z,1667940332.085 [SBIT] Loaded
2022-11-08T20:45:32.085Z,1667940332.085 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-11-08T20:45:32.088Z,1667940332.088 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-11-08T20:45:32.102Z,1667940332.102 [IBIT] Loaded
2022-11-08T20:45:32.102Z,1667940332.102 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-11-08T20:45:32.108Z,1667940332.108 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-11-08T20:45:32.214Z,1667940332.214 [CBIT] Loaded
2022-11-08T20:45:32.214Z,1667940332.214 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-11-08T20:45:32.215Z,1667940332.215 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-11-08T20:45:32.216Z,1667940332.216 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-11-08T20:45:32.260Z,1667940332.260 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-11-08T20:45:32.266Z,1667940332.266 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-11-08T20:45:32.269Z,1667940332.269 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-11-08T20:45:32.280Z,1667940332.280 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-11-08T20:45:32.281Z,1667940332.281 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA34E0
2022-11-08T20:45:32.282Z,1667940332.282 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2022-11-08T20:45:32.286Z,1667940332.286 [Supervisor](INFO): Main Thread ID is 829
2022-11-08T20:45:32.287Z,1667940332.287 [Supervisor](DEBUG): Running supervisor.
2022-11-08T20:45:32.287Z,1667940332.287 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2022-11-08T20:45:32.288Z,1667940332.288 [CommandExec](INFO): Initializing the command executive.
2022-11-08T20:45:32.289Z,1667940332.289 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2022-11-08T20:45:32.291Z,1667940332.291 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2022-11-08T20:45:32.292Z,1667940332.292 [controlThread](DEBUG): Initializing ControlThread
2022-11-08T20:45:32.293Z,1667940332.293 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-11-08T20:45:32.294Z,1667940332.294 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-11-08T20:45:32.294Z,1667940332.294 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-11-08T20:45:32.295Z,1667940332.295 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-11-08T20:45:32.297Z,1667940332.297 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-11-08T20:45:32.298Z,1667940332.298 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-11-08T20:45:32.298Z,1667940332.298 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-11-08T20:45:32.298Z,1667940332.298 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-11-08T20:45:32.299Z,1667940332.299 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-11-08T20:45:32.299Z,1667940332.299 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-11-08T20:45:32.299Z,1667940332.299 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-11-08T20:45:32.300Z,1667940332.300 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-11-08T20:45:32.305Z,1667940332.305 [SBIT](INFO): Initialize SBIT Component.
2022-11-08T20:45:32.306Z,1667940332.306 [SBIT](IMPORTANT): git: 2022-10-11-45-gb822ed017
2022-11-08T20:45:32.306Z,1667940332.306 [SBIT](INFO): git hash: b822ed017b998e9f5c0380c5dda45bcbd9b57e7d
2022-11-08T20:45:32.306Z,1667940332.306 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-11-08T20:45:32.308Z,1667940332.308 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-11-08T20:45:32.309Z,1667940332.309 [SBIT](INFO): Beginning SBIT in 109.000000 seconds.
2022-11-08T20:45:32.309Z,1667940332.309 [IBIT](INFO): Initialize IBIT Component.
2022-11-08T20:45:32.310Z,1667940332.310 [CBIT](DEBUG): Initialize CBIT Component.
2022-11-08T20:45:32.311Z,1667940332.311 [logger ThreadHandler](INFO): Handler Thread ID is 930
2022-11-08T20:45:32.323Z,1667940332.323 [CBIT](DEBUG): Initialized mux pins.
2022-11-08T20:45:32.323Z,1667940332.323 [CBIT](DEBUG): Initializing the watchdog timer.
2022-11-08T20:45:32.332Z,1667940332.332 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 931
2022-11-08T20:45:32.336Z,1667940332.336 [WetLabsBB2FL](INFO): Powering up
2022-11-08T20:45:32.337Z,1667940332.337 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933
2022-11-08T20:45:32.344Z,1667940332.344 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2022-11-08T20:45:32.345Z,1667940332.345 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-11-08T20:45:32.347Z,1667940332.347 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-11-08T20:45:32.348Z,1667940332.348 [CBIT](DEBUG): Initializing heartbeat.
2022-11-08T20:45:32.356Z,1667940332.356 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2022-11-08T20:45:32.373Z,1667940332.373 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2022-11-08T20:45:32.392Z,1667940332.392 [DAT ThreadHandler](INFO): Handler Thread ID is 937
2022-11-08T20:45:32.393Z,1667940332.393 [DAT](INFO): Powering up
2022-11-08T20:45:32.393Z,1667940332.393 [DAT](DEBUG): Initializing DAT.
2022-11-08T20:45:32.396Z,1667940332.396 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 939
2022-11-08T20:45:32.404Z,1667940332.404 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-11-08T20:45:32.405Z,1667940332.405 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-11-08T20:45:32.405Z,1667940332.405 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-11-08T20:45:32.405Z,1667940332.405 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-11-08T20:45:32.405Z,1667940332.405 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-11-08T20:45:32.405Z,1667940332.405 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-11-08T20:45:32.406Z,1667940332.406 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-11-08T20:45:32.406Z,1667940332.406 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-11-08T20:45:32.419Z,1667940332.419 [CBIT](DEBUG): Deactivating GF circuits.
2022-11-08T20:45:32.419Z,1667940332.419 [CBIT](DEBUG): Deactivating emergency mode.
2022-11-08T20:45:32.455Z,1667940332.455 [CBIT](DEBUG): Backplane powered.
2022-11-08T20:45:32.456Z,1667940332.456 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-11-08T20:45:32.485Z,1667940332.485 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-11-08T20:45:32.529Z,1667940332.529 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2022-11-08T20:45:32.530Z,1667940332.530 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-11-08T20:45:32.605Z,1667940332.605 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-11-08T20:45:32.606Z,1667940332.606 [Default:A.Wait](DEBUG): Construct Wait.
2022-11-08T20:45:32.609Z,1667940332.609 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-11-08T20:45:32.660Z,1667940332.660 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-11-08T20:45:32.662Z,1667940332.662 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-11-08T20:45:32.684Z,1667940332.684 [Default:E.Execute](DEBUG): Construct Execute.
2022-11-08T20:45:32.696Z,1667940332.696 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2022-11-08T20:45:32.701Z,1667940332.701 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-11-08T20:45:32.724Z,1667940332.724 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-11-08T20:45:32.779Z,1667940332.779 [Radio_Surface](INFO): Powering up
2022-11-08T20:45:32.893Z,1667940332.893 [MultiRay](INFO): Powering up MultiRay Lights
2022-11-08T20:45:32.936Z,1667940332.936 [Power24vConverter](INFO): Powering up.
2022-11-08T20:45:32.989Z,1667940332.989 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-11-08T20:45:32.996Z,1667940332.996 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-11-08T20:45:32.997Z,1667940332.997 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-11-08T20:45:33.003Z,1667940333.003 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-11-08T20:45:33.004Z,1667940333.004 [MassServo](DEBUG): Initializing EZServoServo.
2022-11-08T20:45:33.012Z,1667940333.012 [MassServo](DEBUG): Initializing MassServo.
2022-11-08T20:45:33.012Z,1667940333.012 [RudderServo](DEBUG): Initializing EZServoServo.
2022-11-08T20:45:33.020Z,1667940333.020 [RudderServo](DEBUG): Initializing RudderServo.
2022-11-08T20:45:33.020Z,1667940333.020 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-11-08T20:45:33.028Z,1667940333.028 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-11-08T20:45:33.139Z,1667940333.139 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-11-08T20:45:33.139Z,1667940333.139 [DropWeight] Hardware Fault, FailCount= 1
2022-11-08T20:45:33.139Z,1667940333.139 [DropWeight](ERROR): Hardware Fault
2022-11-08T20:45:33.172Z,1667940333.172 [MultiRay](IMPORTANT): All lights off
2022-11-08T20:45:33.214Z,1667940333.214 [CommandExec](FAULT): Scheduling is paused
2022-11-08T20:45:33.214Z,1667940333.214 [CBIT](INFO): Critical error at 20221108T204533
2022-11-08T20:45:33.214Z,1667940333.214 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-11-08T20:45:33.225Z,1667940333.225 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-11-08T20:45:33.226Z,1667940333.226 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-11-08T20:45:33.839Z,1667940333.839 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-11-08T20:45:33.839Z,1667940333.839 [RudderServo](FAULT): Rudder failed to initialize
2022-11-08T20:45:33.840Z,1667940333.840 [RudderServo] Communications Fault, FailCount= 1
2022-11-08T20:45:33.840Z,1667940333.840 [RudderServo](ERROR): Communications Fault
2022-11-08T20:45:33.841Z,1667940333.841 [CBIT](INFO): Critical error at 20221108T204533
2022-11-08T20:45:33.844Z,1667940333.844 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-11-08T20:45:34.084Z,1667940334.084 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-11-08T20:45:34.084Z,1667940334.084 [RudderServo](INFO): Powering down
2022-11-08T20:45:34.756Z,1667940334.756 [RudderServo](DEBUG): Initializing EZServoServo.
2022-11-08T20:45:34.876Z,1667940334.876 [RudderServo](DEBUG): Initializing RudderServo.
2022-11-08T20:45:34.880Z,1667940334.880 [CBIT](INFO): Clearing failed state for component RudderServo
2022-11-08T20:45:34.880Z,1667940334.880 [RudderServo] No Fault, FailCount= 1
2022-11-08T20:45:34.947Z,1667940334.947 [WetLabsBB2FL](INFO): Powering down
2022-11-08T20:45:38.660Z,1667940338.660 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-11-08T20:45:48.025Z,1667940348.025 [DAT](INFO): commRate: 800
2022-11-08T20:45:50.091Z,1667940350.091 [DAT](INFO): entering command mode
2022-11-08T20:45:50.292Z,1667940350.292 [DAT](INFO): setting verbose to 3
2022-11-08T20:45:50.546Z,1667940350.546 [DAT](INFO): set verbose to 3
2022-11-08T20:45:50.546Z,1667940350.546 [DAT](INFO): setting DatVerbose to 27440
2022-11-08T20:45:50.797Z,1667940350.797 [DAT](INFO): set DatVerbose to 27440
2022-11-08T20:45:50.797Z,1667940350.797 [DAT](INFO): setting transmit power to 8
2022-11-08T20:45:51.049Z,1667940351.049 [DAT](INFO): set transmit power to 8
2022-11-08T20:45:51.049Z,1667940351.049 [DAT](INFO): setting local address to 10
2022-11-08T20:45:51.301Z,1667940351.301 [DAT](INFO): set local address to 10
2022-11-08T20:45:51.302Z,1667940351.302 [DAT](INFO): Setting time to: 20:45:51 And date to:11/8/2022
2022-11-08T20:45:51.553Z,1667940351.553 [DAT](INFO): Local DAT time set to Tue Nov 8, 2022  20:45:51  

2022-11-08T20:46:01.809Z,1667940361.809 [NAL9602](INFO): Powering up NAL9602
2022-11-08T20:46:12.725Z,1667940372.725 [NAL9602](INFO): NAL9602 initialized
2022-11-08T20:47:21.830Z,1667940441.830 [SBIT](IMPORTANT): Beginning Startup BIT
2022-11-08T20:47:21.834Z,1667940441.834 [CBIT](IMPORTANT): Beginning ground fault scan
2022-11-08T20:47:32.764Z,1667940452.764 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.012609
CHAN A1 (24V): 0.000487
CHAN A2 (12V): -0.005343
CHAN A3 (5V): -0.002678
CHAN B0 (3.3V): -0.002013
CHAN B1 (3.15aV): -0.001837
CHAN B2 (3.15bV): -0.001164
CHAN B3 (GND): -0.000339
OPEN: 0.006223
Full Scale: +/- 1 mA
2022-11-08T20:48:03.418Z,1667940483.418 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005183
2022-11-08T20:48:16.006Z,1667940496.006 [SBIT](IMPORTANT): SBIT PASSED
2022-11-08T20:48:16.006Z,1667940496.006 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-11-08T20:48:16.007Z,1667940496.007 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;

2022-11-08T20:48:16.008Z,1667940496.008 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none;

2022-11-08T20:48:16.008Z,1667940496.008 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere;

2022-11-08T20:48:16.008Z,1667940496.008 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-11-08T20:48:16.009Z,1667940496.009 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2022-11-08T20:48:16.009Z,1667940496.009 [SBIT](IMPORTANT): DAT.surfaceThreshold=-1 meter;

2022-11-08T20:48:16.009Z,1667940496.009 [SBIT](IMPORTANT): Express none _.ayeris_disk_space;

2022-11-08T20:48:16.010Z,1667940496.010 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 250.000000 count;

2022-11-08T20:48:16.010Z,1667940496.010 [SBIT](IMPORTANT): Express none _.ayeris_particle_size;

2022-11-08T20:48:16.010Z,1667940496.010 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;

2022-11-08T20:48:16.010Z,1667940496.010 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;

2022-11-08T20:48:16.010Z,1667940496.010 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=210.367112 cubic_centimeter;

2022-11-08T20:48:16.010Z,1667940496.010 [SBIT](IMPORTANT): VerticalControl.massDefault=22.333859 millimeter;

2022-11-08T20:48:16.395Z,1667940496.395 [MissionManager](IMPORTANT): Started mission Startup
2022-11-08T20:48:16.395Z,1667940496.395 [Startup] Running Loop=1
2022-11-08T20:48:16.395Z,1667940496.395 [Startup](DEBUG): Aggregate::initialize Startup
2022-11-08T20:48:16.396Z,1667940496.396 [Startup:A.GoToSurface] Running Loop=1
2022-11-08T20:48:16.396Z,1667940496.396 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:48:16.396Z,1667940496.396 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:48:16.397Z,1667940496.397 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:48:16.397Z,1667940496.397 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:48:16.398Z,1667940496.398 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:48:16.398Z,1667940496.398 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:48:16.399Z,1667940496.399 [Startup:StartupSatComms] Running Loop=1
2022-11-08T20:48:16.399Z,1667940496.399 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-11-08T20:48:16.399Z,1667940496.399 [Startup:StartupSatComms:A] Running Loop=1
2022-11-08T20:48:16.802Z,1667940496.802 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-11-08T20:48:32.561Z,1667940512.561 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-11-08T20:48:32.561Z,1667940512.561 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-08T20:48:32.562Z,1667940512.562 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2022-11-08T20:48:32.562Z,1667940512.562 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-11-08T20:48:32.573Z,1667940512.573 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-08T20:48:32.573Z,1667940512.573 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-11-08T20:48:32.985Z,1667940512.985 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-08T20:48:32.985Z,1667940512.985 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-11-08T20:48:32.986Z,1667940512.986 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-11-08T20:48:32.986Z,1667940512.986 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2022-11-08T20:48:33.390Z,1667940513.390 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-11-08T20:49:16.615Z,1667940556.615 [Startup:StartupSatComms:A](INFO): Timed out from 2022-11-08T20:48:16.4Z
2022-11-08T20:49:16.615Z,1667940556.615 [Startup:StartupSatComms:A] Stopped
2022-11-08T20:49:16.615Z,1667940556.615 [Startup:StartupSatComms:B] Running Loop=1
2022-11-08T20:49:17.019Z,1667940557.019 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-11-08T20:49:18.972Z,1667940558.972 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:49:18.974Z,1667940558.974 [DAT](INFO): setting remote address to 1
2022-11-08T20:49:19.226Z,1667940559.226 [DAT](INFO): set remote address to 1
2022-11-08T20:49:19.226Z,1667940559.226 [DAT](INFO): entering online mode
2022-11-08T20:49:19.479Z,1667940559.479 [DAT](INFO): commRate: 800
2022-11-08T20:49:19.479Z,1667940559.479 [DAT](INFO): online mode acknowledged
2022-11-08T20:49:19.479Z,1667940559.479 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-08T20:49:22.754Z,1667940562.754 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-08T20:49:24.315Z,1667940564.315 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221108T201236/Courier0007.lzma
2022-11-08T20:49:25.282Z,1667940565.282 [DataOverHttps](INFO): Moved sent file to Logs/20221108T201236/Courier0007.lzma.bak
2022-11-08T20:49:25.282Z,1667940565.282 [DataOverHttps](INFO): SBD MOMSN=17274968
2022-11-08T20:49:33.853Z,1667940573.853 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-08T20:49:34.714Z,1667940574.714 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:49:34.716Z,1667940574.716 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-08T20:49:37.998Z,1667940577.998 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-08T20:49:40.357Z,1667940580.357 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode1.xml
2022-11-08T20:49:40.357Z,1667940580.357 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode1.xml
2022-11-08T20:49:40.410Z,1667940580.410 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:49:40.417Z,1667940580.417 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:49:40.424Z,1667940580.424 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:49:40.427Z,1667940580.427 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:49:40.486Z,1667940580.486 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:49:40.515Z,1667940580.515 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode1"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:49:40.576Z,1667940580.576 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode1.xml
2022-11-08T20:49:42.204Z,1667940582.204 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20221108T204521/Courier0000.lzma
2022-11-08T20:49:43.206Z,1667940583.206 [DataOverHttps](INFO): Moved sent file to Logs/20221108T204521/Courier0000.lzma.bak
2022-11-08T20:49:43.206Z,1667940583.206 [DataOverHttps](INFO): SBD MOMSN=17274970
2022-11-08T20:49:55.153Z,1667940595.153 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-08T20:49:56.103Z,1667940596.103 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:49:56.105Z,1667940596.105 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-08T20:49:56.246Z,1667940596.246 [Startup:StartupSatComms:B] Stopped
2022-11-08T20:49:56.246Z,1667940596.246 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-11-08T20:49:56.246Z,1667940596.246 [Startup:StartupSatComms] Stopped
2022-11-08T20:49:56.246Z,1667940596.246 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-11-08T20:49:56.247Z,1667940596.247 [Startup](INFO): Completed Startup
2022-11-08T20:49:56.247Z,1667940596.247 [MissionManager](INFO): Startup is completed.
2022-11-08T20:49:56.247Z,1667940596.247 [MissionManager](INFO): Uninitializing Mission Startup
2022-11-08T20:49:56.247Z,1667940596.247 [Startup] Stopped
2022-11-08T20:49:56.248Z,1667940596.248 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-11-08T20:49:56.248Z,1667940596.248 [Startup:A.GoToSurface] Stopped
2022-11-08T20:49:56.248Z,1667940596.248 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:49:56.578Z,1667940596.578 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode1.xml
2022-11-08T20:49:56.579Z,1667940596.579 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:49:56.628Z,1667940596.628 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode1.xml
2022-11-08T20:49:56.713Z,1667940596.713 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:49:56.715Z,1667940596.715 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:49:56.721Z,1667940596.721 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:49:56.723Z,1667940596.723 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:49:56.729Z,1667940596.729 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:49:56.766Z,1667940596.766 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode1"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:49:56.767Z,1667940596.767 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode1.xml
2022-11-08T20:49:57.034Z,1667940597.034 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:49:57.035Z,1667940597.035 [multiray_lab] Running Loop=1
2022-11-08T20:49:57.035Z,1667940597.035 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:49:58.395Z,1667940598.395 [multiray_lab](INFO): Timed out from 2022-11-08T20:49:57.0Z
2022-11-08T20:49:58.395Z,1667940598.395 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:49:58.395Z,1667940598.395 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:49:58.395Z,1667940598.395 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:49:58.396Z,1667940598.396 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:49:58.416Z,1667940598.416 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:49:58.416Z,1667940598.416 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:49:58.416Z,1667940598.416 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:49:58.416Z,1667940598.416 [multiray_lab] Stopped
2022-11-08T20:49:58.417Z,1667940598.417 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:49:58.737Z,1667940598.737 [MultiRay](INFO): Red lights on
2022-11-08T20:49:58.791Z,1667940598.791 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:49:58.792Z,1667940598.792 [Default] Running Loop=1
2022-11-08T20:49:58.800Z,1667940598.800 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:49:58.801Z,1667940598.801 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:49:58.801Z,1667940598.801 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:49:58.801Z,1667940598.801 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:49:58.801Z,1667940598.801 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:49:58.801Z,1667940598.801 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:49:58.802Z,1667940598.802 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:49:58.802Z,1667940598.802 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:49:58.802Z,1667940598.802 [Default:A.Wait] Running Loop=1
2022-11-08T20:49:58.803Z,1667940598.803 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:01.182Z,1667940601.182 [DataOverHttps](INFO): Sending 368 bytes from file Logs/20221108T201236/Express0008.lzma
2022-11-08T20:50:02.182Z,1667940602.182 [DataOverHttps](INFO): Moved sent file to Logs/20221108T201236/Express0008.lzma.bak
2022-11-08T20:50:02.182Z,1667940602.182 [DataOverHttps](INFO): SBD MOMSN=17274975
2022-11-08T20:50:04.378Z,1667940604.378 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:04.378Z,1667940604.378 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:04.415Z,1667940604.415 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:04.418Z,1667940604.418 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:04.419Z,1667940604.419 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:04.422Z,1667940604.422 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:04.424Z,1667940604.424 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:04.428Z,1667940604.428 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:04.430Z,1667940604.430 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:04.730Z,1667940604.730 [Default] Stopped
2022-11-08T20:50:04.731Z,1667940604.731 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:04.731Z,1667940604.731 [Default:A.Wait] Stopped
2022-11-08T20:50:04.731Z,1667940604.731 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:04.731Z,1667940604.731 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:04.731Z,1667940604.731 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:04.731Z,1667940604.731 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:04.731Z,1667940604.731 [multiray_lab] Running Loop=1
2022-11-08T20:50:04.731Z,1667940604.731 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:05.931Z,1667940605.931 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:04.7Z
2022-11-08T20:50:05.931Z,1667940605.931 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:05.931Z,1667940605.931 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:05.931Z,1667940605.931 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:05.932Z,1667940605.932 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:05.932Z,1667940605.932 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:05.936Z,1667940605.936 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:05.936Z,1667940605.936 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:05.936Z,1667940605.936 [multiray_lab] Stopped
2022-11-08T20:50:05.937Z,1667940605.937 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:06.205Z,1667940606.205 [DAT](FAULT): Buffer send receipt timeout failure.
2022-11-08T20:50:06.205Z,1667940606.205 [DAT](DEBUG): In sendingTransmitVerify, timeout so go online and set commsState_ = SENDING_FILL_BUFFER
2022-11-08T20:50:06.393Z,1667940606.393 [MultiRay](INFO): White lights on
2022-11-08T20:50:06.447Z,1667940606.447 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:06.447Z,1667940606.447 [Default] Running Loop=1
2022-11-08T20:50:06.447Z,1667940606.447 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:06.447Z,1667940606.447 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:06.447Z,1667940606.447 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:06.448Z,1667940606.448 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:06.452Z,1667940606.452 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:06.452Z,1667940606.452 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:06.453Z,1667940606.453 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:06.453Z,1667940606.453 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:06.453Z,1667940606.453 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:06.453Z,1667940606.453 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:06.457Z,1667940606.457 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:50:06.709Z,1667940606.709 [DAT](INFO): entering online mode
2022-11-08T20:50:06.961Z,1667940606.961 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:07.213Z,1667940607.213 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:07.465Z,1667940607.465 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:07.717Z,1667940607.717 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:07.971Z,1667940607.971 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:08.225Z,1667940608.225 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:08.477Z,1667940608.477 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:08.729Z,1667940608.729 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:08.981Z,1667940608.981 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:09.234Z,1667940609.234 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:09.485Z,1667940609.485 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:09.737Z,1667940609.737 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:09.989Z,1667940609.989 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:10.106Z,1667940610.106 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode0.xml
2022-11-08T20:50:10.107Z,1667940610.107 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode0.xml
2022-11-08T20:50:10.141Z,1667940610.141 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:10.143Z,1667940610.143 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:10.145Z,1667940610.145 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:10.147Z,1667940610.147 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:10.150Z,1667940610.150 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:10.154Z,1667940610.154 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode0"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:10.156Z,1667940610.156 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode0.xml
2022-11-08T20:50:10.241Z,1667940610.241 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:10.361Z,1667940610.361 [Default] Stopped
2022-11-08T20:50:10.361Z,1667940610.361 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:10.361Z,1667940610.361 [Default:A.Wait] Stopped
2022-11-08T20:50:10.361Z,1667940610.361 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:10.361Z,1667940610.361 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:10.362Z,1667940610.362 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:10.362Z,1667940610.362 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:10.362Z,1667940610.362 [multiray_lab] Running Loop=1
2022-11-08T20:50:10.362Z,1667940610.362 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:10.493Z,1667940610.493 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:10.745Z,1667940610.745 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:10.997Z,1667940610.997 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:11.249Z,1667940611.249 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:11.501Z,1667940611.501 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:11.654Z,1667940611.654 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:10.4Z
2022-11-08T20:50:11.654Z,1667940611.654 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:11.654Z,1667940611.654 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:11.655Z,1667940611.655 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:11.655Z,1667940611.655 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:11.655Z,1667940611.655 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:11.655Z,1667940611.655 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:11.656Z,1667940611.656 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:11.656Z,1667940611.656 [multiray_lab] Stopped
2022-11-08T20:50:11.656Z,1667940611.656 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:11.753Z,1667940611.753 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:12.005Z,1667940612.005 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:12.072Z,1667940612.072 [MultiRay](IMPORTANT): All lights off
2022-11-08T20:50:12.105Z,1667940612.105 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:12.105Z,1667940612.105 [Default] Running Loop=1
2022-11-08T20:50:12.105Z,1667940612.105 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:12.105Z,1667940612.105 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:12.105Z,1667940612.105 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:12.105Z,1667940612.105 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:12.106Z,1667940612.106 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:12.106Z,1667940612.106 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:12.106Z,1667940612.106 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:12.107Z,1667940612.107 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:12.107Z,1667940612.107 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:12.107Z,1667940612.107 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:12.257Z,1667940612.257 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:12.509Z,1667940612.509 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:12.761Z,1667940612.761 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:13.013Z,1667940613.013 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:13.265Z,1667940613.265 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:13.517Z,1667940613.517 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:13.772Z,1667940613.772 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:14.021Z,1667940614.021 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:14.273Z,1667940614.273 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:14.525Z,1667940614.525 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:14.777Z,1667940614.777 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:15.029Z,1667940615.029 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:15.289Z,1667940615.289 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:15.541Z,1667940615.541 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:15.793Z,1667940615.793 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:15.835Z,1667940615.835 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:15.836Z,1667940615.836 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:15.853Z,1667940615.853 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:15.855Z,1667940615.855 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:15.889Z,1667940615.889 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:15.891Z,1667940615.891 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:15.893Z,1667940615.893 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:15.897Z,1667940615.897 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:15.899Z,1667940615.899 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:16.022Z,1667940616.022 [Default] Stopped
2022-11-08T20:50:16.022Z,1667940616.022 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:16.022Z,1667940616.022 [Default:A.Wait] Stopped
2022-11-08T20:50:16.022Z,1667940616.022 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:16.022Z,1667940616.022 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:16.022Z,1667940616.022 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:16.023Z,1667940616.023 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:16.023Z,1667940616.023 [multiray_lab] Running Loop=1
2022-11-08T20:50:16.023Z,1667940616.023 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:16.045Z,1667940616.045 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:16.297Z,1667940616.297 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:16.549Z,1667940616.549 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:16.805Z,1667940616.805 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:17.053Z,1667940617.053 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:17.228Z,1667940617.228 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:16.0Z
2022-11-08T20:50:17.228Z,1667940617.228 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:17.228Z,1667940617.228 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:17.229Z,1667940617.229 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:17.229Z,1667940617.229 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:17.229Z,1667940617.229 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:17.229Z,1667940617.229 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:17.229Z,1667940617.229 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:17.230Z,1667940617.230 [multiray_lab] Stopped
2022-11-08T20:50:17.230Z,1667940617.230 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:17.305Z,1667940617.305 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:17.557Z,1667940617.557 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:17.662Z,1667940617.662 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:17.663Z,1667940617.663 [Default] Running Loop=1
2022-11-08T20:50:17.663Z,1667940617.663 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:17.663Z,1667940617.663 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:17.663Z,1667940617.663 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:17.663Z,1667940617.663 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:17.663Z,1667940617.663 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:17.664Z,1667940617.664 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:17.672Z,1667940617.672 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:17.673Z,1667940617.673 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:17.673Z,1667940617.673 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:17.673Z,1667940617.673 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:17.809Z,1667940617.809 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:18.061Z,1667940618.061 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:18.313Z,1667940618.313 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:18.565Z,1667940618.565 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:18.817Z,1667940618.817 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:19.069Z,1667940619.069 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:19.321Z,1667940619.321 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:19.573Z,1667940619.573 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:19.825Z,1667940619.825 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:20.077Z,1667940620.077 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:20.329Z,1667940620.329 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:20.581Z,1667940620.581 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:20.833Z,1667940620.833 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:20.947Z,1667940620.947 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:20.947Z,1667940620.947 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:20.986Z,1667940620.986 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:20.988Z,1667940620.988 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:20.991Z,1667940620.991 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:20.997Z,1667940620.997 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:20.999Z,1667940620.999 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:21.003Z,1667940621.003 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:21.005Z,1667940621.005 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:21.085Z,1667940621.085 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:21.287Z,1667940621.287 [Default] Stopped
2022-11-08T20:50:21.287Z,1667940621.287 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:21.287Z,1667940621.287 [Default:A.Wait] Stopped
2022-11-08T20:50:21.287Z,1667940621.287 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:21.287Z,1667940621.287 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:21.287Z,1667940621.287 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:21.287Z,1667940621.287 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:21.287Z,1667940621.287 [multiray_lab] Running Loop=1
2022-11-08T20:50:21.288Z,1667940621.288 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:21.337Z,1667940621.337 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:21.589Z,1667940621.589 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:21.841Z,1667940621.841 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:21.841Z,1667940621.841 [DAT](FAULT): failed to enter online mode
2022-11-08T20:50:21.841Z,1667940621.841 [DAT](FAULT): Failure returning to online mode
2022-11-08T20:50:21.841Z,1667940621.841 [DAT] Communications Fault, FailCount= 1
2022-11-08T20:50:21.841Z,1667940621.841 [DAT](ERROR): Communications Fault
2022-11-08T20:50:22.087Z,1667940622.087 [CBIT](ERROR): Communications Fault in component: DAT
2022-11-08T20:50:22.244Z,1667940622.244 [DAT](INFO): Powering down
2022-11-08T20:50:22.491Z,1667940622.491 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:21.3Z
2022-11-08T20:50:22.491Z,1667940622.491 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:22.491Z,1667940622.491 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:22.491Z,1667940622.491 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:22.492Z,1667940622.492 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:22.492Z,1667940622.492 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:22.492Z,1667940622.492 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:22.492Z,1667940622.492 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:22.492Z,1667940622.492 [multiray_lab] Stopped
2022-11-08T20:50:22.492Z,1667940622.492 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:22.919Z,1667940622.919 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:22.919Z,1667940622.919 [Default] Running Loop=1
2022-11-08T20:50:22.919Z,1667940622.919 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:22.919Z,1667940622.919 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:22.920Z,1667940622.920 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:22.920Z,1667940622.920 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:22.920Z,1667940622.920 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:22.920Z,1667940622.920 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:22.921Z,1667940622.921 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:22.921Z,1667940622.921 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:22.921Z,1667940622.921 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:22.921Z,1667940622.921 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:23.307Z,1667940623.307 [CBIT](INFO): Clearing failed state for component DAT
2022-11-08T20:50:23.307Z,1667940623.307 [DAT] No Fault, FailCount= 1
2022-11-08T20:50:25.292Z,1667940625.292 [DAT](INFO): Powering up
2022-11-08T20:50:25.292Z,1667940625.292 [DAT](DEBUG): Initializing DAT.
2022-11-08T20:50:25.357Z,1667940625.357 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:25.357Z,1667940625.357 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:25.422Z,1667940625.422 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:25.424Z,1667940625.424 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:25.426Z,1667940625.426 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:25.448Z,1667940625.448 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:25.451Z,1667940625.451 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:25.463Z,1667940625.463 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:25.470Z,1667940625.470 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:25.766Z,1667940625.766 [Default] Stopped
2022-11-08T20:50:25.766Z,1667940625.766 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:25.766Z,1667940625.766 [Default:A.Wait] Stopped
2022-11-08T20:50:25.766Z,1667940625.766 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:25.767Z,1667940625.767 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:25.767Z,1667940625.767 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:25.767Z,1667940625.767 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:25.767Z,1667940625.767 [multiray_lab] Running Loop=1
2022-11-08T20:50:25.767Z,1667940625.767 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:26.937Z,1667940626.937 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:25.8Z
2022-11-08T20:50:26.937Z,1667940626.937 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:26.937Z,1667940626.937 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:26.937Z,1667940626.937 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:26.938Z,1667940626.938 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:26.938Z,1667940626.938 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:26.938Z,1667940626.938 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:26.938Z,1667940626.938 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:26.938Z,1667940626.938 [multiray_lab] Stopped
2022-11-08T20:50:26.938Z,1667940626.938 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:27.333Z,1667940627.333 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:27.334Z,1667940627.334 [Default] Running Loop=1
2022-11-08T20:50:27.334Z,1667940627.334 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:27.334Z,1667940627.334 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:27.334Z,1667940627.334 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:27.334Z,1667940627.334 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:27.335Z,1667940627.335 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:27.335Z,1667940627.335 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:27.335Z,1667940627.335 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:27.335Z,1667940627.335 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:27.336Z,1667940627.336 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:27.356Z,1667940627.356 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:29.237Z,1667940629.237 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:29.238Z,1667940629.238 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:29.256Z,1667940629.256 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:29.258Z,1667940629.258 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:29.260Z,1667940629.260 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:29.262Z,1667940629.262 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:29.264Z,1667940629.264 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:29.294Z,1667940629.294 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:29.296Z,1667940629.296 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:29.350Z,1667940629.350 [Default] Stopped
2022-11-08T20:50:29.350Z,1667940629.350 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:29.350Z,1667940629.350 [Default:A.Wait] Stopped
2022-11-08T20:50:29.350Z,1667940629.350 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:29.350Z,1667940629.350 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:29.350Z,1667940629.350 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:29.351Z,1667940629.351 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:29.351Z,1667940629.351 [multiray_lab] Running Loop=1
2022-11-08T20:50:29.351Z,1667940629.351 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:30.591Z,1667940630.591 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:29.4Z
2022-11-08T20:50:30.591Z,1667940630.591 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:30.591Z,1667940630.591 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:30.591Z,1667940630.591 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:30.591Z,1667940630.591 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:30.592Z,1667940630.592 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:30.592Z,1667940630.592 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:30.593Z,1667940630.593 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:30.593Z,1667940630.593 [multiray_lab] Stopped
2022-11-08T20:50:30.593Z,1667940630.593 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:31.003Z,1667940631.003 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:31.003Z,1667940631.003 [Default] Running Loop=1
2022-11-08T20:50:31.003Z,1667940631.003 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:31.003Z,1667940631.003 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:31.003Z,1667940631.003 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:31.003Z,1667940631.003 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:31.004Z,1667940631.004 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:31.004Z,1667940631.004 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:31.004Z,1667940631.004 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:31.005Z,1667940631.005 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:31.005Z,1667940631.005 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:31.005Z,1667940631.005 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:33.627Z,1667940633.627 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:33.628Z,1667940633.628 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:33.646Z,1667940633.646 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:33.648Z,1667940633.648 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:33.674Z,1667940633.674 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:33.676Z,1667940633.676 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:33.678Z,1667940633.678 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:33.682Z,1667940633.682 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:33.684Z,1667940633.684 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:33.819Z,1667940633.819 [Default] Stopped
2022-11-08T20:50:33.819Z,1667940633.819 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:33.819Z,1667940633.819 [Default:A.Wait] Stopped
2022-11-08T20:50:33.819Z,1667940633.819 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:33.819Z,1667940633.819 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:33.820Z,1667940633.820 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:33.820Z,1667940633.820 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:33.820Z,1667940633.820 [multiray_lab] Running Loop=1
2022-11-08T20:50:33.821Z,1667940633.821 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:35.028Z,1667940635.028 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:33.8Z
2022-11-08T20:50:35.028Z,1667940635.028 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:35.028Z,1667940635.028 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:35.028Z,1667940635.028 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:35.029Z,1667940635.029 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:35.029Z,1667940635.029 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:35.029Z,1667940635.029 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:35.029Z,1667940635.029 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:35.029Z,1667940635.029 [multiray_lab] Stopped
2022-11-08T20:50:35.029Z,1667940635.029 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:35.454Z,1667940635.454 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:35.454Z,1667940635.454 [Default] Running Loop=1
2022-11-08T20:50:35.454Z,1667940635.454 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:35.454Z,1667940635.454 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:35.454Z,1667940635.454 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:35.455Z,1667940635.455 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:35.455Z,1667940635.455 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:35.455Z,1667940635.455 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:35.455Z,1667940635.455 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:35.456Z,1667940635.456 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:35.456Z,1667940635.456 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:35.456Z,1667940635.456 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:37.439Z,1667940637.439 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-11-08T20:50:37.443Z,1667940637.443 [BPC1](INFO): Received data from all battery sticks.
2022-11-08T20:50:38.106Z,1667940638.106 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:38.107Z,1667940638.107 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:38.125Z,1667940638.125 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:38.127Z,1667940638.127 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:38.161Z,1667940638.161 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:38.163Z,1667940638.163 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:38.166Z,1667940638.166 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:38.170Z,1667940638.170 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:38.172Z,1667940638.172 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:38.253Z,1667940638.253 [Default] Stopped
2022-11-08T20:50:38.254Z,1667940638.254 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:38.254Z,1667940638.254 [Default:A.Wait] Stopped
2022-11-08T20:50:38.254Z,1667940638.254 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:38.254Z,1667940638.254 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:38.254Z,1667940638.254 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:38.254Z,1667940638.254 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:38.254Z,1667940638.254 [multiray_lab] Running Loop=1
2022-11-08T20:50:38.254Z,1667940638.254 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:39.459Z,1667940639.459 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:38.3Z
2022-11-08T20:50:39.459Z,1667940639.459 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:39.459Z,1667940639.459 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:39.459Z,1667940639.459 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:39.459Z,1667940639.459 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:39.460Z,1667940639.460 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:39.460Z,1667940639.460 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:39.461Z,1667940639.461 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:39.461Z,1667940639.461 [multiray_lab] Stopped
2022-11-08T20:50:39.461Z,1667940639.461 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:39.867Z,1667940639.867 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:39.867Z,1667940639.867 [Default] Running Loop=1
2022-11-08T20:50:39.868Z,1667940639.868 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:39.868Z,1667940639.868 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:39.868Z,1667940639.868 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:39.869Z,1667940639.869 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:39.869Z,1667940639.869 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:39.869Z,1667940639.869 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:39.869Z,1667940639.869 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:39.870Z,1667940639.870 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:39.870Z,1667940639.870 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:39.870Z,1667940639.870 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:40.430Z,1667940640.430 [DAT](INFO): commRate: 800
2022-11-08T20:50:42.496Z,1667940642.496 [DAT](INFO): entering command mode
2022-11-08T20:50:42.697Z,1667940642.697 [DAT](INFO): setting verbose to 3
2022-11-08T20:50:42.950Z,1667940642.950 [DAT](INFO): set verbose to 3
2022-11-08T20:50:42.950Z,1667940642.950 [DAT](INFO): setting DatVerbose to 27440
2022-11-08T20:50:43.202Z,1667940643.202 [DAT](INFO): set DatVerbose to 27440
2022-11-08T20:50:43.202Z,1667940643.202 [DAT](INFO): setting transmit power to 8
2022-11-08T20:50:43.454Z,1667940643.454 [DAT](INFO): set transmit power to 8
2022-11-08T20:50:43.454Z,1667940643.454 [DAT](INFO): setting local address to 10
2022-11-08T20:50:43.706Z,1667940643.706 [DAT](INFO): set local address to 10
2022-11-08T20:50:43.707Z,1667940643.707 [DAT](INFO): Setting time to: 20:50:43 And date to:11/8/2022
2022-11-08T20:50:43.958Z,1667940643.958 [DAT](INFO): Local DAT time set to Tue Nov 8, 2022  20:50:43  

2022-11-08T20:50:43.958Z,1667940643.958 [DAT](INFO): setting remote address to 1
2022-11-08T20:50:44.210Z,1667940644.210 [DAT](INFO): set remote address to 1
2022-11-08T20:50:44.210Z,1667940644.210 [DAT](INFO): entering online mode
2022-11-08T20:50:44.463Z,1667940644.463 [DAT](INFO): commRate: 800
2022-11-08T20:50:44.463Z,1667940644.463 [DAT](INFO): online mode acknowledged
2022-11-08T20:50:44.463Z,1667940644.463 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-08T20:50:46.249Z,1667940646.249 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:46.250Z,1667940646.250 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:46.293Z,1667940646.293 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:46.295Z,1667940646.295 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:46.297Z,1667940646.297 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:46.299Z,1667940646.299 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:46.301Z,1667940646.301 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:46.324Z,1667940646.324 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:46.326Z,1667940646.326 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:46.362Z,1667940646.362 [Default] Stopped
2022-11-08T20:50:46.363Z,1667940646.363 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:46.363Z,1667940646.363 [Default:A.Wait] Stopped
2022-11-08T20:50:46.363Z,1667940646.363 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:46.363Z,1667940646.363 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:46.363Z,1667940646.363 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:46.363Z,1667940646.363 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:46.363Z,1667940646.363 [multiray_lab] Running Loop=1
2022-11-08T20:50:46.363Z,1667940646.363 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:47.550Z,1667940647.550 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:46.4Z
2022-11-08T20:50:47.550Z,1667940647.550 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:47.550Z,1667940647.550 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:47.550Z,1667940647.550 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:47.551Z,1667940647.551 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:47.551Z,1667940647.551 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:47.551Z,1667940647.551 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:47.551Z,1667940647.551 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:47.551Z,1667940647.551 [multiray_lab] Stopped
2022-11-08T20:50:47.551Z,1667940647.551 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:47.959Z,1667940647.959 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:47.959Z,1667940647.959 [Default] Running Loop=1
2022-11-08T20:50:47.959Z,1667940647.959 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:47.959Z,1667940647.959 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:47.959Z,1667940647.959 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:47.960Z,1667940647.960 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:47.960Z,1667940647.960 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:47.961Z,1667940647.961 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:47.961Z,1667940647.961 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:47.961Z,1667940647.961 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:47.962Z,1667940647.962 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:47.962Z,1667940647.962 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:49.895Z,1667940649.895 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:49.895Z,1667940649.895 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:49.912Z,1667940649.912 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:49.914Z,1667940649.914 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:49.916Z,1667940649.916 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:49.918Z,1667940649.918 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:49.960Z,1667940649.960 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:49.965Z,1667940649.965 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:49.966Z,1667940649.966 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:49.983Z,1667940649.983 [Default] Stopped
2022-11-08T20:50:49.983Z,1667940649.983 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:49.983Z,1667940649.983 [Default:A.Wait] Stopped
2022-11-08T20:50:49.983Z,1667940649.983 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:49.983Z,1667940649.983 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:49.983Z,1667940649.983 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:49.984Z,1667940649.984 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:49.984Z,1667940649.984 [multiray_lab] Running Loop=1
2022-11-08T20:50:49.988Z,1667940649.988 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:51.187Z,1667940651.187 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:49.0Z
2022-11-08T20:50:51.188Z,1667940651.188 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:51.188Z,1667940651.188 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:51.207Z,1667940651.207 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:51.208Z,1667940651.208 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:51.212Z,1667940651.212 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:51.212Z,1667940651.212 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:51.212Z,1667940651.212 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:51.213Z,1667940651.213 [multiray_lab] Stopped
2022-11-08T20:50:51.213Z,1667940651.213 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:51.606Z,1667940651.606 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:51.606Z,1667940651.606 [Default] Running Loop=1
2022-11-08T20:50:51.606Z,1667940651.606 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:51.607Z,1667940651.607 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:51.607Z,1667940651.607 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:51.607Z,1667940651.607 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:51.607Z,1667940651.607 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:51.607Z,1667940651.607 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:51.608Z,1667940651.608 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:51.636Z,1667940651.636 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:51.637Z,1667940651.637 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:51.637Z,1667940651.637 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:53.015Z,1667940653.015 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:53.015Z,1667940653.015 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:53.033Z,1667940653.033 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min
2022-11-08T20:50:53.035Z,1667940653.035 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s
2022-11-08T20:50:53.037Z,1667940653.037 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count
2022-11-08T20:50:53.039Z,1667940653.039 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count
2022-11-08T20:50:53.042Z,1667940653.042 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count
2022-11-08T20:50:53.046Z,1667940653.046 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>

<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Sample="Tethys/Sample"
         xmlns:Sensor="Tethys/Sensor"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd"
       Id="multiray_lab">

    <Description>
        This mission is designed to run in the lab only. Turns on and off MultiRay lights.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="LightDuration"><Description>
        How long to wait before turning on / off lights, default 1 second
    </Description><Units:second/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode0"><Description>
        Light mode = 0
    </Description><Units:count/><Value>0</Value></DefineArg>

    <DefineArg Name="LightMode1"><Description>
        Light mode = 1
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="LightMode2"><Description>
        Light mode = 2
    </Description><Units:count/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

        <Timeout Duration="LightDuration">
            <Assign><Sequence/><Sensor:MultiRay.lightMode/><Arg Name="LightMode2"/></Assign>    
        </Timeout>


</Mission>


2022-11-08T20:50:53.047Z,1667940653.047 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml
2022-11-08T20:50:53.199Z,1667940653.199 [Default] Stopped
2022-11-08T20:50:53.199Z,1667940653.199 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:50:53.199Z,1667940653.199 [Default:A.Wait] Stopped
2022-11-08T20:50:53.199Z,1667940653.199 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:50:53.199Z,1667940653.199 [Default:B.GoToSurface] Stopped
2022-11-08T20:50:53.199Z,1667940653.199 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:50:53.199Z,1667940653.199 [MissionManager](IMPORTANT): Started mission multiray_lab
2022-11-08T20:50:53.200Z,1667940653.200 [multiray_lab] Running Loop=1
2022-11-08T20:50:53.200Z,1667940653.200 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab
2022-11-08T20:50:54.406Z,1667940654.406 [multiray_lab](INFO): Timed out from 2022-11-08T20:50:53.2Z
2022-11-08T20:50:54.406Z,1667940654.406 [multiray_lab:A_Timeout] Running Loop=1
2022-11-08T20:50:54.406Z,1667940654.406 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout
2022-11-08T20:50:54.407Z,1667940654.407 [multiray_lab:A_Timeout:A] Running Loop=1
2022-11-08T20:50:54.407Z,1667940654.407 [multiray_lab:A_Timeout:A] Stopped
2022-11-08T20:50:54.407Z,1667940654.407 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout
2022-11-08T20:50:54.407Z,1667940654.407 [MissionManager](INFO): multiray_lab is completed.
2022-11-08T20:50:54.407Z,1667940654.407 [MissionManager](INFO): Uninitializing Mission multiray_lab
2022-11-08T20:50:54.407Z,1667940654.407 [multiray_lab] Stopped
2022-11-08T20:50:54.408Z,1667940654.408 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab
2022-11-08T20:50:54.569Z,1667940654.569 [DAT](FAULT): Buffer send receipt timeout failure.
2022-11-08T20:50:54.569Z,1667940654.569 [DAT](DEBUG): In sendingTransmitVerify, timeout so go online and set commsState_ = SENDING_FILL_BUFFER
2022-11-08T20:50:54.817Z,1667940654.817 [MissionManager](IMPORTANT): Started mission Default
2022-11-08T20:50:54.817Z,1667940654.817 [Default] Running Loop=1
2022-11-08T20:50:54.817Z,1667940654.817 [Default](DEBUG): Aggregate::initialize Default
2022-11-08T20:50:54.818Z,1667940654.818 [Default:B.GoToSurface] Running Loop=1
2022-11-08T20:50:54.818Z,1667940654.818 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-08T20:50:54.818Z,1667940654.818 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-08T20:50:54.818Z,1667940654.818 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-08T20:50:54.818Z,1667940654.818 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-08T20:50:54.819Z,1667940654.819 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-08T20:50:54.819Z,1667940654.819 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-08T20:50:54.819Z,1667940654.819 [Default:A.Wait] Running Loop=1
2022-11-08T20:50:54.820Z,1667940654.819 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-08T20:50:54.821Z,1667940654.821 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:50:55.073Z,1667940655.073 [DAT](INFO): entering online mode
2022-11-08T20:50:55.325Z,1667940655.325 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:55.577Z,1667940655.577 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:55.829Z,1667940655.829 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:56.081Z,1667940656.081 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:56.333Z,1667940656.333 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:56.585Z,1667940656.585 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:56.837Z,1667940656.837 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:57.089Z,1667940657.089 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:57.341Z,1667940657.341 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:57.593Z,1667940657.593 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:57.845Z,1667940657.845 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:58.097Z,1667940658.097 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:58.349Z,1667940658.349 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:58.601Z,1667940658.601 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:58.855Z,1667940658.855 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:59.109Z,1667940659.109 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:59.361Z,1667940659.361 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:59.613Z,1667940659.613 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:50:59.865Z,1667940659.865 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:00.117Z,1667940660.117 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:00.369Z,1667940660.369 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:00.621Z,1667940660.621 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:00.873Z,1667940660.873 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:01.125Z,1667940661.125 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:01.377Z,1667940661.377 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:01.629Z,1667940661.629 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:01.881Z,1667940661.881 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:02.141Z,1667940662.141 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:02.393Z,1667940662.393 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:02.645Z,1667940662.645 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:02.897Z,1667940662.897 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:03.149Z,1667940663.149 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:03.401Z,1667940663.401 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:03.656Z,1667940663.656 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:03.905Z,1667940663.905 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:04.157Z,1667940664.157 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:04.409Z,1667940664.409 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:04.661Z,1667940664.661 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:04.913Z,1667940664.913 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:05.165Z,1667940665.165 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:05.417Z,1667940665.417 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:05.669Z,1667940665.669 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:05.921Z,1667940665.921 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:06.173Z,1667940666.173 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:06.425Z,1667940666.425 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:06.677Z,1667940666.677 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:06.936Z,1667940666.936 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:07.181Z,1667940667.181 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:07.433Z,1667940667.433 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:07.685Z,1667940667.685 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:07.937Z,1667940667.937 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:08.146Z,1667940668.146 [Default:A.Wait](INFO): Done Waiting.
2022-11-08T20:51:08.146Z,1667940668.146 [Default:A.Wait] Stopped
2022-11-08T20:51:08.146Z,1667940668.146 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-08T20:51:08.189Z,1667940668.189 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:08.441Z,1667940668.441 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:08.559Z,1667940668.559 [Default:CheckIn] Running Loop=1
2022-11-08T20:51:08.560Z,1667940668.560 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-08T20:51:08.560Z,1667940668.560 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-08T20:51:08.693Z,1667940668.693 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:08.949Z,1667940668.949 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:08.973Z,1667940668.973 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-11-08T20:51:09.201Z,1667940669.201 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:09.453Z,1667940669.453 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:09.705Z,1667940669.705 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:09.957Z,1667940669.957 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:10.209Z,1667940670.209 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:51:10.209Z,1667940670.209 [DAT](FAULT): failed to enter online mode
2022-11-08T20:51:10.209Z,1667940670.209 [DAT](FAULT): Failure returning to online mode
2022-11-08T20:51:10.209Z,1667940670.209 [DAT] Communications Fault, FailCount= 2
2022-11-08T20:51:10.209Z,1667940670.209 [DAT](ERROR): Communications Fault
2022-11-08T20:51:10.592Z,1667940670.592 [CBIT](ERROR): Communications Fault in component: DAT
2022-11-08T20:51:10.612Z,1667940670.612 [DAT](INFO): Powering down
2022-11-08T20:51:11.805Z,1667940671.805 [CBIT](INFO): Clearing failed state for component DAT
2022-11-08T20:51:11.805Z,1667940671.805 [DAT] No Fault, FailCount= 2
2022-11-08T20:51:13.660Z,1667940673.660 [DAT](INFO): Powering up
2022-11-08T20:51:13.661Z,1667940673.661 [DAT](DEBUG): Initializing DAT.
2022-11-08T20:51:16.216Z,1667940676.216 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-08T20:51:28.794Z,1667940688.794 [DAT](INFO): commRate: 800
2022-11-08T20:51:30.860Z,1667940690.860 [DAT](INFO): entering command mode
2022-11-08T20:51:31.061Z,1667940691.061 [DAT](INFO): setting verbose to 3
2022-11-08T20:51:31.314Z,1667940691.314 [DAT](INFO): set verbose to 3
2022-11-08T20:51:31.314Z,1667940691.314 [DAT](INFO): setting DatVerbose to 27440
2022-11-08T20:51:31.567Z,1667940691.567 [DAT](INFO): set DatVerbose to 27440
2022-11-08T20:51:31.567Z,1667940691.567 [DAT](INFO): setting transmit power to 8
2022-11-08T20:51:31.818Z,1667940691.818 [DAT](INFO): set transmit power to 8
2022-11-08T20:51:31.818Z,1667940691.818 [DAT](INFO): setting local address to 10
2022-11-08T20:51:32.070Z,1667940692.070 [DAT](INFO): set local address to 10
2022-11-08T20:51:32.071Z,1667940692.071 [DAT](INFO): Setting time to: 20:51:32 And date to:11/8/2022
2022-11-08T20:51:32.322Z,1667940692.322 [DAT](INFO): Local DAT time set to Tue Nov 8, 2022  20:51:32  

2022-11-08T20:51:32.322Z,1667940692.322 [DAT](INFO): setting remote address to 1
2022-11-08T20:51:32.574Z,1667940692.574 [DAT](INFO): set remote address to 1
2022-11-08T20:51:32.574Z,1667940692.574 [DAT](INFO): entering online mode
2022-11-08T20:51:32.827Z,1667940692.827 [DAT](INFO): commRate: 800
2022-11-08T20:51:32.827Z,1667940692.827 [DAT](INFO): online mode acknowledged
2022-11-08T20:51:32.827Z,1667940692.827 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-08T20:51:33.690Z,1667940693.690 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-11-08T20:51:33.690Z,1667940693.690 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-08T20:51:33.691Z,1667940693.691 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2022-11-08T20:51:33.691Z,1667940693.691 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-11-08T20:51:33.710Z,1667940693.710 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-08T20:51:33.710Z,1667940693.710 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-11-08T20:51:34.119Z,1667940694.119 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-08T20:51:34.119Z,1667940694.119 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-11-08T20:51:34.120Z,1667940694.120 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-11-08T20:51:34.120Z,1667940694.120 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2022-11-08T20:51:34.501Z,1667940694.501 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-11-08T20:51:36.110Z,1667940696.110 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-08T20:51:53.261Z,1667940713.261 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-08T20:51:53.513Z,1667940713.513 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:51:53.765Z,1667940713.765 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-08T20:52:03.849Z,1667940723.849 [DAT](FAULT): Buffer send receipt timeout failure.
2022-11-08T20:52:03.849Z,1667940723.849 [DAT](DEBUG): In sendingTransmitVerify, timeout so go online and set commsState_ = SENDING_FILL_BUFFER
2022-11-08T20:52:04.101Z,1667940724.101 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:52:04.357Z,1667940724.357 [DAT](INFO): entering online mode
2022-11-08T20:52:04.605Z,1667940724.605 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:04.857Z,1667940724.857 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:05.109Z,1667940725.109 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:05.361Z,1667940725.361 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:05.613Z,1667940725.613 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:05.865Z,1667940725.865 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:06.117Z,1667940726.117 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:06.369Z,1667940726.369 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:06.621Z,1667940726.621 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:06.873Z,1667940726.873 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:07.125Z,1667940727.125 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:07.377Z,1667940727.377 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:07.629Z,1667940727.629 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:07.881Z,1667940727.881 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:08.133Z,1667940728.133 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:08.385Z,1667940728.385 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:08.637Z,1667940728.637 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:08.889Z,1667940728.889 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:09.141Z,1667940729.141 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:09.393Z,1667940729.393 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:09.645Z,1667940729.645 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:09.897Z,1667940729.897 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:10.149Z,1667940730.149 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:10.401Z,1667940730.401 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:10.653Z,1667940730.653 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:10.905Z,1667940730.905 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:11.157Z,1667940731.157 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:11.409Z,1667940731.409 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:11.661Z,1667940731.661 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:11.913Z,1667940731.913 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:12.165Z,1667940732.165 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:12.417Z,1667940732.417 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:12.669Z,1667940732.669 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:12.921Z,1667940732.921 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:13.173Z,1667940733.173 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:13.427Z,1667940733.427 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:13.677Z,1667940733.677 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:13.929Z,1667940733.929 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:14.181Z,1667940734.181 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:14.433Z,1667940734.433 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:14.685Z,1667940734.685 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:14.937Z,1667940734.937 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:15.189Z,1667940735.189 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:15.441Z,1667940735.441 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:15.693Z,1667940735.693 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:15.945Z,1667940735.945 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:16.197Z,1667940736.197 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:16.449Z,1667940736.449 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:16.701Z,1667940736.701 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:16.953Z,1667940736.953 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:17.205Z,1667940737.205 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:17.457Z,1667940737.457 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:17.709Z,1667940737.709 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:17.961Z,1667940737.961 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:18.214Z,1667940738.214 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:18.465Z,1667940738.465 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:18.717Z,1667940738.717 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:18.982Z,1667940738.982 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:19.233Z,1667940739.233 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:19.485Z,1667940739.485 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:52:19.485Z,1667940739.485 [DAT](FAULT): failed to enter online mode
2022-11-08T20:52:19.485Z,1667940739.485 [DAT](FAULT): Failure returning to online mode
2022-11-08T20:52:19.485Z,1667940739.485 [DAT] Communications Fault, FailCount= 3
2022-11-08T20:52:19.485Z,1667940739.485 [DAT](ERROR): Communications Fault
2022-11-08T20:52:19.736Z,1667940739.736 [CBIT](ERROR): Communications Fault in component: DAT
2022-11-08T20:52:19.888Z,1667940739.888 [DAT](INFO): Powering down
2022-11-08T20:52:20.976Z,1667940740.976 [CBIT](INFO): Clearing failed state for component DAT
2022-11-08T20:52:20.976Z,1667940740.976 [DAT] No Fault, FailCount= 3
2022-11-08T20:52:22.940Z,1667940742.940 [DAT](INFO): Powering up
2022-11-08T20:52:22.940Z,1667940742.940 [DAT](DEBUG): Initializing DAT.
2022-11-08T20:52:38.066Z,1667940758.066 [DAT](INFO): commRate: 800
2022-11-08T20:52:40.132Z,1667940760.132 [DAT](INFO): entering command mode
2022-11-08T20:52:40.334Z,1667940760.334 [DAT](INFO): setting verbose to 3
2022-11-08T20:52:40.586Z,1667940760.586 [DAT](INFO): set verbose to 3
2022-11-08T20:52:40.586Z,1667940760.586 [DAT](INFO): setting DatVerbose to 27440
2022-11-08T20:52:40.838Z,1667940760.838 [DAT](INFO): set DatVerbose to 27440
2022-11-08T20:52:40.838Z,1667940760.838 [DAT](INFO): setting transmit power to 8
2022-11-08T20:52:41.090Z,1667940761.090 [DAT](INFO): set transmit power to 8
2022-11-08T20:52:41.090Z,1667940761.090 [DAT](INFO): setting local address to 10
2022-11-08T20:52:41.342Z,1667940761.342 [DAT](INFO): set local address to 10
2022-11-08T20:52:41.343Z,1667940761.343 [DAT](INFO): Setting time to: 20:52:41 And date to:11/8/2022
2022-11-08T20:52:41.594Z,1667940761.594 [DAT](INFO): Local DAT time set to Tue Nov 8, 2022  20:52:41  

2022-11-08T20:52:41.594Z,1667940761.594 [DAT](INFO): setting remote address to 1
2022-11-08T20:52:41.846Z,1667940761.846 [DAT](INFO): set remote address to 1
2022-11-08T20:52:41.846Z,1667940761.846 [DAT](INFO): entering online mode
2022-11-08T20:52:42.099Z,1667940762.099 [DAT](INFO): commRate: 800
2022-11-08T20:52:42.099Z,1667940762.099 [DAT](INFO): online mode acknowledged
2022-11-08T20:52:42.099Z,1667940762.099 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-08T20:52:45.374Z,1667940765.374 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-08T20:53:02.513Z,1667940782.513 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-08T20:53:02.765Z,1667940782.765 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:53:03.017Z,1667940783.017 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-08T20:53:13.097Z,1667940793.097 [DAT](FAULT): Buffer send receipt timeout failure.
2022-11-08T20:53:13.097Z,1667940793.097 [DAT](DEBUG): In sendingTransmitVerify, timeout so go online and set commsState_ = SENDING_FILL_BUFFER
2022-11-08T20:53:13.349Z,1667940793.349 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-08T20:53:13.601Z,1667940793.601 [DAT](INFO): entering online mode
2022-11-08T20:53:13.853Z,1667940793.853 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:14.105Z,1667940794.105 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:14.357Z,1667940794.357 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:14.609Z,1667940794.609 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:14.861Z,1667940794.861 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:15.113Z,1667940795.113 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:15.365Z,1667940795.365 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:15.617Z,1667940795.617 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:15.869Z,1667940795.869 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:16.121Z,1667940796.121 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:16.374Z,1667940796.374 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:16.625Z,1667940796.625 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:16.877Z,1667940796.877 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:17.129Z,1667940797.129 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:17.381Z,1667940797.381 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:17.633Z,1667940797.633 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:17.886Z,1667940797.886 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:18.137Z,1667940798.137 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:18.389Z,1667940798.389 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:18.641Z,1667940798.641 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:18.893Z,1667940798.893 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:19.145Z,1667940799.145 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:19.397Z,1667940799.397 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:19.649Z,1667940799.649 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:19.905Z,1667940799.905 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:20.153Z,1667940800.153 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:20.405Z,1667940800.405 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:20.657Z,1667940800.657 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:20.909Z,1667940800.909 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:21.173Z,1667940801.173 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:21.425Z,1667940801.425 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:21.677Z,1667940801.677 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:21.929Z,1667940801.929 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:22.181Z,1667940802.181 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:22.433Z,1667940802.433 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:22.685Z,1667940802.685 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:22.937Z,1667940802.937 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:23.189Z,1667940803.189 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:23.441Z,1667940803.441 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:23.693Z,1667940803.693 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:23.945Z,1667940803.945 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:24.197Z,1667940804.197 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:24.449Z,1667940804.449 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:24.701Z,1667940804.701 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:24.953Z,1667940804.953 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:25.205Z,1667940805.205 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:25.457Z,1667940805.457 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:25.709Z,1667940805.709 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:25.965Z,1667940805.965 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:26.217Z,1667940806.217 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:26.469Z,1667940806.469 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:26.721Z,1667940806.721 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:26.973Z,1667940806.973 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:27.225Z,1667940807.225 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:27.477Z,1667940807.477 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:27.729Z,1667940807.729 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:27.990Z,1667940807.990 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:28.233Z,1667940808.233 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:28.485Z,1667940808.485 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:28.737Z,1667940808.737 [DAT](DEBUG): checking for online mode acknowledgment
2022-11-08T20:53:28.737Z,1667940808.737 [DAT](FAULT): failed to enter online mode
2022-11-08T20:53:28.737Z,1667940808.737 [DAT](FAULT): Failure returning to online mode
2022-11-08T20:53:28.737Z,1667940808.737 [DAT] Communications Fault, FailCount= 4
2022-11-08T20:53:28.737Z,1667940808.737 [DAT](ERROR): Communications Fault
2022-11-08T20:53:28.849Z,1667940808.849 [CBIT](ERROR): Communications Fault in component: DAT
2022-11-08T20:53:29.140Z,1667940809.140 [DAT](INFO): Powering down
2022-11-08T20:53:30.037Z,1667940810.037 [CBIT](INFO): Clearing failed state for component DAT
2022-11-08T20:53:30.037Z,1667940810.037 [DAT] No Fault, FailCount= 4
2022-11-08T20:53:32.202Z,1667940812.202 [DAT](INFO): Powering up
2022-11-08T20:53:32.202Z,1667940812.202 [DAT](DEBUG): Initializing DAT.
2022-11-08T20:53:42.318Z,1667940822.318 [CommandExec](IMPORTANT): got command restart system
2022-11-08T20:53:44.424Z,1667940824.424 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-11-08T20:53:44.425Z,1667940824.425 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:44.425Z,1667940824.425 [CommandExec](INFO): Uninitializing the command executive.
2022-11-08T20:53:44.425Z,1667940824.425 [CommandExec](INFO): Uninitializing the command scheduler.
2022-11-08T20:53:44.425Z,1667940824.425 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:44.520Z,1667940824.520 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-11-08T20:53:44.520Z,1667940824.520 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-11-08T20:53:44.520Z,1667940824.520 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:44.521Z,1667940824.521 [NavChartDb](INFO): Join timeout helper Thread ID is 990
2022-11-08T20:53:44.700Z,1667940824.700 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:44.701Z,1667940824.701 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:44.716Z,1667940824.716 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-11-08T20:53:44.716Z,1667940824.716 [DAT ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:44.717Z,1667940824.717 [DAT](INFO): Join timeout helper Thread ID is 991
2022-11-08T20:53:44.804Z,1667940824.804 [DAT](INFO): Powering down
2022-11-08T20:53:44.877Z,1667940824.877 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:44.877Z,1667940824.877 [DAT](INFO): Powering down
2022-11-08T20:53:44.878Z,1667940824.878 [DAT ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:44.880Z,1667940824.880 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-11-08T20:53:44.880Z,1667940824.880 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:44.881Z,1667940824.881 [Radio_Surface](INFO): Join timeout helper Thread ID is 992
2022-11-08T20:53:45.076Z,1667940825.076 [Radio_Surface](INFO): Powering down
2022-11-08T20:53:45.077Z,1667940825.077 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:45.077Z,1667940825.077 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:45.084Z,1667940825.084 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-11-08T20:53:45.084Z,1667940825.084 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:45.085Z,1667940825.085 [Onboard](INFO): Join timeout helper Thread ID is 993
2022-11-08T20:53:45.261Z,1667940825.261 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-11-08T20:53:46.376Z,1667940826.376 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:46.377Z,1667940826.377 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:46.396Z,1667940826.396 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-11-08T20:53:46.396Z,1667940826.396 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:46.397Z,1667940826.397 [DataOverHttps](INFO): Join timeout helper Thread ID is 994
2022-11-08T20:53:47.272Z,1667940827.272 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:47.272Z,1667940827.272 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.285Z,1667940827.285 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-11-08T20:53:47.285Z,1667940827.285 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.286Z,1667940827.286 [BackseatComponent](INFO): Join timeout helper Thread ID is 995
2022-11-08T20:53:47.452Z,1667940827.452 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:47.453Z,1667940827.453 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.473Z,1667940827.473 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-11-08T20:53:47.473Z,1667940827.473 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.473Z,1667940827.473 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 996
2022-11-08T20:53:47.621Z,1667940827.621 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:47.622Z,1667940827.622 [WetLabsBB2FL](INFO): Powering down
2022-11-08T20:53:47.622Z,1667940827.622 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.633Z,1667940827.633 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-11-08T20:53:47.633Z,1667940827.633 [logger ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.633Z,1667940827.633 [logger](INFO): Join timeout helper Thread ID is 997
2022-11-08T20:53:47.672Z,1667940827.672 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:47.673Z,1667940827.673 [logger ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.693Z,1667940827.693 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-11-08T20:53:47.693Z,1667940827.693 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.693Z,1667940827.693 [CommandLine](INFO): Join timeout helper Thread ID is 998
2022-11-08T20:53:47.724Z,1667940827.724 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:47.725Z,1667940827.725 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.733Z,1667940827.733 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-11-08T20:53:47.733Z,1667940827.733 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.733Z,1667940827.733 [CommandExec](INFO): Join timeout helper Thread ID is 999
2022-11-08T20:53:47.735Z,1667940827.735 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-11-08T20:53:47.735Z,1667940827.735 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.735Z,1667940827.735 [controlThread](INFO): Join timeout helper Thread ID is 1000
2022-11-08T20:53:47.776Z,1667940827.776 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-08T20:53:47.777Z,1667940827.777 [controlThread](DEBUG): Uninitializing ControlThread
2022-11-08T20:53:47.777Z,1667940827.777 [AHRS_M2](INFO): Powering down
2022-11-08T20:53:47.849Z,1667940827.849 [MultiRay](INFO): Powering down.
2022-11-08T20:53:47.850Z,1667940827.850 [NAL9602](INFO): Powering down
2022-11-08T20:53:47.852Z,1667940827.852 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-11-08T20:53:47.853Z,1667940827.853 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-11-08T20:53:47.854Z,1667940827.854 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-11-08T20:53:47.854Z,1667940827.854 [MissionManager](INFO): Uninitializing Mission Default
2022-11-08T20:53:47.854Z,1667940827.854 [Default] Stopped
2022-11-08T20:53:47.854Z,1667940827.854 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-08T20:53:47.854Z,1667940827.854 [Default:B.GoToSurface] Stopped
2022-11-08T20:53:47.855Z,1667940827.855 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-08T20:53:47.855Z,1667940827.855 [Default:CheckIn] Stopped
2022-11-08T20:53:47.855Z,1667940827.855 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-08T20:53:47.855Z,1667940827.855 [Default:CheckIn:Read_GPS] Stopped
2022-11-08T20:53:47.858Z,1667940827.858 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-11-08T20:53:47.858Z,1667940827.858 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-11-08T20:53:47.858Z,1667940827.858 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-11-08T20:53:47.859Z,1667940827.859 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-11-08T20:53:47.859Z,1667940827.859 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-11-08T20:53:47.859Z,1667940827.859 [BuoyancyServo](INFO): Powering down
2022-11-08T20:53:47.872Z,1667940827.872 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-11-08T20:53:47.872Z,1667940827.872 [ElevatorServo](INFO): Powering down
2022-11-08T20:53:47.873Z,1667940827.873 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-11-08T20:53:47.873Z,1667940827.873 [MassServo](INFO): Powering down
2022-11-08T20:53:47.874Z,1667940827.874 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-11-08T20:53:47.874Z,1667940827.874 [RudderServo](INFO): Powering down
2022-11-08T20:53:47.875Z,1667940827.875 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-11-08T20:53:47.875Z,1667940827.875 [ThrusterHE](INFO): Powering down
2022-11-08T20:53:47.876Z,1667940827.876 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-11-08T20:53:47.877Z,1667940827.877 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-11-08T20:53:47.877Z,1667940827.877 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-11-08T20:53:47.877Z,1667940827.877 [CBIT](DEBUG): Powering off loads.
2022-11-08T20:53:47.888Z,1667940827.888 [CBIT](DEBUG): Disabling WDT.
2022-11-08T20:53:47.900Z,1667940827.900 [CBIT](DEBUG): Opening all GF detection circuits.
2022-11-08T20:53:47.901Z,1667940827.901 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.937Z,1667940827.937 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.987Z,1667940827.987 [DAT ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.992Z,1667940827.992 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:47.995Z,1667940827.995 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:48.002Z,1667940828.002 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:48.050Z,1667940828.050 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:48.098Z,1667940828.098 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-08T20:53:48.158Z,1667940828.158 [logger ThreadHandler](INFO): Thread cancelled.