2022-11-09T00:33:48.753Z,1667954028.753 [Supervisor](DEBUG): Initializing supervisor.
2022-11-09T00:33:48.758Z,1667954028.758 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-11-09T00:33:48.759Z,1667954028.759 [SyncHandler](INFO): Protected caller Thread ID is 3969
2022-11-09T00:33:48.759Z,1667954028.759 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-11-09T00:33:48.761Z,1667954028.761 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-11-09T00:33:48.761Z,1667954028.761 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3970
2022-11-09T00:33:48.765Z,1667954028.765 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-11-09T00:33:48.783Z,1667954028.783 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-11-09T00:33:48.784Z,1667954028.784 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-11-09T00:33:48.784Z,1667954028.784 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3971
2022-11-09T00:33:48.788Z,1667954028.788 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-11-09T00:33:48.789Z,1667954028.789 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-11-09T00:33:48.790Z,1667954028.790 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3972
2022-11-09T00:33:48.792Z,1667954028.792 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-11-09T00:33:48.792Z,1667954028.792 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-11-09T00:33:48.793Z,1667954028.793 [logger ThreadHandler](INFO): Protected caller Thread ID is 3973
2022-11-09T00:33:48.796Z,1667954028.796 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-11-09T00:33:48.797Z,1667954028.797 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-11-09T00:33:48.798Z,1667954028.798 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-11-09T00:33:49.217Z,1667954029.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-11-09T00:33:49.217Z,1667954029.217 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-11-09T00:33:49.426Z,1667954029.426 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-11-09T00:33:49.427Z,1667954029.427 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-11-09T00:33:49.603Z,1667954029.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-11-09T00:33:49.604Z,1667954029.604 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-11-09T00:33:49.709Z,1667954029.709 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-11-09T00:33:49.710Z,1667954029.710 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-11-09T00:33:50.064Z,1667954030.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-11-09T00:33:50.064Z,1667954030.064 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-11-09T00:33:50.375Z,1667954030.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-11-09T00:33:50.375Z,1667954030.375 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-11-09T00:33:50.470Z,1667954030.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-11-09T00:33:50.471Z,1667954030.471 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-11-09T00:33:50.683Z,1667954030.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-11-09T00:33:50.684Z,1667954030.684 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-11-09T00:33:51.281Z,1667954031.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-11-09T00:33:51.281Z,1667954031.281 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-11-09T00:33:51.887Z,1667954031.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-11-09T00:33:51.888Z,1667954031.888 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-11-09T00:33:51.968Z,1667954031.968 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-11-09T00:33:52.174Z,1667954032.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-11-09T00:33:52.174Z,1667954032.174 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-11-09T00:33:52.309Z,1667954032.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-11-09T00:33:52.310Z,1667954032.310 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-11-09T00:33:53.020Z,1667954033.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-11-09T00:33:53.021Z,1667954033.021 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-11-09T00:33:53.100Z,1667954033.100 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-11-09T00:33:53.381Z,1667954033.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-11-09T00:33:53.383Z,1667954033.383 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2022-11-09T00:33:53.384Z,1667954033.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2022-11-09T00:33:53.557Z,1667954033.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2022-11-09T00:33:53.648Z,1667954033.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2022-11-09T00:33:53.758Z,1667954033.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2022-11-09T00:33:53.856Z,1667954033.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2022-11-09T00:33:53.939Z,1667954033.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2022-11-09T00:33:54.023Z,1667954034.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2022-11-09T00:33:54.135Z,1667954034.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2022-11-09T00:33:54.232Z,1667954034.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2022-11-09T00:33:54.381Z,1667954034.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2022-11-09T00:33:54.646Z,1667954034.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-11-09T00:33:54.647Z,1667954034.647 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2022-11-09T00:33:54.740Z,1667954034.740 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2022-11-09T00:33:54.740Z,1667954034.740 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-11-09T00:33:54.753Z,1667954034.753 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-11-09T00:33:55.088Z,1667954035.088 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-11-09T00:33:55.088Z,1667954035.088 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-11-09T00:33:55.206Z,1667954035.206 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-11-09T00:33:55.206Z,1667954035.206 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-11-09T00:33:55.245Z,1667954035.245 [DeadReckonUsingSpeedCalculator] Loaded
2022-11-09T00:33:55.245Z,1667954035.245 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-11-09T00:33:55.258Z,1667954035.258 [NavChart] Loaded
2022-11-09T00:33:55.258Z,1667954035.258 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-11-09T00:33:55.263Z,1667954035.263 [UniversalFixResidualReporter] Loaded
2022-11-09T00:33:55.264Z,1667954035.264 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-11-09T00:33:55.264Z,1667954035.264 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-11-09T00:33:55.265Z,1667954035.265 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-11-09T00:33:55.275Z,1667954035.275 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-11-09T00:33:55.276Z,1667954035.276 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-11-09T00:33:55.452Z,1667954035.452 [PAR_Licor] Loaded
2022-11-09T00:33:55.452Z,1667954035.452 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-11-09T00:33:55.483Z,1667954035.483 [WetLabsBB2FL] Loaded
2022-11-09T00:33:55.484Z,1667954035.484 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-11-09T00:33:55.485Z,1667954035.485 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065A4E0
2022-11-09T00:33:55.485Z,1667954035.485 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4055
2022-11-09T00:33:55.486Z,1667954035.486 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-11-09T00:33:55.487Z,1667954035.487 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-11-09T00:33:55.541Z,1667954035.541 [VerticalControl](DEBUG): Construct VerticalControl.
2022-11-09T00:33:55.601Z,1667954035.601 [VerticalControl] Loaded
2022-11-09T00:33:55.601Z,1667954035.601 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-11-09T00:33:55.604Z,1667954035.604 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-11-09T00:33:55.644Z,1667954035.644 [HorizontalControl] Loaded
2022-11-09T00:33:55.645Z,1667954035.645 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-11-09T00:33:55.647Z,1667954035.647 [SpeedControl](DEBUG): Construct SpeedControl.
2022-11-09T00:33:55.650Z,1667954035.650 [SpeedControl] Loaded
2022-11-09T00:33:55.650Z,1667954035.650 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-11-09T00:33:55.653Z,1667954035.653 [LoopControl](DEBUG): Construct LoopControl.
2022-11-09T00:33:55.654Z,1667954035.654 [LoopControl] Loaded
2022-11-09T00:33:55.654Z,1667954035.654 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-11-09T00:33:55.654Z,1667954035.654 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-11-09T00:33:55.655Z,1667954035.655 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-11-09T00:33:55.708Z,1667954035.708 [DepthRateCalculator] Loaded
2022-11-09T00:33:55.708Z,1667954035.708 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-11-09T00:33:55.713Z,1667954035.713 [PitchRateCalculator] Loaded
2022-11-09T00:33:55.713Z,1667954035.713 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-11-09T00:33:55.722Z,1667954035.722 [SpeedCalculator] Loaded
2022-11-09T00:33:55.722Z,1667954035.722 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-11-09T00:33:55.727Z,1667954035.727 [YawRateCalculator] Loaded
2022-11-09T00:33:55.727Z,1667954035.727 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-11-09T00:33:55.745Z,1667954035.745 [ElevatorOffsetCalculator] Loaded
2022-11-09T00:33:55.745Z,1667954035.745 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-11-09T00:33:55.745Z,1667954035.745 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-11-09T00:33:55.746Z,1667954035.746 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-11-09T00:33:55.848Z,1667954035.848 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-11-09T00:33:55.848Z,1667954035.848 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-11-09T00:33:55.911Z,1667954035.911 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-11-09T00:33:55.912Z,1667954035.912 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-11-09T00:33:56.043Z,1667954036.043 [BuoyancyServo] Loaded
2022-11-09T00:33:56.044Z,1667954036.044 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-11-09T00:33:56.064Z,1667954036.064 [ElevatorServo] Loaded
2022-11-09T00:33:56.064Z,1667954036.064 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-11-09T00:33:56.083Z,1667954036.083 [MassServo] Loaded
2022-11-09T00:33:56.083Z,1667954036.083 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-11-09T00:33:56.102Z,1667954036.102 [RudderServo] Loaded
2022-11-09T00:33:56.103Z,1667954036.103 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-11-09T00:33:56.117Z,1667954036.117 [ThrusterHE] Loaded
2022-11-09T00:33:56.117Z,1667954036.117 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-11-09T00:33:56.118Z,1667954036.118 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-11-09T00:33:56.118Z,1667954036.118 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-11-09T00:33:56.285Z,1667954036.285 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-11-09T00:33:56.286Z,1667954036.286 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-11-09T00:33:57.322Z,1667954037.322 [AHRS_M2] Loaded
2022-11-09T00:33:57.322Z,1667954037.322 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-11-09T00:33:57.358Z,1667954037.358 [BackseatComponent] Loaded
2022-11-09T00:33:57.358Z,1667954037.358 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-11-09T00:33:57.359Z,1667954037.359 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0
2022-11-09T00:33:57.359Z,1667954037.359 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4056
2022-11-09T00:33:57.362Z,1667954037.362 [LcmUniversalReporter] Loaded
2022-11-09T00:33:57.362Z,1667954037.362 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-11-09T00:33:58.200Z,1667954038.200 [BPC1] Loaded
2022-11-09T00:33:58.200Z,1667954038.200 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-11-09T00:33:58.274Z,1667954038.274 [DataOverHttps] Loaded
2022-11-09T00:33:58.274Z,1667954038.274 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-11-09T00:33:58.275Z,1667954038.275 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2022-11-09T00:33:58.276Z,1667954038.276 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4057
2022-11-09T00:33:58.296Z,1667954038.296 [Depth_Keller] Loaded
2022-11-09T00:33:58.297Z,1667954038.297 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-11-09T00:33:58.302Z,1667954038.302 [DropWeight] Loaded
2022-11-09T00:33:58.302Z,1667954038.302 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-11-09T00:33:58.321Z,1667954038.321 [MultiRay] Loaded
2022-11-09T00:33:58.321Z,1667954038.321 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2022-11-09T00:33:58.383Z,1667954038.383 [NAL9602] Loaded
2022-11-09T00:33:58.384Z,1667954038.384 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-11-09T00:33:58.414Z,1667954038.414 [Onboard] Loaded
2022-11-09T00:33:58.415Z,1667954038.415 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-11-09T00:33:58.416Z,1667954038.416 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0
2022-11-09T00:33:58.416Z,1667954038.416 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4058
2022-11-09T00:33:58.422Z,1667954038.422 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2022-11-09T00:33:58.434Z,1667954038.434 [PowerOnly] Loaded
2022-11-09T00:33:58.434Z,1667954038.434 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-11-09T00:33:58.446Z,1667954038.446 [Power24vConverter] Loaded
2022-11-09T00:33:58.446Z,1667954038.446 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-11-09T00:33:58.460Z,1667954038.460 [Radio_Surface] Loaded
2022-11-09T00:33:58.460Z,1667954038.460 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-11-09T00:33:58.461Z,1667954038.461 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0
2022-11-09T00:33:58.461Z,1667954038.461 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4059
2022-11-09T00:33:58.555Z,1667954038.555 [DAT] Loaded
2022-11-09T00:33:58.555Z,1667954038.555 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-11-09T00:33:58.556Z,1667954038.556 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0
2022-11-09T00:33:58.556Z,1667954038.556 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4060
2022-11-09T00:33:58.557Z,1667954038.557 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-11-09T00:33:58.557Z,1667954038.557 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-11-09T00:33:58.713Z,1667954038.713 [SBIT](DEBUG): Construct Startup Built In Test.
2022-11-09T00:33:58.722Z,1667954038.722 [SBIT] Loaded
2022-11-09T00:33:58.722Z,1667954038.722 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-11-09T00:33:58.725Z,1667954038.725 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-11-09T00:33:58.739Z,1667954038.739 [IBIT] Loaded
2022-11-09T00:33:58.739Z,1667954038.739 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-11-09T00:33:58.745Z,1667954038.745 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-11-09T00:33:58.846Z,1667954038.846 [CBIT] Loaded
2022-11-09T00:33:58.846Z,1667954038.846 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-11-09T00:33:58.847Z,1667954038.847 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-11-09T00:33:58.847Z,1667954038.847 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-11-09T00:33:58.876Z,1667954038.876 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-11-09T00:33:58.882Z,1667954038.882 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-11-09T00:33:58.885Z,1667954038.885 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-11-09T00:33:58.896Z,1667954038.896 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-11-09T00:33:58.897Z,1667954038.897 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA34E0
2022-11-09T00:33:58.897Z,1667954038.897 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4061
2022-11-09T00:33:58.902Z,1667954038.902 [Supervisor](INFO): Main Thread ID is 3663
2022-11-09T00:33:58.902Z,1667954038.902 [Supervisor](DEBUG): Running supervisor.
2022-11-09T00:33:58.903Z,1667954038.903 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4062
2022-11-09T00:33:58.903Z,1667954038.903 [CommandExec](INFO): Initializing the command executive.
2022-11-09T00:33:58.905Z,1667954038.905 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4063
2022-11-09T00:33:58.907Z,1667954038.907 [controlThread ThreadHandler](INFO): Handler Thread ID is 4064
2022-11-09T00:33:58.907Z,1667954038.907 [controlThread](DEBUG): Initializing ControlThread
2022-11-09T00:33:58.909Z,1667954038.909 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-11-09T00:33:58.910Z,1667954038.910 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-11-09T00:33:58.910Z,1667954038.910 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-11-09T00:33:58.913Z,1667954038.913 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-11-09T00:33:58.914Z,1667954038.914 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-11-09T00:33:58.915Z,1667954038.915 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-11-09T00:33:58.915Z,1667954038.915 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-11-09T00:33:58.916Z,1667954038.916 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-11-09T00:33:58.916Z,1667954038.916 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-11-09T00:33:58.916Z,1667954038.916 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-11-09T00:33:58.917Z,1667954038.917 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-11-09T00:33:58.917Z,1667954038.917 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-11-09T00:33:58.923Z,1667954038.923 [SBIT](INFO): Initialize SBIT Component.
2022-11-09T00:33:58.923Z,1667954038.923 [SBIT](IMPORTANT): git: 2022-10-11-49-gb44faf3dc
2022-11-09T00:33:58.923Z,1667954038.923 [SBIT](INFO): git hash: b44faf3dc92774180e7da8d3246e288394a00694
2022-11-09T00:33:58.924Z,1667954038.924 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-11-09T00:33:58.925Z,1667954038.925 [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-09T00:33:58.926Z,1667954038.926 [SBIT](INFO): Beginning SBIT in 109.000000 seconds.
2022-11-09T00:33:58.927Z,1667954038.927 [IBIT](INFO): Initialize IBIT Component.
2022-11-09T00:33:58.928Z,1667954038.928 [CBIT](DEBUG): Initialize CBIT Component.
2022-11-09T00:33:58.929Z,1667954038.929 [logger ThreadHandler](INFO): Handler Thread ID is 4065
2022-11-09T00:33:58.940Z,1667954038.940 [CBIT](DEBUG): Initialized mux pins.
2022-11-09T00:33:58.940Z,1667954038.940 [CBIT](DEBUG): Initializing the watchdog timer.
2022-11-09T00:33:58.949Z,1667954038.949 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4066
2022-11-09T00:33:58.953Z,1667954038.953 [WetLabsBB2FL](INFO): Powering up
2022-11-09T00:33:58.954Z,1667954038.954 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4068
2022-11-09T00:33:58.961Z,1667954038.961 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4069
2022-11-09T00:33:58.962Z,1667954038.962 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-11-09T00:33:58.964Z,1667954038.964 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-11-09T00:33:58.965Z,1667954038.965 [CBIT](DEBUG): Initializing heartbeat.
2022-11-09T00:33:58.973Z,1667954038.973 [Onboard ThreadHandler](INFO): Handler Thread ID is 4070
2022-11-09T00:33:58.990Z,1667954038.990 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4071
2022-11-09T00:33:59.009Z,1667954039.009 [DAT ThreadHandler](INFO): Handler Thread ID is 4072
2022-11-09T00:33:59.009Z,1667954039.009 [DAT](INFO): Powering up
2022-11-09T00:33:59.010Z,1667954039.010 [DAT](DEBUG): Initializing DAT.
2022-11-09T00:33:59.013Z,1667954039.013 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4074
2022-11-09T00:33:59.016Z,1667954039.016 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-11-09T00:33:59.016Z,1667954039.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-11-09T00:33:59.017Z,1667954039.017 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-11-09T00:33:59.017Z,1667954039.017 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-11-09T00:33:59.017Z,1667954039.017 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-11-09T00:33:59.017Z,1667954039.017 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-11-09T00:33:59.017Z,1667954039.017 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-11-09T00:33:59.017Z,1667954039.017 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-11-09T00:33:59.036Z,1667954039.036 [CBIT](DEBUG): Deactivating GF circuits.
2022-11-09T00:33:59.036Z,1667954039.036 [CBIT](DEBUG): Deactivating emergency mode.
2022-11-09T00:33:59.072Z,1667954039.072 [CBIT](DEBUG): Backplane powered.
2022-11-09T00:33:59.073Z,1667954039.073 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-11-09T00:33:59.086Z,1667954039.086 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-11-09T00:33:59.126Z,1667954039.126 [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-09T00:33:59.127Z,1667954039.127 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-11-09T00:33:59.200Z,1667954039.200 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-11-09T00:33:59.202Z,1667954039.202 [Default:A.Wait](DEBUG): Construct Wait.
2022-11-09T00:33:59.204Z,1667954039.204 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-11-09T00:33:59.270Z,1667954039.270 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-11-09T00:33:59.273Z,1667954039.273 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-11-09T00:33:59.294Z,1667954039.294 [Default:E.Execute](DEBUG): Construct Execute.
2022-11-09T00:33:59.298Z,1667954039.298 [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-09T00:33:59.309Z,1667954039.309 [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-09T00:33:59.319Z,1667954039.319 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-11-09T00:33:59.379Z,1667954039.379 [MultiRay](INFO): Start
2022-11-09T00:33:59.379Z,1667954039.379 [MultiRay](INFO): Powering up MultiRay Lights
2022-11-09T00:33:59.396Z,1667954039.396 [Radio_Surface](INFO): Powering up
2022-11-09T00:33:59.413Z,1667954039.413 [Power24vConverter](INFO): Powering up.
2022-11-09T00:33:59.449Z,1667954039.449 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-11-09T00:33:59.465Z,1667954039.465 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-11-09T00:33:59.466Z,1667954039.466 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-11-09T00:33:59.472Z,1667954039.472 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-11-09T00:33:59.473Z,1667954039.473 [MassServo](DEBUG): Initializing EZServoServo.
2022-11-09T00:33:59.480Z,1667954039.480 [MassServo](DEBUG): Initializing MassServo.
2022-11-09T00:33:59.481Z,1667954039.481 [RudderServo](DEBUG): Initializing EZServoServo.
2022-11-09T00:33:59.489Z,1667954039.489 [RudderServo](DEBUG): Initializing RudderServo.
2022-11-09T00:33:59.490Z,1667954039.490 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-11-09T00:33:59.496Z,1667954039.496 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-11-09T00:33:59.785Z,1667954039.785 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-11-09T00:33:59.786Z,1667954039.786 [DropWeight] Hardware Fault, FailCount= 1
2022-11-09T00:33:59.786Z,1667954039.786 [DropWeight](ERROR): Hardware Fault
2022-11-09T00:33:59.786Z,1667954039.786 [MultiRay](INFO): Starting
2022-11-09T00:33:59.786Z,1667954039.786 [MultiRay](INFO): Finished reading config
2022-11-09T00:33:59.813Z,1667954039.813 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:33:59.824Z,1667954039.824 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:33:59.824Z,1667954039.824 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:33:59.864Z,1667954039.864 [CommandExec](FAULT): Scheduling is paused
2022-11-09T00:33:59.872Z,1667954039.872 [CBIT](INFO): Critical error at 20221109T003359
2022-11-09T00:33:59.873Z,1667954039.873 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-11-09T00:33:59.875Z,1667954039.875 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-11-09T00:33:59.876Z,1667954039.876 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-11-09T00:34:00.129Z,1667954040.129 [MultiRay](INFO): Runnable
2022-11-09T00:34:00.448Z,1667954040.448 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-11-09T00:34:00.448Z,1667954040.448 [RudderServo](FAULT): Rudder failed to initialize
2022-11-09T00:34:00.448Z,1667954040.448 [RudderServo] Communications Fault, FailCount= 1
2022-11-09T00:34:00.449Z,1667954040.449 [RudderServo](ERROR): Communications Fault
2022-11-09T00:34:00.450Z,1667954040.450 [CBIT](INFO): Critical error at 20221109T003359
2022-11-09T00:34:00.453Z,1667954040.453 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-11-09T00:34:00.530Z,1667954040.530 [MultiRay](INFO): Pause
2022-11-09T00:34:00.918Z,1667954040.918 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-11-09T00:34:00.918Z,1667954040.918 [RudderServo](INFO): Powering down
2022-11-09T00:34:00.967Z,1667954040.967 [MultiRay](INFO): Paused
2022-11-09T00:34:01.345Z,1667954041.345 [MultiRay](INFO): Paused
2022-11-09T00:34:01.369Z,1667954041.369 [RudderServo](DEBUG): Initializing EZServoServo.
2022-11-09T00:34:01.489Z,1667954041.489 [RudderServo](DEBUG): Initializing RudderServo.
2022-11-09T00:34:01.493Z,1667954041.493 [CBIT](INFO): Clearing failed state for component RudderServo
2022-11-09T00:34:01.493Z,1667954041.493 [RudderServo] No Fault, FailCount= 1
2022-11-09T00:34:01.564Z,1667954041.564 [WetLabsBB2FL](INFO): Powering down
2022-11-09T00:34:01.749Z,1667954041.749 [MultiRay](INFO): Paused
2022-11-09T00:34:02.153Z,1667954042.153 [MultiRay](INFO): Paused
2022-11-09T00:34:02.630Z,1667954042.630 [MultiRay](INFO): Paused
2022-11-09T00:34:02.983Z,1667954042.983 [MultiRay](INFO): Paused
2022-11-09T00:34:03.389Z,1667954043.389 [MultiRay](INFO): Paused
2022-11-09T00:34:03.861Z,1667954043.861 [MultiRay](INFO): Paused
2022-11-09T00:34:04.213Z,1667954044.213 [MultiRay](INFO): Paused
2022-11-09T00:34:04.613Z,1667954044.613 [MultiRay](INFO): Paused
2022-11-09T00:34:05.017Z,1667954045.017 [MultiRay](INFO): Paused
2022-11-09T00:34:05.229Z,1667954045.229 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-11-09T00:34:05.421Z,1667954045.421 [MultiRay](INFO): Paused
2022-11-09T00:34:05.813Z,1667954045.813 [MultiRay](INFO): Paused
2022-11-09T00:34:06.229Z,1667954046.229 [MultiRay](INFO): Paused
2022-11-09T00:34:06.633Z,1667954046.633 [MultiRay](INFO): Paused
2022-11-09T00:34:07.037Z,1667954047.037 [MultiRay](INFO): Paused
2022-11-09T00:34:07.429Z,1667954047.429 [MultiRay](INFO): Paused
2022-11-09T00:34:07.837Z,1667954047.837 [MultiRay](INFO): Paused
2022-11-09T00:34:08.225Z,1667954048.225 [MultiRay](INFO): Paused
2022-11-09T00:34:08.625Z,1667954048.625 [MultiRay](INFO): Paused
2022-11-09T00:34:09.029Z,1667954049.029 [MultiRay](INFO): Paused
2022-11-09T00:34:09.437Z,1667954049.437 [MultiRay](INFO): Paused
2022-11-09T00:34:09.833Z,1667954049.833 [MultiRay](INFO): Paused
2022-11-09T00:34:10.237Z,1667954050.237 [MultiRay](INFO): Paused
2022-11-09T00:34:10.641Z,1667954050.641 [MultiRay](INFO): Paused
2022-11-09T00:34:11.045Z,1667954051.045 [MultiRay](INFO): Paused
2022-11-09T00:34:11.457Z,1667954051.457 [MultiRay](INFO): Paused
2022-11-09T00:34:11.853Z,1667954051.853 [MultiRay](INFO): Paused
2022-11-09T00:34:12.257Z,1667954052.257 [MultiRay](INFO): Paused
2022-11-09T00:34:12.661Z,1667954052.661 [MultiRay](INFO): Paused
2022-11-09T00:34:13.065Z,1667954053.065 [MultiRay](INFO): Paused
2022-11-09T00:34:13.472Z,1667954053.472 [MultiRay](INFO): Paused
2022-11-09T00:34:13.873Z,1667954053.873 [MultiRay](INFO): Paused
2022-11-09T00:34:14.135Z,1667954054.135 [DAT](INFO): commRate: 800
2022-11-09T00:34:14.277Z,1667954054.277 [MultiRay](INFO): Paused
2022-11-09T00:34:14.681Z,1667954054.681 [MultiRay](INFO): Paused
2022-11-09T00:34:15.086Z,1667954055.086 [MultiRay](INFO): Paused
2022-11-09T00:34:15.489Z,1667954055.489 [MultiRay](INFO): Paused
2022-11-09T00:34:15.893Z,1667954055.893 [MultiRay](INFO): Paused
2022-11-09T00:34:16.200Z,1667954056.200 [DAT](INFO): entering command mode
2022-11-09T00:34:16.298Z,1667954056.298 [MultiRay](INFO): Paused
2022-11-09T00:34:16.401Z,1667954056.401 [DAT](INFO): setting verbose to 3
2022-11-09T00:34:16.654Z,1667954056.654 [DAT](INFO): set verbose to 3
2022-11-09T00:34:16.654Z,1667954056.654 [DAT](INFO): setting DatVerbose to 27440
2022-11-09T00:34:16.701Z,1667954056.701 [MultiRay](INFO): Paused
2022-11-09T00:34:16.906Z,1667954056.906 [DAT](INFO): set DatVerbose to 27440
2022-11-09T00:34:16.906Z,1667954056.906 [DAT](INFO): setting transmit power to 8
2022-11-09T00:34:17.105Z,1667954057.105 [MultiRay](INFO): Paused
2022-11-09T00:34:17.159Z,1667954057.159 [DAT](INFO): set transmit power to 8
2022-11-09T00:34:17.159Z,1667954057.159 [DAT](INFO): setting local address to 10
2022-11-09T00:34:17.410Z,1667954057.410 [DAT](INFO): set local address to 10
2022-11-09T00:34:17.411Z,1667954057.411 [DAT](INFO): Setting time to: 0:34:17 And date to:11/9/2022
2022-11-09T00:34:17.509Z,1667954057.509 [MultiRay](INFO): Paused
2022-11-09T00:34:17.662Z,1667954057.662 [DAT](INFO): Local DAT time set to Wed Nov 9, 2022  00:34:17  

2022-11-09T00:34:17.914Z,1667954057.914 [MultiRay](INFO): Paused
2022-11-09T00:34:18.317Z,1667954058.317 [MultiRay](INFO): Paused
2022-11-09T00:34:18.725Z,1667954058.725 [MultiRay](INFO): Paused
2022-11-09T00:34:19.129Z,1667954059.129 [MultiRay](INFO): Paused
2022-11-09T00:34:19.536Z,1667954059.536 [MultiRay](INFO): Paused
2022-11-09T00:34:19.933Z,1667954059.933 [MultiRay](INFO): Paused
2022-11-09T00:34:20.337Z,1667954060.337 [MultiRay](INFO): Paused
2022-11-09T00:34:20.745Z,1667954060.745 [MultiRay](INFO): Paused
2022-11-09T00:34:21.147Z,1667954061.147 [MultiRay](INFO): Paused
2022-11-09T00:34:21.553Z,1667954061.553 [MultiRay](INFO): Paused
2022-11-09T00:34:21.953Z,1667954061.953 [MultiRay](INFO): Paused
2022-11-09T00:34:22.361Z,1667954062.361 [MultiRay](INFO): Paused
2022-11-09T00:34:22.761Z,1667954062.761 [MultiRay](INFO): Paused
2022-11-09T00:34:23.165Z,1667954063.165 [MultiRay](INFO): Paused
2022-11-09T00:34:23.569Z,1667954063.569 [MultiRay](INFO): Paused
2022-11-09T00:34:23.973Z,1667954063.973 [MultiRay](INFO): Paused
2022-11-09T00:34:24.377Z,1667954064.377 [MultiRay](INFO): Paused
2022-11-09T00:34:24.793Z,1667954064.793 [MultiRay](INFO): Paused
2022-11-09T00:34:25.185Z,1667954065.185 [MultiRay](INFO): Paused
2022-11-09T00:34:25.593Z,1667954065.593 [MultiRay](INFO): Paused
2022-11-09T00:34:25.997Z,1667954065.997 [MultiRay](INFO): Paused
2022-11-09T00:34:26.401Z,1667954066.401 [MultiRay](INFO): Paused
2022-11-09T00:34:26.805Z,1667954066.805 [MultiRay](INFO): Paused
2022-11-09T00:34:27.205Z,1667954067.205 [MultiRay](INFO): Paused
2022-11-09T00:34:27.609Z,1667954067.609 [MultiRay](INFO): Paused
2022-11-09T00:34:28.021Z,1667954068.021 [MultiRay](INFO): Paused
2022-11-09T00:34:28.421Z,1667954068.421 [MultiRay](INFO): Paused
2022-11-09T00:34:28.422Z,1667954068.422 [NAL9602](INFO): Powering up NAL9602
2022-11-09T00:34:28.821Z,1667954068.821 [MultiRay](INFO): Paused
2022-11-09T00:34:29.225Z,1667954069.225 [MultiRay](INFO): Paused
2022-11-09T00:34:29.633Z,1667954069.633 [MultiRay](INFO): Paused
2022-11-09T00:34:30.033Z,1667954070.033 [MultiRay](INFO): Paused
2022-11-09T00:34:30.437Z,1667954070.437 [MultiRay](INFO): Paused
2022-11-09T00:34:30.845Z,1667954070.845 [MultiRay](INFO): Paused
2022-11-09T00:34:31.249Z,1667954071.249 [MultiRay](INFO): Paused
2022-11-09T00:34:31.649Z,1667954071.649 [MultiRay](INFO): Paused
2022-11-09T00:34:32.057Z,1667954072.057 [MultiRay](INFO): Paused
2022-11-09T00:34:32.457Z,1667954072.457 [MultiRay](INFO): Paused
2022-11-09T00:34:32.869Z,1667954072.869 [MultiRay](INFO): Paused
2022-11-09T00:34:33.286Z,1667954073.286 [MultiRay](INFO): Paused
2022-11-09T00:34:33.669Z,1667954073.669 [MultiRay](INFO): Paused
2022-11-09T00:34:34.081Z,1667954074.081 [MultiRay](INFO): Paused
2022-11-09T00:34:34.485Z,1667954074.485 [MultiRay](INFO): Paused
2022-11-09T00:34:34.881Z,1667954074.881 [MultiRay](INFO): Paused
2022-11-09T00:34:35.289Z,1667954075.289 [MultiRay](INFO): Paused
2022-11-09T00:34:35.701Z,1667954075.701 [MultiRay](INFO): Paused
2022-11-09T00:34:36.105Z,1667954076.105 [MultiRay](INFO): Paused
2022-11-09T00:34:36.497Z,1667954076.497 [MultiRay](INFO): Paused
2022-11-09T00:34:36.906Z,1667954076.906 [MultiRay](INFO): Paused
2022-11-09T00:34:37.305Z,1667954077.305 [MultiRay](INFO): Paused
2022-11-09T00:34:37.725Z,1667954077.725 [MultiRay](INFO): Paused
2022-11-09T00:34:38.131Z,1667954078.131 [MultiRay](INFO): Paused
2022-11-09T00:34:38.525Z,1667954078.525 [MultiRay](INFO): Paused
2022-11-09T00:34:38.929Z,1667954078.929 [MultiRay](INFO): Paused
2022-11-09T00:34:39.338Z,1667954079.338 [MultiRay](INFO): Paused
2022-11-09T00:34:39.339Z,1667954079.339 [NAL9602](INFO): NAL9602 initialized
2022-11-09T00:34:39.750Z,1667954079.750 [MultiRay](INFO): Paused
2022-11-09T00:34:40.137Z,1667954080.137 [MultiRay](INFO): Paused
2022-11-09T00:34:40.545Z,1667954080.545 [MultiRay](INFO): Paused
2022-11-09T00:34:40.949Z,1667954080.949 [MultiRay](INFO): Paused
2022-11-09T00:34:41.357Z,1667954081.357 [MultiRay](INFO): Paused
2022-11-09T00:34:41.757Z,1667954081.757 [MultiRay](INFO): Paused
2022-11-09T00:34:42.157Z,1667954082.157 [MultiRay](INFO): Paused
2022-11-09T00:34:42.561Z,1667954082.561 [MultiRay](INFO): Paused
2022-11-09T00:34:42.977Z,1667954082.977 [MultiRay](INFO): Paused
2022-11-09T00:34:43.374Z,1667954083.374 [MultiRay](INFO): Paused
2022-11-09T00:34:43.777Z,1667954083.777 [MultiRay](INFO): Paused
2022-11-09T00:34:44.181Z,1667954084.181 [MultiRay](INFO): Paused
2022-11-09T00:34:44.593Z,1667954084.593 [MultiRay](INFO): Paused
2022-11-09T00:34:45.001Z,1667954085.001 [MultiRay](INFO): Paused
2022-11-09T00:34:45.401Z,1667954085.401 [MultiRay](INFO): Paused
2022-11-09T00:34:45.797Z,1667954085.797 [MultiRay](INFO): Paused
2022-11-09T00:34:46.201Z,1667954086.201 [MultiRay](INFO): Paused
2022-11-09T00:34:46.601Z,1667954086.601 [MultiRay](INFO): Paused
2022-11-09T00:34:47.005Z,1667954087.005 [MultiRay](INFO): Paused
2022-11-09T00:34:47.409Z,1667954087.409 [MultiRay](INFO): Paused
2022-11-09T00:34:47.814Z,1667954087.814 [MultiRay](INFO): Paused
2022-11-09T00:34:48.218Z,1667954088.218 [MultiRay](INFO): Paused
2022-11-09T00:34:48.625Z,1667954088.625 [MultiRay](INFO): Paused
2022-11-09T00:34:49.042Z,1667954089.042 [MultiRay](INFO): Paused
2022-11-09T00:34:49.433Z,1667954089.433 [MultiRay](INFO): Paused
2022-11-09T00:34:49.837Z,1667954089.837 [MultiRay](INFO): Paused
2022-11-09T00:34:50.238Z,1667954090.238 [MultiRay](INFO): Paused
2022-11-09T00:34:50.645Z,1667954090.645 [MultiRay](INFO): Paused
2022-11-09T00:34:51.049Z,1667954091.049 [MultiRay](INFO): Paused
2022-11-09T00:34:51.460Z,1667954091.460 [MultiRay](INFO): Paused
2022-11-09T00:34:51.854Z,1667954091.854 [MultiRay](INFO): Paused
2022-11-09T00:34:52.262Z,1667954092.262 [MultiRay](INFO): Paused
2022-11-09T00:34:52.664Z,1667954092.664 [MultiRay](INFO): Paused
2022-11-09T00:34:53.062Z,1667954093.062 [MultiRay](INFO): Paused
2022-11-09T00:34:53.467Z,1667954093.467 [MultiRay](INFO): Paused
2022-11-09T00:34:53.874Z,1667954093.874 [MultiRay](INFO): Paused
2022-11-09T00:34:54.285Z,1667954094.285 [MultiRay](INFO): Paused
2022-11-09T00:34:54.685Z,1667954094.685 [MultiRay](INFO): Paused
2022-11-09T00:34:55.089Z,1667954095.089 [MultiRay](INFO): Paused
2022-11-09T00:34:55.493Z,1667954095.493 [MultiRay](INFO): Paused
2022-11-09T00:34:55.905Z,1667954095.905 [MultiRay](INFO): Paused
2022-11-09T00:34:56.298Z,1667954096.298 [MultiRay](INFO): Paused
2022-11-09T00:34:56.705Z,1667954096.705 [MultiRay](INFO): Paused
2022-11-09T00:34:57.108Z,1667954097.108 [MultiRay](INFO): Paused
2022-11-09T00:34:57.510Z,1667954097.510 [MultiRay](INFO): Paused
2022-11-09T00:34:57.910Z,1667954097.910 [MultiRay](INFO): Paused
2022-11-09T00:34:58.318Z,1667954098.318 [MultiRay](INFO): Paused
2022-11-09T00:34:58.729Z,1667954098.729 [MultiRay](INFO): Paused
2022-11-09T00:34:59.129Z,1667954099.129 [MultiRay](INFO): Paused
2022-11-09T00:34:59.531Z,1667954099.531 [MultiRay](INFO): Paused
2022-11-09T00:34:59.961Z,1667954099.961 [MultiRay](INFO): Paused
2022-11-09T00:35:00.365Z,1667954100.365 [MultiRay](INFO): Paused
2022-11-09T00:35:00.739Z,1667954100.739 [MultiRay](INFO): Paused
2022-11-09T00:35:01.147Z,1667954101.147 [MultiRay](INFO): Paused
2022-11-09T00:35:01.551Z,1667954101.551 [MultiRay](INFO): Paused
2022-11-09T00:35:01.957Z,1667954101.957 [MultiRay](INFO): Paused
2022-11-09T00:35:02.355Z,1667954102.355 [MultiRay](INFO): Paused
2022-11-09T00:35:02.763Z,1667954102.763 [MultiRay](INFO): Paused
2022-11-09T00:35:03.163Z,1667954103.163 [MultiRay](INFO): Paused
2022-11-09T00:35:03.570Z,1667954103.570 [MultiRay](INFO): Paused
2022-11-09T00:35:03.983Z,1667954103.983 [MultiRay](INFO): Paused
2022-11-09T00:35:04.385Z,1667954104.385 [MultiRay](INFO): Paused
2022-11-09T00:35:04.783Z,1667954104.783 [MultiRay](INFO): Paused
2022-11-09T00:35:05.188Z,1667954105.188 [MultiRay](INFO): Paused
2022-11-09T00:35:05.604Z,1667954105.604 [MultiRay](INFO): Paused
2022-11-09T00:35:05.995Z,1667954105.995 [MultiRay](INFO): Paused
2022-11-09T00:35:06.395Z,1667954106.395 [MultiRay](INFO): Paused
2022-11-09T00:35:06.804Z,1667954106.804 [MultiRay](INFO): Paused
2022-11-09T00:35:07.209Z,1667954107.209 [MultiRay](INFO): Paused
2022-11-09T00:35:07.611Z,1667954107.611 [MultiRay](INFO): Paused
2022-11-09T00:35:08.015Z,1667954108.015 [MultiRay](INFO): Paused
2022-11-09T00:35:08.427Z,1667954108.427 [MultiRay](INFO): Paused
2022-11-09T00:35:08.837Z,1667954108.837 [MultiRay](INFO): Paused
2022-11-09T00:35:09.235Z,1667954109.235 [MultiRay](INFO): Paused
2022-11-09T00:35:09.632Z,1667954109.632 [MultiRay](INFO): Paused
2022-11-09T00:35:10.038Z,1667954110.038 [MultiRay](INFO): Paused
2022-11-09T00:35:10.448Z,1667954110.448 [MultiRay](INFO): Paused
2022-11-09T00:35:10.843Z,1667954110.843 [MultiRay](INFO): Paused
2022-11-09T00:35:11.354Z,1667954111.354 [MultiRay](INFO): Paused
2022-11-09T00:35:11.665Z,1667954111.665 [MultiRay](INFO): Paused
2022-11-09T00:35:12.081Z,1667954112.081 [MultiRay](INFO): Paused
2022-11-09T00:35:12.493Z,1667954112.493 [MultiRay](INFO): Paused
2022-11-09T00:35:12.859Z,1667954112.859 [MultiRay](INFO): Paused
2022-11-09T00:35:13.266Z,1667954113.266 [MultiRay](INFO): Paused
2022-11-09T00:35:13.673Z,1667954113.673 [MultiRay](INFO): Paused
2022-11-09T00:35:14.077Z,1667954114.077 [MultiRay](INFO): Paused
2022-11-09T00:35:14.481Z,1667954114.481 [MultiRay](INFO): Paused
2022-11-09T00:35:14.882Z,1667954114.882 [MultiRay](INFO): Paused
2022-11-09T00:35:15.288Z,1667954115.288 [MultiRay](INFO): Paused
2022-11-09T00:35:15.693Z,1667954115.693 [MultiRay](INFO): Paused
2022-11-09T00:35:16.095Z,1667954116.095 [MultiRay](INFO): Paused
2022-11-09T00:35:16.501Z,1667954116.501 [MultiRay](INFO): Paused
2022-11-09T00:35:16.906Z,1667954116.906 [MultiRay](INFO): Paused
2022-11-09T00:35:17.315Z,1667954117.315 [MultiRay](INFO): Paused
2022-11-09T00:35:17.713Z,1667954117.713 [MultiRay](INFO): Paused
2022-11-09T00:35:18.114Z,1667954118.114 [MultiRay](INFO): Paused
2022-11-09T00:35:18.521Z,1667954118.521 [MultiRay](INFO): Paused
2022-11-09T00:35:18.933Z,1667954118.933 [MultiRay](INFO): Paused
2022-11-09T00:35:19.323Z,1667954119.323 [MultiRay](INFO): Paused
2022-11-09T00:35:19.730Z,1667954119.730 [MultiRay](INFO): Paused
2022-11-09T00:35:20.133Z,1667954120.133 [MultiRay](INFO): Paused
2022-11-09T00:35:20.549Z,1667954120.549 [MultiRay](INFO): Paused
2022-11-09T00:35:20.943Z,1667954120.943 [MultiRay](INFO): Paused
2022-11-09T00:35:21.345Z,1667954121.345 [MultiRay](INFO): Paused
2022-11-09T00:35:21.753Z,1667954121.753 [MultiRay](INFO): Paused
2022-11-09T00:35:22.163Z,1667954122.163 [MultiRay](INFO): Paused
2022-11-09T00:35:22.561Z,1667954122.561 [MultiRay](INFO): Paused
2022-11-09T00:35:23.021Z,1667954123.021 [MultiRay](INFO): Paused
2022-11-09T00:35:23.428Z,1667954123.428 [MultiRay](INFO): Paused
2022-11-09T00:35:23.797Z,1667954123.797 [MultiRay](INFO): Paused
2022-11-09T00:35:24.213Z,1667954124.213 [MultiRay](INFO): Paused
2022-11-09T00:35:24.597Z,1667954124.597 [MultiRay](INFO): Paused
2022-11-09T00:35:24.985Z,1667954124.985 [MultiRay](INFO): Paused
2022-11-09T00:35:25.393Z,1667954125.393 [MultiRay](INFO): Paused
2022-11-09T00:35:25.790Z,1667954125.790 [MultiRay](INFO): Paused
2022-11-09T00:35:26.197Z,1667954126.197 [MultiRay](INFO): Paused
2022-11-09T00:35:26.595Z,1667954126.595 [MultiRay](INFO): Paused
2022-11-09T00:35:27.004Z,1667954127.004 [MultiRay](INFO): Paused
2022-11-09T00:35:27.409Z,1667954127.409 [MultiRay](INFO): Paused
2022-11-09T00:35:27.813Z,1667954127.813 [MultiRay](INFO): Paused
2022-11-09T00:35:28.217Z,1667954128.217 [MultiRay](INFO): Paused
2022-11-09T00:35:28.625Z,1667954128.625 [MultiRay](INFO): Paused
2022-11-09T00:35:29.022Z,1667954129.022 [MultiRay](INFO): Paused
2022-11-09T00:35:29.426Z,1667954129.426 [MultiRay](INFO): Paused
2022-11-09T00:35:29.831Z,1667954129.831 [MultiRay](INFO): Paused
2022-11-09T00:35:30.237Z,1667954130.237 [MultiRay](INFO): Paused
2022-11-09T00:35:30.641Z,1667954130.641 [MultiRay](INFO): Paused
2022-11-09T00:35:31.044Z,1667954131.044 [MultiRay](INFO): Paused
2022-11-09T00:35:31.446Z,1667954131.446 [MultiRay](INFO): Paused
2022-11-09T00:35:31.852Z,1667954131.852 [MultiRay](INFO): Paused
2022-11-09T00:35:32.254Z,1667954132.254 [MultiRay](INFO): Paused
2022-11-09T00:35:32.657Z,1667954132.657 [MultiRay](INFO): Paused
2022-11-09T00:35:33.103Z,1667954133.103 [MultiRay](INFO): Paused
2022-11-09T00:35:33.469Z,1667954133.469 [MultiRay](INFO): Paused
2022-11-09T00:35:33.871Z,1667954133.871 [MultiRay](INFO): Paused
2022-11-09T00:35:34.275Z,1667954134.275 [MultiRay](INFO): Paused
2022-11-09T00:35:34.675Z,1667954134.675 [MultiRay](INFO): Paused
2022-11-09T00:35:35.084Z,1667954135.084 [MultiRay](INFO): Paused
2022-11-09T00:35:35.603Z,1667954135.603 [MultiRay](INFO): Paused
2022-11-09T00:35:35.900Z,1667954135.900 [MultiRay](INFO): Paused
2022-11-09T00:35:36.333Z,1667954136.333 [MultiRay](INFO): Paused
2022-11-09T00:35:36.731Z,1667954136.731 [MultiRay](INFO): Paused
2022-11-09T00:35:37.104Z,1667954137.104 [MultiRay](INFO): Paused
2022-11-09T00:35:37.507Z,1667954137.507 [MultiRay](INFO): Paused
2022-11-09T00:35:37.906Z,1667954137.906 [MultiRay](INFO): Paused
2022-11-09T00:35:38.317Z,1667954138.317 [MultiRay](INFO): Paused
2022-11-09T00:35:38.716Z,1667954138.716 [MultiRay](INFO): Paused
2022-11-09T00:35:39.137Z,1667954139.137 [MultiRay](INFO): Paused
2022-11-09T00:35:39.533Z,1667954139.533 [MultiRay](INFO): Paused
2022-11-09T00:35:39.949Z,1667954139.949 [MultiRay](INFO): Paused
2022-11-09T00:35:40.338Z,1667954140.338 [MultiRay](INFO): Paused
2022-11-09T00:35:40.739Z,1667954140.739 [MultiRay](INFO): Paused
2022-11-09T00:35:41.143Z,1667954141.143 [MultiRay](INFO): Paused
2022-11-09T00:35:41.546Z,1667954141.546 [MultiRay](INFO): Paused
2022-11-09T00:35:41.957Z,1667954141.957 [MultiRay](INFO): Paused
2022-11-09T00:35:42.351Z,1667954142.351 [MultiRay](INFO): Paused
2022-11-09T00:35:42.771Z,1667954142.771 [MultiRay](INFO): Paused
2022-11-09T00:35:43.170Z,1667954143.170 [MultiRay](INFO): Paused
2022-11-09T00:35:43.586Z,1667954143.586 [MultiRay](INFO): Paused
2022-11-09T00:35:43.984Z,1667954143.984 [MultiRay](INFO): Paused
2022-11-09T00:35:44.377Z,1667954144.377 [MultiRay](INFO): Paused
2022-11-09T00:35:44.779Z,1667954144.779 [MultiRay](INFO): Paused
2022-11-09T00:35:45.184Z,1667954145.184 [MultiRay](INFO): Paused
2022-11-09T00:35:45.589Z,1667954145.589 [MultiRay](INFO): Paused
2022-11-09T00:35:45.991Z,1667954145.991 [MultiRay](INFO): Paused
2022-11-09T00:35:46.394Z,1667954146.394 [MultiRay](INFO): Paused
2022-11-09T00:35:46.800Z,1667954146.800 [MultiRay](INFO): Paused
2022-11-09T00:35:47.205Z,1667954147.205 [MultiRay](INFO): Paused
2022-11-09T00:35:47.606Z,1667954147.606 [MultiRay](INFO): Paused
2022-11-09T00:35:48.007Z,1667954148.007 [MultiRay](INFO): Paused
2022-11-09T00:35:48.475Z,1667954148.475 [MultiRay](INFO): Paused
2022-11-09T00:35:48.489Z,1667954148.489 [SBIT](IMPORTANT): Beginning Startup BIT
2022-11-09T00:35:48.494Z,1667954148.494 [CBIT](IMPORTANT): Beginning ground fault scan
2022-11-09T00:35:48.849Z,1667954148.849 [MultiRay](INFO): Paused
2022-11-09T00:35:49.245Z,1667954149.245 [MultiRay](INFO): Paused
2022-11-09T00:35:49.641Z,1667954149.641 [MultiRay](INFO): Paused
2022-11-09T00:35:50.030Z,1667954150.030 [MultiRay](INFO): Paused
2022-11-09T00:35:50.434Z,1667954150.434 [MultiRay](INFO): Paused
2022-11-09T00:35:50.839Z,1667954150.839 [MultiRay](INFO): Paused
2022-11-09T00:35:51.243Z,1667954151.243 [MultiRay](INFO): Paused
2022-11-09T00:35:51.647Z,1667954151.647 [MultiRay](INFO): Paused
2022-11-09T00:35:52.057Z,1667954152.057 [MultiRay](INFO): Paused
2022-11-09T00:35:52.454Z,1667954152.454 [MultiRay](INFO): Paused
2022-11-09T00:35:52.859Z,1667954152.859 [MultiRay](INFO): Paused
2022-11-09T00:35:53.263Z,1667954153.263 [MultiRay](INFO): Paused
2022-11-09T00:35:53.668Z,1667954153.668 [MultiRay](INFO): Paused
2022-11-09T00:35:54.110Z,1667954154.110 [MultiRay](INFO): Paused
2022-11-09T00:35:54.477Z,1667954154.477 [MultiRay](INFO): Paused
2022-11-09T00:35:54.878Z,1667954154.878 [MultiRay](INFO): Paused
2022-11-09T00:35:55.284Z,1667954155.284 [MultiRay](INFO): Paused
2022-11-09T00:35:55.687Z,1667954155.687 [MultiRay](INFO): Paused
2022-11-09T00:35:56.152Z,1667954156.152 [MultiRay](INFO): Paused
2022-11-09T00:35:56.502Z,1667954156.502 [MultiRay](INFO): Paused
2022-11-09T00:35:56.921Z,1667954156.921 [MultiRay](INFO): Paused
2022-11-09T00:35:57.322Z,1667954157.322 [MultiRay](INFO): Paused
2022-11-09T00:35:57.707Z,1667954157.707 [MultiRay](INFO): Paused
2022-11-09T00:35:58.111Z,1667954158.111 [MultiRay](INFO): Paused
2022-11-09T00:35:58.517Z,1667954158.517 [MultiRay](INFO): Paused
2022-11-09T00:35:58.919Z,1667954158.919 [MultiRay](INFO): Paused
2022-11-09T00:35:59.325Z,1667954159.325 [MultiRay](INFO): Paused
2022-11-09T00:35:59.397Z,1667954159.397 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009105
CHAN A1 (24V): 0.000566
CHAN A2 (12V): -0.005355
CHAN A3 (5V): -0.002575
CHAN B0 (3.3V): -0.001446
CHAN B1 (3.15aV): -0.001764
CHAN B2 (3.15bV): -0.001447
CHAN B3 (GND): -0.000768
OPEN: 0.005006
Full Scale: +/- 1 mA
2022-11-09T00:35:59.733Z,1667954159.733 [MultiRay](INFO): Paused
2022-11-09T00:36:00.131Z,1667954160.131 [MultiRay](INFO): Paused
2022-11-09T00:36:00.539Z,1667954160.539 [MultiRay](INFO): Paused
2022-11-09T00:36:00.945Z,1667954160.945 [MultiRay](INFO): Paused
2022-11-09T00:36:01.343Z,1667954161.343 [MultiRay](INFO): Paused
2022-11-09T00:36:01.753Z,1667954161.753 [MultiRay](INFO): Paused
2022-11-09T00:36:02.210Z,1667954162.210 [MultiRay](INFO): Paused
2022-11-09T00:36:02.558Z,1667954162.558 [MultiRay](INFO): Paused
2022-11-09T00:36:02.973Z,1667954162.973 [MultiRay](INFO): Paused
2022-11-09T00:36:03.381Z,1667954163.381 [MultiRay](INFO): Paused
2022-11-09T00:36:03.764Z,1667954163.764 [MultiRay](INFO): Paused
2022-11-09T00:36:04.170Z,1667954164.170 [MultiRay](INFO): Paused
2022-11-09T00:36:04.574Z,1667954164.574 [MultiRay](INFO): Paused
2022-11-09T00:36:04.979Z,1667954164.979 [MultiRay](INFO): Paused
2022-11-09T00:36:05.384Z,1667954165.384 [MultiRay](INFO): Paused
2022-11-09T00:36:05.789Z,1667954165.789 [MultiRay](INFO): Paused
2022-11-09T00:36:06.191Z,1667954166.191 [MultiRay](INFO): Paused
2022-11-09T00:36:06.596Z,1667954166.596 [MultiRay](INFO): Paused
2022-11-09T00:36:07.000Z,1667954167.000 [MultiRay](INFO): Paused
2022-11-09T00:36:07.403Z,1667954167.403 [MultiRay](INFO): Paused
2022-11-09T00:36:07.807Z,1667954167.807 [MultiRay](INFO): Paused
2022-11-09T00:36:08.210Z,1667954168.210 [MultiRay](INFO): Paused
2022-11-09T00:36:08.613Z,1667954168.613 [MultiRay](INFO): Paused
2022-11-09T00:36:09.019Z,1667954169.019 [MultiRay](INFO): Paused
2022-11-09T00:36:09.497Z,1667954169.497 [MultiRay](INFO): Paused
2022-11-09T00:36:09.837Z,1667954169.837 [MultiRay](INFO): Paused
2022-11-09T00:36:10.262Z,1667954170.262 [MultiRay](INFO): Paused
2022-11-09T00:36:10.649Z,1667954170.649 [MultiRay](INFO): Paused
2022-11-09T00:36:11.039Z,1667954171.039 [MultiRay](INFO): Paused
2022-11-09T00:36:11.442Z,1667954171.442 [MultiRay](INFO): Paused
2022-11-09T00:36:11.848Z,1667954171.848 [MultiRay](INFO): Paused
2022-11-09T00:36:12.249Z,1667954172.249 [MultiRay](INFO): Paused
2022-11-09T00:36:12.652Z,1667954172.652 [MultiRay](INFO): Paused
2022-11-09T00:36:13.061Z,1667954173.061 [MultiRay](INFO): Paused
2022-11-09T00:36:13.465Z,1667954173.465 [MultiRay](INFO): Paused
2022-11-09T00:36:13.867Z,1667954173.867 [MultiRay](INFO): Paused
2022-11-09T00:36:14.273Z,1667954174.273 [MultiRay](INFO): Paused
2022-11-09T00:36:14.672Z,1667954174.672 [MultiRay](INFO): Paused
2022-11-09T00:36:15.087Z,1667954175.087 [MultiRay](INFO): Paused
2022-11-09T00:36:15.485Z,1667954175.485 [MultiRay](INFO): Paused
2022-11-09T00:36:15.948Z,1667954175.948 [MultiRay](INFO): Paused
2022-11-09T00:36:16.298Z,1667954176.298 [MultiRay](INFO): Paused
2022-11-09T00:36:16.717Z,1667954176.717 [MultiRay](INFO): Paused
2022-11-09T00:36:17.121Z,1667954177.121 [MultiRay](INFO): Paused
2022-11-09T00:36:17.505Z,1667954177.505 [MultiRay](INFO): Paused
2022-11-09T00:36:17.909Z,1667954177.909 [MultiRay](INFO): Paused
2022-11-09T00:36:18.317Z,1667954178.317 [MultiRay](INFO): Paused
2022-11-09T00:36:18.717Z,1667954178.717 [MultiRay](INFO): Paused
2022-11-09T00:36:19.125Z,1667954179.125 [MultiRay](INFO): Paused
2022-11-09T00:36:19.519Z,1667954179.519 [MultiRay](INFO): Paused
2022-11-09T00:36:19.930Z,1667954179.930 [MultiRay](INFO): Paused
2022-11-09T00:36:20.339Z,1667954180.339 [MultiRay](INFO): Paused
2022-11-09T00:36:20.735Z,1667954180.735 [MultiRay](INFO): Paused
2022-11-09T00:36:21.138Z,1667954181.138 [MultiRay](INFO): Paused
2022-11-09T00:36:21.605Z,1667954181.605 [MultiRay](INFO): Paused
2022-11-09T00:36:21.953Z,1667954181.953 [MultiRay](INFO): Paused
2022-11-09T00:36:22.353Z,1667954182.353 [MultiRay](INFO): Paused
2022-11-09T00:36:22.757Z,1667954182.757 [MultiRay](INFO): Paused
2022-11-09T00:36:23.281Z,1667954183.281 [MultiRay](INFO): Paused
2022-11-09T00:36:23.597Z,1667954183.597 [MultiRay](INFO): Paused
2022-11-09T00:36:23.983Z,1667954183.983 [MultiRay](INFO): Paused
2022-11-09T00:36:24.381Z,1667954184.381 [MultiRay](INFO): Paused
2022-11-09T00:36:24.777Z,1667954184.777 [MultiRay](INFO): Paused
2022-11-09T00:36:25.201Z,1667954185.201 [MultiRay](INFO): Paused
2022-11-09T00:36:25.585Z,1667954185.585 [MultiRay](INFO): Paused
2022-11-09T00:36:25.983Z,1667954185.983 [MultiRay](INFO): Paused
2022-11-09T00:36:26.397Z,1667954186.397 [MultiRay](INFO): Paused
2022-11-09T00:36:26.791Z,1667954186.791 [MultiRay](INFO): Paused
2022-11-09T00:36:27.079Z,1667954187.079 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004867
2022-11-09T00:36:27.195Z,1667954187.195 [MultiRay](INFO): Paused
2022-11-09T00:36:27.601Z,1667954187.601 [MultiRay](INFO): Paused
2022-11-09T00:36:28.009Z,1667954188.009 [MultiRay](INFO): Paused
2022-11-09T00:36:28.419Z,1667954188.419 [MultiRay](INFO): Paused
2022-11-09T00:36:28.814Z,1667954188.814 [MultiRay](INFO): Paused
2022-11-09T00:36:29.221Z,1667954189.221 [MultiRay](INFO): Paused
2022-11-09T00:36:29.685Z,1667954189.685 [MultiRay](INFO): Paused
2022-11-09T00:36:30.063Z,1667954190.063 [MultiRay](INFO): Paused
2022-11-09T00:36:30.452Z,1667954190.452 [MultiRay](INFO): Paused
2022-11-09T00:36:30.854Z,1667954190.854 [MultiRay](INFO): Paused
2022-11-09T00:36:31.240Z,1667954191.240 [MultiRay](INFO): Paused
2022-11-09T00:36:31.643Z,1667954191.643 [MultiRay](INFO): Paused
2022-11-09T00:36:32.046Z,1667954192.046 [MultiRay](INFO): Paused
2022-11-09T00:36:32.450Z,1667954192.450 [MultiRay](INFO): Paused
2022-11-09T00:36:32.856Z,1667954192.856 [MultiRay](INFO): Paused
2022-11-09T00:36:33.258Z,1667954193.258 [MultiRay](INFO): Paused
2022-11-09T00:36:33.663Z,1667954193.663 [MultiRay](INFO): Paused
2022-11-09T00:36:34.067Z,1667954194.067 [MultiRay](INFO): Paused
2022-11-09T00:36:34.473Z,1667954194.473 [MultiRay](INFO): Paused
2022-11-09T00:36:34.883Z,1667954194.883 [MultiRay](INFO): Paused
2022-11-09T00:36:35.279Z,1667954195.279 [MultiRay](INFO): Paused
2022-11-09T00:36:35.685Z,1667954195.685 [MultiRay](INFO): Paused
2022-11-09T00:36:36.089Z,1667954196.089 [MultiRay](INFO): Paused
2022-11-09T00:36:36.563Z,1667954196.563 [MultiRay](INFO): Paused
2022-11-09T00:36:36.902Z,1667954196.902 [MultiRay](INFO): Paused
2022-11-09T00:36:37.310Z,1667954197.310 [MultiRay](INFO): Paused
2022-11-09T00:36:37.714Z,1667954197.714 [MultiRay](INFO): Paused
2022-11-09T00:36:38.113Z,1667954198.113 [MultiRay](INFO): Paused
2022-11-09T00:36:38.513Z,1667954198.513 [MultiRay](INFO): Paused
2022-11-09T00:36:38.914Z,1667954198.914 [MultiRay](INFO): Paused
2022-11-09T00:36:39.317Z,1667954199.317 [MultiRay](INFO): Paused
2022-11-09T00:36:39.737Z,1667954199.737 [MultiRay](INFO): Paused
2022-11-09T00:36:40.141Z,1667954200.141 [MultiRay](INFO): Paused
2022-11-09T00:36:40.541Z,1667954200.541 [MultiRay](INFO): Paused
2022-11-09T00:36:40.938Z,1667954200.938 [MultiRay](INFO): Paused
2022-11-09T00:36:41.345Z,1667954201.345 [MultiRay](INFO): Paused
2022-11-09T00:36:41.755Z,1667954201.755 [MultiRay](INFO): Paused
2022-11-09T00:36:42.152Z,1667954202.152 [MultiRay](INFO): Paused
2022-11-09T00:36:42.557Z,1667954202.557 [MultiRay](INFO): Paused
2022-11-09T00:36:42.609Z,1667954202.609 [SBIT](IMPORTANT): SBIT PASSED
2022-11-09T00:36:42.610Z,1667954202.610 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-11-09T00:36:42.610Z,1667954202.610 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;

2022-11-09T00:36:42.610Z,1667954202.610 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none;

2022-11-09T00:36:42.611Z,1667954202.611 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere;

2022-11-09T00:36:42.611Z,1667954202.611 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-11-09T00:36:42.611Z,1667954202.611 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2022-11-09T00:36:42.611Z,1667954202.611 [SBIT](IMPORTANT): DAT.surfaceThreshold=-1 meter;

2022-11-09T00:36:42.611Z,1667954202.611 [SBIT](IMPORTANT): Express none _.ayeris_disk_space;

2022-11-09T00:36:42.611Z,1667954202.611 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 250.000000 count;

2022-11-09T00:36:42.611Z,1667954202.611 [SBIT](IMPORTANT): Express none _.ayeris_particle_size;

2022-11-09T00:36:42.611Z,1667954202.611 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;

2022-11-09T00:36:42.612Z,1667954202.612 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;

2022-11-09T00:36:42.612Z,1667954202.612 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=210.367112 cubic_centimeter;

2022-11-09T00:36:42.612Z,1667954202.612 [SBIT](IMPORTANT): VerticalControl.massDefault=22.333859 millimeter;

2022-11-09T00:36:43.029Z,1667954203.029 [MultiRay](INFO): Paused
2022-11-09T00:36:43.060Z,1667954203.060 [MissionManager](IMPORTANT): Started mission Startup
2022-11-09T00:36:43.060Z,1667954203.060 [Startup] Running Loop=1
2022-11-09T00:36:43.060Z,1667954203.060 [Startup](DEBUG): Aggregate::initialize Startup
2022-11-09T00:36:43.061Z,1667954203.061 [Startup:A.GoToSurface] Running Loop=1
2022-11-09T00:36:43.061Z,1667954203.061 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-09T00:36:43.061Z,1667954203.061 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-09T00:36:43.062Z,1667954203.062 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-09T00:36:43.062Z,1667954203.062 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-09T00:36:43.062Z,1667954203.062 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-09T00:36:43.063Z,1667954203.063 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-09T00:36:43.064Z,1667954203.064 [Startup:StartupSatComms] Running Loop=1
2022-11-09T00:36:43.064Z,1667954203.064 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-11-09T00:36:43.064Z,1667954203.064 [Startup:StartupSatComms:A] Running Loop=1
2022-11-09T00:36:43.381Z,1667954203.381 [MultiRay](INFO): Paused
2022-11-09T00:36:43.394Z,1667954203.394 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-11-09T00:36:43.788Z,1667954203.788 [MultiRay](INFO): Paused
2022-11-09T00:36:44.185Z,1667954204.185 [MultiRay](INFO): Paused
2022-11-09T00:36:44.572Z,1667954204.572 [MultiRay](INFO): Paused
2022-11-09T00:36:44.979Z,1667954204.979 [MultiRay](INFO): Paused
2022-11-09T00:36:45.382Z,1667954205.382 [MultiRay](INFO): Paused
2022-11-09T00:36:45.789Z,1667954205.789 [MultiRay](INFO): Paused
2022-11-09T00:36:46.193Z,1667954206.193 [MultiRay](INFO): Paused
2022-11-09T00:36:46.593Z,1667954206.593 [MultiRay](INFO): Paused
2022-11-09T00:36:46.998Z,1667954206.998 [MultiRay](INFO): Paused
2022-11-09T00:36:47.403Z,1667954207.403 [MultiRay](INFO): Paused
2022-11-09T00:36:47.806Z,1667954207.806 [MultiRay](INFO): Paused
2022-11-09T00:36:48.208Z,1667954208.208 [MultiRay](INFO): Paused
2022-11-09T00:36:48.615Z,1667954208.615 [MultiRay](INFO): Paused
2022-11-09T00:36:49.021Z,1667954209.021 [MultiRay](INFO): Paused
2022-11-09T00:36:49.425Z,1667954209.425 [MultiRay](INFO): Paused
2022-11-09T00:36:49.887Z,1667954209.887 [MultiRay](INFO): Paused
2022-11-09T00:36:50.265Z,1667954210.265 [MultiRay](INFO): Paused
2022-11-09T00:36:50.649Z,1667954210.649 [MultiRay](INFO): Paused
2022-11-09T00:36:51.053Z,1667954211.053 [MultiRay](INFO): Paused
2022-11-09T00:36:51.440Z,1667954211.440 [MultiRay](INFO): Paused
2022-11-09T00:36:51.855Z,1667954211.855 [MultiRay](INFO): Paused
2022-11-09T00:36:52.251Z,1667954212.251 [MultiRay](INFO): Paused
2022-11-09T00:36:52.654Z,1667954212.654 [MultiRay](INFO): Paused
2022-11-09T00:36:53.056Z,1667954213.056 [MultiRay](INFO): Paused
2022-11-09T00:36:53.472Z,1667954213.472 [MultiRay](INFO): Paused
2022-11-09T00:36:53.866Z,1667954213.866 [MultiRay](INFO): Paused
2022-11-09T00:36:54.277Z,1667954214.277 [MultiRay](INFO): Paused
2022-11-09T00:36:54.677Z,1667954214.677 [MultiRay](INFO): Paused
2022-11-09T00:36:55.082Z,1667954215.082 [MultiRay](INFO): Paused
2022-11-09T00:36:55.482Z,1667954215.482 [MultiRay](INFO): Paused
2022-11-09T00:36:55.889Z,1667954215.889 [MultiRay](INFO): Paused
2022-11-09T00:36:56.357Z,1667954216.357 [MultiRay](INFO): Paused
2022-11-09T00:36:56.721Z,1667954216.721 [MultiRay](INFO): Paused
2022-11-09T00:36:57.113Z,1667954217.113 [MultiRay](INFO): Paused
2022-11-09T00:36:57.513Z,1667954217.513 [MultiRay](INFO): Paused
2022-11-09T00:36:57.917Z,1667954217.917 [MultiRay](INFO): Paused
2022-11-09T00:36:58.309Z,1667954218.309 [MultiRay](INFO): Paused
2022-11-09T00:36:58.713Z,1667954218.713 [MultiRay](INFO): Paused
2022-11-09T00:36:59.121Z,1667954219.121 [MultiRay](INFO): Paused
2022-11-09T00:36:59.143Z,1667954219.143 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-11-09T00:36:59.143Z,1667954219.143 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-09T00:36:59.177Z,1667954219.177 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2022-11-09T00:36:59.177Z,1667954219.177 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-11-09T00:36:59.208Z,1667954219.208 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-09T00:36:59.209Z,1667954219.209 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-11-09T00:36:59.521Z,1667954219.521 [MultiRay](INFO): Paused
2022-11-09T00:36:59.542Z,1667954219.542 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-09T00:36:59.542Z,1667954219.542 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-11-09T00:36:59.543Z,1667954219.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-11-09T00:36:59.543Z,1667954219.543 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2022-11-09T00:36:59.929Z,1667954219.929 [MultiRay](INFO): Paused
2022-11-09T00:36:59.955Z,1667954219.955 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-11-09T00:37:00.333Z,1667954220.333 [MultiRay](INFO): Paused
2022-11-09T00:37:00.744Z,1667954220.744 [MultiRay](INFO): Paused
2022-11-09T00:37:01.141Z,1667954221.141 [MultiRay](INFO): Paused
2022-11-09T00:37:01.545Z,1667954221.545 [MultiRay](INFO): Paused
2022-11-09T00:37:01.949Z,1667954221.949 [MultiRay](INFO): Paused
2022-11-09T00:37:02.351Z,1667954222.351 [MultiRay](INFO): Paused
2022-11-09T00:37:02.757Z,1667954222.757 [MultiRay](INFO): Paused
2022-11-09T00:37:03.220Z,1667954223.220 [MultiRay](INFO): Paused
2022-11-09T00:37:03.570Z,1667954223.570 [MultiRay](INFO): Paused
2022-11-09T00:37:03.982Z,1667954223.982 [MultiRay](INFO): Paused
2022-11-09T00:37:04.390Z,1667954224.390 [MultiRay](INFO): Paused
2022-11-09T00:37:04.783Z,1667954224.783 [MultiRay](INFO): Paused
2022-11-09T00:37:05.174Z,1667954225.174 [MultiRay](INFO): Paused
2022-11-09T00:37:05.585Z,1667954225.585 [MultiRay](INFO): Paused
2022-11-09T00:37:05.984Z,1667954225.984 [MultiRay](INFO): Paused
2022-11-09T00:37:06.401Z,1667954226.401 [MultiRay](INFO): Paused
2022-11-09T00:37:06.797Z,1667954226.797 [MultiRay](INFO): Paused
2022-11-09T00:37:07.201Z,1667954227.201 [MultiRay](INFO): Paused
2022-11-09T00:37:07.605Z,1667954227.605 [MultiRay](INFO): Paused
2022-11-09T00:37:08.009Z,1667954228.009 [MultiRay](INFO): Paused
2022-11-09T00:37:08.410Z,1667954228.410 [MultiRay](INFO): Paused
2022-11-09T00:37:08.814Z,1667954228.814 [MultiRay](INFO): Paused
2022-11-09T00:37:09.221Z,1667954229.221 [MultiRay](INFO): Paused
2022-11-09T00:37:09.697Z,1667954229.697 [MultiRay](INFO): Paused
2022-11-09T00:37:10.037Z,1667954230.037 [MultiRay](INFO): Paused
2022-11-09T00:37:10.453Z,1667954230.453 [MultiRay](INFO): Paused
2022-11-09T00:37:10.861Z,1667954230.861 [MultiRay](INFO): Paused
2022-11-09T00:37:11.234Z,1667954231.234 [MultiRay](INFO): Paused
2022-11-09T00:37:11.644Z,1667954231.644 [MultiRay](INFO): Paused
2022-11-09T00:37:12.050Z,1667954232.050 [MultiRay](INFO): Paused
2022-11-09T00:37:12.452Z,1667954232.452 [MultiRay](INFO): Paused
2022-11-09T00:37:12.853Z,1667954232.853 [MultiRay](INFO): Paused
2022-11-09T00:37:13.261Z,1667954233.261 [MultiRay](INFO): Paused
2022-11-09T00:37:13.664Z,1667954233.664 [MultiRay](INFO): Paused
2022-11-09T00:37:14.069Z,1667954234.069 [MultiRay](INFO): Paused
2022-11-09T00:37:14.473Z,1667954234.473 [MultiRay](INFO): Paused
2022-11-09T00:37:14.877Z,1667954234.877 [MultiRay](INFO): Paused
2022-11-09T00:37:15.277Z,1667954235.277 [MultiRay](INFO): Paused
2022-11-09T00:37:15.684Z,1667954235.684 [MultiRay](INFO): Paused
2022-11-09T00:37:16.094Z,1667954236.094 [MultiRay](INFO): Paused
2022-11-09T00:37:16.554Z,1667954236.554 [MultiRay](INFO): Paused
2022-11-09T00:37:16.903Z,1667954236.903 [MultiRay](INFO): Paused
2022-11-09T00:37:17.321Z,1667954237.321 [MultiRay](INFO): Paused
2022-11-09T00:37:17.726Z,1667954237.726 [MultiRay](INFO): Paused
2022-11-09T00:37:18.103Z,1667954238.103 [MultiRay](INFO): Paused
2022-11-09T00:37:18.517Z,1667954238.517 [MultiRay](INFO): Paused
2022-11-09T00:37:18.918Z,1667954238.918 [MultiRay](INFO): Paused
2022-11-09T00:37:19.314Z,1667954239.314 [MultiRay](INFO): Paused
2022-11-09T00:37:19.720Z,1667954239.720 [MultiRay](INFO): Paused
2022-11-09T00:37:20.127Z,1667954240.127 [MultiRay](INFO): Paused
2022-11-09T00:37:20.533Z,1667954240.533 [MultiRay](INFO): Paused
2022-11-09T00:37:20.943Z,1667954240.943 [MultiRay](INFO): Paused
2022-11-09T00:37:21.341Z,1667954241.341 [MultiRay](INFO): Paused
2022-11-09T00:37:21.743Z,1667954241.743 [MultiRay](INFO): Paused
2022-11-09T00:37:22.147Z,1667954242.147 [MultiRay](INFO): Paused
2022-11-09T00:37:22.557Z,1667954242.557 [MultiRay](INFO): Paused
2022-11-09T00:37:22.962Z,1667954242.962 [MultiRay](INFO): Paused
2022-11-09T00:37:23.421Z,1667954243.421 [MultiRay](INFO): Paused
2022-11-09T00:37:23.771Z,1667954243.771 [MultiRay](INFO): Paused
2022-11-09T00:37:24.209Z,1667954244.209 [MultiRay](INFO): Paused
2022-11-09T00:37:24.589Z,1667954244.589 [MultiRay](INFO): Paused
2022-11-09T00:37:24.974Z,1667954244.974 [MultiRay](INFO): Paused
2022-11-09T00:37:25.378Z,1667954245.378 [MultiRay](INFO): Paused
2022-11-09T00:37:25.785Z,1667954245.785 [MultiRay](INFO): Paused
2022-11-09T00:37:26.189Z,1667954246.189 [MultiRay](INFO): Paused
2022-11-09T00:37:26.591Z,1667954246.591 [MultiRay](INFO): Paused
2022-11-09T00:37:26.991Z,1667954246.991 [MultiRay](INFO): Paused
2022-11-09T00:37:27.406Z,1667954247.406 [MultiRay](INFO): Paused
2022-11-09T00:37:27.816Z,1667954247.816 [MultiRay](INFO): Paused
2022-11-09T00:37:28.206Z,1667954248.206 [MultiRay](INFO): Paused
2022-11-09T00:37:28.613Z,1667954248.613 [MultiRay](INFO): Paused
2022-11-09T00:37:29.021Z,1667954249.021 [MultiRay](INFO): Paused
2022-11-09T00:37:29.421Z,1667954249.421 [MultiRay](INFO): Paused
2022-11-09T00:37:29.884Z,1667954249.884 [MultiRay](INFO): Paused
2022-11-09T00:37:30.234Z,1667954250.234 [MultiRay](INFO): Paused
2022-11-09T00:37:30.649Z,1667954250.649 [MultiRay](INFO): Paused
2022-11-09T00:37:31.054Z,1667954251.054 [MultiRay](INFO): Paused
2022-11-09T00:37:31.445Z,1667954251.445 [MultiRay](INFO): Paused
2022-11-09T00:37:31.839Z,1667954251.839 [MultiRay](INFO): Paused
2022-11-09T00:37:32.253Z,1667954252.253 [MultiRay](INFO): Paused
2022-11-09T00:37:32.650Z,1667954252.650 [MultiRay](INFO): Paused
2022-11-09T00:37:33.054Z,1667954253.054 [MultiRay](INFO): Paused
2022-11-09T00:37:33.461Z,1667954253.461 [MultiRay](INFO): Paused
2022-11-09T00:37:33.865Z,1667954253.865 [MultiRay](INFO): Paused
2022-11-09T00:37:34.275Z,1667954254.275 [MultiRay](INFO): Paused
2022-11-09T00:37:34.669Z,1667954254.669 [MultiRay](INFO): Paused
2022-11-09T00:37:35.073Z,1667954255.073 [MultiRay](INFO): Paused
2022-11-09T00:37:35.481Z,1667954255.481 [MultiRay](INFO): Paused
2022-11-09T00:37:35.889Z,1667954255.889 [MultiRay](INFO): Paused
2022-11-09T00:37:36.285Z,1667954256.285 [MultiRay](INFO): Paused
2022-11-09T00:37:36.762Z,1667954256.762 [MultiRay](INFO): Paused
2022-11-09T00:37:37.103Z,1667954257.103 [MultiRay](INFO): Paused
2022-11-09T00:37:37.521Z,1667954257.521 [MultiRay](INFO): Paused
2022-11-09T00:37:37.922Z,1667954257.922 [MultiRay](INFO): Paused
2022-11-09T00:37:38.304Z,1667954258.304 [MultiRay](INFO): Paused
2022-11-09T00:37:38.713Z,1667954258.713 [MultiRay](INFO): Paused
2022-11-09T00:37:39.113Z,1667954259.113 [MultiRay](INFO): Paused
2022-11-09T00:37:39.515Z,1667954259.515 [MultiRay](INFO): Paused
2022-11-09T00:37:39.919Z,1667954259.919 [MultiRay](INFO): Paused
2022-11-09T00:37:40.334Z,1667954260.334 [MultiRay](INFO): Paused
2022-11-09T00:37:40.737Z,1667954260.737 [MultiRay](INFO): Paused
2022-11-09T00:37:41.141Z,1667954261.141 [MultiRay](INFO): Paused
2022-11-09T00:37:41.538Z,1667954261.538 [MultiRay](INFO): Paused
2022-11-09T00:37:41.945Z,1667954261.945 [MultiRay](INFO): Paused
2022-11-09T00:37:42.351Z,1667954262.351 [MultiRay](INFO): Paused
2022-11-09T00:37:42.757Z,1667954262.757 [MultiRay](INFO): Paused
2022-11-09T00:37:43.214Z,1667954263.214 [MultiRay](INFO): Paused
2022-11-09T00:37:43.223Z,1667954263.223 [Startup:StartupSatComms:A](INFO): Timed out from 2022-11-09T00:36:43.1Z
2022-11-09T00:37:43.223Z,1667954263.223 [Startup:StartupSatComms:A] Stopped
2022-11-09T00:37:43.223Z,1667954263.223 [Startup:StartupSatComms:B] Running Loop=1
2022-11-09T00:37:43.566Z,1667954263.566 [MultiRay](INFO): Paused
2022-11-09T00:37:43.575Z,1667954263.575 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-11-09T00:37:44.016Z,1667954264.016 [MultiRay](INFO): Paused
2022-11-09T00:37:44.397Z,1667954264.397 [MultiRay](INFO): Paused
2022-11-09T00:37:44.802Z,1667954264.802 [MultiRay](INFO): Paused
2022-11-09T00:37:44.965Z,1667954264.965 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-09T00:37:44.967Z,1667954264.967 [DAT](INFO): setting remote address to 1
2022-11-09T00:37:45.197Z,1667954265.197 [MultiRay](INFO): Paused
2022-11-09T00:37:45.218Z,1667954265.218 [DAT](INFO): set remote address to 1
2022-11-09T00:37:45.218Z,1667954265.218 [DAT](INFO): entering online mode
2022-11-09T00:37:45.471Z,1667954265.471 [DAT](INFO): commRate: 800
2022-11-09T00:37:45.471Z,1667954265.471 [DAT](INFO): online mode acknowledged
2022-11-09T00:37:45.471Z,1667954265.471 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-09T00:37:45.597Z,1667954265.597 [MultiRay](INFO): Paused
2022-11-09T00:37:46.005Z,1667954266.005 [MultiRay](INFO): Paused
2022-11-09T00:37:46.409Z,1667954266.409 [MultiRay](INFO): Paused
2022-11-09T00:37:46.812Z,1667954266.812 [MultiRay](INFO): Paused
2022-11-09T00:37:47.217Z,1667954267.217 [MultiRay](INFO): Paused
2022-11-09T00:37:47.629Z,1667954267.629 [MultiRay](INFO): Paused
2022-11-09T00:37:48.021Z,1667954268.021 [MultiRay](INFO): Paused
2022-11-09T00:37:48.425Z,1667954268.425 [MultiRay](INFO): Paused
2022-11-09T00:37:48.746Z,1667954268.746 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-09T00:37:48.839Z,1667954268.839 [MultiRay](INFO): Paused
2022-11-09T00:37:49.233Z,1667954269.233 [MultiRay](INFO): Paused
2022-11-09T00:37:49.742Z,1667954269.742 [MultiRay](INFO): Paused
2022-11-09T00:37:50.065Z,1667954270.065 [MultiRay](INFO): Paused
2022-11-09T00:37:50.461Z,1667954270.461 [MultiRay](INFO): Paused
2022-11-09T00:37:50.861Z,1667954270.861 [MultiRay](INFO): Paused
2022-11-09T00:37:51.262Z,1667954271.262 [MultiRay](INFO): Paused
2022-11-09T00:37:51.656Z,1667954271.656 [MultiRay](INFO): Paused
2022-11-09T00:37:51.948Z,1667954271.948 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20221109T002546/Courier0004.lzma
2022-11-09T00:37:52.071Z,1667954272.071 [MultiRay](INFO): Paused
2022-11-09T00:37:52.473Z,1667954272.473 [MultiRay](INFO): Paused
2022-11-09T00:37:52.867Z,1667954272.867 [MultiRay](INFO): Paused
2022-11-09T00:37:52.950Z,1667954272.950 [DataOverHttps](INFO): Moved sent file to Logs/20221109T002546/Courier0004.lzma.bak
2022-11-09T00:37:52.951Z,1667954272.951 [DataOverHttps](INFO): SBD MOMSN=17275876
2022-11-09T00:37:53.276Z,1667954273.276 [MultiRay](INFO): Paused
2022-11-09T00:37:53.691Z,1667954273.691 [MultiRay](INFO): Paused
2022-11-09T00:37:54.094Z,1667954274.094 [MultiRay](INFO): Paused
2022-11-09T00:37:54.485Z,1667954274.485 [MultiRay](INFO): Paused
2022-11-09T00:37:54.896Z,1667954274.896 [MultiRay](INFO): Paused
2022-11-09T00:37:55.293Z,1667954275.293 [MultiRay](INFO): Paused
2022-11-09T00:37:55.701Z,1667954275.701 [MultiRay](INFO): Paused
2022-11-09T00:37:56.133Z,1667954276.133 [MultiRay](INFO): Paused
2022-11-09T00:37:56.622Z,1667954276.622 [MultiRay](INFO): Paused
2022-11-09T00:37:56.917Z,1667954276.917 [MultiRay](INFO): Paused
2022-11-09T00:37:57.325Z,1667954277.325 [MultiRay](INFO): Paused
2022-11-09T00:37:57.729Z,1667954277.729 [MultiRay](INFO): Paused
2022-11-09T00:37:58.125Z,1667954278.125 [MultiRay](INFO): Paused
2022-11-09T00:37:58.525Z,1667954278.525 [MultiRay](INFO): Paused
2022-11-09T00:37:58.941Z,1667954278.941 [MultiRay](INFO): Paused
2022-11-09T00:37:59.341Z,1667954279.341 [MultiRay](INFO): Paused
2022-11-09T00:37:59.737Z,1667954279.737 [MultiRay](INFO): Paused
2022-11-09T00:38:00.143Z,1667954280.143 [MultiRay](INFO): Paused
2022-11-09T00:38:00.549Z,1667954280.549 [MultiRay](INFO): Paused
2022-11-09T00:38:00.952Z,1667954280.952 [MultiRay](INFO): Paused
2022-11-09T00:38:01.356Z,1667954281.356 [MultiRay](INFO): Paused
2022-11-09T00:38:01.761Z,1667954281.761 [MultiRay](INFO): Paused
2022-11-09T00:38:02.165Z,1667954282.165 [MultiRay](INFO): Paused
2022-11-09T00:38:02.568Z,1667954282.568 [MultiRay](INFO): Paused
2022-11-09T00:38:02.970Z,1667954282.970 [MultiRay](INFO): Paused
2022-11-09T00:38:03.441Z,1667954283.441 [MultiRay](INFO): Paused
2022-11-09T00:38:03.786Z,1667954283.786 [MultiRay](INFO): Paused
2022-11-09T00:38:03.873Z,1667954283.873 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-09T00:38:04.210Z,1667954284.210 [MultiRay](INFO): Paused
2022-11-09T00:38:04.637Z,1667954284.637 [MultiRay](INFO): Paused
2022-11-09T00:38:04.989Z,1667954284.989 [MultiRay](INFO): Paused
2022-11-09T00:38:05.161Z,1667954285.161 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-09T00:38:05.187Z,1667954285.187 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-09T00:38:05.397Z,1667954285.397 [MultiRay](INFO): Paused
2022-11-09T00:38:05.801Z,1667954285.801 [MultiRay](INFO): Paused
2022-11-09T00:38:06.209Z,1667954286.209 [MultiRay](INFO): Paused
2022-11-09T00:38:06.605Z,1667954286.605 [MultiRay](INFO): Paused
2022-11-09T00:38:07.021Z,1667954287.021 [MultiRay](INFO): Paused
2022-11-09T00:38:07.413Z,1667954287.413 [MultiRay](INFO): Paused
2022-11-09T00:38:07.842Z,1667954287.842 [MultiRay](INFO): Paused
2022-11-09T00:38:08.225Z,1667954288.225 [MultiRay](INFO): Paused
2022-11-09T00:38:08.438Z,1667954288.438 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-09T00:38:08.635Z,1667954288.635 [MultiRay](INFO): Paused
2022-11-09T00:38:09.040Z,1667954289.040 [MultiRay](INFO): Paused
2022-11-09T00:38:09.336Z,1667954289.336 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20221109T003348/Courier0000.lzma
2022-11-09T00:38:09.452Z,1667954289.452 [MultiRay](INFO): Paused
2022-11-09T00:38:09.907Z,1667954289.907 [MultiRay](INFO): Paused
2022-11-09T00:38:10.250Z,1667954290.250 [MultiRay](INFO): Paused
2022-11-09T00:38:10.339Z,1667954290.339 [DataOverHttps](INFO): Moved sent file to Logs/20221109T003348/Courier0000.lzma.bak
2022-11-09T00:38:10.339Z,1667954290.339 [DataOverHttps](INFO): SBD MOMSN=17275878
2022-11-09T00:38:10.660Z,1667954290.660 [MultiRay](INFO): Paused
2022-11-09T00:38:11.073Z,1667954291.073 [MultiRay](INFO): Paused
2022-11-09T00:38:11.453Z,1667954291.453 [MultiRay](INFO): Paused
2022-11-09T00:38:11.861Z,1667954291.861 [MultiRay](INFO): Paused
2022-11-09T00:38:12.269Z,1667954292.269 [MultiRay](INFO): Paused
2022-11-09T00:38:12.665Z,1667954292.665 [MultiRay](INFO): Paused
2022-11-09T00:38:13.070Z,1667954293.070 [MultiRay](INFO): Paused
2022-11-09T00:38:13.477Z,1667954293.477 [MultiRay](INFO): Paused
2022-11-09T00:38:13.884Z,1667954293.884 [MultiRay](INFO): Paused
2022-11-09T00:38:14.305Z,1667954294.305 [MultiRay](INFO): Paused
2022-11-09T00:38:14.685Z,1667954294.685 [MultiRay](INFO): Paused
2022-11-09T00:38:15.097Z,1667954295.097 [MultiRay](INFO): Paused
2022-11-09T00:38:15.498Z,1667954295.498 [MultiRay](INFO): Paused
2022-11-09T00:38:15.901Z,1667954295.901 [MultiRay](INFO): Paused
2022-11-09T00:38:16.306Z,1667954296.306 [MultiRay](INFO): Paused
2022-11-09T00:38:16.705Z,1667954296.705 [MultiRay](INFO): Paused
2022-11-09T00:38:17.221Z,1667954297.221 [MultiRay](INFO): Paused
2022-11-09T00:38:17.525Z,1667954297.525 [MultiRay](INFO): Paused
2022-11-09T00:38:17.937Z,1667954297.937 [MultiRay](INFO): Paused
2022-11-09T00:38:18.338Z,1667954298.338 [MultiRay](INFO): Paused
2022-11-09T00:38:18.730Z,1667954298.730 [MultiRay](INFO): Paused
2022-11-09T00:38:19.129Z,1667954299.129 [MultiRay](INFO): Paused
2022-11-09T00:38:19.558Z,1667954299.558 [MultiRay](INFO): Paused
2022-11-09T00:38:19.938Z,1667954299.938 [MultiRay](INFO): Paused
2022-11-09T00:38:20.345Z,1667954300.345 [MultiRay](INFO): Paused
2022-11-09T00:38:20.748Z,1667954300.748 [MultiRay](INFO): Paused
2022-11-09T00:38:21.146Z,1667954301.146 [MultiRay](INFO): Paused
2022-11-09T00:38:21.555Z,1667954301.555 [MultiRay](INFO): Paused
2022-11-09T00:38:21.959Z,1667954301.959 [MultiRay](INFO): Paused
2022-11-09T00:38:22.365Z,1667954302.365 [MultiRay](INFO): Paused
2022-11-09T00:38:22.775Z,1667954302.775 [MultiRay](INFO): Paused
2022-11-09T00:38:23.169Z,1667954303.169 [MultiRay](INFO): Paused
2022-11-09T00:38:23.691Z,1667954303.691 [MultiRay](INFO): Paused
2022-11-09T00:38:23.994Z,1667954303.994 [MultiRay](INFO): Paused
2022-11-09T00:38:24.400Z,1667954304.400 [MultiRay](INFO): Paused
2022-11-09T00:38:24.797Z,1667954304.797 [MultiRay](INFO): Paused
2022-11-09T00:38:25.193Z,1667954305.193 [MultiRay](INFO): Paused
2022-11-09T00:38:25.585Z,1667954305.585 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-09T00:38:25.609Z,1667954305.609 [MultiRay](INFO): Paused
2022-11-09T00:38:26.012Z,1667954306.012 [MultiRay](INFO): Paused
2022-11-09T00:38:26.401Z,1667954306.401 [MultiRay](INFO): Paused
2022-11-09T00:38:26.805Z,1667954306.805 [MultiRay](INFO): Paused
2022-11-09T00:38:27.183Z,1667954307.183 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-09T00:38:27.214Z,1667954307.214 [MultiRay](INFO): Paused
2022-11-09T00:38:27.249Z,1667954307.249 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-09T00:38:27.251Z,1667954307.251 [Startup:StartupSatComms:B] Stopped
2022-11-09T00:38:27.252Z,1667954307.252 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-11-09T00:38:27.252Z,1667954307.252 [Startup:StartupSatComms] Stopped
2022-11-09T00:38:27.252Z,1667954307.252 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-11-09T00:38:27.285Z,1667954307.285 [Startup](INFO): Completed Startup
2022-11-09T00:38:27.285Z,1667954307.285 [MissionManager](INFO): Startup is completed.
2022-11-09T00:38:27.285Z,1667954307.285 [MissionManager](INFO): Uninitializing Mission Startup
2022-11-09T00:38:27.285Z,1667954307.285 [Startup] Stopped
2022-11-09T00:38:27.285Z,1667954307.285 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-11-09T00:38:27.285Z,1667954307.285 [Startup:A.GoToSurface] Stopped
2022-11-09T00:38:27.285Z,1667954307.285 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-09T00:38:27.613Z,1667954307.613 [MultiRay](INFO): Paused
2022-11-09T00:38:27.646Z,1667954307.646 [MissionManager](IMPORTANT): Started mission Default
2022-11-09T00:38:27.646Z,1667954307.646 [Default] Running Loop=1
2022-11-09T00:38:27.647Z,1667954307.647 [Default](DEBUG): Aggregate::initialize Default
2022-11-09T00:38:27.647Z,1667954307.647 [Default:B.GoToSurface] Running Loop=1
2022-11-09T00:38:27.647Z,1667954307.647 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-09T00:38:27.647Z,1667954307.647 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-09T00:38:27.647Z,1667954307.647 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-09T00:38:27.648Z,1667954307.648 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-09T00:38:27.648Z,1667954307.648 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-09T00:38:27.648Z,1667954307.648 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-09T00:38:27.649Z,1667954307.649 [Default:A.Wait] Running Loop=1
2022-11-09T00:38:27.649Z,1667954307.649 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:38:28.025Z,1667954308.025 [MultiRay](INFO): Paused
2022-11-09T00:38:28.422Z,1667954308.422 [MultiRay](INFO): Paused
2022-11-09T00:38:28.587Z,1667954308.587 [DataOverHttps](INFO): Sending 788 bytes from file Logs/20221109T001543/Express0005.lzma
2022-11-09T00:38:28.826Z,1667954308.826 [MultiRay](INFO): Paused
2022-11-09T00:38:29.243Z,1667954309.243 [MultiRay](INFO): Paused
2022-11-09T00:38:29.554Z,1667954309.554 [DataOverHttps](INFO): Moved sent file to Logs/20221109T001543/Express0005.lzma.bak
2022-11-09T00:38:29.554Z,1667954309.554 [DataOverHttps](INFO): SBD MOMSN=17275886
2022-11-09T00:38:29.634Z,1667954309.634 [MultiRay](INFO): Paused
2022-11-09T00:38:30.047Z,1667954310.047 [MultiRay](INFO): Paused
2022-11-09T00:38:30.441Z,1667954310.441 [MultiRay](INFO): Paused
2022-11-09T00:38:30.502Z,1667954310.502 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-09T00:38:30.908Z,1667954310.908 [MultiRay](INFO): Paused
2022-11-09T00:38:31.261Z,1667954311.261 [MultiRay](INFO): Paused
2022-11-09T00:38:31.673Z,1667954311.673 [MultiRay](INFO): Paused
2022-11-09T00:38:32.058Z,1667954312.058 [MultiRay](INFO): Paused
2022-11-09T00:38:32.477Z,1667954312.477 [MultiRay](INFO): Paused
2022-11-09T00:38:32.877Z,1667954312.877 [MultiRay](INFO): Paused
2022-11-09T00:38:33.269Z,1667954313.269 [MultiRay](INFO): Paused
2022-11-09T00:38:33.677Z,1667954313.677 [MultiRay](INFO): Paused
2022-11-09T00:38:34.087Z,1667954314.087 [MultiRay](INFO): Paused
2022-11-09T00:38:34.485Z,1667954314.485 [MultiRay](INFO): Paused
2022-11-09T00:38:34.893Z,1667954314.893 [MultiRay](INFO): Paused
2022-11-09T00:38:35.312Z,1667954315.312 [MultiRay](INFO): Paused
2022-11-09T00:38:35.697Z,1667954315.697 [MultiRay](INFO): Paused
2022-11-09T00:38:36.104Z,1667954316.104 [MultiRay](INFO): Paused
2022-11-09T00:38:36.504Z,1667954316.504 [MultiRay](INFO): Paused
2022-11-09T00:38:36.982Z,1667954316.982 [MultiRay](INFO): Paused
2022-11-09T00:38:37.318Z,1667954317.318 [MultiRay](INFO): Paused
2022-11-09T00:38:37.729Z,1667954317.729 [MultiRay](INFO): Paused
2022-11-09T00:38:38.130Z,1667954318.130 [MultiRay](INFO): Paused
2022-11-09T00:38:38.525Z,1667954318.525 [MultiRay](INFO): Paused
2022-11-09T00:38:38.922Z,1667954318.922 [MultiRay](INFO): Paused
2022-11-09T00:38:39.344Z,1667954319.344 [MultiRay](INFO): Paused
2022-11-09T00:38:39.745Z,1667954319.745 [MultiRay](INFO): Paused
2022-11-09T00:38:40.142Z,1667954320.142 [MultiRay](INFO): Paused
2022-11-09T00:38:40.546Z,1667954320.546 [MultiRay](INFO): Paused
2022-11-09T00:38:40.960Z,1667954320.960 [MultiRay](INFO): Paused
2022-11-09T00:38:40.969Z,1667954320.969 [Default:A.Wait](INFO): Done Waiting.
2022-11-09T00:38:40.970Z,1667954320.970 [Default:A.Wait] Stopped
2022-11-09T00:38:40.970Z,1667954320.970 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:38:41.361Z,1667954321.361 [MultiRay](INFO): Paused
2022-11-09T00:38:41.397Z,1667954321.397 [Default:CheckIn] Running Loop=1
2022-11-09T00:38:41.397Z,1667954321.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-09T00:38:41.397Z,1667954321.397 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-09T00:38:41.761Z,1667954321.761 [MultiRay](INFO): Paused
2022-11-09T00:38:41.806Z,1667954321.806 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-11-09T00:38:42.165Z,1667954322.165 [MultiRay](INFO): Paused
2022-11-09T00:38:42.566Z,1667954322.566 [MultiRay](INFO): Paused
2022-11-09T00:38:42.970Z,1667954322.970 [MultiRay](INFO): Paused
2022-11-09T00:38:43.374Z,1667954323.374 [MultiRay](INFO): Paused
2022-11-09T00:38:43.778Z,1667954323.778 [MultiRay](INFO): Paused
2022-11-09T00:38:44.274Z,1667954324.274 [MultiRay](INFO): Paused
2022-11-09T00:38:44.594Z,1667954324.594 [MultiRay](INFO): Paused
2022-11-09T00:38:45.009Z,1667954325.009 [MultiRay](INFO): Paused
2022-11-09T00:38:45.405Z,1667954325.405 [MultiRay](INFO): Paused
2022-11-09T00:38:45.807Z,1667954325.807 [MultiRay](INFO): Paused
2022-11-09T00:38:46.205Z,1667954326.205 [MultiRay](INFO): Paused
2022-11-09T00:38:46.609Z,1667954326.609 [MultiRay](INFO): Paused
2022-11-09T00:38:47.007Z,1667954327.007 [MultiRay](INFO): Paused
2022-11-09T00:38:47.426Z,1667954327.426 [MultiRay](INFO): Paused
2022-11-09T00:38:47.669Z,1667954327.669 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-09T00:38:47.818Z,1667954327.818 [MultiRay](INFO): Paused
2022-11-09T00:38:47.921Z,1667954327.921 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-09T00:38:48.173Z,1667954328.173 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-09T00:38:48.221Z,1667954328.221 [MultiRay](INFO): Paused
2022-11-09T00:38:48.629Z,1667954328.629 [MultiRay](INFO): Paused
2022-11-09T00:38:49.039Z,1667954329.039 [MultiRay](INFO): Paused
2022-11-09T00:38:49.434Z,1667954329.434 [MultiRay](INFO): Paused
2022-11-09T00:38:49.835Z,1667954329.835 [MultiRay](INFO): Paused
2022-11-09T00:38:50.305Z,1667954330.305 [MultiRay](INFO): Paused
2022-11-09T00:38:50.666Z,1667954330.666 [MultiRay](INFO): Paused
2022-11-09T00:38:51.065Z,1667954331.065 [MultiRay](INFO): Paused
2022-11-09T00:38:51.450Z,1667954331.450 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-09T00:38:51.473Z,1667954331.473 [MultiRay](INFO): Paused
2022-11-09T00:38:51.861Z,1667954331.861 [MultiRay](INFO): Paused
2022-11-09T00:38:52.262Z,1667954332.262 [MultiRay](INFO): Paused
2022-11-09T00:38:52.666Z,1667954332.666 [MultiRay](INFO): Paused
2022-11-09T00:38:53.071Z,1667954333.071 [MultiRay](INFO): Paused
2022-11-09T00:38:53.471Z,1667954333.471 [MultiRay](INFO): Paused
2022-11-09T00:38:53.883Z,1667954333.883 [MultiRay](INFO): Paused
2022-11-09T00:38:54.284Z,1667954334.284 [MultiRay](INFO): Paused
2022-11-09T00:38:54.689Z,1667954334.689 [MultiRay](INFO): Paused
2022-11-09T00:38:55.091Z,1667954335.091 [MultiRay](INFO): Paused
2022-11-09T00:38:55.499Z,1667954335.499 [MultiRay](INFO): Paused
2022-11-09T00:38:55.901Z,1667954335.901 [MultiRay](INFO): Paused
2022-11-09T00:38:56.302Z,1667954336.302 [MultiRay](INFO): Paused
2022-11-09T00:38:56.706Z,1667954336.706 [MultiRay](INFO): Paused
2022-11-09T00:38:57.178Z,1667954337.178 [MultiRay](INFO): Paused
2022-11-09T00:38:57.553Z,1667954337.553 [MultiRay](INFO): Paused
2022-11-09T00:38:57.934Z,1667954337.934 [MultiRay](INFO): Paused
2022-11-09T00:38:58.348Z,1667954338.348 [MultiRay](INFO): Paused
2022-11-09T00:38:58.725Z,1667954338.725 [MultiRay](INFO): Paused
2022-11-09T00:38:59.141Z,1667954339.141 [MultiRay](INFO): Paused
2022-11-09T00:38:59.539Z,1667954339.539 [MultiRay](INFO): Paused
2022-11-09T00:38:59.945Z,1667954339.945 [MultiRay](INFO): Paused
2022-11-09T00:39:00.344Z,1667954340.344 [MultiRay](INFO): Paused
2022-11-09T00:39:00.749Z,1667954340.749 [MultiRay](INFO): Paused
2022-11-09T00:39:01.150Z,1667954341.150 [MultiRay](INFO): Paused
2022-11-09T00:39:01.557Z,1667954341.557 [MultiRay](INFO): Paused
2022-11-09T00:39:01.960Z,1667954341.960 [MultiRay](INFO): Paused
2022-11-09T00:39:02.365Z,1667954342.365 [MultiRay](INFO): Paused
2022-11-09T00:39:02.766Z,1667954342.766 [MultiRay](INFO): Paused
2022-11-09T00:39:03.173Z,1667954343.173 [MultiRay](INFO): Paused
2022-11-09T00:39:03.637Z,1667954343.637 [MultiRay](INFO): Paused
2022-11-09T00:39:04.003Z,1667954344.003 [MultiRay](INFO): Paused
2022-11-09T00:39:04.397Z,1667954344.397 [MultiRay](INFO): Paused
2022-11-09T00:39:04.797Z,1667954344.797 [MultiRay](INFO): Paused
2022-11-09T00:39:05.199Z,1667954345.199 [MultiRay](INFO): Paused
2022-11-09T00:39:05.594Z,1667954345.594 [MultiRay](INFO): Paused
2022-11-09T00:39:05.999Z,1667954345.999 [MultiRay](INFO): Paused
2022-11-09T00:39:06.409Z,1667954346.409 [MultiRay](INFO): Paused
2022-11-09T00:39:06.809Z,1667954346.809 [MultiRay](INFO): Paused
2022-11-09T00:39:07.156Z,1667954347.156 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_test.xml
2022-11-09T00:39:07.157Z,1667954347.157 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_test.xml
2022-11-09T00:39:07.213Z,1667954347.213 [MultiRay](INFO): Paused
2022-11-09T00:39:07.278Z,1667954347.278 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min
2022-11-09T00:39:07.281Z,1667954347.281 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s
2022-11-09T00:39:07.284Z,1667954347.284 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count
2022-11-09T00:39:07.295Z,1667954347.295 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count
2022-11-09T00:39:07.298Z,1667954347.298 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count
2022-11-09T00:39:07.303Z,1667954347.303 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool
2022-11-09T00:39:07.321Z,1667954347.321 [multiray_test:C.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.324Z,1667954347.324 [multiray_test:E.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.331Z,1667954347.331 [multiray_test:G.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.339Z,1667954347.339 [multiray_test:I.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.343Z,1667954347.343 [multiray_test:K.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.355Z,1667954347.355 [multiray_test:M.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.378Z,1667954347.378 [multiray_test:O.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.382Z,1667954347.382 [multiray_test:Q.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.397Z,1667954347.397 [multiray_test:R.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.405Z,1667954347.405 [multiray_test:T.Wait](DEBUG): Construct Wait.
2022-11-09T00:39:07.415Z,1667954347.415 [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_test">

    <Description>
        This mission is designed to run in the lab only. Cycles through MultiRay light modes
    </Description>

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

    <DefineArg Name="LightDuration"><Description>
        How long to wait before changing light modes
    </Description><Units:second/><Value>5</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>

    <DefineArg Name="EnabledMultiray"><Description>
        Automatically set to true if the Multiray is enabled.
    </Description><Science:MultiRay.loadAtStartup/></DefineArg>

    <Timeout Duration="MissionTimeout"/>



    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledMultiray"/>
        </While>
    <Sensor:MultiRay.lightModeLog/>
    </ReadData>


    <!-- <ReadDatum>
            <Sensor:MultiRay.lightModeLog/>
    </ReadDatum> -->

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode0"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode1"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode2"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode0"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>
    
    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode1"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode2"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode0"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode1"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode2"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode0"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

</Mission>


2022-11-09T00:39:07.421Z,1667954347.421 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_test.xml
2022-11-09T00:39:07.649Z,1667954347.649 [MultiRay](INFO): Paused
2022-11-09T00:39:07.671Z,1667954347.671 [Default] Stopped
2022-11-09T00:39:07.671Z,1667954347.671 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-09T00:39:07.671Z,1667954347.671 [Default:B.GoToSurface] Stopped
2022-11-09T00:39:07.671Z,1667954347.671 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-09T00:39:07.671Z,1667954347.671 [Default:CheckIn] Stopped
2022-11-09T00:39:07.671Z,1667954347.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-09T00:39:07.672Z,1667954347.672 [Default:CheckIn:Read_GPS] Stopped
2022-11-09T00:39:07.672Z,1667954347.672 [MissionManager](IMPORTANT): Started mission multiray_test
2022-11-09T00:39:07.672Z,1667954347.672 [multiray_test] Running Loop=1
2022-11-09T00:39:07.672Z,1667954347.672 [multiray_test](DEBUG): Aggregate::initialize multiray_test
2022-11-09T00:39:07.672Z,1667954347.672 [multiray_test:A] Running Loop=1
2022-11-09T00:39:07.673Z,1667954347.673 [multiray_test:B] Running Loop=1
2022-11-09T00:39:07.673Z,1667954347.673 [multiray_test:B] Stopped
2022-11-09T00:39:07.673Z,1667954347.673 [multiray_test:C.Wait] Running Loop=1
2022-11-09T00:39:07.673Z,1667954347.673 [multiray_test:C.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:07.673Z,1667954347.673 [multiray_test:A] Running Loop=1
2022-11-09T00:39:07.674Z,1667954347.674 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2022-11-09T00:39:08.029Z,1667954348.029 [MultiRay](INFO): Paused
2022-11-09T00:39:08.425Z,1667954348.425 [MultiRay](INFO): Resume
2022-11-09T00:39:08.489Z,1667954348.489 [MultiRay](INFO): Finish turning things back on in Resume
2022-11-09T00:39:08.625Z,1667954348.625 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2022-11-09T00:39:08.625Z,1667954348.625 [DAT](ERROR): Ack receipt timeout failure.
2022-11-09T00:39:08.829Z,1667954348.829 [MultiRay](INFO): Runnable
2022-11-09T00:39:08.830Z,1667954348.830 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:08.830Z,1667954348.830 [MultiRay](IMPORTANT): Turning off all lights
2022-11-09T00:39:08.848Z,1667954348.848 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:08.869Z,1667954348.869 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:08.877Z,1667954348.877 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER
2022-11-09T00:39:08.880Z,1667954348.880 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:08.893Z,1667954348.893 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:08.905Z,1667954348.905 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:08.917Z,1667954348.917 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:08.918Z,1667954348.918 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:39:09.129Z,1667954349.129 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-09T00:39:09.237Z,1667954349.237 [MultiRay](INFO): Pause
2022-11-09T00:39:09.381Z,1667954349.381 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-09T00:39:09.649Z,1667954349.649 [MultiRay](INFO): Paused
2022-11-09T00:39:10.060Z,1667954350.060 [MultiRay](INFO): Paused
2022-11-09T00:39:10.546Z,1667954350.546 [MultiRay](INFO): Paused
2022-11-09T00:39:10.874Z,1667954350.874 [MultiRay](INFO): Paused
2022-11-09T00:39:11.261Z,1667954351.261 [MultiRay](INFO): Paused
2022-11-09T00:39:11.665Z,1667954351.665 [MultiRay](INFO): Paused
2022-11-09T00:39:12.065Z,1667954352.065 [MultiRay](INFO): Paused
2022-11-09T00:39:12.468Z,1667954352.467 [MultiRay](INFO): Paused
2022-11-09T00:39:12.658Z,1667954352.658 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-09T00:39:12.866Z,1667954352.866 [MultiRay](INFO): Paused
2022-11-09T00:39:13.273Z,1667954353.273 [MultiRay](INFO): Paused
2022-11-09T00:39:13.318Z,1667954353.318 [multiray_test:C.Wait](INFO): Done Waiting.
2022-11-09T00:39:13.318Z,1667954353.318 [multiray_test:C.Wait] Stopped
2022-11-09T00:39:13.318Z,1667954353.318 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:39:13.318Z,1667954353.318 [multiray_test:D] Running Loop=1
2022-11-09T00:39:13.677Z,1667954353.677 [MultiRay](INFO): Paused
2022-11-09T00:39:13.707Z,1667954353.707 [multiray_test:D] Stopped
2022-11-09T00:39:13.707Z,1667954353.707 [multiray_test:E.Wait] Running Loop=1
2022-11-09T00:39:13.707Z,1667954353.707 [multiray_test:E.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:14.085Z,1667954354.085 [MultiRay](INFO): Paused
2022-11-09T00:39:14.485Z,1667954354.485 [MultiRay](INFO): Resume
2022-11-09T00:39:14.516Z,1667954354.516 [MultiRay](INFO): Finish turning things back on in Resume
2022-11-09T00:39:14.889Z,1667954354.889 [MultiRay](INFO): Runnable
2022-11-09T00:39:14.889Z,1667954354.889 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:14.890Z,1667954354.890 [MultiRay](IMPORTANT): Turning on red lights
2022-11-09T00:39:14.941Z,1667954354.941 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:14.953Z,1667954354.953 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:14.982Z,1667954354.982 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:14.993Z,1667954354.993 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:15.022Z,1667954355.022 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:15.037Z,1667954355.037 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:15.038Z,1667954355.038 [MultiRay](INFO): Red lights on
2022-11-09T00:39:15.291Z,1667954355.291 [MultiRay](INFO): Runnable
2022-11-09T00:39:15.694Z,1667954355.694 [MultiRay](INFO): Runnable
2022-11-09T00:39:16.105Z,1667954356.105 [MultiRay](INFO): Runnable
2022-11-09T00:39:16.501Z,1667954356.501 [MultiRay](INFO): Runnable
2022-11-09T00:39:16.906Z,1667954356.906 [MultiRay](INFO): Runnable
2022-11-09T00:39:17.371Z,1667954357.371 [MultiRay](INFO): Runnable
2022-11-09T00:39:17.723Z,1667954357.723 [MultiRay](INFO): Runnable
2022-11-09T00:39:18.137Z,1667954358.137 [MultiRay](INFO): Runnable
2022-11-09T00:39:18.537Z,1667954358.537 [MultiRay](INFO): Runnable
2022-11-09T00:39:18.926Z,1667954358.926 [MultiRay](INFO): Runnable
2022-11-09T00:39:19.333Z,1667954359.333 [MultiRay](INFO): Runnable
2022-11-09T00:39:19.362Z,1667954359.362 [multiray_test:E.Wait](INFO): Done Waiting.
2022-11-09T00:39:19.363Z,1667954359.363 [multiray_test:E.Wait] Stopped
2022-11-09T00:39:19.363Z,1667954359.363 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:39:19.363Z,1667954359.363 [multiray_test:F] Running Loop=1
2022-11-09T00:39:19.743Z,1667954359.743 [MultiRay](INFO): Runnable
2022-11-09T00:39:19.753Z,1667954359.753 [multiray_test:F] Stopped
2022-11-09T00:39:19.753Z,1667954359.753 [multiray_test:G.Wait] Running Loop=1
2022-11-09T00:39:19.753Z,1667954359.753 [multiray_test:G.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:20.138Z,1667954360.138 [MultiRay](INFO): Runnable
2022-11-09T00:39:20.138Z,1667954360.138 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:20.138Z,1667954360.138 [MultiRay](IMPORTANT): Turning on white lights
2022-11-09T00:39:20.186Z,1667954360.186 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:20.202Z,1667954360.202 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:20.230Z,1667954360.230 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:20.246Z,1667954360.246 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:20.274Z,1667954360.274 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:20.291Z,1667954360.291 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:20.292Z,1667954360.292 [MultiRay](INFO): White lights on
2022-11-09T00:39:20.539Z,1667954360.539 [MultiRay](INFO): Runnable
2022-11-09T00:39:20.946Z,1667954360.946 [MultiRay](INFO): Runnable
2022-11-09T00:39:21.357Z,1667954361.357 [MultiRay](INFO): Runnable
2022-11-09T00:39:21.757Z,1667954361.757 [MultiRay](INFO): Runnable
2022-11-09T00:39:22.162Z,1667954362.162 [MultiRay](INFO): Runnable
2022-11-09T00:39:22.562Z,1667954362.562 [MultiRay](INFO): Runnable
2022-11-09T00:39:22.969Z,1667954362.969 [MultiRay](INFO): Runnable
2022-11-09T00:39:23.383Z,1667954363.383 [MultiRay](INFO): Runnable
2022-11-09T00:39:23.839Z,1667954363.839 [MultiRay](INFO): Runnable
2022-11-09T00:39:24.186Z,1667954364.186 [MultiRay](INFO): Runnable
2022-11-09T00:39:24.601Z,1667954364.601 [MultiRay](INFO): Runnable
2022-11-09T00:39:25.001Z,1667954365.001 [MultiRay](INFO): Runnable
2022-11-09T00:39:25.390Z,1667954365.390 [MultiRay](INFO): Runnable
2022-11-09T00:39:25.398Z,1667954365.398 [multiray_test:G.Wait](INFO): Done Waiting.
2022-11-09T00:39:25.399Z,1667954365.399 [multiray_test:G.Wait] Stopped
2022-11-09T00:39:25.399Z,1667954365.399 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:39:25.399Z,1667954365.399 [multiray_test:H] Running Loop=1
2022-11-09T00:39:25.801Z,1667954365.801 [MultiRay](INFO): Runnable
2022-11-09T00:39:25.811Z,1667954365.811 [multiray_test:H] Stopped
2022-11-09T00:39:25.811Z,1667954365.811 [multiray_test:I.Wait] Running Loop=1
2022-11-09T00:39:25.811Z,1667954365.811 [multiray_test:I.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:26.201Z,1667954366.201 [MultiRay](INFO): Runnable
2022-11-09T00:39:26.202Z,1667954366.202 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:26.202Z,1667954366.202 [MultiRay](IMPORTANT): Turning off all lights
2022-11-09T00:39:26.232Z,1667954366.232 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:26.247Z,1667954366.247 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:26.260Z,1667954366.260 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:26.273Z,1667954366.273 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:26.286Z,1667954366.286 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:26.300Z,1667954366.300 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:26.301Z,1667954366.301 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:39:26.609Z,1667954366.609 [MultiRay](INFO): Pause
2022-11-09T00:39:27.004Z,1667954367.004 [MultiRay](INFO): Paused
2022-11-09T00:39:27.420Z,1667954367.420 [MultiRay](INFO): Paused
2022-11-09T00:39:27.814Z,1667954367.814 [MultiRay](INFO): Paused
2022-11-09T00:39:28.215Z,1667954368.215 [MultiRay](INFO): Paused
2022-11-09T00:39:28.626Z,1667954368.626 [MultiRay](INFO): Paused
2022-11-09T00:39:29.026Z,1667954369.026 [MultiRay](INFO): Paused
2022-11-09T00:39:29.433Z,1667954369.433 [MultiRay](INFO): Paused
2022-11-09T00:39:29.813Z,1667954369.813 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-09T00:39:29.835Z,1667954369.835 [MultiRay](INFO): Paused
2022-11-09T00:39:30.065Z,1667954370.065 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-09T00:39:30.236Z,1667954370.236 [MultiRay](INFO): Paused
2022-11-09T00:39:30.317Z,1667954370.317 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-09T00:39:30.702Z,1667954370.702 [MultiRay](INFO): Paused
2022-11-09T00:39:31.054Z,1667954371.054 [MultiRay](INFO): Paused
2022-11-09T00:39:31.473Z,1667954371.473 [MultiRay](INFO): Paused
2022-11-09T00:39:31.482Z,1667954371.482 [multiray_test:I.Wait](INFO): Done Waiting.
2022-11-09T00:39:31.482Z,1667954371.482 [multiray_test:I.Wait] Stopped
2022-11-09T00:39:31.482Z,1667954371.482 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:39:31.482Z,1667954371.482 [multiray_test:J] Running Loop=1
2022-11-09T00:39:31.871Z,1667954371.871 [MultiRay](INFO): Paused
2022-11-09T00:39:31.880Z,1667954371.880 [multiray_test:J] Stopped
2022-11-09T00:39:31.880Z,1667954371.880 [multiray_test:K.Wait] Running Loop=1
2022-11-09T00:39:31.880Z,1667954371.880 [multiray_test:K.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:32.266Z,1667954372.266 [MultiRay](INFO): Paused
2022-11-09T00:39:32.665Z,1667954372.665 [MultiRay](INFO): Resume
2022-11-09T00:39:32.692Z,1667954372.692 [MultiRay](INFO): Finish turning things back on in Resume
2022-11-09T00:39:33.072Z,1667954373.072 [MultiRay](INFO): Runnable
2022-11-09T00:39:33.072Z,1667954373.072 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:33.073Z,1667954373.073 [MultiRay](IMPORTANT): Turning on red lights
2022-11-09T00:39:33.100Z,1667954373.100 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:33.142Z,1667954373.142 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:33.157Z,1667954373.157 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:33.186Z,1667954373.186 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:33.218Z,1667954373.218 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:33.233Z,1667954373.233 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:33.234Z,1667954373.234 [MultiRay](INFO): Red lights on
2022-11-09T00:39:33.470Z,1667954373.470 [MultiRay](INFO): Runnable
2022-11-09T00:39:33.606Z,1667954373.606 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-09T00:39:33.881Z,1667954373.881 [MultiRay](INFO): Runnable
2022-11-09T00:39:34.302Z,1667954374.302 [MultiRay](INFO): Runnable
2022-11-09T00:39:34.682Z,1667954374.682 [MultiRay](INFO): Runnable
2022-11-09T00:39:35.087Z,1667954375.087 [MultiRay](INFO): Runnable
2022-11-09T00:39:35.492Z,1667954375.492 [MultiRay](INFO): Runnable
2022-11-09T00:39:35.897Z,1667954375.897 [MultiRay](INFO): Runnable
2022-11-09T00:39:36.298Z,1667954376.298 [MultiRay](INFO): Runnable
2022-11-09T00:39:36.702Z,1667954376.702 [MultiRay](INFO): Runnable
2022-11-09T00:39:37.176Z,1667954377.176 [MultiRay](INFO): Runnable
2022-11-09T00:39:37.526Z,1667954377.526 [MultiRay](INFO): Runnable
2022-11-09T00:39:37.547Z,1667954377.547 [multiray_test:K.Wait](INFO): Done Waiting.
2022-11-09T00:39:37.547Z,1667954377.547 [multiray_test:K.Wait] Stopped
2022-11-09T00:39:37.547Z,1667954377.547 [multiray_test:K.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:39:37.548Z,1667954377.548 [multiray_test:L] Running Loop=1
2022-11-09T00:39:37.942Z,1667954377.942 [MultiRay](INFO): Runnable
2022-11-09T00:39:37.951Z,1667954377.951 [multiray_test:L] Stopped
2022-11-09T00:39:37.951Z,1667954377.951 [multiray_test:M.Wait] Running Loop=1
2022-11-09T00:39:37.951Z,1667954377.951 [multiray_test:M.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:38.342Z,1667954378.342 [MultiRay](INFO): Runnable
2022-11-09T00:39:38.343Z,1667954378.343 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:38.343Z,1667954378.343 [MultiRay](IMPORTANT): Turning on white lights
2022-11-09T00:39:38.368Z,1667954378.368 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:38.412Z,1667954378.412 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:38.441Z,1667954378.441 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:38.471Z,1667954378.471 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:38.485Z,1667954378.485 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:38.514Z,1667954378.514 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:38.514Z,1667954378.514 [MultiRay](INFO): White lights on
2022-11-09T00:39:38.726Z,1667954378.726 [MultiRay](INFO): Runnable
2022-11-09T00:39:39.124Z,1667954379.124 [MultiRay](INFO): Runnable
2022-11-09T00:39:39.541Z,1667954379.541 [MultiRay](INFO): Runnable
2022-11-09T00:39:39.937Z,1667954379.937 [MultiRay](INFO): Runnable
2022-11-09T00:39:40.345Z,1667954380.345 [MultiRay](INFO): Runnable
2022-11-09T00:39:40.750Z,1667954380.750 [MultiRay](INFO): Runnable
2022-11-09T00:39:41.146Z,1667954381.146 [MultiRay](INFO): Runnable
2022-11-09T00:39:41.553Z,1667954381.553 [MultiRay](INFO): Runnable
2022-11-09T00:39:41.950Z,1667954381.950 [MultiRay](INFO): Runnable
2022-11-09T00:39:42.360Z,1667954382.360 [MultiRay](INFO): Runnable
2022-11-09T00:39:42.361Z,1667954382.361 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-09T00:39:42.775Z,1667954382.775 [MultiRay](INFO): Runnable
2022-11-09T00:39:43.166Z,1667954383.166 [MultiRay](INFO): Runnable
2022-11-09T00:39:43.570Z,1667954383.570 [MultiRay](INFO): Runnable
2022-11-09T00:39:43.579Z,1667954383.579 [multiray_test:M.Wait](INFO): Done Waiting.
2022-11-09T00:39:43.579Z,1667954383.579 [multiray_test:M.Wait] Stopped
2022-11-09T00:39:43.579Z,1667954383.579 [multiray_test:M.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:39:43.579Z,1667954383.579 [multiray_test:N] Running Loop=1
2022-11-09T00:39:44.120Z,1667954384.120 [MultiRay](INFO): Runnable
2022-11-09T00:39:44.163Z,1667954384.163 [multiray_test:N] Stopped
2022-11-09T00:39:44.163Z,1667954384.163 [multiray_test:O.Wait] Running Loop=1
2022-11-09T00:39:44.163Z,1667954384.163 [multiray_test:O.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:44.405Z,1667954384.405 [MultiRay](INFO): Runnable
2022-11-09T00:39:44.406Z,1667954384.406 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:44.406Z,1667954384.406 [MultiRay](IMPORTANT): Turning off all lights
2022-11-09T00:39:44.420Z,1667954384.420 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:44.434Z,1667954384.434 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:44.450Z,1667954384.450 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:44.464Z,1667954384.464 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:44.477Z,1667954384.477 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:44.489Z,1667954384.489 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:44.489Z,1667954384.489 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:39:44.793Z,1667954384.793 [MultiRay](INFO): Pause
2022-11-09T00:39:45.203Z,1667954385.203 [MultiRay](INFO): Paused
2022-11-09T00:39:45.593Z,1667954385.593 [MultiRay](INFO): Paused
2022-11-09T00:39:45.993Z,1667954385.993 [MultiRay](INFO): Paused
2022-11-09T00:39:46.417Z,1667954386.417 [MultiRay](INFO): Paused
2022-11-09T00:39:46.801Z,1667954386.801 [MultiRay](INFO): Paused
2022-11-09T00:39:47.205Z,1667954387.205 [MultiRay](INFO): Paused
2022-11-09T00:39:47.617Z,1667954387.617 [MultiRay](INFO): Paused
2022-11-09T00:39:48.025Z,1667954388.025 [MultiRay](INFO): Paused
2022-11-09T00:39:48.421Z,1667954388.421 [MultiRay](INFO): Paused
2022-11-09T00:39:48.825Z,1667954388.825 [MultiRay](INFO): Paused
2022-11-09T00:39:49.237Z,1667954389.237 [MultiRay](INFO): Paused
2022-11-09T00:39:49.630Z,1667954389.630 [MultiRay](INFO): Paused
2022-11-09T00:39:49.667Z,1667954389.667 [multiray_test:O.Wait](INFO): Done Waiting.
2022-11-09T00:39:49.667Z,1667954389.667 [multiray_test:O.Wait] Stopped
2022-11-09T00:39:49.667Z,1667954389.667 [multiray_test:O.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:39:49.667Z,1667954389.667 [multiray_test:P] Running Loop=1
2022-11-09T00:39:50.037Z,1667954390.037 [MultiRay](INFO): Paused
2022-11-09T00:39:50.082Z,1667954390.082 [multiray_test:P] Stopped
2022-11-09T00:39:50.082Z,1667954390.082 [multiray_test:Q.Wait] Running Loop=1
2022-11-09T00:39:50.082Z,1667954390.082 [multiray_test:Q.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:50.502Z,1667954390.502 [MultiRay](INFO): Paused
2022-11-09T00:39:50.797Z,1667954390.797 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-09T00:39:50.858Z,1667954390.858 [MultiRay](INFO): Resume
2022-11-09T00:39:50.884Z,1667954390.884 [MultiRay](INFO): Finish turning things back on in Resume
2022-11-09T00:39:51.049Z,1667954391.049 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-09T00:39:51.265Z,1667954391.265 [MultiRay](INFO): Runnable
2022-11-09T00:39:51.266Z,1667954391.266 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:51.266Z,1667954391.266 [MultiRay](IMPORTANT): Turning on red lights
2022-11-09T00:39:51.301Z,1667954391.301 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-09T00:39:51.303Z,1667954391.303 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:51.354Z,1667954391.354 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:51.369Z,1667954391.369 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:51.399Z,1667954391.399 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:51.426Z,1667954391.426 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:51.455Z,1667954391.455 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:39:51.455Z,1667954391.455 [MultiRay](INFO): Red lights on
2022-11-09T00:39:51.666Z,1667954391.666 [MultiRay](INFO): Runnable
2022-11-09T00:39:52.054Z,1667954392.054 [MultiRay](INFO): Runnable
2022-11-09T00:39:52.461Z,1667954392.461 [MultiRay](INFO): Runnable
2022-11-09T00:39:52.865Z,1667954392.865 [MultiRay](INFO): Runnable
2022-11-09T00:39:53.268Z,1667954393.268 [MultiRay](INFO): Runnable
2022-11-09T00:39:53.673Z,1667954393.673 [MultiRay](INFO): Runnable
2022-11-09T00:39:54.075Z,1667954394.075 [MultiRay](INFO): Runnable
2022-11-09T00:39:54.479Z,1667954394.479 [MultiRay](INFO): Runnable
2022-11-09T00:39:54.578Z,1667954394.578 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-09T00:39:54.885Z,1667954394.885 [MultiRay](INFO): Runnable
2022-11-09T00:39:55.300Z,1667954395.300 [MultiRay](INFO): Runnable
2022-11-09T00:39:55.686Z,1667954395.686 [MultiRay](INFO): Runnable
2022-11-09T00:39:55.737Z,1667954395.737 [multiray_test:Q.Wait](INFO): Done Waiting.
2022-11-09T00:39:55.737Z,1667954395.737 [multiray_test:Q.Wait] Stopped
2022-11-09T00:39:55.737Z,1667954395.737 [multiray_test:Q.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:39:55.737Z,1667954395.737 [multiray_test:U] Running Loop=1
2022-11-09T00:39:56.113Z,1667954396.113 [MultiRay](INFO): Runnable
2022-11-09T00:39:56.154Z,1667954396.154 [multiray_test:U] Stopped
2022-11-09T00:39:56.155Z,1667954396.155 [multiray_test:R.Wait] Running Loop=1
2022-11-09T00:39:56.155Z,1667954396.155 [multiray_test:R.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:39:56.500Z,1667954396.500 [MultiRay](INFO): Runnable
2022-11-09T00:39:56.501Z,1667954396.501 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:39:56.501Z,1667954396.501 [MultiRay](IMPORTANT): Turning on white lights
2022-11-09T00:39:56.537Z,1667954396.537 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:56.548Z,1667954396.548 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:56.561Z,1667954396.561 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:56.573Z,1667954396.573 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:56.609Z,1667954396.609 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:56.621Z,1667954396.621 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:39:56.622Z,1667954396.622 [MultiRay](INFO): White lights on
2022-11-09T00:39:56.900Z,1667954396.900 [MultiRay](INFO): Runnable
2022-11-09T00:39:57.373Z,1667954397.373 [MultiRay](INFO): Runnable
2022-11-09T00:39:57.718Z,1667954397.718 [MultiRay](INFO): Runnable
2022-11-09T00:39:58.137Z,1667954398.137 [MultiRay](INFO): Runnable
2022-11-09T00:39:58.538Z,1667954398.538 [MultiRay](INFO): Runnable
2022-11-09T00:39:58.929Z,1667954398.929 [MultiRay](INFO): Runnable
2022-11-09T00:39:59.334Z,1667954399.334 [MultiRay](INFO): Runnable
2022-11-09T00:39:59.726Z,1667954399.726 [MultiRay](INFO): Runnable
2022-11-09T00:40:00.131Z,1667954400.131 [MultiRay](INFO): Runnable
2022-11-09T00:40:00.138Z,1667954400.138 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-11-09T00:40:00.138Z,1667954400.138 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-09T00:40:00.139Z,1667954400.139 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2022-11-09T00:40:00.139Z,1667954400.139 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-11-09T00:40:00.149Z,1667954400.149 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-09T00:40:00.150Z,1667954400.150 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-11-09T00:40:00.549Z,1667954400.549 [MultiRay](INFO): Runnable
2022-11-09T00:40:00.565Z,1667954400.565 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-09T00:40:00.565Z,1667954400.565 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-11-09T00:40:00.565Z,1667954400.565 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-11-09T00:40:00.565Z,1667954400.565 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2022-11-09T00:40:00.939Z,1667954400.939 [MultiRay](INFO): Runnable
2022-11-09T00:40:00.947Z,1667954400.947 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-11-09T00:40:01.350Z,1667954401.350 [MultiRay](INFO): Runnable
2022-11-09T00:40:01.754Z,1667954401.754 [MultiRay](INFO): Runnable
2022-11-09T00:40:01.783Z,1667954401.783 [multiray_test:R.Wait](INFO): Done Waiting.
2022-11-09T00:40:01.783Z,1667954401.783 [multiray_test:R.Wait] Stopped
2022-11-09T00:40:01.783Z,1667954401.783 [multiray_test:R.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:01.783Z,1667954401.783 [multiray_test:S] Running Loop=1
2022-11-09T00:40:02.157Z,1667954402.157 [MultiRay](INFO): Runnable
2022-11-09T00:40:02.187Z,1667954402.187 [multiray_test:S] Stopped
2022-11-09T00:40:02.187Z,1667954402.187 [multiray_test:T.Wait] Running Loop=1
2022-11-09T00:40:02.187Z,1667954402.187 [multiray_test:T.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:02.561Z,1667954402.561 [MultiRay](INFO): Runnable
2022-11-09T00:40:02.562Z,1667954402.562 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:40:02.562Z,1667954402.562 [MultiRay](IMPORTANT): Turning off all lights
2022-11-09T00:40:02.593Z,1667954402.593 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:02.607Z,1667954402.607 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:02.620Z,1667954402.620 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:02.633Z,1667954402.633 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:02.645Z,1667954402.645 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:02.661Z,1667954402.661 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:02.662Z,1667954402.662 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:40:02.962Z,1667954402.962 [MultiRay](INFO): Pause
2022-11-09T00:40:03.366Z,1667954403.366 [MultiRay](INFO): Paused
2022-11-09T00:40:03.833Z,1667954403.833 [MultiRay](INFO): Paused
2022-11-09T00:40:04.183Z,1667954404.183 [MultiRay](INFO): Paused
2022-11-09T00:40:04.593Z,1667954404.593 [MultiRay](INFO): Paused
2022-11-09T00:40:04.994Z,1667954404.994 [MultiRay](INFO): Paused
2022-11-09T00:40:05.388Z,1667954405.388 [MultiRay](INFO): Paused
2022-11-09T00:40:05.793Z,1667954405.793 [MultiRay](INFO): Paused
2022-11-09T00:40:06.197Z,1667954406.197 [MultiRay](INFO): Paused
2022-11-09T00:40:06.601Z,1667954406.601 [MultiRay](INFO): Paused
2022-11-09T00:40:06.000Z,1667954407.000 [MultiRay](INFO): Paused
2022-11-09T00:40:07.416Z,1667954407.416 [MultiRay](INFO): Paused
2022-11-09T00:40:07.811Z,1667954407.811 [MultiRay](INFO): Paused
2022-11-09T00:40:07.819Z,1667954407.819 [multiray_test:T.Wait](INFO): Done Waiting.
2022-11-09T00:40:07.819Z,1667954407.819 [multiray_test:T.Wait] Stopped
2022-11-09T00:40:07.819Z,1667954407.819 [multiray_test:T.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:07.819Z,1667954407.819 [multiray_test](INFO): Completed multiray_test
2022-11-09T00:40:07.820Z,1667954407.820 [MissionManager](INFO): multiray_test is completed.
2022-11-09T00:40:07.820Z,1667954407.820 [MissionManager](INFO): Uninitializing Mission multiray_test
2022-11-09T00:40:07.820Z,1667954407.820 [multiray_test] Stopped
2022-11-09T00:40:07.820Z,1667954407.820 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test
2022-11-09T00:40:07.820Z,1667954407.820 [multiray_test:A] Stopped
2022-11-09T00:40:08.217Z,1667954408.217 [MultiRay](INFO): Paused
2022-11-09T00:40:08.226Z,1667954408.226 [MissionManager](IMPORTANT): Started mission Default
2022-11-09T00:40:08.226Z,1667954408.226 [Default] Running Loop=1
2022-11-09T00:40:08.226Z,1667954408.226 [Default](DEBUG): Aggregate::initialize Default
2022-11-09T00:40:08.226Z,1667954408.226 [Default:B.GoToSurface] Running Loop=1
2022-11-09T00:40:08.226Z,1667954408.226 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-09T00:40:08.226Z,1667954408.226 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-09T00:40:08.227Z,1667954408.227 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-09T00:40:08.227Z,1667954408.227 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-09T00:40:08.227Z,1667954408.227 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-09T00:40:08.228Z,1667954408.228 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-09T00:40:08.228Z,1667954408.228 [Default:A.Wait] Running Loop=1
2022-11-09T00:40:08.228Z,1667954408.228 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:08.621Z,1667954408.621 [MultiRay](INFO): Paused
2022-11-09T00:40:09.022Z,1667954409.022 [MultiRay](INFO): Paused
2022-11-09T00:40:09.426Z,1667954409.426 [MultiRay](INFO): Paused
2022-11-09T00:40:09.837Z,1667954409.837 [MultiRay](INFO): Paused
2022-11-09T00:40:10.242Z,1667954410.242 [MultiRay](INFO): Paused
2022-11-09T00:40:10.652Z,1667954410.652 [MultiRay](INFO): Paused
2022-11-09T00:40:11.108Z,1667954411.108 [MultiRay](INFO): Paused
2022-11-09T00:40:11.459Z,1667954411.459 [MultiRay](INFO): Paused
2022-11-09T00:40:11.733Z,1667954411.733 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2022-11-09T00:40:11.866Z,1667954411.866 [MultiRay](INFO): Paused
2022-11-09T00:40:11.985Z,1667954411.985 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-11-09T00:40:12.237Z,1667954412.237 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-11-09T00:40:12.313Z,1667954412.313 [MultiRay](INFO): Paused
2022-11-09T00:40:12.696Z,1667954412.696 [MultiRay](INFO): Paused
2022-11-09T00:40:13.096Z,1667954413.096 [MultiRay](INFO): Paused
2022-11-09T00:40:13.503Z,1667954413.503 [MultiRay](INFO): Paused
2022-11-09T00:40:13.907Z,1667954413.907 [MultiRay](INFO): Paused
2022-11-09T00:40:14.336Z,1667954414.336 [MultiRay](INFO): Paused
2022-11-09T00:40:14.717Z,1667954414.717 [MultiRay](INFO): Paused
2022-11-09T00:40:15.118Z,1667954415.118 [MultiRay](INFO): Paused
2022-11-09T00:40:15.525Z,1667954415.525 [MultiRay](INFO): Paused
2022-11-09T00:40:15.534Z,1667954415.534 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-11-09T00:40:15.933Z,1667954415.933 [MultiRay](INFO): Paused
2022-11-09T00:40:16.333Z,1667954416.333 [MultiRay](INFO): Paused
2022-11-09T00:40:16.734Z,1667954416.734 [MultiRay](INFO): Paused
2022-11-09T00:40:17.141Z,1667954417.141 [MultiRay](INFO): Paused
2022-11-09T00:40:17.691Z,1667954417.691 [MultiRay](INFO): Paused
2022-11-09T00:40:17.973Z,1667954417.973 [MultiRay](INFO): Paused
2022-11-09T00:40:18.365Z,1667954418.365 [MultiRay](INFO): Paused
2022-11-09T00:40:18.769Z,1667954418.769 [MultiRay](INFO): Paused
2022-11-09T00:40:19.166Z,1667954419.166 [MultiRay](INFO): Paused
2022-11-09T00:40:19.566Z,1667954419.566 [MultiRay](INFO): Paused
2022-11-09T00:40:19.616Z,1667954419.616 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_test.xml
2022-11-09T00:40:19.637Z,1667954419.637 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_test.xml
2022-11-09T00:40:19.703Z,1667954419.703 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min
2022-11-09T00:40:19.705Z,1667954419.705 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s
2022-11-09T00:40:19.707Z,1667954419.707 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count
2022-11-09T00:40:19.710Z,1667954419.710 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count
2022-11-09T00:40:19.712Z,1667954419.712 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count
2022-11-09T00:40:19.714Z,1667954419.714 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool
2022-11-09T00:40:19.717Z,1667954419.717 [multiray_test:C.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.720Z,1667954419.720 [multiray_test:E.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.723Z,1667954419.723 [multiray_test:G.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.756Z,1667954419.756 [multiray_test:I.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.758Z,1667954419.758 [multiray_test:K.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.761Z,1667954419.761 [multiray_test:M.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.763Z,1667954419.763 [multiray_test:O.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.766Z,1667954419.766 [multiray_test:Q.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.769Z,1667954419.769 [multiray_test:R.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.771Z,1667954419.771 [multiray_test:T.Wait](DEBUG): Construct Wait.
2022-11-09T00:40:19.806Z,1667954419.806 [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_test">

    <Description>
        This mission is designed to run in the lab only. Cycles through MultiRay light modes
    </Description>

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

    <DefineArg Name="LightDuration"><Description>
        How long to wait before changing light modes
    </Description><Units:second/><Value>5</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>

    <DefineArg Name="EnabledMultiray"><Description>
        Automatically set to true if the Multiray is enabled.
    </Description><Science:MultiRay.loadAtStartup/></DefineArg>

    <Timeout Duration="MissionTimeout"/>



    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledMultiray"/>
        </While>
    <Sensor:MultiRay.lightModeLog/>
    </ReadData>


    <!-- <ReadDatum>
            <Sensor:MultiRay.lightModeLog/>
    </ReadDatum> -->

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode0"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode1"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode2"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode0"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>
    
    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode1"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode2"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode0"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode1"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode2"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

    <Assign><Sequence/><Sensor:MultiRay.lightModeCommand/><Arg Name="LightMode0"/></Assign>    
    <Guidance:Wait>
                <Sequence/>
                <Setting><Guidance:Wait.duration/><Arg Name="LightDuration"/></Setting>
            </Guidance:Wait>

</Mission>


2022-11-09T00:40:19.808Z,1667954419.808 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_test.xml
2022-11-09T00:40:19.969Z,1667954419.969 [MultiRay](INFO): Paused
2022-11-09T00:40:19.982Z,1667954419.982 [Default] Stopped
2022-11-09T00:40:19.982Z,1667954419.982 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-09T00:40:19.983Z,1667954419.983 [Default:A.Wait] Stopped
2022-11-09T00:40:19.983Z,1667954419.983 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:19.983Z,1667954419.983 [Default:B.GoToSurface] Stopped
2022-11-09T00:40:19.983Z,1667954419.983 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-09T00:40:19.983Z,1667954419.983 [MissionManager](IMPORTANT): Started mission multiray_test
2022-11-09T00:40:19.983Z,1667954419.983 [multiray_test] Running Loop=1
2022-11-09T00:40:19.983Z,1667954419.983 [multiray_test](DEBUG): Aggregate::initialize multiray_test
2022-11-09T00:40:19.983Z,1667954419.983 [multiray_test:A] Running Loop=1
2022-11-09T00:40:19.984Z,1667954419.984 [multiray_test:B] Running Loop=1
2022-11-09T00:40:20.012Z,1667954420.012 [multiray_test:B] Stopped
2022-11-09T00:40:20.013Z,1667954420.013 [multiray_test:C.Wait] Running Loop=1
2022-11-09T00:40:20.013Z,1667954420.013 [multiray_test:C.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:20.013Z,1667954420.013 [multiray_test:A] Running Loop=1
2022-11-09T00:40:20.013Z,1667954420.013 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2022-11-09T00:40:20.373Z,1667954420.373 [MultiRay](INFO): Paused
2022-11-09T00:40:20.780Z,1667954420.780 [MultiRay](INFO): Resume
2022-11-09T00:40:20.820Z,1667954420.820 [MultiRay](INFO): Finish turning things back on in Resume
2022-11-09T00:40:21.177Z,1667954421.177 [MultiRay](INFO): Runnable
2022-11-09T00:40:21.178Z,1667954421.178 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:40:21.178Z,1667954421.178 [MultiRay](IMPORTANT): Turning off all lights
2022-11-09T00:40:21.200Z,1667954421.200 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:21.214Z,1667954421.214 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:21.228Z,1667954421.228 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:21.241Z,1667954421.241 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:21.253Z,1667954421.253 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:21.265Z,1667954421.265 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:21.266Z,1667954421.266 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:40:21.585Z,1667954421.585 [MultiRay](INFO): Pause
2022-11-09T00:40:21.991Z,1667954421.991 [MultiRay](INFO): Paused
2022-11-09T00:40:22.389Z,1667954422.389 [MultiRay](INFO): Paused
2022-11-09T00:40:22.797Z,1667954422.797 [MultiRay](INFO): Paused
2022-11-09T00:40:23.197Z,1667954423.197 [MultiRay](INFO): Paused
2022-11-09T00:40:23.608Z,1667954423.608 [MultiRay](INFO): Paused
2022-11-09T00:40:24.009Z,1667954424.009 [MultiRay](INFO): Paused
2022-11-09T00:40:24.474Z,1667954424.474 [MultiRay](INFO): Paused
2022-11-09T00:40:24.822Z,1667954424.822 [MultiRay](INFO): Paused
2022-11-09T00:40:25.241Z,1667954425.241 [MultiRay](INFO): Paused
2022-11-09T00:40:25.638Z,1667954425.638 [MultiRay](INFO): Paused
2022-11-09T00:40:25.667Z,1667954425.667 [multiray_test:C.Wait](INFO): Done Waiting.
2022-11-09T00:40:25.667Z,1667954425.667 [multiray_test:C.Wait] Stopped
2022-11-09T00:40:25.667Z,1667954425.667 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:25.667Z,1667954425.667 [multiray_test:D] Running Loop=1
2022-11-09T00:40:26.029Z,1667954426.029 [MultiRay](INFO): Paused
2022-11-09T00:40:26.037Z,1667954426.037 [multiray_test:D] Stopped
2022-11-09T00:40:26.038Z,1667954426.038 [multiray_test:E.Wait] Running Loop=1
2022-11-09T00:40:26.038Z,1667954426.038 [multiray_test:E.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:26.430Z,1667954426.430 [MultiRay](INFO): Paused
2022-11-09T00:40:26.836Z,1667954426.836 [MultiRay](INFO): Resume
2022-11-09T00:40:26.908Z,1667954426.908 [MultiRay](INFO): Finish turning things back on in Resume
2022-11-09T00:40:27.241Z,1667954427.241 [MultiRay](INFO): Runnable
2022-11-09T00:40:27.242Z,1667954427.242 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:40:27.242Z,1667954427.242 [MultiRay](IMPORTANT): Turning on red lights
2022-11-09T00:40:27.279Z,1667954427.279 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:27.295Z,1667954427.295 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:27.323Z,1667954427.323 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:27.354Z,1667954427.354 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:27.382Z,1667954427.382 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:27.411Z,1667954427.411 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:27.411Z,1667954427.411 [MultiRay](INFO): Red lights on
2022-11-09T00:40:27.649Z,1667954427.649 [MultiRay](INFO): Runnable
2022-11-09T00:40:28.049Z,1667954428.049 [MultiRay](INFO): Runnable
2022-11-09T00:40:28.450Z,1667954428.450 [MultiRay](INFO): Runnable
2022-11-09T00:40:28.849Z,1667954428.849 [MultiRay](INFO): Runnable
2022-11-09T00:40:29.265Z,1667954429.265 [MultiRay](INFO): Runnable
2022-11-09T00:40:29.666Z,1667954429.666 [MultiRay](INFO): Runnable
2022-11-09T00:40:30.066Z,1667954430.066 [MultiRay](INFO): Runnable
2022-11-09T00:40:30.473Z,1667954430.473 [MultiRay](INFO): Runnable
2022-11-09T00:40:30.882Z,1667954430.882 [MultiRay](INFO): Runnable
2022-11-09T00:40:31.341Z,1667954431.341 [MultiRay](INFO): Runnable
2022-11-09T00:40:31.691Z,1667954431.691 [MultiRay](INFO): Runnable
2022-11-09T00:40:31.750Z,1667954431.750 [multiray_test:E.Wait](INFO): Done Waiting.
2022-11-09T00:40:31.750Z,1667954431.750 [multiray_test:E.Wait] Stopped
2022-11-09T00:40:31.750Z,1667954431.750 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:31.751Z,1667954431.751 [multiray_test:F] Running Loop=1
2022-11-09T00:40:32.101Z,1667954432.101 [MultiRay](INFO): Runnable
2022-11-09T00:40:32.110Z,1667954432.110 [multiray_test:F] Stopped
2022-11-09T00:40:32.110Z,1667954432.110 [multiray_test:G.Wait] Running Loop=1
2022-11-09T00:40:32.110Z,1667954432.110 [multiray_test:G.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:32.505Z,1667954432.505 [MultiRay](INFO): Runnable
2022-11-09T00:40:32.505Z,1667954432.505 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:40:32.506Z,1667954432.506 [MultiRay](IMPORTANT): Turning on white lights
2022-11-09T00:40:32.548Z,1667954432.548 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:32.562Z,1667954432.562 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:32.589Z,1667954432.589 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:32.602Z,1667954432.602 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:32.629Z,1667954432.629 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:32.641Z,1667954432.641 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:32.642Z,1667954432.642 [MultiRay](INFO): White lights on
2022-11-09T00:40:32.689Z,1667954432.689 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2022-11-09T00:40:32.689Z,1667954432.689 [DAT](ERROR): Ack receipt timeout failure.
2022-11-09T00:40:32.901Z,1667954432.901 [MultiRay](INFO): Runnable
2022-11-09T00:40:32.941Z,1667954432.941 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER
2022-11-09T00:40:33.298Z,1667954433.298 [MultiRay](INFO): Runnable
2022-11-09T00:40:33.703Z,1667954433.703 [MultiRay](INFO): Runnable
2022-11-09T00:40:34.106Z,1667954434.106 [MultiRay](INFO): Runnable
2022-11-09T00:40:34.513Z,1667954434.513 [MultiRay](INFO): Runnable
2022-11-09T00:40:34.914Z,1667954434.914 [MultiRay](INFO): Runnable
2022-11-09T00:40:35.341Z,1667954435.341 [MultiRay](INFO): Runnable
2022-11-09T00:40:35.724Z,1667954435.724 [MultiRay](INFO): Runnable
2022-11-09T00:40:36.129Z,1667954436.129 [MultiRay](INFO): Runnable
2022-11-09T00:40:36.550Z,1667954436.550 [MultiRay](INFO): Runnable
2022-11-09T00:40:36.938Z,1667954436.938 [MultiRay](INFO): Runnable
2022-11-09T00:40:37.338Z,1667954437.338 [MultiRay](INFO): Runnable
2022-11-09T00:40:37.811Z,1667954437.811 [MultiRay](INFO): Runnable
2022-11-09T00:40:37.847Z,1667954437.847 [multiray_test:G.Wait](INFO): Done Waiting.
2022-11-09T00:40:37.847Z,1667954437.847 [multiray_test:G.Wait] Stopped
2022-11-09T00:40:37.847Z,1667954437.847 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:37.848Z,1667954437.848 [multiray_test:H] Running Loop=1
2022-11-09T00:40:38.160Z,1667954438.160 [MultiRay](INFO): Runnable
2022-11-09T00:40:38.169Z,1667954438.169 [multiray_test:H] Stopped
2022-11-09T00:40:38.169Z,1667954438.169 [multiray_test:I.Wait] Running Loop=1
2022-11-09T00:40:38.169Z,1667954438.169 [multiray_test:I.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:38.573Z,1667954438.573 [MultiRay](INFO): Runnable
2022-11-09T00:40:38.573Z,1667954438.573 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:40:38.574Z,1667954438.574 [MultiRay](IMPORTANT): Turning off all lights
2022-11-09T00:40:38.596Z,1667954438.596 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:38.644Z,1667954438.644 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:38.669Z,1667954438.669 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:38.680Z,1667954438.680 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:38.693Z,1667954438.693 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:38.705Z,1667954438.705 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:38.705Z,1667954438.705 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:40:38.977Z,1667954438.977 [MultiRay](INFO): Pause
2022-11-09T00:40:39.385Z,1667954439.385 [MultiRay](INFO): Paused
2022-11-09T00:40:39.762Z,1667954439.762 [MultiRay](INFO): Paused
2022-11-09T00:40:40.167Z,1667954440.167 [MultiRay](INFO): Paused
2022-11-09T00:40:40.568Z,1667954440.568 [MultiRay](INFO): Paused
2022-11-09T00:40:40.970Z,1667954440.970 [MultiRay](INFO): Paused
2022-11-09T00:40:41.381Z,1667954441.381 [MultiRay](INFO): Paused
2022-11-09T00:40:41.782Z,1667954441.782 [MultiRay](INFO): Paused
2022-11-09T00:40:42.184Z,1667954442.184 [MultiRay](INFO): Paused
2022-11-09T00:40:42.600Z,1667954442.600 [MultiRay](INFO): Paused
2022-11-09T00:40:42.994Z,1667954442.994 [MultiRay](INFO): Paused
2022-11-09T00:40:43.397Z,1667954443.397 [MultiRay](INFO): Paused
2022-11-09T00:40:43.806Z,1667954443.806 [MultiRay](INFO): Paused
2022-11-09T00:40:43.814Z,1667954443.814 [multiray_test:I.Wait](INFO): Done Waiting.
2022-11-09T00:40:43.815Z,1667954443.815 [multiray_test:I.Wait] Stopped
2022-11-09T00:40:43.815Z,1667954443.815 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:43.815Z,1667954443.815 [multiray_test:J] Running Loop=1
2022-11-09T00:40:44.206Z,1667954444.206 [MultiRay](INFO): Paused
2022-11-09T00:40:44.231Z,1667954444.231 [multiray_test:J] Stopped
2022-11-09T00:40:44.231Z,1667954444.231 [multiray_test:K.Wait] Running Loop=1
2022-11-09T00:40:44.231Z,1667954444.231 [multiray_test:K.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:44.617Z,1667954444.617 [MultiRay](INFO): Paused
2022-11-09T00:40:45.080Z,1667954445.080 [MultiRay](INFO): Resume
2022-11-09T00:40:45.105Z,1667954445.105 [MultiRay](INFO): Finish turning things back on in Resume
2022-11-09T00:40:45.422Z,1667954445.422 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-11-09T00:40:45.426Z,1667954445.426 [BPC1](INFO): Received data from all battery sticks.
2022-11-09T00:40:45.428Z,1667954445.428 [MultiRay](INFO): Runnable
2022-11-09T00:40:45.428Z,1667954445.428 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:40:45.428Z,1667954445.428 [MultiRay](IMPORTANT): Turning on red lights
2022-11-09T00:40:45.472Z,1667954445.472 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:45.500Z,1667954445.500 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:45.513Z,1667954445.513 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:45.541Z,1667954445.541 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:45.570Z,1667954445.570 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:45.600Z,1667954445.600 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:45.600Z,1667954445.600 [MultiRay](INFO): Red lights on
2022-11-09T00:40:45.826Z,1667954445.826 [MultiRay](INFO): Runnable
2022-11-09T00:40:46.226Z,1667954446.226 [MultiRay](INFO): Runnable
2022-11-09T00:40:46.630Z,1667954446.630 [MultiRay](INFO): Runnable
2022-11-09T00:40:47.034Z,1667954447.034 [MultiRay](INFO): Runnable
2022-11-09T00:40:47.446Z,1667954447.446 [MultiRay](INFO): Runnable
2022-11-09T00:40:47.855Z,1667954447.855 [MultiRay](INFO): Runnable
2022-11-09T00:40:48.246Z,1667954448.246 [MultiRay](INFO): Runnable
2022-11-09T00:40:48.657Z,1667954448.657 [MultiRay](INFO): Runnable
2022-11-09T00:40:49.055Z,1667954449.055 [MultiRay](INFO): Runnable
2022-11-09T00:40:49.458Z,1667954449.458 [MultiRay](INFO): Runnable
2022-11-09T00:40:49.865Z,1667954449.865 [MultiRay](INFO): Runnable
2022-11-09T00:40:49.873Z,1667954449.873 [multiray_test:K.Wait](INFO): Done Waiting.
2022-11-09T00:40:49.873Z,1667954449.873 [multiray_test:K.Wait] Stopped
2022-11-09T00:40:49.874Z,1667954449.874 [multiray_test:K.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:49.874Z,1667954449.874 [multiray_test:L] Running Loop=1
2022-11-09T00:40:50.273Z,1667954450.273 [MultiRay](INFO): Runnable
2022-11-09T00:40:50.282Z,1667954450.282 [multiray_test:L] Stopped
2022-11-09T00:40:50.282Z,1667954450.282 [multiray_test:M.Wait] Running Loop=1
2022-11-09T00:40:50.282Z,1667954450.282 [multiray_test:M.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:50.671Z,1667954450.671 [MultiRay](INFO): Runnable
2022-11-09T00:40:50.672Z,1667954450.672 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:40:50.672Z,1667954450.672 [MultiRay](IMPORTANT): Turning on white lights
2022-11-09T00:40:50.709Z,1667954450.709 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:50.720Z,1667954450.720 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:50.749Z,1667954450.749 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:50.765Z,1667954450.765 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:50.794Z,1667954450.794 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:50.805Z,1667954450.805 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:50.806Z,1667954450.806 [MultiRay](INFO): White lights on
2022-11-09T00:40:51.077Z,1667954451.077 [MultiRay](INFO): Runnable
2022-11-09T00:40:51.481Z,1667954451.481 [MultiRay](INFO): Runnable
2022-11-09T00:40:51.885Z,1667954451.885 [MultiRay](INFO): Runnable
2022-11-09T00:40:52.286Z,1667954452.286 [MultiRay](INFO): Runnable
2022-11-09T00:40:52.693Z,1667954452.693 [MultiRay](INFO): Runnable
2022-11-09T00:40:53.094Z,1667954453.094 [MultiRay](INFO): Runnable
2022-11-09T00:40:53.502Z,1667954453.502 [MultiRay](INFO): Runnable
2022-11-09T00:40:53.909Z,1667954453.909 [MultiRay](INFO): Runnable
2022-11-09T00:40:54.306Z,1667954454.306 [MultiRay](INFO): Runnable
2022-11-09T00:40:54.725Z,1667954454.725 [MultiRay](INFO): Runnable
2022-11-09T00:40:55.114Z,1667954455.114 [MultiRay](INFO): Runnable
2022-11-09T00:40:55.521Z,1667954455.521 [MultiRay](INFO): Runnable
2022-11-09T00:40:55.933Z,1667954455.933 [MultiRay](INFO): Runnable
2022-11-09T00:40:55.954Z,1667954455.954 [multiray_test:M.Wait](INFO): Done Waiting.
2022-11-09T00:40:55.954Z,1667954455.954 [multiray_test:M.Wait] Stopped
2022-11-09T00:40:55.954Z,1667954455.954 [multiray_test:M.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:40:55.955Z,1667954455.955 [multiray_test:N] Running Loop=1
2022-11-09T00:40:56.325Z,1667954456.325 [MultiRay](INFO): Runnable
2022-11-09T00:40:56.355Z,1667954456.355 [multiray_test:N] Stopped
2022-11-09T00:40:56.355Z,1667954456.355 [multiray_test:O.Wait] Running Loop=1
2022-11-09T00:40:56.355Z,1667954456.355 [multiray_test:O.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:40:56.733Z,1667954456.733 [MultiRay](INFO): Runnable
2022-11-09T00:40:56.734Z,1667954456.734 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:40:56.734Z,1667954456.734 [MultiRay](IMPORTANT): Turning off all lights
2022-11-09T00:40:56.786Z,1667954456.786 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:56.800Z,1667954456.800 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:56.813Z,1667954456.813 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:40:56.825Z,1667954456.825 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:56.838Z,1667954456.838 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:56.849Z,1667954456.849 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:40:56.850Z,1667954456.850 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:40:57.145Z,1667954457.145 [MultiRay](INFO): Pause
2022-11-09T00:40:57.537Z,1667954457.537 [MultiRay](INFO): Paused
2022-11-09T00:40:57.946Z,1667954457.946 [MultiRay](INFO): Paused
2022-11-09T00:40:58.345Z,1667954458.345 [MultiRay](INFO): Paused
2022-11-09T00:40:58.761Z,1667954458.761 [MultiRay](INFO): Paused
2022-11-09T00:40:59.153Z,1667954459.153 [MultiRay](INFO): Paused
2022-11-09T00:40:59.558Z,1667954459.558 [MultiRay](INFO): Paused
2022-11-09T00:40:59.963Z,1667954459.963 [MultiRay](INFO): Paused
2022-11-09T00:41:00.363Z,1667954460.363 [MultiRay](INFO): Paused
2022-11-09T00:41:00.766Z,1667954460.766 [MultiRay](INFO): Paused
2022-11-09T00:41:01.177Z,1667954461.177 [MultiRay](INFO): Paused
2022-11-09T00:41:01.578Z,1667954461.578 [MultiRay](INFO): Paused
2022-11-09T00:41:01.983Z,1667954461.983 [MultiRay](INFO): Paused
2022-11-09T00:41:02.003Z,1667954462.003 [multiray_test:O.Wait](INFO): Done Waiting.
2022-11-09T00:41:02.003Z,1667954462.003 [multiray_test:O.Wait] Stopped
2022-11-09T00:41:02.004Z,1667954462.004 [multiray_test:O.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:41:02.004Z,1667954462.004 [multiray_test:P] Running Loop=1
2022-11-09T00:41:02.398Z,1667954462.398 [MultiRay](INFO): Paused
2022-11-09T00:41:02.427Z,1667954462.427 [multiray_test:P] Stopped
2022-11-09T00:41:02.427Z,1667954462.427 [multiray_test:Q.Wait] Running Loop=1
2022-11-09T00:41:02.427Z,1667954462.427 [multiray_test:Q.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:41:02.790Z,1667954462.790 [MultiRay](INFO): Paused
2022-11-09T00:41:03.194Z,1667954463.194 [MultiRay](INFO): Resume
2022-11-09T00:41:03.224Z,1667954463.224 [MultiRay](INFO): Finish turning things back on in Resume
2022-11-09T00:41:03.598Z,1667954463.598 [MultiRay](INFO): Runnable
2022-11-09T00:41:03.598Z,1667954463.598 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:41:03.599Z,1667954463.599 [MultiRay](IMPORTANT): Turning on red lights
2022-11-09T00:41:03.645Z,1667954463.645 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:03.657Z,1667954463.657 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:03.687Z,1667954463.687 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:41:03.716Z,1667954463.716 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:41:03.730Z,1667954463.730 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:41:03.759Z,1667954463.759 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:41:03.764Z,1667954463.764 [MultiRay](INFO): Red lights on
2022-11-09T00:41:04.008Z,1667954464.008 [MultiRay](INFO): Runnable
2022-11-09T00:41:04.414Z,1667954464.414 [MultiRay](INFO): Runnable
2022-11-09T00:41:04.806Z,1667954464.806 [MultiRay](INFO): Runnable
2022-11-09T00:41:05.214Z,1667954465.214 [MultiRay](INFO): Runnable
2022-11-09T00:41:05.620Z,1667954465.620 [MultiRay](INFO): Runnable
2022-11-09T00:41:06.025Z,1667954466.025 [MultiRay](INFO): Runnable
2022-11-09T00:41:06.422Z,1667954466.422 [MultiRay](INFO): Runnable
2022-11-09T00:41:06.833Z,1667954466.833 [MultiRay](INFO): Runnable
2022-11-09T00:41:07.235Z,1667954467.235 [MultiRay](INFO): Runnable
2022-11-09T00:41:07.654Z,1667954467.654 [MultiRay](INFO): Runnable
2022-11-09T00:41:08.050Z,1667954468.050 [MultiRay](INFO): Runnable
2022-11-09T00:41:08.058Z,1667954468.058 [multiray_test:Q.Wait](INFO): Done Waiting.
2022-11-09T00:41:08.058Z,1667954468.058 [multiray_test:Q.Wait] Stopped
2022-11-09T00:41:08.058Z,1667954468.058 [multiray_test:Q.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:41:08.058Z,1667954468.058 [multiray_test:U] Running Loop=1
2022-11-09T00:41:08.450Z,1667954468.450 [MultiRay](INFO): Runnable
2022-11-09T00:41:08.459Z,1667954468.459 [multiray_test:U] Stopped
2022-11-09T00:41:08.459Z,1667954468.459 [multiray_test:R.Wait] Running Loop=1
2022-11-09T00:41:08.459Z,1667954468.459 [multiray_test:R.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:41:08.854Z,1667954468.854 [MultiRay](INFO): Runnable
2022-11-09T00:41:08.854Z,1667954468.854 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:41:08.854Z,1667954468.854 [MultiRay](IMPORTANT): Turning on white lights
2022-11-09T00:41:08.896Z,1667954468.896 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:08.910Z,1667954468.910 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:08.937Z,1667954468.937 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:08.949Z,1667954468.949 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:41:08.978Z,1667954468.978 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:08.989Z,1667954468.989 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:08.990Z,1667954468.990 [MultiRay](INFO): White lights on
2022-11-09T00:41:09.256Z,1667954469.256 [MultiRay](INFO): Runnable
2022-11-09T00:41:09.662Z,1667954469.662 [MultiRay](INFO): Runnable
2022-11-09T00:41:10.067Z,1667954470.067 [MultiRay](INFO): Runnable
2022-11-09T00:41:10.473Z,1667954470.473 [MultiRay](INFO): Runnable
2022-11-09T00:41:10.871Z,1667954470.871 [MultiRay](INFO): Runnable
2022-11-09T00:41:11.278Z,1667954471.278 [MultiRay](INFO): Runnable
2022-11-09T00:41:11.678Z,1667954471.678 [MultiRay](INFO): Runnable
2022-11-09T00:41:12.089Z,1667954472.089 [MultiRay](INFO): Runnable
2022-11-09T00:41:12.487Z,1667954472.487 [MultiRay](INFO): Runnable
2022-11-09T00:41:12.904Z,1667954472.904 [MultiRay](INFO): Runnable
2022-11-09T00:41:13.301Z,1667954473.301 [MultiRay](INFO): Runnable
2022-11-09T00:41:13.734Z,1667954473.734 [MultiRay](INFO): Runnable
2022-11-09T00:41:14.106Z,1667954474.106 [MultiRay](INFO): Runnable
2022-11-09T00:41:14.115Z,1667954474.115 [multiray_test:R.Wait](INFO): Done Waiting.
2022-11-09T00:41:14.115Z,1667954474.115 [multiray_test:R.Wait] Stopped
2022-11-09T00:41:14.115Z,1667954474.115 [multiray_test:R.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:41:14.115Z,1667954474.115 [multiray_test:S] Running Loop=1
2022-11-09T00:41:14.513Z,1667954474.513 [MultiRay](INFO): Runnable
2022-11-09T00:41:14.530Z,1667954474.530 [multiray_test:S] Stopped
2022-11-09T00:41:14.530Z,1667954474.530 [multiray_test:T.Wait] Running Loop=1
2022-11-09T00:41:14.530Z,1667954474.530 [multiray_test:T.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:41:14.921Z,1667954474.921 [MultiRay](INFO): Runnable
2022-11-09T00:41:14.922Z,1667954474.922 [MultiRay](IMPORTANT): New light mode loaded
2022-11-09T00:41:14.922Z,1667954474.922 [MultiRay](IMPORTANT): Turning off all lights
2022-11-09T00:41:14.944Z,1667954474.944 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:14.956Z,1667954474.956 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:14.984Z,1667954474.984 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:14.997Z,1667954474.997 [MultiRay](DEBUG): Recieved MultiRay unrecognized response
2022-11-09T00:41:15.009Z,1667954475.009 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:41:15.029Z,1667954475.029 [MultiRay](IMPORTANT): Recieved MultiRay acknowledge
2022-11-09T00:41:15.029Z,1667954475.029 [MultiRay](IMPORTANT): All lights off
2022-11-09T00:41:15.319Z,1667954475.319 [MultiRay](INFO): Pause
2022-11-09T00:41:15.722Z,1667954475.722 [MultiRay](INFO): Paused
2022-11-09T00:41:16.126Z,1667954476.126 [MultiRay](INFO): Paused
2022-11-09T00:41:16.532Z,1667954476.532 [MultiRay](INFO): Paused
2022-11-09T00:41:16.931Z,1667954476.931 [MultiRay](INFO): Paused
2022-11-09T00:41:17.341Z,1667954477.341 [MultiRay](INFO): Paused
2022-11-09T00:41:17.742Z,1667954477.742 [MultiRay](INFO): Paused
2022-11-09T00:41:18.147Z,1667954478.147 [MultiRay](INFO): Paused
2022-11-09T00:41:18.553Z,1667954478.553 [MultiRay](INFO): Paused
2022-11-09T00:41:18.954Z,1667954478.954 [MultiRay](INFO): Paused
2022-11-09T00:41:19.358Z,1667954479.358 [MultiRay](INFO): Paused
2022-11-09T00:41:19.768Z,1667954479.768 [MultiRay](INFO): Paused
2022-11-09T00:41:20.166Z,1667954480.166 [MultiRay](INFO): Paused
2022-11-09T00:41:20.175Z,1667954480.175 [multiray_test:T.Wait](INFO): Done Waiting.
2022-11-09T00:41:20.175Z,1667954480.175 [multiray_test:T.Wait] Stopped
2022-11-09T00:41:20.175Z,1667954480.175 [multiray_test:T.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:41:20.175Z,1667954480.175 [multiray_test](INFO): Completed multiray_test
2022-11-09T00:41:20.176Z,1667954480.176 [MissionManager](INFO): multiray_test is completed.
2022-11-09T00:41:20.176Z,1667954480.176 [MissionManager](INFO): Uninitializing Mission multiray_test
2022-11-09T00:41:20.176Z,1667954480.176 [multiray_test] Stopped
2022-11-09T00:41:20.176Z,1667954480.176 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test
2022-11-09T00:41:20.192Z,1667954480.192 [multiray_test:A] Stopped
2022-11-09T00:41:20.573Z,1667954480.573 [MultiRay](INFO): Paused
2022-11-09T00:41:20.631Z,1667954480.631 [MissionManager](IMPORTANT): Started mission Default
2022-11-09T00:41:20.631Z,1667954480.631 [Default] Running Loop=1
2022-11-09T00:41:20.631Z,1667954480.631 [Default](DEBUG): Aggregate::initialize Default
2022-11-09T00:41:20.631Z,1667954480.631 [Default:B.GoToSurface] Running Loop=1
2022-11-09T00:41:20.631Z,1667954480.631 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-09T00:41:20.631Z,1667954480.631 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-09T00:41:20.632Z,1667954480.632 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-09T00:41:20.632Z,1667954480.632 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-09T00:41:20.633Z,1667954480.633 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-09T00:41:20.633Z,1667954480.633 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-09T00:41:20.633Z,1667954480.633 [Default:A.Wait] Running Loop=1
2022-11-09T00:41:20.633Z,1667954480.633 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-09T00:41:20.985Z,1667954480.985 [MultiRay](INFO): Paused
2022-11-09T00:41:21.381Z,1667954481.381 [MultiRay](INFO): Paused
2022-11-09T00:41:21.796Z,1667954481.796 [MultiRay](INFO): Paused
2022-11-09T00:41:22.197Z,1667954482.197 [MultiRay](INFO): Paused
2022-11-09T00:41:22.606Z,1667954482.606 [MultiRay](INFO): Paused
2022-11-09T00:41:23.026Z,1667954483.026 [MultiRay](INFO): Paused
2022-11-09T00:41:23.405Z,1667954483.405 [MultiRay](INFO): Paused
2022-11-09T00:41:23.805Z,1667954483.805 [MultiRay](INFO): Paused
2022-11-09T00:41:24.217Z,1667954484.217 [MultiRay](INFO): Paused
2022-11-09T00:41:24.628Z,1667954484.628 [MultiRay](INFO): Paused
2022-11-09T00:41:25.017Z,1667954485.017 [MultiRay](INFO): Paused
2022-11-09T00:41:25.431Z,1667954485.431 [MultiRay](INFO): Paused
2022-11-09T00:41:25.833Z,1667954485.833 [MultiRay](INFO): Paused
2022-11-09T00:41:26.230Z,1667954486.230 [MultiRay](INFO): Paused
2022-11-09T00:41:26.634Z,1667954486.634 [MultiRay](INFO): Paused
2022-11-09T00:41:27.038Z,1667954487.038 [MultiRay](INFO): Paused
2022-11-09T00:41:27.441Z,1667954487.441 [MultiRay](INFO): Paused
2022-11-09T00:41:27.855Z,1667954487.855 [MultiRay](INFO): Paused
2022-11-09T00:41:28.253Z,1667954488.253 [MultiRay](INFO): Paused
2022-11-09T00:41:28.654Z,1667954488.654 [MultiRay](INFO): Paused
2022-11-09T00:41:29.054Z,1667954489.054 [MultiRay](INFO): Paused
2022-11-09T00:41:29.462Z,1667954489.462 [MultiRay](INFO): Paused
2022-11-09T00:41:29.866Z,1667954489.866 [MultiRay](INFO): Paused
2022-11-09T00:41:30.280Z,1667954490.280 [MultiRay](INFO): Paused
2022-11-09T00:41:30.677Z,1667954490.677 [MultiRay](INFO): Paused
2022-11-09T00:41:31.077Z,1667954491.077 [MultiRay](INFO): Paused
2022-11-09T00:41:31.489Z,1667954491.489 [MultiRay](INFO): Paused
2022-11-09T00:41:31.885Z,1667954491.885 [MultiRay](INFO): Paused
2022-11-09T00:41:32.289Z,1667954492.289 [MultiRay](INFO): Paused
2022-11-09T00:41:32.717Z,1667954492.717 [MultiRay](INFO): Paused
2022-11-09T00:41:33.097Z,1667954493.097 [MultiRay](INFO): Paused
2022-11-09T00:41:33.501Z,1667954493.501 [MultiRay](INFO): Paused
2022-11-09T00:41:33.905Z,1667954493.905 [MultiRay](INFO): Paused
2022-11-09T00:41:33.916Z,1667954493.916 [Default:A.Wait](INFO): Done Waiting.
2022-11-09T00:41:33.916Z,1667954493.916 [Default:A.Wait] Stopped
2022-11-09T00:41:33.916Z,1667954493.916 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-09T00:41:34.317Z,1667954494.317 [MultiRay](INFO): Paused
2022-11-09T00:41:34.411Z,1667954494.411 [Default:CheckIn] Running Loop=1
2022-11-09T00:41:34.411Z,1667954494.411 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-09T00:41:34.411Z,1667954494.411 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-09T00:41:34.713Z,1667954494.713 [MultiRay](INFO): Paused
2022-11-09T00:41:35.125Z,1667954495.125 [MultiRay](INFO): Paused
2022-11-09T00:41:35.534Z,1667954495.534 [MultiRay](INFO): Paused
2022-11-09T00:41:35.925Z,1667954495.925 [MultiRay](INFO): Paused
2022-11-09T00:41:36.329Z,1667954496.329 [MultiRay](INFO): Paused
2022-11-09T00:41:36.733Z,1667954496.733 [MultiRay](INFO): Paused
2022-11-09T00:41:37.141Z,1667954497.141 [MultiRay](INFO): Paused
2022-11-09T00:41:37.561Z,1667954497.561 [MultiRay](INFO): Paused
2022-11-09T00:41:37.962Z,1667954497.962 [MultiRay](INFO): Paused
2022-11-09T00:41:38.354Z,1667954498.354 [MultiRay](INFO): Paused
2022-11-09T00:41:38.757Z,1667954498.757 [MultiRay](INFO): Paused
2022-11-09T00:41:39.162Z,1667954499.162 [MultiRay](INFO): Paused
2022-11-09T00:41:39.566Z,1667954499.566 [MultiRay](INFO): Paused
2022-11-09T00:41:39.974Z,1667954499.974 [MultiRay](INFO): Paused
2022-11-09T00:41:40.366Z,1667954500.366 [MultiRay](INFO): Paused
2022-11-09T00:41:40.774Z,1667954500.774 [MultiRay](INFO): Paused
2022-11-09T00:41:41.181Z,1667954501.181 [MultiRay](INFO): Paused
2022-11-09T00:41:41.585Z,1667954501.585 [MultiRay](INFO): Paused
2022-11-09T00:41:41.996Z,1667954501.996 [MultiRay](INFO): Paused
2022-11-09T00:41:42.393Z,1667954502.393 [MultiRay](INFO): Paused
2022-11-09T00:41:42.794Z,1667954502.794 [MultiRay](INFO): Paused
2022-11-09T00:41:43.201Z,1667954503.201 [MultiRay](INFO): Paused
2022-11-09T00:41:43.598Z,1667954503.598 [MultiRay](INFO): Paused
2022-11-09T00:41:44.002Z,1667954504.002 [MultiRay](INFO): Paused
2022-11-09T00:41:44.416Z,1667954504.416 [MultiRay](INFO): Paused
2022-11-09T00:41:44.817Z,1667954504.817 [MultiRay](INFO): Paused
2022-11-09T00:41:45.227Z,1667954505.227 [MultiRay](INFO): Paused
2022-11-09T00:41:45.625Z,1667954505.625 [MultiRay](INFO): Paused
2022-11-09T00:41:46.026Z,1667954506.026 [MultiRay](INFO): Paused
2022-11-09T00:41:46.433Z,1667954506.433 [MultiRay](INFO): Paused
2022-11-09T00:41:46.844Z,1667954506.844 [MultiRay](INFO): Paused
2022-11-09T00:41:47.238Z,1667954507.238 [MultiRay](INFO): Paused
2022-11-09T00:41:47.645Z,1667954507.645 [MultiRay](INFO): Paused
2022-11-09T00:41:48.047Z,1667954508.047 [MultiRay](INFO): Paused
2022-11-09T00:41:48.453Z,1667954508.453 [MultiRay](INFO): Paused
2022-11-09T00:41:48.857Z,1667954508.857 [MultiRay](INFO): Paused
2022-11-09T00:41:49.261Z,1667954509.261 [MultiRay](INFO): Paused
2022-11-09T00:41:49.663Z,1667954509.663 [MultiRay](INFO): Paused
2022-11-09T00:41:50.065Z,1667954510.065 [MultiRay](INFO): Paused
2022-11-09T00:41:50.470Z,1667954510.470 [MultiRay](INFO): Paused
2022-11-09T00:41:50.870Z,1667954510.870 [MultiRay](INFO): Paused
2022-11-09T00:41:51.281Z,1667954511.281 [MultiRay](INFO): Paused
2022-11-09T00:41:51.682Z,1667954511.682 [MultiRay](INFO): Paused
2022-11-09T00:41:52.086Z,1667954512.086 [MultiRay](INFO): Paused
2022-11-09T00:41:52.493Z,1667954512.493 [MultiRay](INFO): Paused
2022-11-09T00:41:52.894Z,1667954512.894 [MultiRay](INFO): Paused
2022-11-09T00:41:53.303Z,1667954513.303 [MultiRay](INFO): Paused
2022-11-09T00:41:53.709Z,1667954513.709 [MultiRay](INFO): Paused
2022-11-09T00:41:54.109Z,1667954514.109 [MultiRay](INFO): Paused
2022-11-09T00:41:54.514Z,1667954514.514 [MultiRay](INFO): Paused
2022-11-09T00:41:54.915Z,1667954514.915 [MultiRay](INFO): Paused
2022-11-09T00:41:55.321Z,1667954515.321 [MultiRay](INFO): Paused
2022-11-09T00:41:55.722Z,1667954515.722 [MultiRay](INFO): Paused
2022-11-09T00:41:56.130Z,1667954516.130 [MultiRay](INFO): Paused
2022-11-09T00:41:56.533Z,1667954516.533 [MultiRay](INFO): Paused
2022-11-09T00:41:56.943Z,1667954516.943 [MultiRay](INFO): Paused
2022-11-09T00:41:57.338Z,1667954517.338 [MultiRay](INFO): Paused
2022-11-09T00:41:57.742Z,1667954517.742 [MultiRay](INFO): Paused
2022-11-09T00:41:58.145Z,1667954518.145 [MultiRay](INFO): Paused
2022-11-09T00:41:58.561Z,1667954518.561 [MultiRay](INFO): Paused
2022-11-09T00:41:58.957Z,1667954518.957 [MultiRay](INFO): Paused
2022-11-09T00:41:59.358Z,1667954519.358 [MultiRay](INFO): Paused
2022-11-09T00:41:59.766Z,1667954519.766 [MultiRay](INFO): Paused
2022-11-09T00:42:00.173Z,1667954520.173 [MultiRay](INFO): Paused
2022-11-09T00:42:00.570Z,1667954520.570 [MultiRay](INFO): Paused
2022-11-09T00:42:00.978Z,1667954520.978 [MultiRay](INFO): Paused
2022-11-09T00:42:01.374Z,1667954521.374 [MultiRay](INFO): Paused
2022-11-09T00:42:01.783Z,1667954521.783 [MultiRay](INFO): Paused
2022-11-09T00:42:02.199Z,1667954522.199 [MultiRay](INFO): Paused
2022-11-09T00:42:02.590Z,1667954522.590 [MultiRay](INFO): Paused
2022-11-09T00:42:02.995Z,1667954522.995 [MultiRay](INFO): Paused
2022-11-09T00:42:03.399Z,1667954523.399 [MultiRay](INFO): Paused
2022-11-09T00:42:03.802Z,1667954523.802 [MultiRay](INFO): Paused
2022-11-09T00:42:04.202Z,1667954524.202 [MultiRay](INFO): Paused
2022-11-09T00:42:04.610Z,1667954524.610 [MultiRay](INFO): Paused
2022-11-09T00:42:05.016Z,1667954525.016 [MultiRay](INFO): Paused
2022-11-09T00:42:05.421Z,1667954525.421 [MultiRay](INFO): Paused
2022-11-09T00:42:05.825Z,1667954525.825 [MultiRay](INFO): Paused
2022-11-09T00:42:06.226Z,1667954526.226 [MultiRay](INFO): Paused
2022-11-09T00:42:06.631Z,1667954526.631 [MultiRay](INFO): Paused
2022-11-09T00:42:07.057Z,1667954527.057 [MultiRay](INFO): Paused
2022-11-09T00:42:07.437Z,1667954527.437 [MultiRay](INFO): Paused
2022-11-09T00:42:07.857Z,1667954527.857 [MultiRay](INFO): Paused
2022-11-09T00:42:08.266Z,1667954528.266 [MultiRay](INFO): Paused
2022-11-09T00:42:08.661Z,1667954528.661 [MultiRay](INFO): Paused
2022-11-09T00:42:09.065Z,1667954529.065 [MultiRay](INFO): Paused
2022-11-09T00:42:09.466Z,1667954529.466 [MultiRay](INFO): Paused
2022-11-09T00:42:09.861Z,1667954529.861 [MultiRay](INFO): Paused
2022-11-09T00:42:10.273Z,1667954530.273 [MultiRay](INFO): Paused
2022-11-09T00:42:10.673Z,1667954530.673 [MultiRay](INFO): Paused
2022-11-09T00:42:11.074Z,1667954531.074 [MultiRay](INFO): Paused
2022-11-09T00:42:11.478Z,1667954531.478 [MultiRay](INFO): Paused
2022-11-09T00:42:11.889Z,1667954531.889 [MultiRay](INFO): Paused
2022-11-09T00:42:12.286Z,1667954532.286 [MultiRay](INFO): Paused
2022-11-09T00:42:12.690Z,1667954532.690 [MultiRay](INFO): Paused
2022-11-09T00:42:13.094Z,1667954533.094 [MultiRay](INFO): Paused
2022-11-09T00:42:13.501Z,1667954533.501 [MultiRay](INFO): Paused
2022-11-09T00:42:13.911Z,1667954533.911 [MultiRay](INFO): Paused
2022-11-09T00:42:14.313Z,1667954534.313 [MultiRay](INFO): Paused
2022-11-09T00:42:14.710Z,1667954534.710 [MultiRay](INFO): Paused
2022-11-09T00:42:15.120Z,1667954535.120 [MultiRay](INFO): Paused
2022-11-09T00:42:15.521Z,1667954535.521 [MultiRay](INFO): Paused
2022-11-09T00:42:15.922Z,1667954535.922 [MultiRay](INFO): Paused
2022-11-09T00:42:16.326Z,1667954536.326 [MultiRay](INFO): Paused
2022-11-09T00:42:16.731Z,1667954536.731 [MultiRay](INFO): Paused
2022-11-09T00:42:17.134Z,1667954537.134 [MultiRay](INFO): Paused
2022-11-09T00:42:17.538Z,1667954537.538 [MultiRay](INFO): Paused
2022-11-09T00:42:17.942Z,1667954537.942 [MultiRay](INFO): Paused
2022-11-09T00:42:18.349Z,1667954538.349 [MultiRay](INFO): Paused
2022-11-09T00:42:18.746Z,1667954538.746 [MultiRay](INFO): Paused
2022-11-09T00:42:19.154Z,1667954539.154 [MultiRay](INFO): Paused
2022-11-09T00:42:19.561Z,1667954539.561 [MultiRay](INFO): Paused
2022-11-09T00:42:19.962Z,1667954539.962 [MultiRay](INFO): Paused
2022-11-09T00:42:20.371Z,1667954540.371 [MultiRay](INFO): Paused
2022-11-09T00:42:20.766Z,1667954540.766 [MultiRay](INFO): Paused
2022-11-09T00:42:21.170Z,1667954541.170 [MultiRay](INFO): Paused
2022-11-09T00:42:21.582Z,1667954541.582 [MultiRay](INFO): Paused
2022-11-09T00:42:21.983Z,1667954541.983 [MultiRay](INFO): Paused
2022-11-09T00:42:22.386Z,1667954542.386 [MultiRay](INFO): Paused
2022-11-09T00:42:22.790Z,1667954542.790 [MultiRay](INFO): Paused
2022-11-09T00:42:23.197Z,1667954543.197 [MultiRay](INFO): Paused
2022-11-09T00:42:23.598Z,1667954543.598 [MultiRay](INFO): Paused
2022-11-09T00:42:24.002Z,1667954544.002 [MultiRay](INFO): Paused
2022-11-09T00:42:24.402Z,1667954544.402 [MultiRay](INFO): Paused
2022-11-09T00:42:24.809Z,1667954544.809 [MultiRay](INFO): Paused
2022-11-09T00:42:25.216Z,1667954545.216 [MultiRay](INFO): Paused
2022-11-09T00:42:25.617Z,1667954545.617 [MultiRay](INFO): Paused
2022-11-09T00:42:26.025Z,1667954546.025 [MultiRay](INFO): Paused
2022-11-09T00:42:26.442Z,1667954546.442 [MultiRay](INFO): Paused
2022-11-09T00:42:26.827Z,1667954546.827 [MultiRay](INFO): Paused
2022-11-09T00:42:27.243Z,1667954547.243 [MultiRay](INFO): Paused
2022-11-09T00:42:27.635Z,1667954547.635 [MultiRay](INFO): Paused
2022-11-09T00:42:28.043Z,1667954548.043 [MultiRay](INFO): Paused
2022-11-09T00:42:28.450Z,1667954548.450 [MultiRay](INFO): Paused
2022-11-09T00:42:28.860Z,1667954548.860 [MultiRay](INFO): Paused
2022-11-09T00:42:29.257Z,1667954549.257 [MultiRay](INFO): Paused
2022-11-09T00:42:29.658Z,1667954549.658 [MultiRay](INFO): Paused
2022-11-09T00:42:30.062Z,1667954550.062 [MultiRay](INFO): Paused
2022-11-09T00:42:30.473Z,1667954550.473 [MultiRay](INFO): Paused
2022-11-09T00:42:30.874Z,1667954550.874 [MultiRay](INFO): Paused
2022-11-09T00:42:31.274Z,1667954551.274 [MultiRay](INFO): Paused
2022-11-09T00:42:31.678Z,1667954551.678 [MultiRay](INFO): Paused
2022-11-09T00:42:32.089Z,1667954552.089 [MultiRay](INFO): Paused
2022-11-09T00:42:32.490Z,1667954552.490 [MultiRay](INFO): Paused
2022-11-09T00:42:32.886Z,1667954552.886 [MultiRay](INFO): Paused
2022-11-09T00:42:33.297Z,1667954553.297 [MultiRay](INFO): Paused
2022-11-09T00:42:33.695Z,1667954553.695 [MultiRay](INFO): Paused
2022-11-09T00:42:34.102Z,1667954554.102 [MultiRay](INFO): Paused
2022-11-09T00:42:34.506Z,1667954554.506 [MultiRay](INFO): Paused
2022-11-09T00:42:34.906Z,1667954554.906 [MultiRay](INFO): Paused
2022-11-09T00:42:35.318Z,1667954555.318 [MultiRay](INFO): Paused
2022-11-09T00:42:35.721Z,1667954555.721 [MultiRay](INFO): Paused
2022-11-09T00:42:36.125Z,1667954556.125 [MultiRay](INFO): Paused
2022-11-09T00:42:36.527Z,1667954556.527 [MultiRay](INFO): Paused
2022-11-09T00:42:36.927Z,1667954556.927 [MultiRay](INFO): Paused
2022-11-09T00:42:37.343Z,1667954557.343 [MultiRay](INFO): Paused
2022-11-09T00:42:37.738Z,1667954557.738 [MultiRay](INFO): Paused
2022-11-09T00:42:38.142Z,1667954558.142 [MultiRay](INFO): Paused
2022-11-09T00:42:38.557Z,1667954558.557 [MultiRay](INFO): Paused
2022-11-09T00:42:38.965Z,1667954558.965 [MultiRay](INFO): Paused
2022-11-09T00:42:39.358Z,1667954559.358 [MultiRay](INFO): Paused
2022-11-09T00:42:39.762Z,1667954559.762 [MultiRay](INFO): Paused
2022-11-09T00:42:40.159Z,1667954560.159 [MultiRay](INFO): Paused
2022-11-09T00:42:40.567Z,1667954560.567 [MultiRay](INFO): Paused
2022-11-09T00:42:40.973Z,1667954560.973 [MultiRay](INFO): Paused
2022-11-09T00:42:41.370Z,1667954561.370 [MultiRay](INFO): Paused
2022-11-09T00:42:41.778Z,1667954561.778 [MultiRay](INFO): Paused
2022-11-09T00:42:42.190Z,1667954562.190 [MultiRay](INFO): Paused
2022-11-09T00:42:42.585Z,1667954562.585 [MultiRay](INFO): Paused
2022-11-09T00:42:42.993Z,1667954562.993 [MultiRay](INFO): Paused
2022-11-09T00:42:43.397Z,1667954563.397 [MultiRay](INFO): Paused
2022-11-09T00:42:43.797Z,1667954563.797 [MultiRay](INFO): Paused
2022-11-09T00:42:44.201Z,1667954564.201 [MultiRay](INFO): Paused
2022-11-09T00:42:44.609Z,1667954564.609 [MultiRay](INFO): Paused
2022-11-09T00:42:44.897Z,1667954564.897 [CommandExec](IMPORTANT): got command quit
2022-11-09T00:42:45.014Z,1667954565.014 [MultiRay](INFO): Paused
2022-11-09T00:42:45.424Z,1667954565.424 [MultiRay](INFO): Paused
2022-11-09T00:42:45.817Z,1667954565.817 [MultiRay](INFO): Paused
2022-11-09T00:42:45.900Z,1667954565.900 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-11-09T00:42:45.901Z,1667954565.901 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:45.901Z,1667954565.901 [CommandExec](INFO): Uninitializing the command executive.
2022-11-09T00:42:45.901Z,1667954565.901 [CommandExec](INFO): Uninitializing the command scheduler.
2022-11-09T00:42:45.901Z,1667954565.901 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:46.032Z,1667954566.032 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-11-09T00:42:46.032Z,1667954566.032 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-11-09T00:42:46.032Z,1667954566.032 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:46.033Z,1667954566.033 [NavChartDb](INFO): Join timeout helper Thread ID is 4114
2022-11-09T00:42:46.221Z,1667954566.221 [MultiRay](INFO): Paused
2022-11-09T00:42:46.401Z,1667954566.401 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:46.401Z,1667954566.401 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:46.416Z,1667954566.416 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-11-09T00:42:46.416Z,1667954566.416 [DAT ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:46.417Z,1667954566.417 [DAT](INFO): Join timeout helper Thread ID is 4115
2022-11-09T00:42:46.608Z,1667954566.608 [DAT](INFO): Powering down
2022-11-09T00:42:46.636Z,1667954566.636 [MultiRay](INFO): Paused
2022-11-09T00:42:46.685Z,1667954566.685 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:46.685Z,1667954566.685 [DAT](INFO): Powering down
2022-11-09T00:42:46.686Z,1667954566.686 [DAT ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:46.688Z,1667954566.688 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-11-09T00:42:46.689Z,1667954566.689 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:46.689Z,1667954566.689 [Radio_Surface](INFO): Join timeout helper Thread ID is 4116
2022-11-09T00:42:46.833Z,1667954566.833 [Radio_Surface](INFO): Powering down
2022-11-09T00:42:46.834Z,1667954566.834 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:46.834Z,1667954566.834 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:46.840Z,1667954566.840 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-11-09T00:42:46.840Z,1667954566.840 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:46.841Z,1667954566.841 [Onboard](INFO): Join timeout helper Thread ID is 4117
2022-11-09T00:42:47.030Z,1667954567.030 [MultiRay](INFO): Paused
2022-11-09T00:42:47.434Z,1667954567.434 [MultiRay](INFO): Paused
2022-11-09T00:42:47.838Z,1667954567.838 [MultiRay](INFO): Paused
2022-11-09T00:42:48.253Z,1667954568.253 [MultiRay](INFO): Paused
2022-11-09T00:42:48.642Z,1667954568.642 [MultiRay](INFO): Paused
2022-11-09T00:42:48.697Z,1667954568.697 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-11-09T00:42:49.046Z,1667954569.046 [MultiRay](INFO): Paused
2022-11-09T00:42:49.432Z,1667954569.432 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:49.433Z,1667954569.433 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:49.448Z,1667954569.448 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-11-09T00:42:49.448Z,1667954569.448 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:49.449Z,1667954569.449 [DataOverHttps](INFO): Join timeout helper Thread ID is 4118
2022-11-09T00:42:49.489Z,1667954569.489 [MultiRay](INFO): Paused
2022-11-09T00:42:49.700Z,1667954569.700 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:49.701Z,1667954569.701 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:49.713Z,1667954569.713 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-11-09T00:42:49.713Z,1667954569.713 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:49.714Z,1667954569.714 [BackseatComponent](INFO): Join timeout helper Thread ID is 4119
2022-11-09T00:42:49.857Z,1667954569.857 [MultiRay](INFO): Paused
2022-11-09T00:42:49.908Z,1667954569.908 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:49.909Z,1667954569.909 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:49.937Z,1667954569.937 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-11-09T00:42:49.937Z,1667954569.937 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:49.938Z,1667954569.938 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4120
2022-11-09T00:42:50.266Z,1667954570.266 [MultiRay](INFO): Paused
2022-11-09T00:42:50.312Z,1667954570.312 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:50.313Z,1667954570.313 [WetLabsBB2FL](INFO): Powering down
2022-11-09T00:42:50.313Z,1667954570.313 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.325Z,1667954570.325 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-11-09T00:42:50.325Z,1667954570.325 [logger ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.325Z,1667954570.325 [logger](INFO): Join timeout helper Thread ID is 4121
2022-11-09T00:42:50.329Z,1667954570.329 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:50.329Z,1667954570.329 [logger ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.345Z,1667954570.345 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-11-09T00:42:50.345Z,1667954570.345 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.345Z,1667954570.345 [CommandLine](INFO): Join timeout helper Thread ID is 4122
2022-11-09T00:42:50.404Z,1667954570.404 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:50.405Z,1667954570.405 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.425Z,1667954570.425 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-11-09T00:42:50.425Z,1667954570.425 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.425Z,1667954570.425 [CommandExec](INFO): Join timeout helper Thread ID is 4123
2022-11-09T00:42:50.427Z,1667954570.427 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-11-09T00:42:50.427Z,1667954570.427 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.427Z,1667954570.427 [controlThread](INFO): Join timeout helper Thread ID is 4124
2022-11-09T00:42:50.648Z,1667954570.648 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-09T00:42:50.649Z,1667954570.649 [controlThread](DEBUG): Uninitializing ControlThread
2022-11-09T00:42:50.649Z,1667954570.649 [AHRS_M2](INFO): Powering down
2022-11-09T00:42:50.721Z,1667954570.721 [MultiRay](INFO): Powering down.
2022-11-09T00:42:50.722Z,1667954570.722 [NAL9602](INFO): Powering down
2022-11-09T00:42:50.724Z,1667954570.724 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-11-09T00:42:50.725Z,1667954570.725 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-11-09T00:42:50.725Z,1667954570.725 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-11-09T00:42:50.726Z,1667954570.726 [MissionManager](INFO): Uninitializing Mission Default
2022-11-09T00:42:50.726Z,1667954570.726 [Default] Stopped
2022-11-09T00:42:50.726Z,1667954570.726 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-09T00:42:50.726Z,1667954570.726 [Default:B.GoToSurface] Stopped
2022-11-09T00:42:50.726Z,1667954570.726 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-09T00:42:50.726Z,1667954570.726 [Default:CheckIn] Stopped
2022-11-09T00:42:50.726Z,1667954570.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-09T00:42:50.727Z,1667954570.727 [Default:CheckIn:Read_GPS] Stopped
2022-11-09T00:42:50.729Z,1667954570.729 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-11-09T00:42:50.730Z,1667954570.730 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-11-09T00:42:50.730Z,1667954570.730 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-11-09T00:42:50.730Z,1667954570.730 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-11-09T00:42:50.731Z,1667954570.731 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-11-09T00:42:50.731Z,1667954570.731 [BuoyancyServo](INFO): Powering down
2022-11-09T00:42:50.744Z,1667954570.744 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-11-09T00:42:50.744Z,1667954570.744 [ElevatorServo](INFO): Powering down
2022-11-09T00:42:50.745Z,1667954570.745 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-11-09T00:42:50.745Z,1667954570.745 [MassServo](INFO): Powering down
2022-11-09T00:42:50.746Z,1667954570.746 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-11-09T00:42:50.746Z,1667954570.746 [RudderServo](INFO): Powering down
2022-11-09T00:42:50.747Z,1667954570.747 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-11-09T00:42:50.747Z,1667954570.747 [ThrusterHE](INFO): Powering down
2022-11-09T00:42:50.748Z,1667954570.748 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-11-09T00:42:50.749Z,1667954570.749 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-11-09T00:42:50.749Z,1667954570.749 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-11-09T00:42:50.749Z,1667954570.749 [CBIT](DEBUG): Powering off loads.
2022-11-09T00:42:50.760Z,1667954570.760 [CBIT](DEBUG): Disabling WDT.
2022-11-09T00:42:50.772Z,1667954570.772 [CBIT](DEBUG): Opening all GF detection circuits.
2022-11-09T00:42:50.773Z,1667954570.773 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.811Z,1667954570.811 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.857Z,1667954570.857 [DAT ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.861Z,1667954570.861 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.864Z,1667954570.864 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.871Z,1667954570.871 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.914Z,1667954570.914 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:50.955Z,1667954570.955 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-09T00:42:51.014Z,1667954571.014 [logger ThreadHandler](INFO): Thread cancelled.