2022-07-12T17:24:49.864Z,1657646689.864 [Supervisor](DEBUG): Initializing supervisor.
2022-07-12T17:24:49.868Z,1657646689.868 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-07-12T17:24:49.869Z,1657646689.869 [SyncHandler](INFO): Protected caller Thread ID is 993
2022-07-12T17:24:49.869Z,1657646689.869 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-07-12T17:24:49.870Z,1657646689.870 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-07-12T17:24:49.871Z,1657646689.871 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 994
2022-07-12T17:24:49.875Z,1657646689.875 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-07-12T17:24:49.893Z,1657646689.893 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-07-12T17:24:49.894Z,1657646689.894 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-07-12T17:24:49.894Z,1657646689.894 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 995
2022-07-12T17:24:49.898Z,1657646689.898 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-07-12T17:24:49.899Z,1657646689.899 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-07-12T17:24:49.900Z,1657646689.900 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 996
2022-07-12T17:24:49.902Z,1657646689.902 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-07-12T17:24:49.903Z,1657646689.903 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-07-12T17:24:49.903Z,1657646689.903 [logger ThreadHandler](INFO): Protected caller Thread ID is 997
2022-07-12T17:24:49.907Z,1657646689.907 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-07-12T17:24:49.907Z,1657646689.907 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-07-12T17:24:49.909Z,1657646689.909 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-07-12T17:24:50.334Z,1657646690.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-07-12T17:24:50.334Z,1657646690.334 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-07-12T17:24:50.544Z,1657646690.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-07-12T17:24:50.545Z,1657646690.545 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-07-12T17:24:50.626Z,1657646690.626 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-07-12T17:24:50.846Z,1657646690.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-07-12T17:24:50.846Z,1657646690.846 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-07-12T17:24:50.925Z,1657646690.925 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-07-12T17:24:51.021Z,1657646691.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-07-12T17:24:51.021Z,1657646691.021 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-07-12T17:24:51.373Z,1657646691.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-07-12T17:24:51.374Z,1657646691.374 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-07-12T17:24:51.499Z,1657646691.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-07-12T17:24:51.499Z,1657646691.499 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-07-12T17:24:52.060Z,1657646692.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-07-12T17:24:52.061Z,1657646692.061 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-07-12T17:24:52.252Z,1657646692.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-07-12T17:24:52.253Z,1657646692.253 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-07-12T17:24:52.635Z,1657646692.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-07-12T17:24:52.636Z,1657646692.636 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-07-12T17:24:52.912Z,1657646692.912 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-07-12T17:24:52.913Z,1657646692.913 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-07-12T17:24:53.025Z,1657646693.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-07-12T17:24:53.025Z,1657646693.025 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-07-12T17:24:53.733Z,1657646693.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-07-12T17:24:53.734Z,1657646693.734 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-07-12T17:24:54.095Z,1657646694.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-07-12T17:24:54.096Z,1657646694.096 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-07-12T17:24:54.557Z,1657646694.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-07-12T17:24:54.559Z,1657646694.559 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-07-12T17:24:54.560Z,1657646694.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-07-12T17:24:54.898Z,1657646694.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-07-12T17:24:55.259Z,1657646695.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-07-12T17:24:55.394Z,1657646695.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-07-12T17:24:55.480Z,1657646695.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-07-12T17:24:55.746Z,1657646695.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-07-12T17:24:55.746Z,1657646695.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-07-12T17:24:55.849Z,1657646695.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-07-12T17:24:55.960Z,1657646695.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-07-12T17:24:56.054Z,1657646696.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-07-12T17:24:56.197Z,1657646696.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-07-12T17:24:56.296Z,1657646696.296 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-07-12T17:24:56.389Z,1657646696.389 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2022-07-12T17:24:56.390Z,1657646696.390 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-07-12T17:24:56.396Z,1657646696.396 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-07-12T17:24:56.407Z,1657646696.407 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-07-12T17:24:56.408Z,1657646696.408 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-07-12T17:24:56.504Z,1657646696.504 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-07-12T17:24:56.504Z,1657646696.504 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-07-12T17:24:56.557Z,1657646696.557 [VerticalControl](DEBUG): Construct VerticalControl.
2022-07-12T17:24:56.618Z,1657646696.618 [VerticalControl] Loaded
2022-07-12T17:24:56.618Z,1657646696.618 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-07-12T17:24:56.621Z,1657646696.621 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-07-12T17:24:56.661Z,1657646696.661 [HorizontalControl] Loaded
2022-07-12T17:24:56.661Z,1657646696.661 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-07-12T17:24:56.663Z,1657646696.663 [SpeedControl](DEBUG): Construct SpeedControl.
2022-07-12T17:24:56.667Z,1657646696.667 [SpeedControl] Loaded
2022-07-12T17:24:56.667Z,1657646696.667 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-07-12T17:24:56.669Z,1657646696.669 [LoopControl](DEBUG): Construct LoopControl.
2022-07-12T17:24:56.670Z,1657646696.670 [LoopControl] Loaded
2022-07-12T17:24:56.670Z,1657646696.670 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-07-12T17:24:56.671Z,1657646696.671 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-07-12T17:24:56.671Z,1657646696.671 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-07-12T17:24:56.818Z,1657646696.818 [BuoyancyServo] Loaded
2022-07-12T17:24:56.819Z,1657646696.819 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-07-12T17:24:56.838Z,1657646696.838 [ElevatorServo] Loaded
2022-07-12T17:24:56.838Z,1657646696.838 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-07-12T17:24:56.857Z,1657646696.857 [MassServo] Loaded
2022-07-12T17:24:56.857Z,1657646696.857 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-07-12T17:24:56.875Z,1657646696.875 [RudderServo] Loaded
2022-07-12T17:24:56.875Z,1657646696.875 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-07-12T17:24:56.890Z,1657646696.890 [ThrusterHE] Loaded
2022-07-12T17:24:56.890Z,1657646696.890 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-07-12T17:24:56.890Z,1657646696.890 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-07-12T17:24:56.891Z,1657646696.891 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-07-12T17:24:56.942Z,1657646696.942 [DepthRateCalculator] Loaded
2022-07-12T17:24:56.943Z,1657646696.943 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-07-12T17:24:56.947Z,1657646696.947 [PitchRateCalculator] Loaded
2022-07-12T17:24:56.947Z,1657646696.947 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-07-12T17:24:56.957Z,1657646696.957 [SpeedCalculator] Loaded
2022-07-12T17:24:56.957Z,1657646696.957 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-07-12T17:24:56.962Z,1657646696.962 [YawRateCalculator] Loaded
2022-07-12T17:24:56.962Z,1657646696.962 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-07-12T17:24:56.980Z,1657646696.980 [ElevatorOffsetCalculator] Loaded
2022-07-12T17:24:56.981Z,1657646696.981 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-07-12T17:24:56.981Z,1657646696.981 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-07-12T17:24:56.982Z,1657646696.982 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-07-12T17:24:57.091Z,1657646697.091 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-07-12T17:24:57.091Z,1657646697.091 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-07-12T17:24:57.105Z,1657646697.105 [NavChart] Loaded
2022-07-12T17:24:57.105Z,1657646697.105 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-07-12T17:24:57.111Z,1657646697.111 [UniversalFixResidualReporter] Loaded
2022-07-12T17:24:57.111Z,1657646697.111 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-07-12T17:24:57.112Z,1657646697.112 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-07-12T17:24:57.112Z,1657646697.112 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-07-12T17:24:57.501Z,1657646697.501 [AHRS_M2] Loaded
2022-07-12T17:24:57.502Z,1657646697.502 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-07-12T17:24:57.538Z,1657646697.538 [BackseatComponent] Loaded
2022-07-12T17:24:57.538Z,1657646697.538 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-07-12T17:24:57.539Z,1657646697.539 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2022-07-12T17:24:57.540Z,1657646697.540 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1079
2022-07-12T17:24:57.542Z,1657646697.542 [LcmUniversalReporter] Loaded
2022-07-12T17:24:57.543Z,1657646697.543 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-07-12T17:24:58.367Z,1657646698.367 [BPC1] Loaded
2022-07-12T17:24:58.368Z,1657646698.368 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-07-12T17:24:58.440Z,1657646698.440 [DataOverHttps] Loaded
2022-07-12T17:24:58.440Z,1657646698.440 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-07-12T17:24:58.441Z,1657646698.441 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2022-07-12T17:24:58.441Z,1657646698.441 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1080
2022-07-12T17:24:58.462Z,1657646698.462 [Depth_Keller] Loaded
2022-07-12T17:24:58.462Z,1657646698.462 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-07-12T17:24:58.467Z,1657646698.467 [DropWeight] Loaded
2022-07-12T17:24:58.467Z,1657646698.467 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-07-12T17:24:58.527Z,1657646698.527 [NAL9602] Loaded
2022-07-12T17:24:58.528Z,1657646698.528 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-07-12T17:24:58.556Z,1657646698.556 [Onboard] Loaded
2022-07-12T17:24:58.557Z,1657646698.557 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-07-12T17:24:58.558Z,1657646698.558 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0
2022-07-12T17:24:58.558Z,1657646698.558 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1081
2022-07-12T17:24:58.570Z,1657646698.570 [Power24vConverter] Loaded
2022-07-12T17:24:58.570Z,1657646698.570 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-07-12T17:24:58.583Z,1657646698.583 [Radio_Surface] Loaded
2022-07-12T17:24:58.583Z,1657646698.583 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-07-12T17:24:58.584Z,1657646698.584 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095E4E0
2022-07-12T17:24:58.584Z,1657646698.584 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1082
2022-07-12T17:24:58.672Z,1657646698.672 [DAT] Loaded
2022-07-12T17:24:58.672Z,1657646698.672 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-07-12T17:24:58.673Z,1657646698.673 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4098E4E0
2022-07-12T17:24:58.673Z,1657646698.673 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1083
2022-07-12T17:24:58.674Z,1657646698.674 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-07-12T17:24:58.674Z,1657646698.674 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-07-12T17:24:58.733Z,1657646698.733 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-07-12T17:24:58.733Z,1657646698.733 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-07-12T17:24:58.837Z,1657646698.837 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-07-12T17:24:58.838Z,1657646698.838 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-07-12T17:24:58.864Z,1657646698.864 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-07-12T17:24:58.865Z,1657646698.865 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-07-12T17:24:59.036Z,1657646699.036 [CTD_Seabird] Loaded
2022-07-12T17:24:59.037Z,1657646699.037 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-07-12T17:24:59.038Z,1657646699.038 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABB4E0
2022-07-12T17:24:59.038Z,1657646699.038 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1084
2022-07-12T17:24:59.059Z,1657646699.059 [PAR_Licor] Loaded
2022-07-12T17:24:59.059Z,1657646699.059 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-07-12T17:24:59.090Z,1657646699.090 [WetLabsBB2FL] Loaded
2022-07-12T17:24:59.091Z,1657646699.091 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-07-12T17:24:59.092Z,1657646699.092 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0
2022-07-12T17:24:59.092Z,1657646699.092 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1085
2022-07-12T17:24:59.093Z,1657646699.093 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-07-12T17:24:59.093Z,1657646699.093 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-07-12T17:24:59.925Z,1657646699.925 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-07-12T17:24:59.925Z,1657646699.925 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-07-12T17:25:00.064Z,1657646700.064 [SBIT](DEBUG): Construct Startup Built In Test.
2022-07-12T17:25:00.073Z,1657646700.073 [SBIT] Loaded
2022-07-12T17:25:00.074Z,1657646700.074 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-07-12T17:25:00.077Z,1657646700.077 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-07-12T17:25:00.090Z,1657646700.090 [IBIT] Loaded
2022-07-12T17:25:00.090Z,1657646700.090 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-07-12T17:25:00.096Z,1657646700.096 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-07-12T17:25:00.212Z,1657646700.212 [CBIT] Loaded
2022-07-12T17:25:00.212Z,1657646700.212 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-07-12T17:25:00.212Z,1657646700.212 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-07-12T17:25:00.218Z,1657646700.218 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-07-12T17:25:00.221Z,1657646700.221 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-07-12T17:25:00.232Z,1657646700.232 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-07-12T17:25:00.233Z,1657646700.233 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0
2022-07-12T17:25:00.233Z,1657646700.233 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1086
2022-07-12T17:25:00.238Z,1657646700.238 [Supervisor](INFO): Main Thread ID is 829
2022-07-12T17:25:00.238Z,1657646700.238 [Supervisor](DEBUG): Running supervisor.
2022-07-12T17:25:00.238Z,1657646700.238 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1087
2022-07-12T17:25:00.239Z,1657646700.239 [CommandExec](INFO): Initializing the command executive.
2022-07-12T17:25:00.240Z,1657646700.240 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1088
2022-07-12T17:25:00.243Z,1657646700.243 [controlThread ThreadHandler](INFO): Handler Thread ID is 1089
2022-07-12T17:25:00.244Z,1657646700.244 [controlThread](DEBUG): Initializing ControlThread
2022-07-12T17:25:00.244Z,1657646700.244 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-07-12T17:25:00.246Z,1657646700.246 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-07-12T17:25:00.247Z,1657646700.247 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-07-12T17:25:00.247Z,1657646700.247 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-07-12T17:25:00.249Z,1657646700.249 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-07-12T17:25:00.249Z,1657646700.249 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-07-12T17:25:00.249Z,1657646700.249 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-07-12T17:25:00.250Z,1657646700.250 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-07-12T17:25:00.250Z,1657646700.250 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-07-12T17:25:00.251Z,1657646700.251 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-07-12T17:25:00.252Z,1657646700.252 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-07-12T17:25:00.259Z,1657646700.259 [SBIT](INFO): Initialize SBIT Component.
2022-07-12T17:25:00.259Z,1657646700.259 [SBIT](IMPORTANT): git: 2022-06-21
2022-07-12T17:25:00.260Z,1657646700.260 [SBIT](INFO): git hash: b6c6091eef7de6ede355e185f165831d25302f84
2022-07-12T17:25:00.260Z,1657646700.260 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-07-12T17:25:00.261Z,1657646700.261 [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
2022-07-12T17:25:00.262Z,1657646700.262 [SBIT](INFO): Beginning SBIT in 48.000000 seconds.
2022-07-12T17:25:00.263Z,1657646700.263 [IBIT](INFO): Initialize IBIT Component.
2022-07-12T17:25:00.264Z,1657646700.264 [CBIT](DEBUG): Initialize CBIT Component.
2022-07-12T17:25:00.265Z,1657646700.265 [logger ThreadHandler](INFO): Handler Thread ID is 1090
2022-07-12T17:25:00.275Z,1657646700.275 [CBIT](DEBUG): Initialized mux pins.
2022-07-12T17:25:00.275Z,1657646700.275 [CBIT](DEBUG): Initializing the watchdog timer.
2022-07-12T17:25:00.283Z,1657646700.283 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1091
2022-07-12T17:25:00.295Z,1657646700.295 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1092
2022-07-12T17:25:00.296Z,1657646700.296 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-07-12T17:25:00.299Z,1657646700.299 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-07-12T17:25:00.299Z,1657646700.299 [CBIT](DEBUG): Initializing heartbeat.
2022-07-12T17:25:00.307Z,1657646700.307 [Onboard ThreadHandler](INFO): Handler Thread ID is 1093
2022-07-12T17:25:00.325Z,1657646700.325 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1094
2022-07-12T17:25:00.344Z,1657646700.344 [DAT ThreadHandler](INFO): Handler Thread ID is 1095
2022-07-12T17:25:00.344Z,1657646700.344 [DAT](INFO): Powering up
2022-07-12T17:25:00.345Z,1657646700.345 [DAT](DEBUG): Initializing DAT.
2022-07-12T17:25:00.348Z,1657646700.348 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1097
2022-07-12T17:25:00.349Z,1657646700.349 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-07-12T17:25:00.352Z,1657646700.352 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1099
2022-07-12T17:25:00.355Z,1657646700.355 [WetLabsBB2FL](INFO): Powering up
2022-07-12T17:25:00.356Z,1657646700.356 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1101
2022-07-12T17:25:00.360Z,1657646700.360 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-07-12T17:25:00.360Z,1657646700.360 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-07-12T17:25:00.360Z,1657646700.360 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-07-12T17:25:00.360Z,1657646700.360 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-07-12T17:25:00.360Z,1657646700.360 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-07-12T17:25:00.360Z,1657646700.360 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-07-12T17:25:00.361Z,1657646700.361 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-07-12T17:25:00.361Z,1657646700.361 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-07-12T17:25:00.371Z,1657646700.371 [CBIT](DEBUG): Deactivating GF circuits.
2022-07-12T17:25:00.371Z,1657646700.371 [CBIT](DEBUG): Deactivating emergency mode.
2022-07-12T17:25:00.407Z,1657646700.407 [CBIT](DEBUG): Backplane powered.
2022-07-12T17:25:00.408Z,1657646700.408 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-07-12T17:25:00.420Z,1657646700.420 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-07-12T17:25:00.439Z,1657646700.439 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2022-07-12T17:25:00.440Z,1657646700.440 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-07-12T17:25:00.508Z,1657646700.508 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-07-12T17:25:00.509Z,1657646700.509 [Default:A.Wait](DEBUG): Construct Wait.
2022-07-12T17:25:00.527Z,1657646700.527 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-07-12T17:25:00.555Z,1657646700.555 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-07-12T17:25:00.557Z,1657646700.557 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-07-12T17:25:00.591Z,1657646700.591 [Default:E.Execute](DEBUG): Construct Execute.
2022-07-12T17:25:00.613Z,1657646700.613 [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>


2022-07-12T17:25:00.617Z,1657646700.617 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-07-12T17:25:00.639Z,1657646700.639 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-07-12T17:25:00.713Z,1657646700.713 [Power24vConverter](INFO): Powering up.
2022-07-12T17:25:00.731Z,1657646700.731 [Radio_Surface](INFO): Powering up
2022-07-12T17:25:00.760Z,1657646700.760 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-07-12T17:25:00.775Z,1657646700.775 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-07-12T17:25:00.776Z,1657646700.776 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-07-12T17:25:00.783Z,1657646700.783 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-07-12T17:25:00.784Z,1657646700.784 [MassServo](DEBUG): Initializing EZServoServo.
2022-07-12T17:25:00.791Z,1657646700.791 [MassServo](DEBUG): Initializing MassServo.
2022-07-12T17:25:00.792Z,1657646700.792 [RudderServo](DEBUG): Initializing EZServoServo.
2022-07-12T17:25:00.799Z,1657646700.799 [RudderServo](DEBUG): Initializing RudderServo.
2022-07-12T17:25:00.800Z,1657646700.800 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-07-12T17:25:00.815Z,1657646700.815 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-07-12T17:25:01.052Z,1657646701.052 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-07-12T17:25:01.052Z,1657646701.052 [DropWeight] Hardware Fault, FailCount= 1
2022-07-12T17:25:01.052Z,1657646701.052 [DropWeight](ERROR): Hardware Fault
2022-07-12T17:25:01.098Z,1657646701.098 [CommandExec](FAULT): Scheduling is paused
2022-07-12T17:25:01.107Z,1657646701.107 [CBIT](INFO): Critical error at 20220712T172501
2022-07-12T17:25:01.107Z,1657646701.107 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-07-12T17:25:01.109Z,1657646701.109 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-07-12T17:25:01.110Z,1657646701.110 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-07-12T17:25:01.524Z,1657646701.524 [CBIT](INFO): Critical error at 20220712T172501
2022-07-12T17:25:02.463Z,1657646702.463 [WetLabsBB2FL](INFO): Powering down
2022-07-12T17:25:06.540Z,1657646706.540 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-07-12T17:25:07.415Z,1657646707.415 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-07-12T17:25:15.214Z,1657646715.214 [DAT](INFO): commRate: 800
2022-07-12T17:25:17.279Z,1657646717.279 [DAT](INFO): entering command mode
2022-07-12T17:25:17.480Z,1657646717.480 [DAT](INFO): setting verbose to 3
2022-07-12T17:25:17.732Z,1657646717.732 [DAT](INFO): set verbose to 3
2022-07-12T17:25:17.733Z,1657646717.733 [DAT](INFO): setting DatVerbose to 27440
2022-07-12T17:25:17.984Z,1657646717.984 [DAT](INFO): set DatVerbose to 27440
2022-07-12T17:25:17.984Z,1657646717.984 [DAT](INFO): setting transmit power to 8
2022-07-12T17:25:18.236Z,1657646718.236 [DAT](INFO): set transmit power to 8
2022-07-12T17:25:18.236Z,1657646718.236 [DAT](INFO): setting local address to 5
2022-07-12T17:25:18.488Z,1657646718.488 [DAT](INFO): set local address to 5
2022-07-12T17:25:18.489Z,1657646718.489 [DAT](INFO): Setting time to: 17:25:18 And date to:7/12/2022
2022-07-12T17:25:18.740Z,1657646718.740 [DAT](INFO): Local DAT time set to Tue Jul 12, 2022  17:25:18  

2022-07-12T17:25:28.904Z,1657646728.904 [NAL9602](INFO): Powering up NAL9602
2022-07-12T17:25:39.812Z,1657646739.812 [NAL9602](INFO): NAL9602 initialized
2022-07-12T17:25:48.746Z,1657646748.746 [SBIT](IMPORTANT): Beginning Startup BIT
2022-07-12T17:25:48.751Z,1657646748.751 [CBIT](IMPORTANT): Beginning ground fault scan
2022-07-12T17:25:59.665Z,1657646759.665 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.006252
CHAN A1 (24V): 0.168413
CHAN A2 (12V): -0.004248
CHAN A3 (5V): -0.002717
CHAN B0 (3.3V): -0.001257
CHAN B1 (3.15aV): -0.001219
CHAN B2 (3.15bV): -0.001514
CHAN B3 (GND): -0.000245
OPEN: 0.004693
Full Scale: +/- 1 mA
2022-07-12T17:26:42.858Z,1657646802.858 [SBIT](IMPORTANT): SBIT PASSED
2022-07-12T17:26:42.858Z,1657646802.858 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-07-12T17:26:42.859Z,1657646802.859 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2022-07-12T17:26:42.859Z,1657646802.859 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2022-07-12T17:26:42.859Z,1657646802.859 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2022-07-12T17:26:42.859Z,1657646802.859 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=158 cubic_centimeter;

2022-07-12T17:26:42.859Z,1657646802.859 [SBIT](IMPORTANT): VerticalControl.massDefault=7 millimeter;

2022-07-12T17:26:43.268Z,1657646803.268 [MissionManager](IMPORTANT): Started mission Startup
2022-07-12T17:26:43.269Z,1657646803.269 [Startup] Running Loop=1
2022-07-12T17:26:43.269Z,1657646803.269 [Startup](DEBUG): Aggregate::initialize Startup
2022-07-12T17:26:43.269Z,1657646803.269 [Startup:A.GoToSurface] Running Loop=1
2022-07-12T17:26:43.269Z,1657646803.269 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-07-12T17:26:43.269Z,1657646803.269 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-07-12T17:26:43.270Z,1657646803.270 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-07-12T17:26:43.270Z,1657646803.270 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-07-12T17:26:43.271Z,1657646803.271 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-07-12T17:26:43.272Z,1657646803.272 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-07-12T17:26:43.273Z,1657646803.273 [Startup:StartupSatComms] Running Loop=1
2022-07-12T17:26:43.273Z,1657646803.273 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-07-12T17:26:43.273Z,1657646803.273 [Startup:StartupSatComms:A] Running Loop=1
2022-07-12T17:26:43.661Z,1657646803.661 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-07-12T17:27:03.879Z,1657646823.879 [CommandExec](IMPORTANT): got command failComponent
2022-07-12T17:27:03.879Z,1657646823.879 [CommandExec](IMPORTANT): Failed components:
2022-07-12T17:27:03.880Z,1657646823.880 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-07-12T17:27:28.184Z,1657646848.184 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.015491
2022-07-12T17:27:43.472Z,1657646863.472 [Startup:StartupSatComms:A](INFO): Timed out from 2022-07-12T17:26:43.3Z
2022-07-12T17:27:43.472Z,1657646863.472 [Startup:StartupSatComms:A] Stopped
2022-07-12T17:27:43.472Z,1657646863.472 [Startup:StartupSatComms:B] Running Loop=1
2022-07-12T17:27:43.889Z,1657646863.889 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-07-12T17:27:50.666Z,1657646870.666 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220712T171558/Courier0007.lzma
2022-07-12T17:27:51.670Z,1657646871.670 [DataOverHttps](INFO): Moved sent file to Logs/20220712T171558/Courier0007.lzma.bak
2022-07-12T17:27:51.670Z,1657646871.670 [DataOverHttps](INFO): SBD MOMSN=16971963
2022-07-12T17:28:00.452Z,1657646880.452 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-07-12T17:28:00.453Z,1657646880.453 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-07-12T17:28:00.505Z,1657646880.505 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-07-12T17:28:00.856Z,1657646880.856 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-07-12T17:28:00.856Z,1657646880.856 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-07-12T17:28:07.238Z,1657646887.238 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20220712T172449/Courier0000.lzma
2022-07-12T17:28:08.240Z,1657646888.240 [DataOverHttps](INFO): Moved sent file to Logs/20220712T172449/Courier0000.lzma.bak
2022-07-12T17:28:08.241Z,1657646888.241 [DataOverHttps](INFO): SBD MOMSN=16971965
2022-07-12T17:28:16.540Z,1657646896.540 [DataOverHttps](IMPORTANT): SBD MTMSN=20220712T172815
2022-07-12T17:28:23.803Z,1657646903.803 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20220712T171558/Express0005.lzma
2022-07-12T17:28:23.806Z,1657646903.806 [DataOverHttps](INFO): Received command: configSet VerticalControl.buoyancyNeutral 158 cc persist;configSet VerticalControl.massDefault 26 mm persist
2022-07-12T17:28:24.427Z,1657646904.427 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 158 cubic_centimeter persist
2022-07-12T17:28:24.431Z,1657646904.431 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 26 millimeter persist
2022-07-12T17:28:24.982Z,1657646904.982 [DataOverHttps](INFO): Moved sent file to Logs/20220712T171558/Express0005.lzma.bak
2022-07-12T17:28:24.991Z,1657646904.991 [DataOverHttps](INFO): SBD MOMSN=16971998
2022-07-12T17:28:29.747Z,1657646909.747 [CommandExec](IMPORTANT): got command failComponent
2022-07-12T17:28:29.747Z,1657646909.747 [CommandExec](IMPORTANT): Failed components:
2022-07-12T17:28:29.748Z,1657646909.748 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-07-12T17:28:40.734Z,1657646920.734 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220712T172449/Courier0004.lzma
2022-07-12T17:28:41.736Z,1657646921.736 [DataOverHttps](INFO): Moved sent file to Logs/20220712T172449/Courier0004.lzma.bak
2022-07-12T17:28:41.737Z,1657646921.737 [DataOverHttps](INFO): SBD MOMSN=16972019
2022-07-12T17:28:42.164Z,1657646922.164 [CommandExec](IMPORTANT): got command failComponent
2022-07-12T17:28:42.164Z,1657646922.164 [CommandExec](IMPORTANT): Failed components:
2022-07-12T17:28:42.164Z,1657646922.164 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-07-12T17:28:43.648Z,1657646923.648 [Startup:StartupSatComms:B](INFO): Timed out from 2022-07-12T17:27:43.5Z
2022-07-12T17:28:43.648Z,1657646923.648 [Startup:StartupSatComms:B] Stopped
2022-07-12T17:28:43.648Z,1657646923.648 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-07-12T17:28:43.648Z,1657646923.648 [Startup:StartupSatComms] Stopped
2022-07-12T17:28:43.648Z,1657646923.648 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-07-12T17:28:43.649Z,1657646923.649 [Startup](INFO): Completed Startup
2022-07-12T17:28:43.649Z,1657646923.649 [MissionManager](INFO): Startup is completed.
2022-07-12T17:28:43.649Z,1657646923.649 [MissionManager](INFO): Uninitializing Mission Startup
2022-07-12T17:28:43.649Z,1657646923.649 [Startup] Stopped
2022-07-12T17:28:43.649Z,1657646923.649 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-07-12T17:28:43.649Z,1657646923.649 [Startup:A.GoToSurface] Stopped
2022-07-12T17:28:43.650Z,1657646923.650 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-07-12T17:28:44.056Z,1657646924.056 [MissionManager](IMPORTANT): Started mission Default
2022-07-12T17:28:44.057Z,1657646924.057 [Default] Running Loop=1
2022-07-12T17:28:44.057Z,1657646924.057 [Default](DEBUG): Aggregate::initialize Default
2022-07-12T17:28:44.057Z,1657646924.057 [Default:B.GoToSurface] Running Loop=1
2022-07-12T17:28:44.057Z,1657646924.057 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-07-12T17:28:44.057Z,1657646924.057 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-07-12T17:28:44.057Z,1657646924.057 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-07-12T17:28:44.058Z,1657646924.058 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-07-12T17:28:44.058Z,1657646924.058 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-07-12T17:28:44.058Z,1657646924.058 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-07-12T17:28:44.059Z,1657646924.059 [Default:A.Wait] Running Loop=1
2022-07-12T17:28:44.059Z,1657646924.059 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-07-12T17:28:51.652Z,1657646931.652 [CommandExec](IMPORTANT): got command restart application
2022-07-12T17:28:52.655Z,1657646932.655 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:52.655Z,1657646932.655 [CommandExec](INFO): Uninitializing the command executive.
2022-07-12T17:28:52.655Z,1657646932.655 [CommandExec](INFO): Uninitializing the command scheduler.
2022-07-12T17:28:52.655Z,1657646932.655 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:52.810Z,1657646932.810 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-07-12T17:28:52.811Z,1657646932.811 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-07-12T17:28:52.811Z,1657646932.811 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:52.812Z,1657646932.812 [NavChartDb](INFO): Join timeout helper Thread ID is 1121
2022-07-12T17:28:52.831Z,1657646932.831 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:52.831Z,1657646932.831 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:52.851Z,1657646932.851 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-07-12T17:28:52.851Z,1657646932.851 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:52.851Z,1657646932.851 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1122
2022-07-12T17:28:53.323Z,1657646933.323 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:53.323Z,1657646933.323 [WetLabsBB2FL](INFO): Powering down
2022-07-12T17:28:53.324Z,1657646933.324 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:53.331Z,1657646933.331 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-07-12T17:28:53.331Z,1657646933.331 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:53.331Z,1657646933.331 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1123
2022-07-12T17:28:53.343Z,1657646933.343 [CTD_Seabird](INFO): Powering down
2022-07-12T17:28:53.355Z,1657646933.355 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:53.355Z,1657646933.355 [CTD_Seabird](INFO): Powering down
2022-07-12T17:28:53.367Z,1657646933.367 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:53.367Z,1657646933.367 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-07-12T17:28:53.367Z,1657646933.367 [DAT ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:53.368Z,1657646933.368 [DAT](INFO): Join timeout helper Thread ID is 1124
2022-07-12T17:28:53.591Z,1657646933.591 [DAT](INFO): Powering down
2022-07-12T17:28:53.667Z,1657646933.667 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:53.668Z,1657646933.668 [DAT](INFO): Powering down
2022-07-12T17:28:53.668Z,1657646933.668 [DAT ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:53.683Z,1657646933.683 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-07-12T17:28:53.683Z,1657646933.683 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:53.683Z,1657646933.683 [Radio_Surface](INFO): Join timeout helper Thread ID is 1125
2022-07-12T17:28:53.879Z,1657646933.879 [Radio_Surface](INFO): Powering down
2022-07-12T17:28:53.880Z,1657646933.880 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:53.880Z,1657646933.880 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:53.888Z,1657646933.888 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-07-12T17:28:53.888Z,1657646933.888 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:53.888Z,1657646933.888 [Onboard](INFO): Join timeout helper Thread ID is 1126
2022-07-12T17:28:54.339Z,1657646934.339 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-07-12T17:28:55.499Z,1657646935.499 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:55.499Z,1657646935.499 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:55.517Z,1657646935.517 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-07-12T17:28:55.517Z,1657646935.517 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:55.518Z,1657646935.518 [DataOverHttps](INFO): Join timeout helper Thread ID is 1127
2022-07-12T17:28:56.347Z,1657646936.347 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:56.347Z,1657646936.347 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.363Z,1657646936.363 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-07-12T17:28:56.363Z,1657646936.363 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.364Z,1657646936.364 [BackseatComponent](INFO): Join timeout helper Thread ID is 1128
2022-07-12T17:28:56.435Z,1657646936.435 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:56.435Z,1657646936.435 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.452Z,1657646936.452 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-07-12T17:28:56.452Z,1657646936.452 [logger ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.452Z,1657646936.452 [logger](INFO): Join timeout helper Thread ID is 1129
2022-07-12T17:28:56.461Z,1657646936.461 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:56.461Z,1657646936.461 [logger ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.462Z,1657646936.462 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-07-12T17:28:56.462Z,1657646936.462 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.463Z,1657646936.463 [CommandLine](INFO): Join timeout helper Thread ID is 1130
2022-07-12T17:28:56.479Z,1657646936.479 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:56.479Z,1657646936.479 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.483Z,1657646936.483 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-07-12T17:28:56.483Z,1657646936.483 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.484Z,1657646936.484 [CommandExec](INFO): Join timeout helper Thread ID is 1131
2022-07-12T17:28:56.485Z,1657646936.485 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-07-12T17:28:56.485Z,1657646936.485 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.485Z,1657646936.485 [controlThread](INFO): Join timeout helper Thread ID is 1132
2022-07-12T17:28:56.551Z,1657646936.551 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-07-12T17:28:56.551Z,1657646936.551 [controlThread](DEBUG): Uninitializing ControlThread
2022-07-12T17:28:56.551Z,1657646936.551 [AHRS_M2](INFO): Powering down
2022-07-12T17:28:56.624Z,1657646936.624 [NAL9602](INFO): Powering down
2022-07-12T17:28:56.626Z,1657646936.626 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-07-12T17:28:56.627Z,1657646936.627 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-07-12T17:28:56.627Z,1657646936.627 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-07-12T17:28:56.628Z,1657646936.628 [MissionManager](INFO): Uninitializing Mission Default
2022-07-12T17:28:56.628Z,1657646936.628 [Default] Stopped
2022-07-12T17:28:56.628Z,1657646936.628 [Default](DEBUG): Aggregate::uninitialize Default
2022-07-12T17:28:56.628Z,1657646936.628 [Default:A.Wait] Stopped
2022-07-12T17:28:56.628Z,1657646936.628 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-07-12T17:28:56.628Z,1657646936.628 [Default:B.GoToSurface] Stopped
2022-07-12T17:28:56.629Z,1657646936.629 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-07-12T17:28:56.632Z,1657646936.632 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-07-12T17:28:56.632Z,1657646936.632 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-07-12T17:28:56.632Z,1657646936.632 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-07-12T17:28:56.633Z,1657646936.633 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-07-12T17:28:56.633Z,1657646936.633 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-07-12T17:28:56.633Z,1657646936.633 [BuoyancyServo](INFO): Powering down
2022-07-12T17:28:56.647Z,1657646936.647 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-07-12T17:28:56.647Z,1657646936.647 [ElevatorServo](INFO): Powering down
2022-07-12T17:28:56.648Z,1657646936.648 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-07-12T17:28:56.648Z,1657646936.648 [MassServo](INFO): Powering down
2022-07-12T17:28:56.649Z,1657646936.649 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-07-12T17:28:56.649Z,1657646936.649 [RudderServo](INFO): Powering down
2022-07-12T17:28:56.651Z,1657646936.651 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-07-12T17:28:56.651Z,1657646936.651 [ThrusterHE](INFO): Powering down
2022-07-12T17:28:56.652Z,1657646936.652 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-07-12T17:28:56.652Z,1657646936.652 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-07-12T17:28:56.653Z,1657646936.653 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-07-12T17:28:56.653Z,1657646936.653 [CBIT](DEBUG): Powering off loads.
2022-07-12T17:28:56.667Z,1657646936.667 [CBIT](DEBUG): Disabling WDT.
2022-07-12T17:28:56.678Z,1657646936.678 [CBIT](DEBUG): Opening all GF detection circuits.
2022-07-12T17:28:56.680Z,1657646936.680 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.721Z,1657646936.721 [DAT ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.726Z,1657646936.726 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.728Z,1657646936.728 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.734Z,1657646936.734 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.777Z,1657646936.777 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.811Z,1657646936.811 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.814Z,1657646936.814 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.846Z,1657646936.846 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-07-12T17:28:56.910Z,1657646936.910 [logger ThreadHandler](INFO): Thread cancelled.