2021-10-06T22:28:56.092Z,1633559336.092 [Supervisor](DEBUG): Initializing supervisor.
2021-10-06T22:28:56.097Z,1633559336.097 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-10-06T22:28:56.098Z,1633559336.098 [SyncHandler](INFO): Protected caller Thread ID is 8644
2021-10-06T22:28:56.098Z,1633559336.098 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-10-06T22:28:56.099Z,1633559336.099 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-10-06T22:28:56.099Z,1633559336.099 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8645
2021-10-06T22:28:56.104Z,1633559336.104 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-10-06T22:28:56.124Z,1633559336.124 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-10-06T22:28:56.125Z,1633559336.125 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-10-06T22:28:56.125Z,1633559336.125 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8646
2021-10-06T22:28:56.129Z,1633559336.129 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-10-06T22:28:56.131Z,1633559336.131 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-10-06T22:28:56.131Z,1633559336.131 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8647
2021-10-06T22:28:56.133Z,1633559336.133 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-10-06T22:28:56.134Z,1633559336.134 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-10-06T22:28:56.135Z,1633559336.135 [logger ThreadHandler](INFO): Protected caller Thread ID is 8648
2021-10-06T22:28:56.139Z,1633559336.139 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-10-06T22:28:56.139Z,1633559336.139 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-10-06T22:28:56.142Z,1633559336.142 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-10-06T22:28:56.644Z,1633559336.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-10-06T22:28:56.645Z,1633559336.645 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-10-06T22:28:56.796Z,1633559336.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-10-06T22:28:56.796Z,1633559336.796 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-10-06T22:28:56.880Z,1633559336.880 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-10-06T22:28:57.112Z,1633559337.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-10-06T22:28:57.112Z,1633559337.112 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-10-06T22:28:57.192Z,1633559337.192 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-10-06T22:28:57.289Z,1633559337.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-10-06T22:28:57.290Z,1633559337.290 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-10-06T22:28:57.654Z,1633559337.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-10-06T22:28:57.655Z,1633559337.655 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-10-06T22:28:57.782Z,1633559337.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-10-06T22:28:57.783Z,1633559337.783 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-10-06T22:28:58.327Z,1633559338.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-10-06T22:28:58.328Z,1633559338.328 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-10-06T22:28:58.593Z,1633559338.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-10-06T22:28:58.594Z,1633559338.594 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-10-06T22:28:58.995Z,1633559338.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-10-06T22:28:58.996Z,1633559338.996 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-10-06T22:28:59.245Z,1633559339.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-10-06T22:28:59.245Z,1633559339.245 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-10-06T22:28:59.357Z,1633559339.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-10-06T22:28:59.357Z,1633559339.357 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-10-06T22:29:00.045Z,1633559340.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-10-06T22:29:00.046Z,1633559340.046 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-10-06T22:29:00.406Z,1633559340.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-10-06T22:29:00.406Z,1633559340.406 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-10-06T22:29:00.624Z,1633559340.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-10-06T22:29:00.626Z,1633559340.626 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2021-10-06T22:29:00.628Z,1633559340.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2021-10-06T22:29:00.831Z,1633559340.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2021-10-06T22:29:00.916Z,1633559340.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2021-10-06T22:29:01.060Z,1633559341.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2021-10-06T22:29:01.336Z,1633559341.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2021-10-06T22:29:02.100Z,1633559342.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-10-06T22:29:02.101Z,1633559342.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2021-10-06T22:29:02.208Z,1633559342.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2021-10-06T22:29:02.323Z,1633559342.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2021-10-06T22:29:02.419Z,1633559342.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2021-10-06T22:29:02.569Z,1633559342.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2021-10-06T22:29:02.670Z,1633559342.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2021-10-06T22:29:02.765Z,1633559342.765 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2021-10-06T22:29:02.765Z,1633559342.765 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-10-06T22:29:02.781Z,1633559342.781 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-10-06T22:29:02.794Z,1633559342.794 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-10-06T22:29:02.794Z,1633559342.794 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-10-06T22:29:02.900Z,1633559342.900 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-10-06T22:29:02.901Z,1633559342.901 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-10-06T22:29:02.954Z,1633559342.954 [VerticalControl](DEBUG): Construct VerticalControl.
2021-10-06T22:29:03.019Z,1633559343.019 [VerticalControl] Loaded
2021-10-06T22:29:03.019Z,1633559343.019 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-10-06T22:29:03.022Z,1633559343.022 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-10-06T22:29:03.063Z,1633559343.063 [HorizontalControl] Loaded
2021-10-06T22:29:03.063Z,1633559343.063 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-10-06T22:29:03.065Z,1633559343.065 [SpeedControl](DEBUG): Construct SpeedControl.
2021-10-06T22:29:03.069Z,1633559343.069 [SpeedControl] Loaded
2021-10-06T22:29:03.069Z,1633559343.069 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-10-06T22:29:03.072Z,1633559343.072 [LoopControl](DEBUG): Construct LoopControl.
2021-10-06T22:29:03.072Z,1633559343.072 [LoopControl] Loaded
2021-10-06T22:29:03.072Z,1633559343.072 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-10-06T22:29:03.073Z,1633559343.073 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-10-06T22:29:03.073Z,1633559343.073 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-10-06T22:29:03.202Z,1633559343.202 [BuoyancyServo] Loaded
2021-10-06T22:29:03.203Z,1633559343.203 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-10-06T22:29:03.223Z,1633559343.223 [ElevatorServo] Loaded
2021-10-06T22:29:03.224Z,1633559343.224 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-10-06T22:29:03.243Z,1633559343.243 [MassServo] Loaded
2021-10-06T22:29:03.243Z,1633559343.243 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-10-06T22:29:03.262Z,1633559343.262 [RudderServo] Loaded
2021-10-06T22:29:03.262Z,1633559343.262 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-10-06T22:29:03.278Z,1633559343.278 [ThrusterHE] Loaded
2021-10-06T22:29:03.278Z,1633559343.278 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2021-10-06T22:29:03.279Z,1633559343.279 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-10-06T22:29:03.279Z,1633559343.279 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-10-06T22:29:03.323Z,1633559343.323 [DepthRateCalculator] Loaded
2021-10-06T22:29:03.323Z,1633559343.323 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-10-06T22:29:03.328Z,1633559343.328 [PitchRateCalculator] Loaded
2021-10-06T22:29:03.328Z,1633559343.328 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-10-06T22:29:03.339Z,1633559343.339 [SpeedCalculator] Loaded
2021-10-06T22:29:03.340Z,1633559343.340 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-10-06T22:29:03.354Z,1633559343.354 [TempGradientCalculator] Loaded
2021-10-06T22:29:03.354Z,1633559343.354 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-10-06T22:29:03.359Z,1633559343.359 [YawRateCalculator] Loaded
2021-10-06T22:29:03.359Z,1633559343.359 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-10-06T22:29:03.377Z,1633559343.377 [ElevatorOffsetCalculator] Loaded
2021-10-06T22:29:03.377Z,1633559343.377 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-10-06T22:29:03.378Z,1633559343.378 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-10-06T22:29:03.378Z,1633559343.378 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-10-06T22:29:03.468Z,1633559343.468 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-10-06T22:29:03.468Z,1633559343.468 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-10-06T22:29:03.482Z,1633559343.482 [NavChart] Loaded
2021-10-06T22:29:03.483Z,1633559343.483 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-10-06T22:29:03.489Z,1633559343.489 [UniversalFixResidualReporter] Loaded
2021-10-06T22:29:03.489Z,1633559343.489 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-10-06T22:29:03.489Z,1633559343.489 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-10-06T22:29:03.490Z,1633559343.490 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-10-06T22:29:03.861Z,1633559343.861 [AHRS_M2] Loaded
2021-10-06T22:29:03.862Z,1633559343.862 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-10-06T22:29:03.892Z,1633559343.892 [BackseatComponent] Loaded
2021-10-06T22:29:03.893Z,1633559343.893 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-10-06T22:29:03.894Z,1633559343.894 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4089D4E0
2021-10-06T22:29:03.894Z,1633559343.894 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8731
2021-10-06T22:29:03.897Z,1633559343.897 [LcmUniversalReporter] Loaded
2021-10-06T22:29:03.897Z,1633559343.897 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-10-06T22:29:04.717Z,1633559344.717 [BPC1] Loaded
2021-10-06T22:29:04.717Z,1633559344.717 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-10-06T22:29:04.792Z,1633559344.792 [DataOverHttps] Loaded
2021-10-06T22:29:04.793Z,1633559344.793 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-10-06T22:29:04.794Z,1633559344.794 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408CD4E0
2021-10-06T22:29:04.794Z,1633559344.794 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8732
2021-10-06T22:29:04.815Z,1633559344.815 [Depth_Keller] Loaded
2021-10-06T22:29:04.816Z,1633559344.816 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-10-06T22:29:04.820Z,1633559344.820 [DropWeight] Loaded
2021-10-06T22:29:04.821Z,1633559344.821 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-10-06T22:29:04.884Z,1633559344.884 [NAL9602] Loaded
2021-10-06T22:29:04.885Z,1633559344.885 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-10-06T22:29:04.915Z,1633559344.915 [Onboard] Loaded
2021-10-06T22:29:04.916Z,1633559344.916 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-10-06T22:29:04.917Z,1633559344.917 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2021-10-06T22:29:04.917Z,1633559344.917 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8733
2021-10-06T22:29:04.931Z,1633559344.931 [Power24vConverter] Loaded
2021-10-06T22:29:04.931Z,1633559344.931 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-10-06T22:29:04.945Z,1633559344.945 [Radio_Surface] Loaded
2021-10-06T22:29:04.945Z,1633559344.945 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-10-06T22:29:04.946Z,1633559344.946 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2021-10-06T22:29:04.947Z,1633559344.947 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8734
2021-10-06T22:29:04.948Z,1633559344.948 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-10-06T22:29:04.948Z,1633559344.948 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-10-06T22:29:05.008Z,1633559345.008 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-10-06T22:29:05.009Z,1633559345.009 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-10-06T22:29:05.127Z,1633559345.127 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-10-06T22:29:05.127Z,1633559345.127 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-10-06T22:29:05.161Z,1633559345.161 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-10-06T22:29:05.161Z,1633559345.161 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-10-06T22:29:05.339Z,1633559345.339 [CTD_Seabird] Loaded
2021-10-06T22:29:05.339Z,1633559345.339 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-10-06T22:29:05.340Z,1633559345.340 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0
2021-10-06T22:29:05.341Z,1633559345.341 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8735
2021-10-06T22:29:05.375Z,1633559345.375 [ESPComponent] Loaded
2021-10-06T22:29:05.375Z,1633559345.375 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-10-06T22:29:05.396Z,1633559345.396 [PAR_Licor] Loaded
2021-10-06T22:29:05.396Z,1633559345.396 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-10-06T22:29:05.429Z,1633559345.429 [WetLabsBB2FL] Loaded
2021-10-06T22:29:05.430Z,1633559345.430 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-10-06T22:29:05.431Z,1633559345.431 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2021-10-06T22:29:05.431Z,1633559345.431 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8736
2021-10-06T22:29:05.432Z,1633559345.432 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-10-06T22:29:05.433Z,1633559345.433 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-10-06T22:29:05.757Z,1633559345.757 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-10-06T22:29:05.758Z,1633559345.758 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-10-06T22:29:05.909Z,1633559345.909 [SBIT](DEBUG): Construct Startup Built In Test.
2021-10-06T22:29:05.919Z,1633559345.919 [SBIT] Loaded
2021-10-06T22:29:05.919Z,1633559345.919 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-10-06T22:29:05.922Z,1633559345.922 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-10-06T22:29:05.936Z,1633559345.936 [IBIT] Loaded
2021-10-06T22:29:05.936Z,1633559345.936 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-10-06T22:29:05.942Z,1633559345.942 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-10-06T22:29:06.049Z,1633559346.049 [CBIT] Loaded
2021-10-06T22:29:06.049Z,1633559346.049 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-10-06T22:29:06.050Z,1633559346.050 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-10-06T22:29:06.056Z,1633559346.056 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-10-06T22:29:06.059Z,1633559346.059 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-10-06T22:29:06.071Z,1633559346.071 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-10-06T22:29:06.072Z,1633559346.072 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0
2021-10-06T22:29:06.072Z,1633559346.072 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8737
2021-10-06T22:29:06.077Z,1633559346.077 [Supervisor](INFO): Main Thread ID is 8643
2021-10-06T22:29:06.077Z,1633559346.077 [Supervisor](DEBUG): Running supervisor.
2021-10-06T22:29:06.078Z,1633559346.078 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8738
2021-10-06T22:29:06.078Z,1633559346.078 [CommandExec](INFO): Initializing the command executive.
2021-10-06T22:29:06.080Z,1633559346.080 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8739
2021-10-06T22:29:06.082Z,1633559346.082 [controlThread ThreadHandler](INFO): Handler Thread ID is 8740
2021-10-06T22:29:06.083Z,1633559346.083 [controlThread](DEBUG): Initializing ControlThread
2021-10-06T22:29:06.084Z,1633559346.084 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-10-06T22:29:06.085Z,1633559346.085 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-10-06T22:29:06.086Z,1633559346.086 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-10-06T22:29:06.087Z,1633559346.087 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-10-06T22:29:06.088Z,1633559346.088 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-10-06T22:29:06.089Z,1633559346.089 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-10-06T22:29:06.089Z,1633559346.089 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-10-06T22:29:06.089Z,1633559346.089 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-10-06T22:29:06.090Z,1633559346.090 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-10-06T22:29:06.090Z,1633559346.090 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-10-06T22:29:06.091Z,1633559346.091 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-10-06T22:29:06.092Z,1633559346.092 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-10-06T22:29:06.098Z,1633559346.098 [SBIT](INFO): Initialize SBIT Component.
2021-10-06T22:29:06.099Z,1633559346.099 [SBIT](IMPORTANT): git: 2021-09-30a
2021-10-06T22:29:06.099Z,1633559346.099 [SBIT](INFO): git hash: 921004aa1e1e6e697b3aa6ab2c76f23053d013df
2021-10-06T22:29:06.099Z,1633559346.099 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-10-06T22:29:06.100Z,1633559346.100 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-10-06T22:29:06.102Z,1633559346.102 [SBIT](INFO): Beginning SBIT in 57.000000 seconds.
2021-10-06T22:29:06.102Z,1633559346.102 [IBIT](INFO): Initialize IBIT Component.
2021-10-06T22:29:06.103Z,1633559346.103 [CBIT](DEBUG): Initialize CBIT Component.
2021-10-06T22:29:06.104Z,1633559346.104 [logger ThreadHandler](INFO): Handler Thread ID is 8741
2021-10-06T22:29:06.114Z,1633559346.114 [CBIT](DEBUG): Initialized mux pins.
2021-10-06T22:29:06.114Z,1633559346.114 [CBIT](DEBUG): Initializing the watchdog timer.
2021-10-06T22:29:06.122Z,1633559346.122 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8742
2021-10-06T22:29:06.134Z,1633559346.134 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8743
2021-10-06T22:29:06.135Z,1633559346.135 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-10-06T22:29:06.138Z,1633559346.138 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-10-06T22:29:06.139Z,1633559346.139 [CBIT](DEBUG): Initializing heartbeat.
2021-10-06T22:29:06.146Z,1633559346.146 [Onboard ThreadHandler](INFO): Handler Thread ID is 8744
2021-10-06T22:29:06.164Z,1633559346.164 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8745
2021-10-06T22:29:06.186Z,1633559346.186 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8746
2021-10-06T22:29:06.187Z,1633559346.187 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-10-06T22:29:06.191Z,1633559346.191 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8748
2021-10-06T22:29:06.194Z,1633559346.194 [WetLabsBB2FL](INFO): Powering up
2021-10-06T22:29:06.196Z,1633559346.196 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8750
2021-10-06T22:29:06.202Z,1633559346.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-10-06T22:29:06.203Z,1633559346.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-10-06T22:29:06.203Z,1633559346.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-10-06T22:29:06.203Z,1633559346.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-10-06T22:29:06.203Z,1633559346.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-10-06T22:29:06.203Z,1633559346.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-10-06T22:29:06.203Z,1633559346.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-10-06T22:29:06.204Z,1633559346.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-10-06T22:29:06.204Z,1633559346.204 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-10-06T22:29:06.204Z,1633559346.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-10-06T22:29:06.204Z,1633559346.204 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-10-06T22:29:06.204Z,1633559346.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-10-06T22:29:06.204Z,1633559346.204 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-10-06T22:29:06.205Z,1633559346.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-10-06T22:29:06.205Z,1633559346.205 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-10-06T22:29:06.205Z,1633559346.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-10-06T22:29:06.210Z,1633559346.210 [CBIT](DEBUG): Deactivating GF circuits.
2021-10-06T22:29:06.210Z,1633559346.210 [CBIT](DEBUG): Deactivating emergency mode.
2021-10-06T22:29:06.246Z,1633559346.246 [CBIT](DEBUG): Backplane powered.
2021-10-06T22:29:06.247Z,1633559346.247 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-10-06T22:29:06.263Z,1633559346.263 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-10-06T22:29:06.291Z,1633559346.291 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2021-10-06T22:29:06.292Z,1633559346.292 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-10-06T22:29:06.383Z,1633559346.383 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-10-06T22:29:06.384Z,1633559346.384 [Default:A.Wait](DEBUG): Construct Wait.
2021-10-06T22:29:06.398Z,1633559346.398 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-10-06T22:29:06.425Z,1633559346.425 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-10-06T22:29:06.449Z,1633559346.449 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-10-06T22:29:06.468Z,1633559346.468 [Default:E.Execute](DEBUG): Construct Execute.
2021-10-06T22:29:06.504Z,1633559346.504 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2021-10-06T22:29:06.509Z,1633559346.509 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-10-06T22:29:06.529Z,1633559346.529 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-10-06T22:29:06.570Z,1633559346.570 [Radio_Surface](INFO): Powering up
2021-10-06T22:29:06.649Z,1633559346.649 [Power24vConverter](INFO): Powering up.
2021-10-06T22:29:06.728Z,1633559346.728 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-10-06T22:29:06.729Z,1633559346.729 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-10-06T22:29:06.739Z,1633559346.739 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-10-06T22:29:06.742Z,1633559346.742 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-10-06T22:29:06.744Z,1633559346.744 [MassServo](DEBUG): Initializing EZServoServo.
2021-10-06T22:29:06.747Z,1633559346.747 [MassServo](DEBUG): Initializing MassServo.
2021-10-06T22:29:06.748Z,1633559346.748 [RudderServo](DEBUG): Initializing EZServoServo.
2021-10-06T22:29:06.758Z,1633559346.758 [RudderServo](DEBUG): Initializing RudderServo.
2021-10-06T22:29:06.759Z,1633559346.759 [ThrusterHE](DEBUG): Initializing EZServoServo.
2021-10-06T22:29:06.766Z,1633559346.766 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2021-10-06T22:29:06.944Z,1633559346.944 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2021-10-06T22:29:06.944Z,1633559346.944 [DropWeight] Hardware Fault, FailCount= 1
2021-10-06T22:29:06.944Z,1633559346.944 [DropWeight](ERROR): Hardware Fault
2021-10-06T22:29:07.017Z,1633559347.017 [CommandExec](FAULT): Scheduling is paused
2021-10-06T22:29:07.017Z,1633559347.017 [CBIT](INFO): Critical error at 20211006T222906
2021-10-06T22:29:07.017Z,1633559347.017 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-10-06T22:29:07.020Z,1633559347.020 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-10-06T22:29:07.020Z,1633559347.020 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-10-06T22:29:07.654Z,1633559347.654 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-10-06T22:29:07.654Z,1633559347.654 [RudderServo](FAULT): Rudder failed to initialize
2021-10-06T22:29:07.654Z,1633559347.654 [RudderServo] Communications Fault, FailCount= 1
2021-10-06T22:29:07.654Z,1633559347.654 [RudderServo](ERROR): Communications Fault
2021-10-06T22:29:07.667Z,1633559347.667 [CBIT](INFO): Critical error at 20211006T222907
2021-10-06T22:29:07.669Z,1633559347.669 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-10-06T22:29:07.895Z,1633559347.895 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-10-06T22:29:07.895Z,1633559347.895 [RudderServo](INFO): Powering down
2021-10-06T22:29:08.302Z,1633559348.302 [WetLabsBB2FL](INFO): Powering down
2021-10-06T22:29:08.555Z,1633559348.555 [RudderServo](DEBUG): Initializing EZServoServo.
2021-10-06T22:29:08.675Z,1633559348.675 [RudderServo](DEBUG): Initializing RudderServo.
2021-10-06T22:29:08.679Z,1633559348.679 [CBIT](INFO): Clearing failed state for component RudderServo
2021-10-06T22:29:08.679Z,1633559348.679 [RudderServo] No Fault, FailCount= 1
2021-10-06T22:29:13.218Z,1633559353.218 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-10-06T22:29:35.209Z,1633559375.209 [NAL9602](INFO): Powering up NAL9602
2021-10-06T22:29:46.112Z,1633559386.112 [NAL9602](INFO): NAL9602 initialized
2021-10-06T22:30:02.631Z,1633559402.631 [CommandExec](IMPORTANT): got command failComponent
2021-10-06T22:30:02.631Z,1633559402.631 [CommandExec](IMPORTANT): Failed components:
2021-10-06T22:30:02.631Z,1633559402.631 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2021-10-06T22:30:03.905Z,1633559403.905 [SBIT](IMPORTANT): Beginning Startup BIT
2021-10-06T22:30:03.910Z,1633559403.910 [CBIT](IMPORTANT): Beginning ground fault scan
2021-10-06T22:30:14.955Z,1633559414.955 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.010939
CHAN A1 (24V): -0.008391
CHAN A2 (12V): -0.004319
CHAN A3 (5V): -0.002823
CHAN B0 (3.3V): -0.000839
CHAN B1 (3.15aV): -0.002089
CHAN B2 (3.15bV): -0.001006
CHAN B3 (GND): -0.000592
OPEN: 0.003275
Full Scale: +/- 1 mA
2021-10-06T22:30:58.075Z,1633559458.075 [SBIT](IMPORTANT): SBIT PASSED
2021-10-06T22:30:58.076Z,1633559458.076 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-10-06T22:30:58.077Z,1633559458.077 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=32 count;

