2022-11-08T18:45:20.026Z,1667933120.026 [Supervisor](DEBUG): Initializing supervisor. 2022-11-08T18:45:20.030Z,1667933120.030 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-11-08T18:45:20.031Z,1667933120.031 [SyncHandler](INFO): Protected caller Thread ID is 835 2022-11-08T18:45:20.031Z,1667933120.031 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-11-08T18:45:20.032Z,1667933120.032 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-11-08T18:45:20.033Z,1667933120.033 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836 2022-11-08T18:45:20.037Z,1667933120.037 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-11-08T18:45:20.054Z,1667933120.054 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-11-08T18:45:20.055Z,1667933120.055 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-11-08T18:45:20.055Z,1667933120.055 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837 2022-11-08T18:45:20.059Z,1667933120.059 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-11-08T18:45:20.060Z,1667933120.060 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-11-08T18:45:20.061Z,1667933120.061 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838 2022-11-08T18:45:20.063Z,1667933120.063 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-11-08T18:45:20.064Z,1667933120.064 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-11-08T18:45:20.064Z,1667933120.064 [logger ThreadHandler](INFO): Protected caller Thread ID is 839 2022-11-08T18:45:20.068Z,1667933120.068 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-11-08T18:45:20.068Z,1667933120.068 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-11-08T18:45:20.072Z,1667933120.072 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-11-08T18:45:20.519Z,1667933120.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-11-08T18:45:20.521Z,1667933120.521 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-11-08T18:45:20.744Z,1667933120.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-11-08T18:45:20.745Z,1667933120.745 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-11-08T18:45:20.938Z,1667933120.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-11-08T18:45:20.939Z,1667933120.939 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-11-08T18:45:21.047Z,1667933121.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-11-08T18:45:21.048Z,1667933121.048 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-11-08T18:45:21.444Z,1667933121.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-11-08T18:45:21.446Z,1667933121.446 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-11-08T18:45:21.780Z,1667933121.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-11-08T18:45:21.782Z,1667933121.782 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-11-08T18:45:21.878Z,1667933121.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-11-08T18:45:21.880Z,1667933121.880 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-11-08T18:45:22.105Z,1667933122.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-11-08T18:45:22.107Z,1667933122.107 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-11-08T18:45:22.637Z,1667933122.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-11-08T18:45:22.638Z,1667933122.638 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-11-08T18:45:23.002Z,1667933123.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-11-08T18:45:23.003Z,1667933123.003 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-11-08T18:45:23.085Z,1667933123.085 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-11-08T18:45:23.302Z,1667933123.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-11-08T18:45:23.302Z,1667933123.302 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-11-08T18:45:23.543Z,1667933123.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-11-08T18:45:23.544Z,1667933123.544 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-11-08T18:45:24.621Z,1667933124.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-11-08T18:45:24.622Z,1667933124.622 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-11-08T18:45:24.702Z,1667933124.702 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-11-08T18:45:25.001Z,1667933125.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-11-08T18:45:25.003Z,1667933125.003 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2022-11-08T18:45:25.006Z,1667933125.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2022-11-08T18:45:25.205Z,1667933125.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2022-11-08T18:45:25.299Z,1667933125.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2022-11-08T18:45:25.414Z,1667933125.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2022-11-08T18:45:25.516Z,1667933125.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2022-11-08T18:45:25.600Z,1667933125.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2022-11-08T18:45:25.687Z,1667933125.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2022-11-08T18:45:25.802Z,1667933125.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2022-11-08T18:45:25.903Z,1667933125.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2022-11-08T18:45:26.061Z,1667933126.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2022-11-08T18:45:26.333Z,1667933126.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-11-08T18:45:26.334Z,1667933126.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2022-11-08T18:45:26.429Z,1667933126.429 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2022-11-08T18:45:26.433Z,1667933126.433 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-11-08T18:45:26.828Z,1667933126.828 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-11-08T18:45:26.829Z,1667933126.829 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-11-08T18:45:26.974Z,1667933126.974 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-11-08T18:45:26.975Z,1667933126.975 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-11-08T18:45:27.015Z,1667933127.015 [DeadReckonUsingSpeedCalculator] Loaded 2022-11-08T18:45:27.015Z,1667933127.015 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2022-11-08T18:45:27.028Z,1667933127.028 [NavChart] Loaded 2022-11-08T18:45:27.029Z,1667933127.029 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-11-08T18:45:27.034Z,1667933127.034 [UniversalFixResidualReporter] Loaded 2022-11-08T18:45:27.035Z,1667933127.035 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-11-08T18:45:27.035Z,1667933127.035 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-11-08T18:45:27.036Z,1667933127.036 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-11-08T18:45:27.052Z,1667933127.052 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-11-08T18:45:27.052Z,1667933127.052 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-11-08T18:45:27.306Z,1667933127.306 [CTD_Seabird] Loaded 2022-11-08T18:45:27.306Z,1667933127.306 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-11-08T18:45:27.307Z,1667933127.307 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4065A4E0 2022-11-08T18:45:27.308Z,1667933127.308 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 921 2022-11-08T18:45:27.328Z,1667933127.328 [PAR_Licor] Loaded 2022-11-08T18:45:27.329Z,1667933127.329 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-11-08T18:45:27.360Z,1667933127.360 [WetLabsBB2FL] Loaded 2022-11-08T18:45:27.360Z,1667933127.360 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-11-08T18:45:27.362Z,1667933127.362 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4068A4E0 2022-11-08T18:45:27.362Z,1667933127.362 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 922 2022-11-08T18:45:27.363Z,1667933127.363 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-11-08T18:45:27.364Z,1667933127.364 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-11-08T18:45:27.437Z,1667933127.437 [VerticalControl](DEBUG): Construct VerticalControl. 2022-11-08T18:45:27.500Z,1667933127.500 [VerticalControl] Loaded 2022-11-08T18:45:27.500Z,1667933127.500 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-11-08T18:45:27.503Z,1667933127.503 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-11-08T18:45:27.545Z,1667933127.545 [HorizontalControl] Loaded 2022-11-08T18:45:27.545Z,1667933127.545 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-11-08T18:45:27.548Z,1667933127.548 [SpeedControl](DEBUG): Construct SpeedControl. 2022-11-08T18:45:27.551Z,1667933127.551 [SpeedControl] Loaded 2022-11-08T18:45:27.551Z,1667933127.551 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-11-08T18:45:27.554Z,1667933127.554 [LoopControl](DEBUG): Construct LoopControl. 2022-11-08T18:45:27.555Z,1667933127.555 [LoopControl] Loaded 2022-11-08T18:45:27.555Z,1667933127.555 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-11-08T18:45:27.555Z,1667933127.555 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-11-08T18:45:27.556Z,1667933127.556 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-11-08T18:45:27.630Z,1667933127.630 [DepthRateCalculator] Loaded 2022-11-08T18:45:27.630Z,1667933127.630 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-11-08T18:45:27.635Z,1667933127.635 [PitchRateCalculator] Loaded 2022-11-08T18:45:27.636Z,1667933127.636 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-11-08T18:45:27.645Z,1667933127.645 [SpeedCalculator] Loaded 2022-11-08T18:45:27.646Z,1667933127.646 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-11-08T18:45:27.650Z,1667933127.650 [YawRateCalculator] Loaded 2022-11-08T18:45:27.650Z,1667933127.650 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-11-08T18:45:27.669Z,1667933127.669 [ElevatorOffsetCalculator] Loaded 2022-11-08T18:45:27.670Z,1667933127.670 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-11-08T18:45:27.670Z,1667933127.670 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-11-08T18:45:27.671Z,1667933127.671 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-11-08T18:45:27.800Z,1667933127.800 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-11-08T18:45:27.801Z,1667933127.801 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-11-08T18:45:27.889Z,1667933127.889 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-11-08T18:45:27.890Z,1667933127.890 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-11-08T18:45:28.051Z,1667933128.051 [BuoyancyServo] Loaded 2022-11-08T18:45:28.052Z,1667933128.052 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-11-08T18:45:28.072Z,1667933128.072 [ElevatorServo] Loaded 2022-11-08T18:45:28.072Z,1667933128.072 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-11-08T18:45:28.092Z,1667933128.092 [MassServo] Loaded 2022-11-08T18:45:28.092Z,1667933128.092 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-11-08T18:45:28.112Z,1667933128.112 [RudderServo] Loaded 2022-11-08T18:45:28.112Z,1667933128.112 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-11-08T18:45:28.127Z,1667933128.127 [ThrusterHE] Loaded 2022-11-08T18:45:28.128Z,1667933128.128 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-11-08T18:45:28.128Z,1667933128.128 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-11-08T18:45:28.129Z,1667933128.129 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-11-08T18:45:28.330Z,1667933128.330 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-11-08T18:45:28.332Z,1667933128.332 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-11-08T18:45:29.083Z,1667933129.083 [AHRS_M2] Loaded 2022-11-08T18:45:29.083Z,1667933129.083 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-11-08T18:45:29.334Z,1667933129.334 [BackseatComponent] Loaded 2022-11-08T18:45:29.334Z,1667933129.334 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-11-08T18:45:29.335Z,1667933129.335 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0 2022-11-08T18:45:29.335Z,1667933129.335 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923 2022-11-08T18:45:29.338Z,1667933129.338 [LcmUniversalReporter] Loaded 2022-11-08T18:45:29.339Z,1667933129.339 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-11-08T18:45:30.191Z,1667933130.191 [BPC1] Loaded 2022-11-08T18:45:30.191Z,1667933130.191 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-11-08T18:45:30.333Z,1667933130.333 [DataOverHttps] Loaded 2022-11-08T18:45:30.334Z,1667933130.334 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-11-08T18:45:30.335Z,1667933130.335 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0 2022-11-08T18:45:30.335Z,1667933130.335 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2022-11-08T18:45:30.357Z,1667933130.357 [Depth_Keller] Loaded 2022-11-08T18:45:30.357Z,1667933130.357 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-11-08T18:45:30.362Z,1667933130.362 [DropWeight] Loaded 2022-11-08T18:45:30.362Z,1667933130.362 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-11-08T18:45:30.380Z,1667933130.380 [MultiRay] Loaded 2022-11-08T18:45:30.381Z,1667933130.381 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2022-11-08T18:45:30.446Z,1667933130.446 [NAL9602] Loaded 2022-11-08T18:45:30.446Z,1667933130.446 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-11-08T18:45:30.477Z,1667933130.477 [Onboard] Loaded 2022-11-08T18:45:30.477Z,1667933130.477 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-11-08T18:45:30.478Z,1667933130.478 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0 2022-11-08T18:45:30.479Z,1667933130.479 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2022-11-08T18:45:30.485Z,1667933130.485 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2022-11-08T18:45:30.496Z,1667933130.496 [PowerOnly] Loaded 2022-11-08T18:45:30.497Z,1667933130.497 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2022-11-08T18:45:30.509Z,1667933130.509 [Power24vConverter] Loaded 2022-11-08T18:45:30.509Z,1667933130.509 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-11-08T18:45:30.523Z,1667933130.523 [Radio_Surface] Loaded 2022-11-08T18:45:30.523Z,1667933130.523 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-11-08T18:45:30.524Z,1667933130.524 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0 2022-11-08T18:45:30.525Z,1667933130.525 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2022-11-08T18:45:30.621Z,1667933130.621 [DAT] Loaded 2022-11-08T18:45:30.621Z,1667933130.621 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2022-11-08T18:45:30.622Z,1667933130.622 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B454E0 2022-11-08T18:45:30.622Z,1667933130.622 [DAT ThreadHandler](INFO): Protected caller Thread ID is 927 2022-11-08T18:45:30.623Z,1667933130.623 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-11-08T18:45:30.625Z,1667933130.625 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-11-08T18:45:31.403Z,1667933131.403 [SBIT](DEBUG): Construct Startup Built In Test. 2022-11-08T18:45:31.413Z,1667933131.413 [SBIT] Loaded 2022-11-08T18:45:31.414Z,1667933131.414 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-11-08T18:45:31.416Z,1667933131.416 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-11-08T18:45:31.429Z,1667933131.429 [IBIT] Loaded 2022-11-08T18:45:31.430Z,1667933131.430 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-11-08T18:45:31.435Z,1667933131.435 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-11-08T18:45:31.543Z,1667933131.543 [CBIT] Loaded 2022-11-08T18:45:31.543Z,1667933131.543 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-11-08T18:45:31.544Z,1667933131.544 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-11-08T18:45:31.544Z,1667933131.544 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-11-08T18:45:31.589Z,1667933131.589 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-11-08T18:45:31.595Z,1667933131.595 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-11-08T18:45:31.598Z,1667933131.598 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-11-08T18:45:31.609Z,1667933131.609 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-11-08T18:45:31.610Z,1667933131.610 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD34E0 2022-11-08T18:45:31.610Z,1667933131.610 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928 2022-11-08T18:45:31.615Z,1667933131.615 [Supervisor](INFO): Main Thread ID is 830 2022-11-08T18:45:31.615Z,1667933131.615 [Supervisor](DEBUG): Running supervisor. 2022-11-08T18:45:31.616Z,1667933131.616 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929 2022-11-08T18:45:31.616Z,1667933131.616 [CommandExec](INFO): Initializing the command executive. 2022-11-08T18:45:31.618Z,1667933131.618 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930 2022-11-08T18:45:31.620Z,1667933131.620 [controlThread ThreadHandler](INFO): Handler Thread ID is 931 2022-11-08T18:45:31.620Z,1667933131.620 [controlThread](DEBUG): Initializing ControlThread 2022-11-08T18:45:31.622Z,1667933131.622 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T18:45:31.622Z,1667933131.622 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-11-08T18:45:31.623Z,1667933131.623 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-11-08T18:45:31.625Z,1667933131.625 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-11-08T18:45:31.627Z,1667933131.627 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-11-08T18:45:31.628Z,1667933131.628 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-11-08T18:45:31.628Z,1667933131.628 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-11-08T18:45:31.629Z,1667933131.629 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-11-08T18:45:31.629Z,1667933131.629 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-11-08T18:45:31.630Z,1667933131.630 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-11-08T18:45:31.630Z,1667933131.630 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-11-08T18:45:31.630Z,1667933131.630 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-11-08T18:45:31.639Z,1667933131.639 [SBIT](INFO): Initialize SBIT Component. 2022-11-08T18:45:31.640Z,1667933131.640 [SBIT](IMPORTANT): git: 2022-10-11-45-gb822ed017 2022-11-08T18:45:31.640Z,1667933131.640 [SBIT](INFO): git hash: b822ed017b998e9f5c0380c5dda45bcbd9b57e7d 2022-11-08T18:45:31.640Z,1667933131.640 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-11-08T18:45:31.642Z,1667933131.642 [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-08T18:45:31.643Z,1667933131.643 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2022-11-08T18:45:31.643Z,1667933131.643 [IBIT](INFO): Initialize IBIT Component. 2022-11-08T18:45:31.644Z,1667933131.644 [CBIT](DEBUG): Initialize CBIT Component. 2022-11-08T18:45:31.645Z,1667933131.645 [logger ThreadHandler](INFO): Handler Thread ID is 932 2022-11-08T18:45:31.657Z,1667933131.657 [CBIT](DEBUG): Initialized mux pins. 2022-11-08T18:45:31.657Z,1667933131.657 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2022-11-08T18:45:31.657Z,1667933131.657 [CBIT](DEBUG): Initializing the watchdog timer. 2022-11-08T18:45:31.665Z,1667933131.665 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 933 2022-11-08T18:45:31.666Z,1667933131.666 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-11-08T18:45:31.671Z,1667933131.671 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 935 2022-11-08T18:45:31.674Z,1667933131.674 [WetLabsBB2FL](INFO): Powering up 2022-11-08T18:45:31.675Z,1667933131.675 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937 2022-11-08T18:45:31.681Z,1667933131.681 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2022-11-08T18:45:31.682Z,1667933131.682 [CBIT](DEBUG): Initializing heartbeat. 2022-11-08T18:45:31.683Z,1667933131.683 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938 2022-11-08T18:45:31.683Z,1667933131.683 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-11-08T18:45:31.689Z,1667933131.689 [Onboard ThreadHandler](INFO): Handler Thread ID is 939 2022-11-08T18:45:31.720Z,1667933131.720 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940 2022-11-08T18:45:31.725Z,1667933131.725 [DAT ThreadHandler](INFO): Handler Thread ID is 941 2022-11-08T18:45:31.726Z,1667933131.726 [DAT](INFO): Powering up 2022-11-08T18:45:31.726Z,1667933131.726 [DAT](DEBUG): Initializing DAT. 2022-11-08T18:45:31.730Z,1667933131.730 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2022-11-08T18:45:31.738Z,1667933131.738 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-11-08T18:45:31.738Z,1667933131.738 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-11-08T18:45:31.739Z,1667933131.739 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-11-08T18:45:31.739Z,1667933131.739 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-11-08T18:45:31.739Z,1667933131.739 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-11-08T18:45:31.739Z,1667933131.739 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-11-08T18:45:31.739Z,1667933131.739 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-11-08T18:45:31.739Z,1667933131.739 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-11-08T18:45:31.753Z,1667933131.753 [CBIT](DEBUG): Deactivating GF circuits. 2022-11-08T18:45:31.753Z,1667933131.753 [CBIT](DEBUG): Deactivating emergency mode. 2022-11-08T18:45:31.789Z,1667933131.789 [CBIT](DEBUG): Backplane powered. 2022-11-08T18:45:31.790Z,1667933131.790 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-11-08T18:45:31.822Z,1667933131.822 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-11-08T18:45:31.849Z,1667933131.849 [MissionManager](DEBUG): 2022-11-08T18:45:31.849Z,1667933131.849 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-11-08T18:45:31.919Z,1667933131.919 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-11-08T18:45:31.920Z,1667933131.920 [Default:A.Wait](DEBUG): Construct Wait. 2022-11-08T18:45:31.934Z,1667933131.934 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-11-08T18:45:31.963Z,1667933131.963 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-11-08T18:45:31.965Z,1667933131.965 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-11-08T18:45:31.980Z,1667933131.980 [Default:E.Execute](DEBUG): Construct Execute. 2022-11-08T18:45:31.984Z,1667933131.984 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2022-11-08T18:45:32.001Z,1667933132.001 [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-08T18:45:32.013Z,1667933132.013 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-11-08T18:45:32.121Z,1667933132.121 [Radio_Surface](INFO): Powering up 2022-11-08T18:45:32.158Z,1667933132.158 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar 2022-11-08T18:45:32.158Z,1667933132.158 [MultiRay](INFO): Powering up MultiRay Lights 2022-11-08T18:45:32.202Z,1667933132.202 [Power24vConverter](INFO): Powering up. 2022-11-08T18:45:32.209Z,1667933132.209 [DepthRateCalculator](ERROR): Depth measurement is not active 2022-11-08T18:45:32.243Z,1667933132.243 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-11-08T18:45:32.249Z,1667933132.249 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-11-08T18:45:32.250Z,1667933132.250 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-11-08T18:45:32.257Z,1667933132.257 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-11-08T18:45:32.258Z,1667933132.258 [MassServo](DEBUG): Initializing EZServoServo. 2022-11-08T18:45:32.265Z,1667933132.265 [MassServo](DEBUG): Initializing MassServo. 2022-11-08T18:45:32.266Z,1667933132.266 [RudderServo](DEBUG): Initializing EZServoServo. 2022-11-08T18:45:32.277Z,1667933132.277 [RudderServo](DEBUG): Initializing RudderServo. 2022-11-08T18:45:32.278Z,1667933132.278 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-11-08T18:45:32.286Z,1667933132.286 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-11-08T18:45:32.288Z,1667933132.288 [CommandExec](FAULT): Scheduling is paused 2022-11-08T18:45:32.288Z,1667933132.288 [CBIT](INFO): Critical error at 20221108T184531 2022-11-08T18:45:32.288Z,1667933132.288 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-11-08T18:45:32.456Z,1667933132.456 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-11-08T18:45:32.456Z,1667933132.456 [DropWeight] Hardware Fault, FailCount= 1 2022-11-08T18:45:32.456Z,1667933132.456 [DropWeight](ERROR): Hardware Fault 2022-11-08T18:45:32.482Z,1667933132.482 [MultiRay](IMPORTANT): All lights off 2022-11-08T18:45:32.520Z,1667933132.520 [CBIT](INFO): Critical error at 20221108T184532 2022-11-08T18:45:32.531Z,1667933132.531 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-11-08T18:45:32.531Z,1667933132.531 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-11-08T18:45:33.173Z,1667933133.173 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-11-08T18:45:33.173Z,1667933133.173 [RudderServo](FAULT): Rudder failed to initialize 2022-11-08T18:45:33.173Z,1667933133.173 [RudderServo] Communications Fault, FailCount= 1 2022-11-08T18:45:33.173Z,1667933133.173 [RudderServo](ERROR): Communications Fault 2022-11-08T18:45:33.175Z,1667933133.175 [CBIT](INFO): Critical error at 20221108T184532 2022-11-08T18:45:33.178Z,1667933133.178 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-11-08T18:45:33.374Z,1667933133.374 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-11-08T18:45:33.374Z,1667933133.374 [RudderServo](INFO): Powering down 2022-11-08T18:45:34.050Z,1667933134.050 [RudderServo](DEBUG): Initializing EZServoServo. 2022-11-08T18:45:34.170Z,1667933134.170 [RudderServo](DEBUG): Initializing RudderServo. 2022-11-08T18:45:34.174Z,1667933134.174 [CBIT](INFO): Clearing failed state for component RudderServo 2022-11-08T18:45:34.174Z,1667933134.174 [RudderServo] No Fault, FailCount= 1 2022-11-08T18:45:34.285Z,1667933134.285 [WetLabsBB2FL](INFO): Powering down 2022-11-08T18:45:37.942Z,1667933137.942 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-11-08T18:45:38.698Z,1667933138.698 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2022-11-08T18:45:47.100Z,1667933147.100 [DAT](INFO): commRate: 800 2022-11-08T18:45:49.165Z,1667933149.165 [DAT](INFO): entering command mode 2022-11-08T18:45:49.366Z,1667933149.366 [DAT](INFO): setting verbose to 3 2022-11-08T18:45:49.619Z,1667933149.619 [DAT](INFO): set verbose to 3 2022-11-08T18:45:49.619Z,1667933149.619 [DAT](INFO): setting DatVerbose to 27440 2022-11-08T18:45:49.872Z,1667933149.872 [DAT](INFO): set DatVerbose to 27440 2022-11-08T18:45:49.873Z,1667933149.873 [DAT](INFO): setting transmit power to 8 2022-11-08T18:45:50.127Z,1667933150.127 [DAT](INFO): set transmit power to 8 2022-11-08T18:45:50.127Z,1667933150.127 [DAT](INFO): setting local address to 10 2022-11-08T18:45:50.379Z,1667933150.379 [DAT](INFO): set local address to 10 2022-11-08T18:45:50.380Z,1667933150.380 [DAT](INFO): Setting time to: 18:45:50 And date to:11/8/2022 2022-11-08T18:45:50.631Z,1667933150.631 [DAT](INFO): Local DAT time set to Tue Nov 8, 2022 18:45:50 2022-11-08T18:46:00.366Z,1667933160.366 [SBIT](IMPORTANT): Beginning Startup BIT 2022-11-08T18:46:00.371Z,1667933160.371 [CBIT](IMPORTANT): Beginning ground fault scan 2022-11-08T18:46:00.700Z,1667933160.700 [NAL9602](INFO): Powering up NAL9602 2022-11-08T18:46:11.314Z,1667933171.314 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.012642 CHAN A1 (24V): 0.000490 CHAN A2 (12V): -0.005690 CHAN A3 (5V): -0.003449 CHAN B0 (3.3V): -0.001520 CHAN B1 (3.15aV): -0.001918 CHAN B2 (3.15bV): -0.001518 CHAN B3 (GND): -0.000592 OPEN: 0.006738 Full Scale: +/- 1 mA 2022-11-08T18:46:11.619Z,1667933171.619 [NAL9602](INFO): NAL9602 initialized 2022-11-08T18:46:13.711Z,1667933173.711 [SBIT](FAULT): Mass: EXPECTED:0.009400 ACTUAL:0.007323 2022-11-08T18:46:54.454Z,1667933214.454 [SBIT](CRITICAL): SBIT FAILED 2022-11-08T18:46:54.454Z,1667933214.454 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2022-11-08T18:46:54.455Z,1667933214.455 [CBIT](INFO): Critical error at 20221108T184654 2022-11-08T18:46:54.851Z,1667933214.851 [MissionManager](IMPORTANT): Started mission Startup 2022-11-08T18:46:54.851Z,1667933214.851 [Startup] Running Loop=1 2022-11-08T18:46:54.852Z,1667933214.852 [Startup](DEBUG): Aggregate::initialize Startup 2022-11-08T18:46:54.852Z,1667933214.852 [Startup:A.GoToSurface] Running Loop=1 2022-11-08T18:46:54.852Z,1667933214.852 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:46:54.852Z,1667933214.852 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:46:54.853Z,1667933214.853 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:46:54.873Z,1667933214.873 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:46:54.874Z,1667933214.874 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:46:54.874Z,1667933214.874 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:46:54.876Z,1667933214.876 [Startup:StartupSatComms] Running Loop=1 2022-11-08T18:46:54.876Z,1667933214.876 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-11-08T18:46:54.876Z,1667933214.876 [Startup:StartupSatComms:A] Running Loop=1 2022-11-08T18:46:55.248Z,1667933215.248 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-11-08T18:47:55.063Z,1667933275.063 [Startup:StartupSatComms:A](INFO): Timed out from 2022-11-08T18:46:54.9Z 2022-11-08T18:47:55.063Z,1667933275.063 [Startup:StartupSatComms:A] Stopped 2022-11-08T18:47:55.064Z,1667933275.064 [Startup:StartupSatComms:B] Running Loop=1 2022-11-08T18:47:55.464Z,1667933275.464 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-11-08T18:48:05.684Z,1667933285.684 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004898 2022-11-08T18:48:15.033Z,1667933295.033 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221108T182539/Courier0004.lzma 2022-11-08T18:48:16.036Z,1667933296.036 [DataOverHttps](INFO): Moved sent file to Logs/20221108T182539/Courier0004.lzma.bak 2022-11-08T18:48:16.036Z,1667933296.036 [DataOverHttps](INFO): SBD MOMSN=17274093 2022-11-08T18:48:31.657Z,1667933311.657 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221108T184031/Courier0000.lzma 2022-11-08T18:48:32.002Z,1667933312.002 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-11-08T18:48:32.002Z,1667933312.002 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T18:48:32.003Z,1667933312.003 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2022-11-08T18:48:32.003Z,1667933312.003 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T18:48:32.014Z,1667933312.014 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T18:48:32.014Z,1667933312.014 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T18:48:32.420Z,1667933312.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T18:48:32.420Z,1667933312.420 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-11-08T18:48:32.421Z,1667933312.421 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T18:48:32.421Z,1667933312.421 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2022-11-08T18:48:32.660Z,1667933312.660 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184031/Courier0000.lzma.bak 2022-11-08T18:48:32.660Z,1667933312.660 [DataOverHttps](INFO): SBD MOMSN=17274095 2022-11-08T18:48:32.834Z,1667933312.834 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T18:48:48.470Z,1667933328.470 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20221108T184519/Courier0000.lzma 2022-11-08T18:48:49.472Z,1667933329.472 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Courier0000.lzma.bak 2022-11-08T18:48:49.472Z,1667933329.472 [DataOverHttps](INFO): SBD MOMSN=17274098 2022-11-08T18:48:54.652Z,1667933334.652 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:48:54.652Z,1667933334.652 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:48:54.733Z,1667933334.733 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:48:54.744Z,1667933334.744 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:48:54.751Z,1667933334.751 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:48:54.759Z,1667933334.759 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:48:54.766Z,1667933334.766 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:48:54.795Z,1667933334.795 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:48:54.797Z,1667933334.797 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:48:55.065Z,1667933335.065 [Startup:StartupSatComms:B](INFO): Timed out from 2022-11-08T18:47:55.1Z 2022-11-08T18:48:55.065Z,1667933335.065 [Startup:StartupSatComms:B] Stopped 2022-11-08T18:48:55.065Z,1667933335.065 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-11-08T18:48:55.065Z,1667933335.065 [Startup:StartupSatComms] Stopped 2022-11-08T18:48:55.065Z,1667933335.065 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-11-08T18:48:55.066Z,1667933335.066 [Startup](INFO): Completed Startup 2022-11-08T18:48:55.066Z,1667933335.066 [MissionManager](INFO): Startup is completed. 2022-11-08T18:48:55.066Z,1667933335.066 [MissionManager](INFO): Uninitializing Mission Startup 2022-11-08T18:48:55.066Z,1667933335.066 [Startup] Stopped 2022-11-08T18:48:55.066Z,1667933335.066 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-11-08T18:48:55.067Z,1667933335.067 [Startup:A.GoToSurface] Stopped 2022-11-08T18:48:55.067Z,1667933335.067 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:48:55.440Z,1667933335.440 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:48:55.440Z,1667933335.440 [multiray_lab] Running Loop=1 2022-11-08T18:48:55.440Z,1667933335.440 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:48:56.656Z,1667933336.656 [multiray_lab](INFO): Timed out from 2022-11-08T18:48:55.4Z 2022-11-08T18:48:56.656Z,1667933336.656 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:48:56.656Z,1667933336.656 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:48:56.656Z,1667933336.656 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:48:56.656Z,1667933336.656 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:48:56.656Z,1667933336.656 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:48:56.657Z,1667933336.657 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:48:56.657Z,1667933336.657 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:48:56.657Z,1667933336.657 [multiray_lab] Stopped 2022-11-08T18:48:56.657Z,1667933336.657 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:48:57.141Z,1667933337.141 [MultiRay](INFO): Red lights on 2022-11-08T18:48:57.245Z,1667933337.245 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:48:57.245Z,1667933337.245 [Default] Running Loop=1 2022-11-08T18:48:57.245Z,1667933337.245 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:48:57.245Z,1667933337.245 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:48:57.245Z,1667933337.245 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:48:57.245Z,1667933337.245 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:48:57.253Z,1667933337.253 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:48:57.253Z,1667933337.253 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:48:57.258Z,1667933337.258 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:48:57.258Z,1667933337.258 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:48:57.274Z,1667933337.274 [Default:A.Wait] Running Loop=1 2022-11-08T18:48:57.274Z,1667933337.274 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:49:02.640Z,1667933342.640 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T18:49:02.641Z,1667933342.641 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T18:49:02.676Z,1667933342.676 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:49:02.711Z,1667933342.711 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:49:02.713Z,1667933342.713 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:49:02.720Z,1667933342.720 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:49:02.730Z,1667933342.730 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:49:02.743Z,1667933342.743 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:49:02.744Z,1667933342.744 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T18:49:02.752Z,1667933342.752 [Default] Stopped 2022-11-08T18:49:02.752Z,1667933342.752 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:49:02.752Z,1667933342.752 [Default:A.Wait] Stopped 2022-11-08T18:49:02.752Z,1667933342.752 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:49:02.752Z,1667933342.752 [Default:B.GoToSurface] Stopped 2022-11-08T18:49:02.753Z,1667933342.753 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:49:02.753Z,1667933342.753 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:49:02.753Z,1667933342.753 [multiray_lab] Running Loop=1 2022-11-08T18:49:02.753Z,1667933342.753 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:49:03.928Z,1667933343.928 [multiray_lab](INFO): Timed out from 2022-11-08T18:49:02.8Z 2022-11-08T18:49:03.928Z,1667933343.928 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:49:03.928Z,1667933343.928 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:49:03.929Z,1667933343.929 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:49:03.929Z,1667933343.929 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:49:03.929Z,1667933343.929 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:49:03.929Z,1667933343.929 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:49:03.929Z,1667933343.929 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:49:03.929Z,1667933343.929 [multiray_lab] Stopped 2022-11-08T18:49:03.929Z,1667933343.929 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:49:04.398Z,1667933344.398 [MultiRay](IMPORTANT): All lights off 2022-11-08T18:49:04.438Z,1667933344.438 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:49:04.442Z,1667933344.442 [Default] Running Loop=1 2022-11-08T18:49:04.442Z,1667933344.442 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:49:04.442Z,1667933344.442 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:49:04.442Z,1667933344.442 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:49:04.443Z,1667933344.443 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:49:04.443Z,1667933344.443 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:49:04.443Z,1667933344.443 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:49:04.443Z,1667933344.443 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:49:04.444Z,1667933344.444 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:49:04.444Z,1667933344.444 [Default:A.Wait] Running Loop=1 2022-11-08T18:49:04.444Z,1667933344.444 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:49:05.304Z,1667933345.304 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:05.304Z,1667933345.304 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:05.342Z,1667933345.342 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:49:05.344Z,1667933345.344 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:49:05.350Z,1667933345.350 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:49:05.352Z,1667933345.352 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:49:05.400Z,1667933345.400 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:49:05.409Z,1667933345.409 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:49:05.418Z,1667933345.418 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:05.555Z,1667933345.555 [Default] Stopped 2022-11-08T18:49:05.555Z,1667933345.555 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:49:05.555Z,1667933345.555 [Default:A.Wait] Stopped 2022-11-08T18:49:05.555Z,1667933345.555 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:49:05.555Z,1667933345.555 [Default:B.GoToSurface] Stopped 2022-11-08T18:49:05.555Z,1667933345.555 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:49:05.555Z,1667933345.555 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:49:05.556Z,1667933345.556 [multiray_lab] Running Loop=1 2022-11-08T18:49:05.556Z,1667933345.556 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:49:06.756Z,1667933346.756 [multiray_lab](INFO): Timed out from 2022-11-08T18:49:05.6Z 2022-11-08T18:49:06.756Z,1667933346.756 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:49:06.757Z,1667933346.757 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:49:06.757Z,1667933346.757 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:49:06.757Z,1667933346.757 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:49:06.757Z,1667933346.757 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:49:06.782Z,1667933346.782 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:49:06.782Z,1667933346.782 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:49:06.782Z,1667933346.782 [multiray_lab] Stopped 2022-11-08T18:49:06.782Z,1667933346.782 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:49:07.204Z,1667933347.204 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:49:07.204Z,1667933347.204 [Default] Running Loop=1 2022-11-08T18:49:07.205Z,1667933347.205 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:49:07.205Z,1667933347.205 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:49:07.205Z,1667933347.205 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:49:07.205Z,1667933347.205 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:49:07.205Z,1667933347.205 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:49:07.206Z,1667933347.206 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:49:07.206Z,1667933347.206 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:49:07.206Z,1667933347.206 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:49:07.207Z,1667933347.207 [Default:A.Wait] Running Loop=1 2022-11-08T18:49:07.207Z,1667933347.207 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:49:08.519Z,1667933348.519 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20221107T225542/Express0035.lzma 2022-11-08T18:49:09.520Z,1667933349.520 [DataOverHttps](INFO): Moved sent file to Logs/20221107T225542/Express0035.lzma.bak 2022-11-08T18:49:09.520Z,1667933349.520 [DataOverHttps](INFO): SBD MOMSN=17274108 2022-11-08T18:49:12.212Z,1667933352.212 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:12.212Z,1667933352.212 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:12.231Z,1667933352.231 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:49:12.233Z,1667933352.233 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:49:12.251Z,1667933352.251 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:49:12.254Z,1667933352.254 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:49:12.265Z,1667933352.265 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:49:12.277Z,1667933352.277 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:49:12.283Z,1667933352.283 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:12.411Z,1667933352.411 [Default] Stopped 2022-11-08T18:49:12.411Z,1667933352.411 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:49:12.411Z,1667933352.411 [Default:A.Wait] Stopped 2022-11-08T18:49:12.411Z,1667933352.411 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:49:12.411Z,1667933352.411 [Default:B.GoToSurface] Stopped 2022-11-08T18:49:12.411Z,1667933352.411 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:49:12.411Z,1667933352.411 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:49:12.411Z,1667933352.411 [multiray_lab] Running Loop=1 2022-11-08T18:49:12.412Z,1667933352.412 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:49:13.616Z,1667933353.616 [multiray_lab](INFO): Timed out from 2022-11-08T18:49:12.4Z 2022-11-08T18:49:13.616Z,1667933353.616 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:49:13.616Z,1667933353.616 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:49:13.616Z,1667933353.616 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:49:13.617Z,1667933353.617 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:49:13.617Z,1667933353.617 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:49:13.617Z,1667933353.617 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:49:13.617Z,1667933353.617 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:49:13.617Z,1667933353.617 [multiray_lab] Stopped 2022-11-08T18:49:13.617Z,1667933353.617 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:49:14.092Z,1667933354.092 [MultiRay](INFO): Red lights on 2022-11-08T18:49:14.142Z,1667933354.142 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:49:14.143Z,1667933354.143 [Default] Running Loop=1 2022-11-08T18:49:14.143Z,1667933354.143 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:49:14.143Z,1667933354.143 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:49:14.143Z,1667933354.143 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:49:14.143Z,1667933354.143 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:49:14.143Z,1667933354.143 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:49:14.144Z,1667933354.144 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:49:14.144Z,1667933354.144 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:49:14.144Z,1667933354.144 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:49:14.145Z,1667933354.145 [Default:A.Wait] Running Loop=1 2022-11-08T18:49:14.145Z,1667933354.145 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:49:18.772Z,1667933358.772 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:18.772Z,1667933358.772 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:18.805Z,1667933358.805 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:49:18.807Z,1667933358.807 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:49:18.809Z,1667933358.809 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:49:18.812Z,1667933358.812 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:49:18.814Z,1667933358.814 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:49:18.818Z,1667933358.818 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:49:18.820Z,1667933358.820 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode1.xml 2022-11-08T18:49:18.907Z,1667933358.907 [Default] Stopped 2022-11-08T18:49:18.907Z,1667933358.907 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:49:18.907Z,1667933358.907 [Default:A.Wait] Stopped 2022-11-08T18:49:18.907Z,1667933358.907 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:49:18.907Z,1667933358.907 [Default:B.GoToSurface] Stopped 2022-11-08T18:49:18.908Z,1667933358.908 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:49:18.908Z,1667933358.908 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:49:18.908Z,1667933358.908 [multiray_lab] Running Loop=1 2022-11-08T18:49:18.908Z,1667933358.908 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:49:20.106Z,1667933360.106 [multiray_lab](INFO): Timed out from 2022-11-08T18:49:18.9Z 2022-11-08T18:49:20.107Z,1667933360.107 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:49:20.107Z,1667933360.107 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:49:20.107Z,1667933360.107 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:49:20.107Z,1667933360.107 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:49:20.107Z,1667933360.107 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:49:20.108Z,1667933360.108 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:49:20.108Z,1667933360.108 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:49:20.108Z,1667933360.108 [multiray_lab] Stopped 2022-11-08T18:49:20.108Z,1667933360.108 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:49:20.583Z,1667933360.583 [MultiRay](INFO): Red lights on 2022-11-08T18:49:20.641Z,1667933360.641 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:49:20.641Z,1667933360.641 [Default] Running Loop=1 2022-11-08T18:49:20.641Z,1667933360.641 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:49:20.641Z,1667933360.641 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:49:20.641Z,1667933360.641 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:49:20.642Z,1667933360.642 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:49:20.642Z,1667933360.642 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:49:20.643Z,1667933360.643 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:49:20.643Z,1667933360.643 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:49:20.643Z,1667933360.643 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:49:20.643Z,1667933360.643 [Default:A.Wait] Running Loop=1 2022-11-08T18:49:20.644Z,1667933360.644 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:49:27.716Z,1667933367.716 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:49:27.717Z,1667933367.717 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:49:27.765Z,1667933367.765 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:49:27.771Z,1667933367.771 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:49:27.773Z,1667933367.773 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:49:27.780Z,1667933367.780 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:49:27.814Z,1667933367.814 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:49:27.828Z,1667933367.828 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:49:27.834Z,1667933367.834 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:49:28.177Z,1667933368.177 [Default] Stopped 2022-11-08T18:49:28.177Z,1667933368.177 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:49:28.177Z,1667933368.177 [Default:A.Wait] Stopped 2022-11-08T18:49:28.177Z,1667933368.177 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:49:28.177Z,1667933368.177 [Default:B.GoToSurface] Stopped 2022-11-08T18:49:28.177Z,1667933368.177 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:49:28.177Z,1667933368.177 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:49:28.177Z,1667933368.177 [multiray_lab] Running Loop=1 2022-11-08T18:49:28.178Z,1667933368.178 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:49:29.456Z,1667933369.456 [multiray_lab](INFO): Timed out from 2022-11-08T18:49:28.2Z 2022-11-08T18:49:29.456Z,1667933369.456 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:49:29.456Z,1667933369.456 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:49:29.457Z,1667933369.457 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:49:29.459Z,1667933369.459 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:49:29.459Z,1667933369.459 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:49:29.460Z,1667933369.460 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:49:29.460Z,1667933369.460 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:49:29.461Z,1667933369.461 [multiray_lab] Stopped 2022-11-08T18:49:29.463Z,1667933369.463 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:49:29.871Z,1667933369.871 [MultiRay](INFO): White lights on 2022-11-08T18:49:29.921Z,1667933369.921 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:49:29.921Z,1667933369.921 [Default] Running Loop=1 2022-11-08T18:49:29.921Z,1667933369.921 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:49:29.921Z,1667933369.921 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:49:29.921Z,1667933369.921 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:49:29.926Z,1667933369.926 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:49:29.926Z,1667933369.926 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:49:29.927Z,1667933369.927 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:49:29.927Z,1667933369.927 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:49:29.927Z,1667933369.927 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:49:29.927Z,1667933369.927 [Default:A.Wait] Running Loop=1 2022-11-08T18:49:29.928Z,1667933369.928 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:49:32.664Z,1667933372.664 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T18:49:32.665Z,1667933372.665 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T18:49:32.719Z,1667933372.719 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:49:32.721Z,1667933372.721 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:49:32.727Z,1667933372.727 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:49:32.729Z,1667933372.729 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:49:32.751Z,1667933372.751 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:49:32.775Z,1667933372.775 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:49:32.776Z,1667933372.776 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T18:49:32.801Z,1667933372.801 [Default] Stopped 2022-11-08T18:49:32.801Z,1667933372.801 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:49:32.801Z,1667933372.801 [Default:A.Wait] Stopped 2022-11-08T18:49:32.801Z,1667933372.801 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:49:32.802Z,1667933372.802 [Default:B.GoToSurface] Stopped 2022-11-08T18:49:32.809Z,1667933372.809 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:49:32.809Z,1667933372.809 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:49:32.809Z,1667933372.809 [multiray_lab] Running Loop=1 2022-11-08T18:49:32.809Z,1667933372.809 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:49:33.844Z,1667933373.844 [multiray_lab](INFO): Timed out from 2022-11-08T18:49:32.8Z 2022-11-08T18:49:33.844Z,1667933373.844 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:49:33.845Z,1667933373.845 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:49:33.845Z,1667933373.845 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:49:33.845Z,1667933373.845 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:49:33.845Z,1667933373.845 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:49:33.846Z,1667933373.846 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:49:33.846Z,1667933373.846 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:49:33.846Z,1667933373.846 [multiray_lab] Stopped 2022-11-08T18:49:33.846Z,1667933373.846 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:49:34.303Z,1667933374.303 [MultiRay](IMPORTANT): All lights off 2022-11-08T18:49:34.320Z,1667933374.320 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:49:34.321Z,1667933374.321 [Default] Running Loop=1 2022-11-08T18:49:34.321Z,1667933374.321 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:49:34.321Z,1667933374.321 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:49:34.321Z,1667933374.321 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:49:34.321Z,1667933374.321 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:49:34.321Z,1667933374.321 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:49:34.322Z,1667933374.322 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:49:34.322Z,1667933374.322 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:49:34.323Z,1667933374.323 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:49:34.323Z,1667933374.323 [Default:A.Wait] Running Loop=1 2022-11-08T18:49:34.323Z,1667933374.323 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:49:47.593Z,1667933387.593 [Default:A.Wait](INFO): Done Waiting. 2022-11-08T18:49:47.593Z,1667933387.593 [Default:A.Wait] Stopped 2022-11-08T18:49:47.593Z,1667933387.593 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:49:47.997Z,1667933387.997 [Default:CheckIn] Running Loop=1 2022-11-08T18:49:47.997Z,1667933387.997 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-08T18:49:47.997Z,1667933387.997 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-08T18:49:48.375Z,1667933388.375 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-11-08T18:51:07.951Z,1667933467.951 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2022-11-08T18:51:15.221Z,1667933475.221 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-11-08T18:51:33.077Z,1667933493.077 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-11-08T18:51:33.077Z,1667933493.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T18:51:33.079Z,1667933493.079 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2022-11-08T18:51:33.079Z,1667933493.079 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T18:51:33.089Z,1667933493.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T18:51:33.089Z,1667933493.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T18:51:33.490Z,1667933493.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T18:51:33.490Z,1667933493.490 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-11-08T18:51:33.491Z,1667933493.491 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T18:51:33.491Z,1667933493.491 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2022-11-08T18:51:33.870Z,1667933493.870 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T18:52:20.929Z,1667933540.929 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:20.930Z,1667933540.930 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:20.963Z,1667933540.963 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:52:20.965Z,1667933540.965 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:52:20.968Z,1667933540.968 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:52:20.969Z,1667933540.969 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:52:20.972Z,1667933540.972 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:52:20.977Z,1667933540.977 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:52:20.978Z,1667933540.978 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:21.144Z,1667933541.144 [Default] Stopped 2022-11-08T18:52:21.144Z,1667933541.144 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:52:21.144Z,1667933541.144 [Default:B.GoToSurface] Stopped 2022-11-08T18:52:21.144Z,1667933541.144 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:52:21.144Z,1667933541.144 [Default:CheckIn] Stopped 2022-11-08T18:52:21.144Z,1667933541.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-08T18:52:21.144Z,1667933541.144 [Default:CheckIn:Read_GPS] Stopped 2022-11-08T18:52:21.145Z,1667933541.145 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:52:21.145Z,1667933541.145 [multiray_lab] Running Loop=1 2022-11-08T18:52:21.145Z,1667933541.145 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:52:22.409Z,1667933542.409 [multiray_lab](INFO): Timed out from 2022-11-08T18:52:21.1Z 2022-11-08T18:52:22.409Z,1667933542.409 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:52:22.409Z,1667933542.409 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:52:22.410Z,1667933542.410 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:52:22.410Z,1667933542.410 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:52:22.410Z,1667933542.410 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:52:22.410Z,1667933542.410 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:52:22.411Z,1667933542.411 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:52:22.411Z,1667933542.411 [multiray_lab] Stopped 2022-11-08T18:52:22.411Z,1667933542.411 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:52:22.795Z,1667933542.795 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:52:22.795Z,1667933542.795 [Default] Running Loop=1 2022-11-08T18:52:22.796Z,1667933542.796 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:52:22.796Z,1667933542.796 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:52:22.796Z,1667933542.796 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:52:22.796Z,1667933542.796 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:52:22.796Z,1667933542.796 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:52:22.797Z,1667933542.797 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:52:22.797Z,1667933542.797 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:52:22.797Z,1667933542.797 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:52:22.798Z,1667933542.798 [Default:A.Wait] Running Loop=1 2022-11-08T18:52:22.798Z,1667933542.798 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:52:27.905Z,1667933547.905 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:27.905Z,1667933547.905 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:27.923Z,1667933547.923 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:52:27.925Z,1667933547.925 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:52:27.927Z,1667933547.927 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:52:27.929Z,1667933547.929 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:52:27.931Z,1667933547.931 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:52:27.936Z,1667933547.936 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:52:27.937Z,1667933547.937 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:28.030Z,1667933548.030 [Default] Stopped 2022-11-08T18:52:28.030Z,1667933548.030 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:52:28.030Z,1667933548.030 [Default:A.Wait] Stopped 2022-11-08T18:52:28.030Z,1667933548.030 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:52:28.031Z,1667933548.031 [Default:B.GoToSurface] Stopped 2022-11-08T18:52:28.031Z,1667933548.031 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:52:28.031Z,1667933548.031 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:52:28.031Z,1667933548.031 [multiray_lab] Running Loop=1 2022-11-08T18:52:28.031Z,1667933548.031 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:52:29.229Z,1667933549.229 [multiray_lab](INFO): Timed out from 2022-11-08T18:52:28.0Z 2022-11-08T18:52:29.229Z,1667933549.229 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:52:29.229Z,1667933549.229 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:52:29.229Z,1667933549.229 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:52:29.229Z,1667933549.229 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:52:29.230Z,1667933549.230 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:52:29.230Z,1667933549.230 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:52:29.230Z,1667933549.230 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:52:29.230Z,1667933549.230 [multiray_lab] Stopped 2022-11-08T18:52:29.230Z,1667933549.230 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:52:29.684Z,1667933549.684 [MultiRay](INFO): White lights on 2022-11-08T18:52:29.721Z,1667933549.721 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:52:29.721Z,1667933549.721 [Default] Running Loop=1 2022-11-08T18:52:29.721Z,1667933549.721 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:52:29.722Z,1667933549.722 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:52:29.750Z,1667933549.750 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:52:29.750Z,1667933549.750 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:52:29.750Z,1667933549.750 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:52:29.751Z,1667933549.751 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:52:29.751Z,1667933549.751 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:52:29.751Z,1667933549.751 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:52:29.752Z,1667933549.752 [Default:A.Wait] Running Loop=1 2022-11-08T18:52:29.752Z,1667933549.752 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:52:34.089Z,1667933554.089 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:34.089Z,1667933554.089 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:34.145Z,1667933554.145 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T18:52:34.147Z,1667933554.147 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T18:52:34.149Z,1667933554.149 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T18:52:34.152Z,1667933554.152 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T18:52:34.154Z,1667933554.154 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T18:52:34.178Z,1667933554.178 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T18:52:34.180Z,1667933554.180 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode2.xml 2022-11-08T18:52:34.515Z,1667933554.515 [Default] Stopped 2022-11-08T18:52:34.515Z,1667933554.515 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T18:52:34.515Z,1667933554.515 [Default:A.Wait] Stopped 2022-11-08T18:52:34.515Z,1667933554.515 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:52:34.515Z,1667933554.515 [Default:B.GoToSurface] Stopped 2022-11-08T18:52:34.515Z,1667933554.515 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T18:52:34.515Z,1667933554.515 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T18:52:34.515Z,1667933554.515 [multiray_lab] Running Loop=1 2022-11-08T18:52:34.516Z,1667933554.516 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T18:52:34.855Z,1667933554.855 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-11-08T18:52:34.858Z,1667933554.858 [BPC1](INFO): Received data from all battery sticks. 2022-11-08T18:52:35.692Z,1667933555.692 [multiray_lab](INFO): Timed out from 2022-11-08T18:52:34.5Z 2022-11-08T18:52:35.693Z,1667933555.693 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T18:52:35.693Z,1667933555.693 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T18:52:35.693Z,1667933555.693 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T18:52:35.693Z,1667933555.693 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T18:52:35.693Z,1667933555.693 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T18:52:35.694Z,1667933555.694 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T18:52:35.694Z,1667933555.694 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T18:52:35.694Z,1667933555.694 [multiray_lab] Stopped 2022-11-08T18:52:35.694Z,1667933555.694 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T18:52:36.143Z,1667933556.143 [MultiRay](INFO): White lights on 2022-11-08T18:52:36.187Z,1667933556.187 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T18:52:36.187Z,1667933556.187 [Default] Running Loop=1 2022-11-08T18:52:36.187Z,1667933556.187 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T18:52:36.187Z,1667933556.187 [Default:B.GoToSurface] Running Loop=1 2022-11-08T18:52:36.187Z,1667933556.187 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T18:52:36.187Z,1667933556.187 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T18:52:36.188Z,1667933556.188 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T18:52:36.188Z,1667933556.188 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T18:52:36.188Z,1667933556.188 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T18:52:36.189Z,1667933556.189 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T18:52:36.189Z,1667933556.189 [Default:A.Wait] Running Loop=1 2022-11-08T18:52:36.189Z,1667933556.189 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T18:52:49.405Z,1667933569.405 [Default:A.Wait](INFO): Done Waiting. 2022-11-08T18:52:49.405Z,1667933569.405 [Default:A.Wait] Stopped 2022-11-08T18:52:49.405Z,1667933569.405 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T18:52:49.808Z,1667933569.808 [Default:CheckIn] Running Loop=1 2022-11-08T18:52:49.809Z,1667933569.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-08T18:52:49.809Z,1667933569.809 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-08T18:54:34.039Z,1667933674.039 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2022-11-08T18:54:34.039Z,1667933674.039 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T18:54:34.041Z,1667933674.041 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2022-11-08T18:54:34.041Z,1667933674.041 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T18:54:34.051Z,1667933674.051 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T18:54:34.051Z,1667933674.051 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T18:54:34.472Z,1667933674.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T18:54:34.472Z,1667933674.472 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2022-11-08T18:54:34.472Z,1667933674.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T18:54:34.472Z,1667933674.472 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2022-11-08T18:54:34.873Z,1667933674.873 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T18:56:12.618Z,1667933772.618 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-11-08T18:56:12.618Z,1667933772.618 [NAL9602] Data Fault, FailCount= 1 2022-11-08T18:56:12.618Z,1667933772.618 [NAL9602](ERROR): Data Fault 2022-11-08T18:56:12.635Z,1667933772.635 [CBIT](ERROR): Data Fault in component: NAL9602 2022-11-08T18:56:13.014Z,1667933773.014 [NAL9602](INFO): Powering down 2022-11-08T18:56:13.840Z,1667933773.840 [CBIT](INFO): Clearing failed state for component NAL9602 2022-11-08T18:56:13.840Z,1667933773.840 [NAL9602] No Fault, FailCount= 1 2022-11-08T18:56:43.312Z,1667933803.312 [NAL9602](INFO): Powering up NAL9602 2022-11-08T18:56:54.221Z,1667933814.221 [NAL9602](INFO): NAL9602 initialized 2022-11-08T18:57:35.044Z,1667933855.044 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2022-11-08T18:57:35.044Z,1667933855.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T18:57:35.045Z,1667933855.045 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2022-11-08T18:57:35.045Z,1667933855.045 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T18:57:35.056Z,1667933855.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T18:57:35.056Z,1667933855.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T18:57:35.465Z,1667933855.465 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T18:57:35.465Z,1667933855.465 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2022-11-08T18:57:35.465Z,1667933855.465 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T18:57:35.465Z,1667933855.465 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2022-11-08T18:57:35.858Z,1667933855.858 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T18:57:49.982Z,1667933869.982 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-08T18:52:49.8Z 2022-11-08T18:57:49.982Z,1667933869.982 [Default:CheckIn:Read_GPS] Stopped 2022-11-08T18:57:49.982Z,1667933869.982 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-11-08T18:57:50.397Z,1667933870.397 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-11-08T18:57:57.645Z,1667933877.645 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20221108T184519/Courier0004.lzma 2022-11-08T18:57:58.648Z,1667933878.648 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Courier0004.lzma.bak 2022-11-08T18:57:58.648Z,1667933878.648 [DataOverHttps](INFO): SBD MOMSN=17274131 2022-11-08T18:58:16.733Z,1667933896.733 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20221107T225542/Express0038.lzma 2022-11-08T18:58:16.924Z,1667933896.924 [DataOverHttps](INFO): Moved sent file to Logs/20221107T225542/Express0038.lzma.bak 2022-11-08T18:58:16.924Z,1667933896.924 [DataOverHttps](INFO): SBD MOMSN=17274133 2022-11-08T18:58:33.893Z,1667933913.893 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221108T175608/Express0001.lzma 2022-11-08T18:58:34.896Z,1667933914.896 [DataOverHttps](INFO): Moved sent file to Logs/20221108T175608/Express0001.lzma.bak 2022-11-08T18:58:34.896Z,1667933914.896 [DataOverHttps](INFO): SBD MOMSN=17274140 2022-11-08T18:58:51.985Z,1667933931.985 [DataOverHttps](INFO): Sending 1058 bytes from file Logs/20221108T180042/Express0001.lzma 2022-11-08T18:58:52.980Z,1667933932.980 [DataOverHttps](INFO): Moved sent file to Logs/20221108T180042/Express0001.lzma.bak 2022-11-08T18:58:52.980Z,1667933932.980 [DataOverHttps](INFO): SBD MOMSN=17274144 2022-11-08T18:59:09.869Z,1667933949.869 [DataOverHttps](INFO): Sending 1290 bytes from file Logs/20221108T180042/Express0005.lzma 2022-11-08T18:59:18.164Z,1667933958.164 [DataOverHttps](INFO): Sending 1290 bytes from file Logs/20221108T180042/Express0005.lzma 2022-11-08T18:59:19.164Z,1667933959.164 [DataOverHttps](INFO): Moved sent file to Logs/20221108T180042/Express0005.lzma.bak 2022-11-08T18:59:19.164Z,1667933959.164 [DataOverHttps](INFO): SBD MOMSN=17274171 2022-11-08T18:59:36.085Z,1667933976.085 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221108T180042/Express0008.lzma 2022-11-08T18:59:37.088Z,1667933977.088 [DataOverHttps](INFO): Moved sent file to Logs/20221108T180042/Express0008.lzma.bak 2022-11-08T18:59:37.088Z,1667933977.088 [DataOverHttps](INFO): SBD MOMSN=17274362 2022-11-08T18:59:54.264Z,1667933994.264 [DataOverHttps](INFO): Sending 756 bytes from file Logs/20221108T182539/Express0001.lzma 2022-11-08T18:59:55.264Z,1667933995.264 [DataOverHttps](INFO): Moved sent file to Logs/20221108T182539/Express0001.lzma.bak 2022-11-08T18:59:55.264Z,1667933995.264 [DataOverHttps](INFO): SBD MOMSN=17274365 2022-11-08T19:00:11.609Z,1667934011.609 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221108T182539/Express0005.lzma 2022-11-08T19:00:12.592Z,1667934012.592 [DataOverHttps](INFO): Moved sent file to Logs/20221108T182539/Express0005.lzma.bak 2022-11-08T19:00:12.592Z,1667934012.592 [DataOverHttps](INFO): SBD MOMSN=17274383 2022-11-08T19:00:29.489Z,1667934029.489 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221108T184031/Express0001.lzma 2022-11-08T19:00:30.492Z,1667934030.492 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184031/Express0001.lzma.bak 2022-11-08T19:00:30.492Z,1667934030.492 [DataOverHttps](INFO): SBD MOMSN=17274391 2022-11-08T19:00:32.829Z,1667934032.829 [CBIT](INFO): Clearing failed state for component DropWeight 2022-11-08T19:00:32.829Z,1667934032.829 [DropWeight] No Fault, FailCount= 1 2022-11-08T19:00:36.050Z,1667934036.050 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2022-11-08T19:00:36.050Z,1667934036.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:00:36.052Z,1667934036.052 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2022-11-08T19:00:36.052Z,1667934036.052 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:00:36.078Z,1667934036.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:00:36.079Z,1667934036.079 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:00:36.475Z,1667934036.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:00:36.475Z,1667934036.475 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2022-11-08T19:00:36.476Z,1667934036.476 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:00:36.476Z,1667934036.476 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2022-11-08T19:00:36.859Z,1667934036.859 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:00:47.474Z,1667934047.474 [DataOverHttps](INFO): Sending 740 bytes from file Logs/20221108T184519/Express0001.lzma 2022-11-08T19:00:48.476Z,1667934048.476 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Express0001.lzma.bak 2022-11-08T19:00:48.476Z,1667934048.476 [DataOverHttps](INFO): SBD MOMSN=17274393 2022-11-08T19:01:05.287Z,1667934065.287 [DataOverHttps](INFO): Sending 500 bytes from file Logs/20221108T184519/Express0005.lzma 2022-11-08T19:01:06.288Z,1667934066.288 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Express0005.lzma.bak 2022-11-08T19:01:06.288Z,1667934066.288 [DataOverHttps](INFO): SBD MOMSN=17274408 2022-11-08T19:01:08.764Z,1667934068.764 [Default:CheckIn:Read_Iridium] Stopped 2022-11-08T19:01:08.764Z,1667934068.764 [Default:CheckIn:C.Wait] Running Loop=1 2022-11-08T19:01:08.765Z,1667934068.765 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-11-08T19:01:17.650Z,1667934077.650 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T19:01:17.651Z,1667934077.651 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T19:01:17.747Z,1667934077.747 [MissionManager](INFO): DefineArg multiray_lab.MissionTimeout = 1.000000 min 2022-11-08T19:01:17.749Z,1667934077.749 [MissionManager](INFO): DefineArg multiray_lab.LightDuration = 1.000000 s 2022-11-08T19:01:17.763Z,1667934077.763 [MissionManager](INFO): DefineArg multiray_lab.LightMode0 = 0.000000 count 2022-11-08T19:01:17.765Z,1667934077.765 [MissionManager](INFO): DefineArg multiray_lab.LightMode1 = 1.000000 count 2022-11-08T19:01:17.769Z,1667934077.769 [MissionManager](INFO): DefineArg multiray_lab.LightMode2 = 2.000000 count 2022-11-08T19:01:17.814Z,1667934077.814 [MissionManager](DEBUG): This mission is designed to run in the lab only. Turns on and off MultiRay lights. How long to let the mission run. 1 How long to wait before turning on / off lights, default 1 second 1 Light mode = 0 0 Light mode = 1 1 Light mode = 2 2 2022-11-08T19:01:17.817Z,1667934077.817 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_mode0.xml 2022-11-08T19:01:18.064Z,1667934078.064 [Default] Stopped 2022-11-08T19:01:18.064Z,1667934078.064 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-08T19:01:18.064Z,1667934078.064 [Default:B.GoToSurface] Stopped 2022-11-08T19:01:18.064Z,1667934078.064 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-08T19:01:18.064Z,1667934078.064 [Default:CheckIn] Stopped 2022-11-08T19:01:18.064Z,1667934078.064 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-08T19:01:18.064Z,1667934078.064 [Default:CheckIn:C.Wait] Stopped 2022-11-08T19:01:18.065Z,1667934078.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T19:01:18.065Z,1667934078.065 [MissionManager](IMPORTANT): Started mission multiray_lab 2022-11-08T19:01:18.065Z,1667934078.065 [multiray_lab] Running Loop=1 2022-11-08T19:01:18.065Z,1667934078.065 [multiray_lab](DEBUG): Aggregate::initialize multiray_lab 2022-11-08T19:01:19.277Z,1667934079.277 [multiray_lab](INFO): Timed out from 2022-11-08T19:01:18.1Z 2022-11-08T19:01:19.277Z,1667934079.277 [multiray_lab:A_Timeout] Running Loop=1 2022-11-08T19:01:19.277Z,1667934079.277 [multiray_lab:A_Timeout](DEBUG): Aggregate::initialize multiray_lab:A_Timeout 2022-11-08T19:01:19.277Z,1667934079.277 [multiray_lab:A_Timeout:A] Running Loop=1 2022-11-08T19:01:19.313Z,1667934079.313 [multiray_lab:A_Timeout:A] Stopped 2022-11-08T19:01:19.314Z,1667934079.314 [multiray_lab:A_Timeout](INFO): Completed multiray_lab:A_Timeout 2022-11-08T19:01:19.322Z,1667934079.322 [MissionManager](INFO): multiray_lab is completed. 2022-11-08T19:01:19.322Z,1667934079.322 [MissionManager](INFO): Uninitializing Mission multiray_lab 2022-11-08T19:01:19.322Z,1667934079.322 [multiray_lab] Stopped 2022-11-08T19:01:19.322Z,1667934079.322 [multiray_lab](DEBUG): Aggregate::uninitialize multiray_lab 2022-11-08T19:01:19.739Z,1667934079.739 [MultiRay](IMPORTANT): All lights off 2022-11-08T19:01:19.757Z,1667934079.757 [MissionManager](IMPORTANT): Started mission Default 2022-11-08T19:01:19.757Z,1667934079.757 [Default] Running Loop=1 2022-11-08T19:01:19.757Z,1667934079.757 [Default](DEBUG): Aggregate::initialize Default 2022-11-08T19:01:19.757Z,1667934079.757 [Default:B.GoToSurface] Running Loop=1 2022-11-08T19:01:19.757Z,1667934079.757 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-08T19:01:19.790Z,1667934079.790 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-08T19:01:19.790Z,1667934079.790 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-08T19:01:19.791Z,1667934079.791 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-08T19:01:19.791Z,1667934079.791 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-08T19:01:19.791Z,1667934079.791 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-08T19:01:19.792Z,1667934079.792 [Default:A.Wait] Running Loop=1 2022-11-08T19:01:19.792Z,1667934079.792 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-08T19:01:33.057Z,1667934093.057 [Default:A.Wait](INFO): Done Waiting. 2022-11-08T19:01:33.057Z,1667934093.057 [Default:A.Wait] Stopped 2022-11-08T19:01:33.057Z,1667934093.057 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T19:01:33.468Z,1667934093.468 [Default:CheckIn] Running Loop=1 2022-11-08T19:01:33.468Z,1667934093.468 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-08T19:01:33.468Z,1667934093.468 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-08T19:03:37.065Z,1667934217.065 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2022-11-08T19:03:37.065Z,1667934217.065 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:03:37.066Z,1667934217.066 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2022-11-08T19:03:37.066Z,1667934217.066 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:03:37.077Z,1667934217.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:03:37.077Z,1667934217.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:03:37.489Z,1667934217.489 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:03:37.489Z,1667934217.489 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2022-11-08T19:03:37.490Z,1667934217.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:03:37.490Z,1667934217.490 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2022-11-08T19:03:37.882Z,1667934217.882 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:06:33.625Z,1667934393.625 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-08T19:01:33.5Z 2022-11-08T19:06:33.625Z,1667934393.625 [Default:CheckIn:Read_GPS] Stopped 2022-11-08T19:06:33.625Z,1667934393.625 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-11-08T19:06:38.042Z,1667934398.042 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2022-11-08T19:06:38.046Z,1667934398.046 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:06:38.047Z,1667934398.047 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2022-11-08T19:06:38.047Z,1667934398.047 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:06:38.072Z,1667934398.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:06:38.072Z,1667934398.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:06:38.486Z,1667934398.486 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:06:38.486Z,1667934398.486 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2022-11-08T19:06:38.487Z,1667934398.487 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:06:38.487Z,1667934398.487 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2022-11-08T19:06:38.900Z,1667934398.900 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:06:40.637Z,1667934400.637 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221108T184519/Courier0007.lzma 2022-11-08T19:06:41.640Z,1667934401.640 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Courier0007.lzma.bak 2022-11-08T19:06:41.640Z,1667934401.640 [DataOverHttps](INFO): SBD MOMSN=17274460 2022-11-08T19:06:57.832Z,1667934417.832 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-11-08T19:06:57.832Z,1667934417.832 [NAL9602] Data Fault, FailCount= 2 2022-11-08T19:06:57.832Z,1667934417.832 [NAL9602](ERROR): Data Fault 2022-11-08T19:06:57.853Z,1667934417.853 [CBIT](ERROR): Data Fault in component: NAL9602 2022-11-08T19:06:58.233Z,1667934418.233 [NAL9602](INFO): Powering down 2022-11-08T19:06:58.733Z,1667934418.733 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20221108T184519/Express0008.lzma 2022-11-08T19:06:59.075Z,1667934419.075 [CBIT](INFO): Clearing failed state for component NAL9602 2022-11-08T19:06:59.076Z,1667934419.076 [NAL9602] No Fault, FailCount= 2 2022-11-08T19:06:59.736Z,1667934419.736 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Express0008.lzma.bak 2022-11-08T19:06:59.736Z,1667934419.736 [DataOverHttps](INFO): SBD MOMSN=17274462 2022-11-08T19:07:02.304Z,1667934422.304 [Default:CheckIn:Read_Iridium] Stopped 2022-11-08T19:07:02.304Z,1667934422.304 [Default:CheckIn:C.Wait] Running Loop=1 2022-11-08T19:07:02.304Z,1667934422.304 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-11-08T19:07:28.536Z,1667934448.536 [NAL9602](INFO): Powering up NAL9602 2022-11-08T19:07:39.446Z,1667934459.446 [NAL9602](INFO): NAL9602 initialized 2022-11-08T19:09:39.036Z,1667934579.036 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2022-11-08T19:09:39.036Z,1667934579.036 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:09:39.038Z,1667934579.038 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2022-11-08T19:09:39.038Z,1667934579.038 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:09:39.049Z,1667934579.049 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:09:39.049Z,1667934579.049 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:09:39.463Z,1667934579.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:09:39.463Z,1667934579.463 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2022-11-08T19:09:39.463Z,1667934579.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:09:39.463Z,1667934579.463 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2022-11-08T19:09:39.842Z,1667934579.842 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:12:02.923Z,1667934722.923 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-11-08T19:12:02.923Z,1667934722.923 [Default:CheckIn:C.Wait] Stopped 2022-11-08T19:12:02.923Z,1667934722.923 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T19:12:02.923Z,1667934722.923 [Default:CheckIn:D] Running Loop=1 2022-11-08T19:12:03.334Z,1667934723.334 [Default:CheckIn:D] Stopped 2022-11-08T19:12:03.334Z,1667934723.334 [Default:CheckIn:E] Running Loop=1 2022-11-08T19:12:03.724Z,1667934723.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.726280 min 2022-11-08T19:12:03.724Z,1667934723.724 [Default:CheckIn:E] Stopped 2022-11-08T19:12:03.724Z,1667934723.724 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-11-08T19:12:03.724Z,1667934723.724 [Default:CheckIn] Stopped 2022-11-08T19:12:03.724Z,1667934723.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-08T19:12:03.725Z,1667934723.725 [Default:CheckIn](INFO): Running loop #2 2022-11-08T19:12:03.725Z,1667934723.725 [Default:CheckIn] Running Loop=2 2022-11-08T19:12:03.725Z,1667934723.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-08T19:12:03.725Z,1667934723.725 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-08T19:12:40.102Z,1667934760.102 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2022-11-08T19:12:40.102Z,1667934760.102 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:12:40.103Z,1667934760.103 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2022-11-08T19:12:40.104Z,1667934760.104 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:12:40.114Z,1667934760.114 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:12:40.114Z,1667934760.114 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:12:40.532Z,1667934760.532 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:12:40.532Z,1667934760.532 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2022-11-08T19:12:40.533Z,1667934760.533 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:12:40.533Z,1667934760.533 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2022-11-08T19:12:40.895Z,1667934760.895 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:15:41.075Z,1667934941.075 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2022-11-08T19:15:41.075Z,1667934941.075 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:15:41.076Z,1667934941.076 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2022-11-08T19:15:41.076Z,1667934941.076 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:15:41.087Z,1667934941.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:15:41.087Z,1667934941.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:15:41.495Z,1667934941.495 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:15:41.496Z,1667934941.496 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2022-11-08T19:15:41.498Z,1667934941.498 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:15:41.499Z,1667934941.499 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2022-11-08T19:15:41.906Z,1667934941.906 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:17:03.897Z,1667935023.897 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-08T19:12:03.7Z 2022-11-08T19:17:03.897Z,1667935023.897 [Default:CheckIn:Read_GPS] Stopped 2022-11-08T19:17:03.897Z,1667935023.897 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-11-08T19:17:10.753Z,1667935030.753 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221108T184519/Courier0010.lzma 2022-11-08T19:17:11.756Z,1667935031.756 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Courier0010.lzma.bak 2022-11-08T19:17:11.756Z,1667935031.756 [DataOverHttps](INFO): SBD MOMSN=17274486 2022-11-08T19:17:28.574Z,1667935048.574 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20221108T184519/Express0011.lzma 2022-11-08T19:17:29.576Z,1667935049.576 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Express0011.lzma.bak 2022-11-08T19:17:29.576Z,1667935049.576 [DataOverHttps](INFO): SBD MOMSN=17274489 2022-11-08T19:17:32.227Z,1667935052.227 [Default:CheckIn:Read_Iridium] Stopped 2022-11-08T19:17:32.228Z,1667935052.228 [Default:CheckIn:C.Wait] Running Loop=1 2022-11-08T19:17:32.228Z,1667935052.228 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-11-08T19:17:40.677Z,1667935060.677 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-11-08T19:17:40.678Z,1667935060.678 [NAL9602] Data Fault, FailCount= 3 2022-11-08T19:17:40.678Z,1667935060.678 [NAL9602](ERROR): Data Fault 2022-11-08T19:17:40.723Z,1667935060.723 [CBIT](ERROR): Data Fault in component: NAL9602 2022-11-08T19:17:41.084Z,1667935061.084 [NAL9602](INFO): Powering down 2022-11-08T19:17:41.956Z,1667935061.956 [CBIT](INFO): Clearing failed state for component NAL9602 2022-11-08T19:17:41.974Z,1667935061.974 [NAL9602] No Fault, FailCount= 3 2022-11-08T19:18:11.400Z,1667935091.400 [NAL9602](INFO): Powering up NAL9602 2022-11-08T19:18:22.315Z,1667935102.315 [NAL9602](INFO): NAL9602 initialized 2022-11-08T19:18:42.138Z,1667935122.138 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2022-11-08T19:18:42.138Z,1667935122.138 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:18:42.139Z,1667935122.139 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2022-11-08T19:18:42.139Z,1667935122.139 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:18:42.151Z,1667935122.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:18:42.151Z,1667935122.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:18:42.558Z,1667935122.558 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:18:42.558Z,1667935122.558 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2022-11-08T19:18:42.559Z,1667935122.559 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:18:42.559Z,1667935122.559 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2022-11-08T19:18:42.923Z,1667935122.923 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:21:43.118Z,1667935303.118 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2022-11-08T19:21:43.118Z,1667935303.118 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:21:43.119Z,1667935303.119 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2022-11-08T19:21:43.120Z,1667935303.120 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:21:43.135Z,1667935303.135 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:21:43.135Z,1667935303.135 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:21:43.546Z,1667935303.546 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:21:43.546Z,1667935303.546 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2022-11-08T19:21:43.547Z,1667935303.547 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:21:43.547Z,1667935303.547 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2022-11-08T19:21:43.948Z,1667935303.948 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:22:32.828Z,1667935352.828 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-11-08T19:22:32.828Z,1667935352.828 [Default:CheckIn:C.Wait] Stopped 2022-11-08T19:22:32.828Z,1667935352.828 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T19:22:32.829Z,1667935352.829 [Default:CheckIn:D] Running Loop=1 2022-11-08T19:22:33.233Z,1667935353.233 [Default:CheckIn:D] Stopped 2022-11-08T19:22:33.233Z,1667935353.233 [Default:CheckIn:E] Running Loop=1 2022-11-08T19:22:33.651Z,1667935353.651 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.224599 min 2022-11-08T19:22:33.651Z,1667935353.651 [Default:CheckIn:E] Stopped 2022-11-08T19:22:33.651Z,1667935353.651 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-11-08T19:22:33.652Z,1667935353.652 [Default:CheckIn] Stopped 2022-11-08T19:22:33.652Z,1667935353.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-08T19:22:33.652Z,1667935353.652 [Default:CheckIn](INFO): Running loop #3 2022-11-08T19:22:33.652Z,1667935353.652 [Default:CheckIn] Running Loop=3 2022-11-08T19:22:33.652Z,1667935353.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-08T19:22:33.652Z,1667935353.652 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-08T19:24:44.133Z,1667935484.133 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2022-11-08T19:24:44.133Z,1667935484.133 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:24:44.135Z,1667935484.135 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2022-11-08T19:24:44.135Z,1667935484.135 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:24:44.149Z,1667935484.149 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:24:44.150Z,1667935484.150 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:24:44.559Z,1667935484.559 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:24:44.559Z,1667935484.559 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2022-11-08T19:24:44.560Z,1667935484.560 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:24:44.560Z,1667935484.560 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2022-11-08T19:24:44.917Z,1667935484.917 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:27:33.793Z,1667935653.793 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-08T19:22:33.7Z 2022-11-08T19:27:33.793Z,1667935653.793 [Default:CheckIn:Read_GPS] Stopped 2022-11-08T19:27:33.794Z,1667935653.794 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-11-08T19:27:41.201Z,1667935661.201 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221108T184519/Courier0013.lzma 2022-11-08T19:27:42.204Z,1667935662.204 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Courier0013.lzma.bak 2022-11-08T19:27:42.204Z,1667935662.204 [DataOverHttps](INFO): SBD MOMSN=17274524 2022-11-08T19:27:45.126Z,1667935665.126 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2022-11-08T19:27:45.126Z,1667935665.126 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:27:45.127Z,1667935665.127 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2022-11-08T19:27:45.127Z,1667935665.127 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:27:45.139Z,1667935665.139 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:27:45.139Z,1667935665.139 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:27:45.549Z,1667935665.549 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:27:45.550Z,1667935665.550 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2022-11-08T19:27:45.552Z,1667935665.552 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:27:45.552Z,1667935665.552 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2022-11-08T19:27:45.905Z,1667935665.905 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:27:59.093Z,1667935679.093 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20221108T184519/Express0014.lzma 2022-11-08T19:28:00.096Z,1667935680.096 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Express0014.lzma.bak 2022-11-08T19:28:00.096Z,1667935680.096 [DataOverHttps](INFO): SBD MOMSN=17274529 2022-11-08T19:28:02.500Z,1667935682.500 [Default:CheckIn:Read_Iridium] Stopped 2022-11-08T19:28:02.500Z,1667935682.500 [Default:CheckIn:C.Wait] Running Loop=1 2022-11-08T19:28:02.500Z,1667935682.500 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-11-08T19:28:24.685Z,1667935704.685 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-11-08T19:28:24.685Z,1667935704.685 [NAL9602] Data Fault, FailCount= 4 2022-11-08T19:28:24.685Z,1667935704.685 [NAL9602](ERROR): Data Fault 2022-11-08T19:28:24.702Z,1667935704.702 [CBIT](ERROR): Data Fault in component: NAL9602 2022-11-08T19:28:25.091Z,1667935705.091 [NAL9602](INFO): Powering down 2022-11-08T19:28:25.922Z,1667935705.922 [CBIT](INFO): Clearing failed state for component NAL9602 2022-11-08T19:28:25.922Z,1667935705.922 [NAL9602] No Fault, FailCount= 4 2022-11-08T19:28:55.385Z,1667935735.385 [NAL9602](INFO): Powering up NAL9602 2022-11-08T19:29:06.297Z,1667935746.297 [NAL9602](INFO): NAL9602 initialized 2022-11-08T19:30:34.368Z,1667935834.368 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-11-08T19:30:34.368Z,1667935834.368 [DropWeight] Hardware Fault, FailCount= 1 2022-11-08T19:30:34.368Z,1667935834.368 [DropWeight](ERROR): Hardware Fault 2022-11-08T19:30:34.423Z,1667935834.423 [CBIT](INFO): Critical error at 20221108T193034 2022-11-08T19:30:34.423Z,1667935834.423 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-11-08T19:30:34.426Z,1667935834.426 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-11-08T19:30:34.426Z,1667935834.426 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-11-08T19:30:34.794Z,1667935834.794 [CBIT](INFO): Critical error at 20221108T193034 2022-11-08T19:30:46.111Z,1667935846.111 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2022-11-08T19:30:46.111Z,1667935846.111 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:30:46.112Z,1667935846.112 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2022-11-08T19:30:46.112Z,1667935846.112 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:30:46.122Z,1667935846.122 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:30:46.123Z,1667935846.123 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:30:46.528Z,1667935846.528 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:30:46.528Z,1667935846.528 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2022-11-08T19:30:46.529Z,1667935846.529 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:30:46.529Z,1667935846.529 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2022-11-08T19:30:46.895Z,1667935846.895 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:33:03.048Z,1667935983.048 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-11-08T19:33:03.048Z,1667935983.048 [Default:CheckIn:C.Wait] Stopped 2022-11-08T19:33:03.048Z,1667935983.048 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T19:33:03.049Z,1667935983.049 [Default:CheckIn:D] Running Loop=1 2022-11-08T19:33:03.455Z,1667935983.455 [Default:CheckIn:D] Stopped 2022-11-08T19:33:03.456Z,1667935983.456 [Default:CheckIn:E] Running Loop=1 2022-11-08T19:33:03.884Z,1667935983.884 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.728302 min 2022-11-08T19:33:03.884Z,1667935983.884 [Default:CheckIn:E] Stopped 2022-11-08T19:33:03.885Z,1667935983.885 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-11-08T19:33:03.885Z,1667935983.885 [Default:CheckIn] Stopped 2022-11-08T19:33:03.885Z,1667935983.885 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-08T19:33:03.885Z,1667935983.885 [Default:CheckIn](INFO): Running loop #4 2022-11-08T19:33:03.885Z,1667935983.885 [Default:CheckIn] Running Loop=4 2022-11-08T19:33:03.885Z,1667935983.885 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-08T19:33:03.885Z,1667935983.885 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-08T19:33:47.103Z,1667936027.103 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2022-11-08T19:33:47.103Z,1667936027.103 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:33:47.104Z,1667936027.104 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2022-11-08T19:33:47.104Z,1667936027.104 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:33:47.114Z,1667936027.114 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:33:47.115Z,1667936027.115 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:33:47.536Z,1667936027.536 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:33:47.536Z,1667936027.536 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2022-11-08T19:33:47.536Z,1667936027.536 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:33:47.537Z,1667936027.537 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2022-11-08T19:33:47.893Z,1667936027.893 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:36:48.099Z,1667936208.099 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2022-11-08T19:36:48.099Z,1667936208.099 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:36:48.100Z,1667936208.100 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17 2022-11-08T19:36:48.100Z,1667936208.100 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:36:48.111Z,1667936208.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:36:48.111Z,1667936208.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:36:48.508Z,1667936208.508 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:36:48.508Z,1667936208.508 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2022-11-08T19:36:48.508Z,1667936208.508 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:36:48.508Z,1667936208.508 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17 2022-11-08T19:36:48.885Z,1667936208.885 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:38:04.048Z,1667936284.048 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-08T19:33:03.9Z 2022-11-08T19:38:04.048Z,1667936284.048 [Default:CheckIn:Read_GPS] Stopped 2022-11-08T19:38:04.049Z,1667936284.049 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-11-08T19:38:11.377Z,1667936291.377 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20221108T184519/Courier0016.lzma 2022-11-08T19:38:12.380Z,1667936292.380 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Courier0016.lzma.bak 2022-11-08T19:38:12.380Z,1667936292.380 [DataOverHttps](INFO): SBD MOMSN=17274566 2022-11-08T19:38:29.333Z,1667936309.333 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20221108T184519/Express0017.lzma 2022-11-08T19:38:30.336Z,1667936310.336 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Express0017.lzma.bak 2022-11-08T19:38:30.336Z,1667936310.336 [DataOverHttps](INFO): SBD MOMSN=17274571 2022-11-08T19:38:32.751Z,1667936312.751 [Default:CheckIn:Read_Iridium] Stopped 2022-11-08T19:38:32.751Z,1667936312.751 [Default:CheckIn:C.Wait] Running Loop=1 2022-11-08T19:38:32.751Z,1667936312.751 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-11-08T19:39:08.662Z,1667936348.662 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-11-08T19:39:08.662Z,1667936348.662 [NAL9602] Data Fault, FailCount= 5 2022-11-08T19:39:08.662Z,1667936348.662 [NAL9602](ERROR): Data Fault 2022-11-08T19:39:08.680Z,1667936348.680 [CBIT](ERROR): Data Fault in component: NAL9602 2022-11-08T19:39:08.680Z,1667936348.680 [CBIT](CRITICAL): Data Fault in component: NAL9602 2022-11-08T19:39:09.068Z,1667936349.068 [NAL9602](INFO): Powering down 2022-11-08T19:39:09.098Z,1667936349.098 [CBIT](INFO): Critical error at 20221108T193908 2022-11-08T19:39:49.070Z,1667936389.070 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2022-11-08T19:39:49.070Z,1667936389.070 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:39:49.071Z,1667936389.071 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18 2022-11-08T19:39:49.071Z,1667936389.071 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:39:49.081Z,1667936389.081 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:39:49.082Z,1667936389.082 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:39:49.486Z,1667936389.486 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:39:49.486Z,1667936389.486 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2022-11-08T19:39:49.487Z,1667936389.487 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:39:49.487Z,1667936389.487 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18 2022-11-08T19:39:49.872Z,1667936389.872 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:41:08.668Z,1667936468.668 [CBIT](INFO): Clearing failed state for component NAL9602 2022-11-08T19:41:08.668Z,1667936468.668 [NAL9602] No Fault, FailCount= 5 2022-11-08T19:41:09.061Z,1667936469.061 [NAL9602](INFO): Powering up NAL9602 2022-11-08T19:41:19.956Z,1667936479.956 [NAL9602](INFO): NAL9602 initialized 2022-11-08T19:42:50.062Z,1667936570.062 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2022-11-08T19:42:50.062Z,1667936570.062 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:42:50.063Z,1667936570.063 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19 2022-11-08T19:42:50.063Z,1667936570.063 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:42:50.074Z,1667936570.074 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:42:50.074Z,1667936570.074 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:42:50.503Z,1667936570.503 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:42:50.503Z,1667936570.503 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2022-11-08T19:42:50.504Z,1667936570.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:42:50.504Z,1667936570.504 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19 2022-11-08T19:42:50.898Z,1667936570.898 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:43:33.312Z,1667936613.312 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-11-08T19:43:33.312Z,1667936613.312 [Default:CheckIn:C.Wait] Stopped 2022-11-08T19:43:33.312Z,1667936613.312 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T19:43:33.313Z,1667936613.313 [Default:CheckIn:D] Running Loop=1 2022-11-08T19:43:33.728Z,1667936613.728 [Default:CheckIn:D] Stopped 2022-11-08T19:43:33.728Z,1667936613.728 [Default:CheckIn:E] Running Loop=1 2022-11-08T19:43:34.135Z,1667936614.135 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.232845 min 2022-11-08T19:43:34.135Z,1667936614.135 [Default:CheckIn:E] Stopped 2022-11-08T19:43:34.135Z,1667936614.135 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-11-08T19:43:34.135Z,1667936614.135 [Default:CheckIn] Stopped 2022-11-08T19:43:34.136Z,1667936614.136 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-08T19:43:34.136Z,1667936614.136 [Default:CheckIn](INFO): Running loop #5 2022-11-08T19:43:34.136Z,1667936614.136 [Default:CheckIn] Running Loop=5 2022-11-08T19:43:34.136Z,1667936614.136 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-08T19:43:34.136Z,1667936614.136 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-08T19:45:34.511Z,1667936734.511 [CBIT](INFO): Clearing failed state for component DropWeight 2022-11-08T19:45:34.511Z,1667936734.511 [DropWeight] No Fault, FailCount= 1 2022-11-08T19:45:51.087Z,1667936751.087 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2022-11-08T19:45:51.088Z,1667936751.088 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:45:51.089Z,1667936751.089 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20 2022-11-08T19:45:51.089Z,1667936751.089 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:45:51.099Z,1667936751.099 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:45:51.100Z,1667936751.100 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:45:51.471Z,1667936751.471 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:45:51.472Z,1667936751.472 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2022-11-08T19:45:51.472Z,1667936751.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:45:51.472Z,1667936751.472 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20 2022-11-08T19:45:51.861Z,1667936751.861 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:48:34.285Z,1667936914.285 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-08T19:43:34.1Z 2022-11-08T19:48:34.285Z,1667936914.285 [Default:CheckIn:Read_GPS] Stopped 2022-11-08T19:48:34.286Z,1667936914.286 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-11-08T19:48:41.845Z,1667936921.845 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20221108T184519/Courier0019.lzma 2022-11-08T19:48:42.848Z,1667936922.848 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Courier0019.lzma.bak 2022-11-08T19:48:42.848Z,1667936922.848 [DataOverHttps](INFO): SBD MOMSN=17274590 2022-11-08T19:48:52.066Z,1667936932.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2022-11-08T19:48:52.066Z,1667936932.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:48:52.068Z,1667936932.068 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 21 2022-11-08T19:48:52.068Z,1667936932.068 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:48:52.109Z,1667936932.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:48:52.109Z,1667936932.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:48:52.462Z,1667936932.462 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:48:52.462Z,1667936932.462 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2022-11-08T19:48:52.463Z,1667936932.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:48:52.463Z,1667936932.463 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 21 2022-11-08T19:48:52.853Z,1667936932.853 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:49:00.265Z,1667936940.265 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20221108T184519/Express0020.lzma 2022-11-08T19:49:01.268Z,1667936941.268 [DataOverHttps](INFO): Moved sent file to Logs/20221108T184519/Express0020.lzma.bak 2022-11-08T19:49:01.268Z,1667936941.268 [DataOverHttps](INFO): SBD MOMSN=17274593 2022-11-08T19:49:03.812Z,1667936943.812 [Default:CheckIn:Read_Iridium] Stopped 2022-11-08T19:49:03.812Z,1667936943.812 [Default:CheckIn:C.Wait] Running Loop=1 2022-11-08T19:49:03.812Z,1667936943.812 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-11-08T19:51:21.521Z,1667937081.521 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-11-08T19:51:21.521Z,1667937081.521 [NAL9602] Data Fault, FailCount= 1 2022-11-08T19:51:21.521Z,1667937081.521 [NAL9602](ERROR): Data Fault 2022-11-08T19:51:21.538Z,1667937081.538 [CBIT](ERROR): Data Fault in component: NAL9602 2022-11-08T19:51:21.929Z,1667937081.929 [NAL9602](INFO): Powering down 2022-11-08T19:51:22.741Z,1667937082.741 [CBIT](INFO): Clearing failed state for component NAL9602 2022-11-08T19:51:22.742Z,1667937082.742 [NAL9602] No Fault, FailCount= 1 2022-11-08T19:51:51.964Z,1667937111.964 [NAL9602](INFO): Powering up NAL9602 2022-11-08T19:51:53.206Z,1667937113.206 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2022-11-08T19:51:53.206Z,1667937113.206 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:51:53.207Z,1667937113.207 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 22 2022-11-08T19:51:53.207Z,1667937113.207 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:51:53.262Z,1667937113.262 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:51:53.263Z,1667937113.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:51:53.625Z,1667937113.625 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:51:53.625Z,1667937113.625 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2022-11-08T19:51:53.625Z,1667937113.625 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:51:53.625Z,1667937113.625 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 22 2022-11-08T19:51:54.029Z,1667937114.029 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:52:02.876Z,1667937122.876 [NAL9602](INFO): NAL9602 initialized 2022-11-08T19:54:04.520Z,1667937244.520 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-11-08T19:54:04.520Z,1667937244.520 [Default:CheckIn:C.Wait] Stopped 2022-11-08T19:54:04.520Z,1667937244.520 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-11-08T19:54:04.520Z,1667937244.520 [Default:CheckIn:D] Running Loop=1 2022-11-08T19:54:04.912Z,1667937244.912 [Default:CheckIn:D] Stopped 2022-11-08T19:54:04.912Z,1667937244.912 [Default:CheckIn:E] Running Loop=1 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.752584 min 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn:E] Stopped 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn] Stopped 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn](INFO): Running loop #6 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn] Running Loop=6 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-08T19:54:05.313Z,1667937245.313 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-08T19:54:54.198Z,1667937294.198 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2022-11-08T19:54:54.198Z,1667937294.198 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:54:54.200Z,1667937294.200 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 23 2022-11-08T19:54:54.200Z,1667937294.200 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:54:54.211Z,1667937294.211 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:54:54.211Z,1667937294.211 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:54:54.619Z,1667937294.619 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:54:54.620Z,1667937294.620 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2022-11-08T19:54:54.620Z,1667937294.620 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:54:54.620Z,1667937294.620 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 23 2022-11-08T19:54:54.999Z,1667937294.999 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:57:55.182Z,1667937475.182 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24 2022-11-08T19:57:55.183Z,1667937475.183 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-08T19:57:55.184Z,1667937475.184 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 24 2022-11-08T19:57:55.184Z,1667937475.184 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-08T19:57:55.194Z,1667937475.194 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-08T19:57:55.195Z,1667937475.195 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-08T19:57:55.597Z,1667937475.597 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-08T19:57:55.597Z,1667937475.597 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24 2022-11-08T19:57:55.597Z,1667937475.597 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-08T19:57:55.597Z,1667937475.597 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 24 2022-11-08T19:57:56.083Z,1667937476.083 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-08T19:58:42.691Z,1667937522.691 [CommandExec](IMPORTANT): got command restart system 2022-11-08T19:58:44.810Z,1667937524.810 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:44.811Z,1667937524.811 [CommandExec](INFO): Uninitializing the command executive. 2022-11-08T19:58:44.811Z,1667937524.811 [CommandExec](INFO): Uninitializing the command scheduler. 2022-11-08T19:58:44.811Z,1667937524.811 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:44.986Z,1667937524.986 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-11-08T19:58:44.986Z,1667937524.986 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-11-08T19:58:44.986Z,1667937524.986 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:44.987Z,1667937524.987 [NavChartDb](INFO): Join timeout helper Thread ID is 1018 2022-11-08T19:58:45.170Z,1667937525.170 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:45.170Z,1667937525.170 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:45.174Z,1667937525.174 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2022-11-08T19:58:45.174Z,1667937525.174 [DAT ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:45.175Z,1667937525.175 [DAT](INFO): Join timeout helper Thread ID is 1019 2022-11-08T19:58:45.202Z,1667937525.202 [DAT](INFO): Powering down 2022-11-08T19:58:45.279Z,1667937525.279 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:45.279Z,1667937525.279 [DAT](INFO): Powering down 2022-11-08T19:58:45.280Z,1667937525.280 [DAT ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:45.298Z,1667937525.298 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-11-08T19:58:45.298Z,1667937525.298 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:45.298Z,1667937525.298 [Radio_Surface](INFO): Join timeout helper Thread ID is 1020 2022-11-08T19:58:45.462Z,1667937525.462 [Radio_Surface](INFO): Powering down 2022-11-08T19:58:45.463Z,1667937525.463 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:45.463Z,1667937525.463 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:45.470Z,1667937525.470 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-11-08T19:58:45.470Z,1667937525.470 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:45.470Z,1667937525.470 [Onboard](INFO): Join timeout helper Thread ID is 1021 2022-11-08T19:58:48.722Z,1667937528.722 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:48.722Z,1667937528.722 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:48.742Z,1667937528.742 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-11-08T19:58:48.742Z,1667937528.742 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:48.742Z,1667937528.742 [DataOverHttps](INFO): Join timeout helper Thread ID is 1022 2022-11-08T19:58:48.786Z,1667937528.786 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:48.789Z,1667937528.789 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:48.799Z,1667937528.799 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2022-11-08T19:58:48.799Z,1667937528.799 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:48.800Z,1667937528.800 [BackseatComponent](INFO): Join timeout helper Thread ID is 1023 2022-11-08T19:58:48.922Z,1667937528.922 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:48.922Z,1667937528.922 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:48.931Z,1667937528.931 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-11-08T19:58:48.931Z,1667937528.931 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:48.931Z,1667937528.931 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1024 2022-11-08T19:58:49.026Z,1667937529.026 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:49.026Z,1667937529.026 [WetLabsBB2FL](INFO): Powering down 2022-11-08T19:58:49.027Z,1667937529.027 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:49.031Z,1667937529.031 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-11-08T19:58:49.031Z,1667937529.031 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:49.031Z,1667937529.031 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1025 2022-11-08T19:58:49.702Z,1667937529.702 [CTD_Seabird](INFO): Powering down 2022-11-08T19:58:49.714Z,1667937529.714 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:49.714Z,1667937529.714 [CTD_Seabird](INFO): Powering down 2022-11-08T19:58:49.726Z,1667937529.726 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:49.743Z,1667937529.743 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-11-08T19:58:49.743Z,1667937529.743 [logger ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:49.743Z,1667937529.743 [logger](INFO): Join timeout helper Thread ID is 1026 2022-11-08T19:58:49.771Z,1667937529.771 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:49.771Z,1667937529.771 [logger ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:49.779Z,1667937529.779 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-11-08T19:58:49.779Z,1667937529.779 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:49.779Z,1667937529.779 [CommandLine](INFO): Join timeout helper Thread ID is 1027 2022-11-08T19:58:49.798Z,1667937529.798 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-08T19:58:49.798Z,1667937529.798 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-11-08T19:58:49.807Z,1667937529.