2021-10-06T22:30:58.077Z,1633559458.077 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 hour;

2021-10-06T22:30:58.077Z,1633559458.077 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-10-06T22:30:58.077Z,1633559458.077 [SBIT](IMPORTANT): CBIT.gf24Offset=-18.106 microampere;

2021-10-06T22:30:58.077Z,1633559458.077 [SBIT](IMPORTANT): CBIT.stopDepth=295 meter;

2021-10-06T22:30:58.077Z,1633559458.077 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-10-06T22:30:58.077Z,1633559458.077 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2021-10-06T22:30:58.078Z,1633559458.078 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2021-10-06T22:30:58.078Z,1633559458.078 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2021-10-06T22:30:58.078Z,1633559458.078 [SBIT](IMPORTANT): Express linearApproximation platform_pitch_angle 5.000000 degree;

2021-10-06T22:30:58.078Z,1633559458.078 [SBIT](IMPORTANT): Express none platform_roll_angle;

2021-10-06T22:30:58.078Z,1633559458.078 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=391.537766 cubic_centimeter;

2021-10-06T22:30:58.079Z,1633559458.079 [SBIT](IMPORTANT): VerticalControl.massDefault=9.394078 millimeter;

2021-10-06T22:30:58.449Z,1633559458.449 [MissionManager](IMPORTANT): Started mission Startup
2021-10-06T22:30:58.449Z,1633559458.449 [Startup] Running Loop=1
2021-10-06T22:30:58.449Z,1633559458.449 [Startup](DEBUG): Aggregate::initialize Startup
2021-10-06T22:30:58.449Z,1633559458.449 [Startup:A.GoToSurface] Running Loop=1
2021-10-06T22:30:58.449Z,1633559458.449 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-10-06T22:30:58.450Z,1633559458.450 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-10-06T22:30:58.450Z,1633559458.450 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-10-06T22:30:58.451Z,1633559458.451 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-10-06T22:30:58.451Z,1633559458.451 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-10-06T22:30:58.452Z,1633559458.452 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-10-06T22:30:58.453Z,1633559458.453 [Startup:StartupSatComms] Running Loop=1
2021-10-06T22:30:58.453Z,1633559458.453 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-10-06T22:30:58.453Z,1633559458.453 [Startup:StartupSatComms:A] Running Loop=1
2021-10-06T22:30:58.838Z,1633559458.838 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-10-06T22:31:51.383Z,1633559511.383 [CommandExec](IMPORTANT): got command failComponent
2021-10-06T22:31:51.383Z,1633559511.383 [CommandExec](IMPORTANT): Failed components:
2021-10-06T22:31:51.383Z,1633559511.383 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2021-10-06T22:31:58.636Z,1633559518.636 [Startup:StartupSatComms:A](INFO): Timed out from 2021-10-06T22:30:58.5Z
2021-10-06T22:31:58.636Z,1633559518.636 [Startup:StartupSatComms:A] Stopped
2021-10-06T22:31:58.637Z,1633559518.637 [Startup:StartupSatComms:B] Running Loop=1
2021-10-06T22:31:59.041Z,1633559519.041 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-10-06T22:32:06.359Z,1633559526.359 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-10-06T22:32:06.359Z,1633559526.359 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-06T22:32:06.386Z,1633559526.386 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-06T22:32:06.792Z,1633559526.792 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-06T22:32:06.792Z,1633559526.792 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-10-06T22:32:09.601Z,1633559529.601 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005451
2021-10-06T22:32:16.905Z,1633559536.905 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211006T180237/Courier0073.lzma
2021-10-06T22:32:17.908Z,1633559537.908 [DataOverHttps](INFO): Moved sent file to Logs/20211006T180237/Courier0073.lzma.bak
2021-10-06T22:32:17.908Z,1633559537.908 [DataOverHttps](INFO): SBD MOMSN=15975877
2021-10-06T22:32:27.723Z,1633559547.723 [CommandExec](IMPORTANT): got command strobe on
2021-10-06T22:32:27.724Z,1633559547.724 [CommandExec](IMPORTANT): Activating strobe
2021-10-06T22:32:33.394Z,1633559553.394 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20211006T222856/Courier0000.lzma
2021-10-06T22:32:34.396Z,1633559554.396 [DataOverHttps](INFO): Moved sent file to Logs/20211006T222856/Courier0000.lzma.bak
2021-10-06T22:32:34.396Z,1633559554.396 [DataOverHttps](INFO): SBD MOMSN=15975879
2021-10-06T22:32:49.174Z,1633559569.174 [DataOverHttps](INFO): Sending 369 bytes from file Logs/20211006T180237/Express0074.lzma
2021-10-06T22:32:50.176Z,1633559570.176 [DataOverHttps](INFO): Moved sent file to Logs/20211006T180237/Express0074.lzma.bak
2021-10-06T22:32:50.176Z,1633559570.176 [DataOverHttps](INFO): SBD MOMSN=15975884
2021-10-06T22:32:58.853Z,1633559578.853 [Startup:StartupSatComms:B](INFO): Timed out from 2021-10-06T22:31:58.6Z
2021-10-06T22:32:58.853Z,1633559578.853 [Startup:StartupSatComms:B] Stopped
2021-10-06T22:32:58.853Z,1633559578.853 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-10-06T22:32:58.853Z,1633559578.853 [Startup:StartupSatComms] Stopped
2021-10-06T22:32:58.853Z,1633559578.853 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-10-06T22:32:58.854Z,1633559578.854 [Startup](INFO): Completed Startup
2021-10-06T22:32:58.854Z,1633559578.854 [MissionManager](INFO): Startup is completed.
2021-10-06T22:32:58.855Z,1633559578.855 [MissionManager](INFO): Uninitializing Mission Startup
2021-10-06T22:32:58.855Z,1633559578.855 [Startup] Stopped
2021-10-06T22:32:58.855Z,1633559578.855 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-10-06T22:32:58.855Z,1633559578.855 [Startup:A.GoToSurface] Stopped
2021-10-06T22:32:58.855Z,1633559578.855 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-10-06T22:32:59.237Z,1633559579.237 [MissionManager](IMPORTANT): Started mission Default
2021-10-06T22:32:59.237Z,1633559579.237 [Default] Running Loop=1
2021-10-06T22:32:59.237Z,1633559579.237 [Default](DEBUG): Aggregate::initialize Default
2021-10-06T22:32:59.237Z,1633559579.237 [Default:B.GoToSurface] Running Loop=1
2021-10-06T22:32:59.237Z,1633559579.237 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-10-06T22:32:59.237Z,1633559579.237 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-10-06T22:32:59.238Z,1633559579.238 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-10-06T22:32:59.238Z,1633559579.238 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-10-06T22:32:59.239Z,1633559579.239 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-10-06T22:32:59.239Z,1633559579.239 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-10-06T22:32:59.239Z,1633559579.239 [Default:A.Wait] Running Loop=1
2021-10-06T22:32:59.239Z,1633559579.239 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-10-06T22:33:05.733Z,1633559585.733 [DataOverHttps](INFO): Sending 1109 bytes from file Logs/20211006T222856/Express0001.lzma
2021-10-06T22:33:06.732Z,1633559586.732 [DataOverHttps](INFO): Moved sent file to Logs/20211006T222856/Express0001.lzma.bak
2021-10-06T22:33:06.732Z,1633559586.732 [DataOverHttps](INFO): SBD MOMSN=15975892
2021-10-06T22:33:12.568Z,1633559592.568 [Default:A.Wait](INFO): Done Waiting.
2021-10-06T22:33:12.568Z,1633559592.568 [Default:A.Wait] Stopped
2021-10-06T22:33:12.568Z,1633559592.568 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-10-06T22:33:12.969Z,1633559592.969 [Default:CheckIn] Running Loop=1
2021-10-06T22:33:12.969Z,1633559592.969 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-10-06T22:33:12.970Z,1633559592.970 [Default:CheckIn:Read_GPS] Running Loop=1
2021-10-06T22:33:13.390Z,1633559593.390 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-10-06T22:34:20.479Z,1633559660.479 [CommandExec](IMPORTANT): got command gfscan
2021-10-06T22:34:20.863Z,1633559660.863 [CBIT](IMPORTANT): Beginning ground fault scan
2021-10-06T22:34:31.785Z,1633559671.785 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.195256
CHAN A1 (24V): -0.013746
CHAN A2 (12V): 0.111473
CHAN A3 (5V): 0.037549
CHAN B0 (3.3V): 0.026722
CHAN B1 (3.15aV): 0.025029
CHAN B2 (3.15bV): 0.025427
CHAN B3 (GND): -0.005162
OPEN: 0.011123
Full Scale: +/- 1 mA
2021-10-06T22:34:49.123Z,1633559689.123 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-10-06T22:35:07.335Z,1633559707.335 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-10-06T22:35:07.335Z,1633559707.335 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-10-06T22:35:07.347Z,1633559707.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-10-06T22:35:07.760Z,1633559707.760 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-10-06T22:35:07.760Z,1633559707.760 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-10-06T22:36:18.672Z,1633559778.672 [CommandExec](IMPORTANT): got command gfscan
2021-10-06T22:36:18.841Z,1633559778.841 [CBIT](IMPORTANT): Beginning ground fault scan
2021-10-06T22:36:28.911Z,1633559788.911 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 3.
2021-10-06T22:36:28.931Z,1633559788.931 [BPC1](INFO): Received data from all battery sticks.
2021-10-06T22:36:29.735Z,1633559789.735 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.012870
CHAN A1 (24V): -0.009068
CHAN A2 (12V): -0.004820
CHAN A3 (5V): -0.002119
CHAN B0 (3.3V): -0.001431
CHAN B1 (3.15aV): -0.001073
CHAN B2 (3.15bV): -0.000378
CHAN B3 (GND): -0.000144
OPEN: 0.003561
Full Scale: +/- 1 mA
2021-10-06T22:36:32.835Z,1633559792.835 [CommandExec](IMPORTANT): got command quit
2021-10-06T22:36:33.838Z,1633559793.838 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:33.838Z,1633559793.838 [CommandExec](INFO): Uninitializing the command executive.
2021-10-06T22:36:33.838Z,1633559793.838 [CommandExec](INFO): Uninitializing the command scheduler.
2021-10-06T22:36:33.839Z,1633559793.839 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:33.978Z,1633559793.978 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-10-06T22:36:33.978Z,1633559793.978 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-10-06T22:36:33.978Z,1633559793.978 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:33.979Z,1633559793.979 [NavChartDb](INFO): Join timeout helper Thread ID is 8773
2021-10-06T22:36:34.326Z,1633559794.326 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:34.326Z,1633559794.326 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:34.338Z,1633559794.338 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-10-06T22:36:34.338Z,1633559794.338 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:34.339Z,1633559794.339 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8774
2021-10-06T22:36:34.398Z,1633559794.398 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:34.398Z,1633559794.398 [WetLabsBB2FL](INFO): Powering down
2021-10-06T22:36:34.399Z,1633559794.399 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:34.410Z,1633559794.410 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-10-06T22:36:34.410Z,1633559794.410 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:34.410Z,1633559794.410 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8775
2021-10-06T22:36:35.046Z,1633559795.046 [CTD_Seabird](INFO): Powering down
2021-10-06T22:36:35.058Z,1633559795.058 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:35.058Z,1633559795.058 [CTD_Seabird](INFO): Powering down
2021-10-06T22:36:35.078Z,1633559795.078 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:35.086Z,1633559795.086 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-10-06T22:36:35.086Z,1633559795.086 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:35.087Z,1633559795.087 [Radio_Surface](INFO): Join timeout helper Thread ID is 8776
2021-10-06T22:36:35.102Z,1633559795.102 [Radio_Surface](INFO): Powering down
2021-10-06T22:36:35.103Z,1633559795.103 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:35.103Z,1633559795.103 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:35.104Z,1633559795.104 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-10-06T22:36:35.104Z,1633559795.104 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:35.104Z,1633559795.104 [Onboard](INFO): Join timeout helper Thread ID is 8777
2021-10-06T22:36:36.518Z,1633559796.518 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:36.518Z,1633559796.518 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:36.539Z,1633559796.539 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-10-06T22:36:36.539Z,1633559796.539 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:36.539Z,1633559796.539 [DataOverHttps](INFO): Join timeout helper Thread ID is 8778
2021-10-06T22:36:37.218Z,1633559797.218 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:37.220Z,1633559797.220 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.231Z,1633559797.231 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-10-06T22:36:37.231Z,1633559797.231 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.231Z,1633559797.231 [BackseatComponent](INFO): Join timeout helper Thread ID is 8779
2021-10-06T22:36:37.410Z,1633559797.410 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:37.410Z,1633559797.410 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.415Z,1633559797.415 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-10-06T22:36:37.415Z,1633559797.415 [logger ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.415Z,1633559797.415 [logger](INFO): Join timeout helper Thread ID is 8780
2021-10-06T22:36:37.460Z,1633559797.460 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:37.461Z,1633559797.461 [logger ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.479Z,1633559797.479 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-10-06T22:36:37.479Z,1633559797.479 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.479Z,1633559797.479 [CommandLine](INFO): Join timeout helper Thread ID is 8781
2021-10-06T22:36:37.502Z,1633559797.502 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:37.502Z,1633559797.502 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.519Z,1633559797.519 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2021-10-06T22:36:37.519Z,1633559797.519 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.519Z,1633559797.519 [CommandExec](INFO): Join timeout helper Thread ID is 8782
2021-10-06T22:36:37.520Z,1633559797.520 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-10-06T22:36:37.520Z,1633559797.520 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.521Z,1633559797.521 [controlThread](INFO): Join timeout helper Thread ID is 8783
2021-10-06T22:36:37.782Z,1633559797.782 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-10-06T22:36:37.782Z,1633559797.782 [controlThread](DEBUG): Uninitializing ControlThread
2021-10-06T22:36:37.783Z,1633559797.783 [AHRS_M2](INFO): Powering down
2021-10-06T22:36:37.855Z,1633559797.855 [NAL9602](INFO): Powering down
2021-10-06T22:36:37.857Z,1633559797.857 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-10-06T22:36:37.859Z,1633559797.859 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-10-06T22:36:37.860Z,1633559797.860 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-10-06T22:36:37.861Z,1633559797.861 [MissionManager](INFO): Uninitializing Mission Default
2021-10-06T22:36:37.861Z,1633559797.861 [Default] Stopped
2021-10-06T22:36:37.861Z,1633559797.861 [Default](DEBUG): Aggregate::uninitialize Default
2021-10-06T22:36:37.861Z,1633559797.861 [Default:B.GoToSurface] Stopped
2021-10-06T22:36:37.861Z,1633559797.861 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-10-06T22:36:37.861Z,1633559797.861 [Default:CheckIn] Stopped
2021-10-06T22:36:37.861Z,1633559797.861 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-10-06T22:36:37.861Z,1633559797.861 [Default:CheckIn:Read_GPS] Stopped
2021-10-06T22:36:37.865Z,1633559797.865 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-10-06T22:36:37.865Z,1633559797.865 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-10-06T22:36:37.866Z,1633559797.866 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-10-06T22:36:37.866Z,1633559797.866 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-10-06T22:36:37.866Z,1633559797.866 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-10-06T22:36:37.867Z,1633559797.867 [BuoyancyServo](INFO): Powering down
2021-10-06T22:36:37.878Z,1633559797.878 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-10-06T22:36:37.878Z,1633559797.878 [ElevatorServo](INFO): Powering down
2021-10-06T22:36:37.879Z,1633559797.879 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-10-06T22:36:37.879Z,1633559797.879 [MassServo](INFO): Powering down
2021-10-06T22:36:37.880Z,1633559797.880 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-10-06T22:36:37.880Z,1633559797.880 [RudderServo](INFO): Powering down
2021-10-06T22:36:37.881Z,1633559797.881 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2021-10-06T22:36:37.881Z,1633559797.881 [ThrusterHE](INFO): Powering down
2021-10-06T22:36:37.882Z,1633559797.882 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-10-06T22:36:37.882Z,1633559797.882 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-10-06T22:36:37.883Z,1633559797.883 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-10-06T22:36:37.883Z,1633559797.883 [CBIT](DEBUG): Powering off loads.
2021-10-06T22:36:37.894Z,1633559797.894 [CBIT](DEBUG): Disabling WDT.
2021-10-06T22:36:37.906Z,1633559797.906 [CBIT](DEBUG): Opening all GF detection circuits.
2021-10-06T22:36:37.907Z,1633559797.907 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.959Z,1633559797.959 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.962Z,1633559797.962 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:37.969Z,1633559797.969 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:38.019Z,1633559798.019 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:38.052Z,1633559798.052 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:38.057Z,1633559798.057 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:38.091Z,1633559798.091 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-10-06T22:36:38.160Z,1633559798.160 [logger ThreadHandler](INFO): Thread cancelled.