2024-07-03T19:40:56.232Z,1720035656.232 [Supervisor](DEBUG): Initializing supervisor. 2024-07-03T19:40:56.236Z,1720035656.236 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-07-03T19:40:56.236Z,1720035656.236 [SyncHandler](INFO): Protected caller Thread ID is 2818 2024-07-03T19:40:56.237Z,1720035656.237 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-07-03T19:40:56.238Z,1720035656.238 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-07-03T19:40:56.238Z,1720035656.238 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2819 2024-07-03T19:40:56.242Z,1720035656.242 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-07-03T19:40:56.260Z,1720035656.260 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-07-03T19:40:56.261Z,1720035656.261 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-07-03T19:40:56.261Z,1720035656.261 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2820 2024-07-03T19:40:56.265Z,1720035656.265 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-07-03T19:40:56.266Z,1720035656.266 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-07-03T19:40:56.266Z,1720035656.266 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2821 2024-07-03T19:40:56.268Z,1720035656.268 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-07-03T19:40:56.269Z,1720035656.269 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-07-03T19:40:56.270Z,1720035656.270 [logger ThreadHandler](INFO): Protected caller Thread ID is 2822 2024-07-03T19:40:56.273Z,1720035656.273 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-07-03T19:40:56.274Z,1720035656.274 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-07-03T19:40:56.275Z,1720035656.275 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-07-03T19:40:56.762Z,1720035656.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-07-03T19:40:56.763Z,1720035656.763 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-07-03T19:40:56.993Z,1720035656.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-07-03T19:40:56.993Z,1720035656.993 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-07-03T19:40:57.087Z,1720035657.087 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-07-03T19:40:57.336Z,1720035657.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-07-03T19:40:57.337Z,1720035657.337 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-07-03T19:40:57.429Z,1720035657.429 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-07-03T19:40:57.540Z,1720035657.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-07-03T19:40:57.541Z,1720035657.541 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-07-03T19:40:57.904Z,1720035657.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-07-03T19:40:57.904Z,1720035657.904 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-07-03T19:40:58.050Z,1720035658.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-07-03T19:40:58.051Z,1720035658.051 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-07-03T19:40:58.683Z,1720035658.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-07-03T19:40:58.684Z,1720035658.684 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-07-03T19:40:58.888Z,1720035658.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-07-03T19:40:58.889Z,1720035658.889 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-07-03T19:40:59.315Z,1720035659.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-07-03T19:40:59.316Z,1720035659.316 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-07-03T19:40:59.623Z,1720035659.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-07-03T19:40:59.623Z,1720035659.623 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-07-03T19:40:59.746Z,1720035659.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-07-03T19:40:59.747Z,1720035659.747 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-07-03T19:41:00.604Z,1720035660.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-07-03T19:41:00.605Z,1720035660.605 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-07-03T19:41:01.011Z,1720035661.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-07-03T19:41:01.012Z,1720035661.012 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-07-03T19:41:01.508Z,1720035661.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-07-03T19:41:01.509Z,1720035661.509 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2024-07-03T19:41:01.511Z,1720035661.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2024-07-03T19:41:01.644Z,1720035661.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2024-07-03T19:41:01.741Z,1720035661.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2024-07-03T19:41:01.891Z,1720035661.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2024-07-03T19:41:01.989Z,1720035661.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2024-07-03T19:41:02.263Z,1720035662.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-07-03T19:41:02.263Z,1720035662.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2024-07-03T19:41:02.380Z,1720035662.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2024-07-03T19:41:02.500Z,1720035662.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2024-07-03T19:41:02.608Z,1720035662.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2024-07-03T19:41:02.772Z,1720035662.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2024-07-03T19:41:02.882Z,1720035662.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2024-07-03T19:41:02.981Z,1720035662.981 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2024-07-03T19:41:02.982Z,1720035662.982 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-07-03T19:41:02.986Z,1720035662.986 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-07-03T19:41:02.997Z,1720035662.997 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-07-03T19:41:02.997Z,1720035662.997 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-07-03T19:41:03.104Z,1720035663.104 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-07-03T19:41:03.104Z,1720035663.104 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-07-03T19:41:03.156Z,1720035663.156 [VerticalControl](DEBUG): Construct VerticalControl. 2024-07-03T19:41:03.215Z,1720035663.215 [VerticalControl] Loaded 2024-07-03T19:41:03.215Z,1720035663.215 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-07-03T19:41:03.218Z,1720035663.218 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-07-03T19:41:03.265Z,1720035663.265 [HorizontalControl] Loaded 2024-07-03T19:41:03.265Z,1720035663.265 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-07-03T19:41:03.267Z,1720035663.267 [SpeedControl](DEBUG): Construct SpeedControl. 2024-07-03T19:41:03.270Z,1720035663.270 [SpeedControl] Loaded 2024-07-03T19:41:03.271Z,1720035663.271 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-07-03T19:41:03.273Z,1720035663.273 [LoopControl](DEBUG): Construct LoopControl. 2024-07-03T19:41:03.274Z,1720035663.274 [LoopControl] Loaded 2024-07-03T19:41:03.274Z,1720035663.274 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-07-03T19:41:03.274Z,1720035663.274 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-07-03T19:41:03.275Z,1720035663.275 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-07-03T19:41:03.403Z,1720035663.403 [BuoyancyServo] Loaded 2024-07-03T19:41:03.403Z,1720035663.403 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-07-03T19:41:03.425Z,1720035663.425 [ElevatorServo] Loaded 2024-07-03T19:41:03.425Z,1720035663.425 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-07-03T19:41:03.445Z,1720035663.445 [MassServo] Loaded 2024-07-03T19:41:03.446Z,1720035663.446 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-07-03T19:41:03.466Z,1720035663.466 [RudderServo] Loaded 2024-07-03T19:41:03.466Z,1720035663.466 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-07-03T19:41:03.482Z,1720035663.482 [ThrusterHE] Loaded 2024-07-03T19:41:03.483Z,1720035663.483 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-07-03T19:41:03.483Z,1720035663.483 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-07-03T19:41:03.484Z,1720035663.484 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-07-03T19:41:03.532Z,1720035663.532 [DepthRateCalculator] Loaded 2024-07-03T19:41:03.533Z,1720035663.533 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-07-03T19:41:03.537Z,1720035663.537 [PitchRateCalculator] Loaded 2024-07-03T19:41:03.538Z,1720035663.538 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-07-03T19:41:03.547Z,1720035663.547 [SpeedCalculator] Loaded 2024-07-03T19:41:03.547Z,1720035663.547 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-07-03T19:41:03.552Z,1720035663.552 [YawRateCalculator] Loaded 2024-07-03T19:41:03.552Z,1720035663.552 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-07-03T19:41:03.570Z,1720035663.570 [ElevatorOffsetCalculator] Loaded 2024-07-03T19:41:03.570Z,1720035663.570 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-07-03T19:41:03.571Z,1720035663.571 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-07-03T19:41:03.571Z,1720035663.571 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-07-03T19:41:03.680Z,1720035663.680 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-07-03T19:41:03.680Z,1720035663.680 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-07-03T19:41:03.693Z,1720035663.693 [NavChart] Loaded 2024-07-03T19:41:03.694Z,1720035663.694 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-07-03T19:41:03.699Z,1720035663.699 [UniversalFixResidualReporter] Loaded 2024-07-03T19:41:03.700Z,1720035663.700 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-07-03T19:41:03.700Z,1720035663.700 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-07-03T19:41:03.701Z,1720035663.701 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-07-03T19:41:04.107Z,1720035664.107 [BackseatComponent] Loaded 2024-07-03T19:41:04.108Z,1720035664.108 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-07-03T19:41:04.109Z,1720035664.109 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0 2024-07-03T19:41:04.109Z,1720035664.109 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2905 2024-07-03T19:41:04.112Z,1720035664.112 [LcmUniversalReporter] Loaded 2024-07-03T19:41:04.112Z,1720035664.112 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-07-03T19:41:04.119Z,1720035664.119 [BioacousticsDataBridge] Loaded 2024-07-03T19:41:04.119Z,1720035664.119 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2024-07-03T19:41:04.947Z,1720035664.947 [BPC1] Loaded 2024-07-03T19:41:04.947Z,1720035664.947 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-07-03T19:41:05.019Z,1720035665.019 [DataOverHttps] Loaded 2024-07-03T19:41:05.019Z,1720035665.019 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-07-03T19:41:05.020Z,1720035665.020 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0 2024-07-03T19:41:05.020Z,1720035665.020 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2906 2024-07-03T19:41:05.043Z,1720035665.043 [Depth_Keller] Loaded 2024-07-03T19:41:05.044Z,1720035665.044 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-07-03T19:41:05.049Z,1720035665.049 [DropWeight] Loaded 2024-07-03T19:41:05.049Z,1720035665.049 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-07-03T19:41:05.113Z,1720035665.113 [NAL9602] Loaded 2024-07-03T19:41:05.114Z,1720035665.114 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-07-03T19:41:05.144Z,1720035665.144 [Onboard] Loaded 2024-07-03T19:41:05.144Z,1720035665.144 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-07-03T19:41:05.145Z,1720035665.145 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0 2024-07-03T19:41:05.145Z,1720035665.145 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2907 2024-07-03T19:41:05.159Z,1720035665.159 [Power24vConverter] Loaded 2024-07-03T19:41:05.160Z,1720035665.160 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-07-03T19:41:05.175Z,1720035665.175 [Radio_Surface] Loaded 2024-07-03T19:41:05.175Z,1720035665.175 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-07-03T19:41:05.176Z,1720035665.176 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0 2024-07-03T19:41:05.177Z,1720035665.177 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2908 2024-07-03T19:41:05.192Z,1720035665.192 [Sonardyne_Nano] Loaded 2024-07-03T19:41:05.192Z,1720035665.192 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-07-03T19:41:05.193Z,1720035665.193 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-07-03T19:41:05.193Z,1720035665.193 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-07-03T19:41:05.248Z,1720035665.248 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-07-03T19:41:05.248Z,1720035665.248 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-07-03T19:41:05.413Z,1720035665.413 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-07-03T19:41:05.413Z,1720035665.413 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-07-03T19:41:05.439Z,1720035665.439 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-07-03T19:41:05.439Z,1720035665.439 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-07-03T19:41:05.608Z,1720035665.608 [CTD_Seabird] Loaded 2024-07-03T19:41:05.608Z,1720035665.608 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-07-03T19:41:05.609Z,1720035665.609 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0 2024-07-03T19:41:05.610Z,1720035665.610 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2909 2024-07-03T19:41:05.632Z,1720035665.632 [PAR_Licor] Loaded 2024-07-03T19:41:05.632Z,1720035665.632 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-07-03T19:41:05.676Z,1720035665.676 [WetLabsBB2FL] Loaded 2024-07-03T19:41:05.677Z,1720035665.677 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-07-03T19:41:05.678Z,1720035665.678 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0 2024-07-03T19:41:05.678Z,1720035665.678 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2910 2024-07-03T19:41:05.679Z,1720035665.679 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-07-03T19:41:05.679Z,1720035665.679 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-07-03T19:41:06.005Z,1720035666.005 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-07-03T19:41:06.005Z,1720035666.005 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-07-03T19:41:06.425Z,1720035666.425 [SBIT](DEBUG): Construct Startup Built In Test. 2024-07-03T19:41:06.435Z,1720035666.435 [SBIT] Loaded 2024-07-03T19:41:06.436Z,1720035666.436 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-07-03T19:41:06.438Z,1720035666.438 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-07-03T19:41:06.451Z,1720035666.451 [IBIT] Loaded 2024-07-03T19:41:06.452Z,1720035666.452 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-07-03T19:41:06.457Z,1720035666.457 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-07-03T19:41:06.561Z,1720035666.561 [CBIT] Loaded 2024-07-03T19:41:06.562Z,1720035666.562 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-07-03T19:41:06.562Z,1720035666.562 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-07-03T19:41:06.568Z,1720035666.568 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-07-03T19:41:06.571Z,1720035666.571 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-07-03T19:41:06.582Z,1720035666.582 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-07-03T19:41:06.583Z,1720035666.583 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0 2024-07-03T19:41:06.584Z,1720035666.584 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2911 2024-07-03T19:41:06.588Z,1720035666.588 [Supervisor](INFO): Main Thread ID is 2817 2024-07-03T19:41:06.588Z,1720035666.588 [Supervisor](DEBUG): Running supervisor. 2024-07-03T19:41:06.589Z,1720035666.589 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2912 2024-07-03T19:41:06.590Z,1720035666.590 [CommandExec](INFO): Initializing the command executive. 2024-07-03T19:41:06.591Z,1720035666.591 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2913 2024-07-03T19:41:06.593Z,1720035666.593 [controlThread ThreadHandler](INFO): Handler Thread ID is 2914 2024-07-03T19:41:06.594Z,1720035666.594 [controlThread](DEBUG): Initializing ControlThread 2024-07-03T19:41:06.595Z,1720035666.595 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-07-03T19:41:06.597Z,1720035666.597 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-07-03T19:41:06.597Z,1720035666.597 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-07-03T19:41:06.598Z,1720035666.598 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-07-03T19:41:06.599Z,1720035666.599 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-07-03T19:41:06.600Z,1720035666.600 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-07-03T19:41:06.600Z,1720035666.600 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-07-03T19:41:06.600Z,1720035666.600 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-07-03T19:41:06.601Z,1720035666.601 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-07-03T19:41:06.602Z,1720035666.602 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-07-03T19:41:06.602Z,1720035666.602 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-07-03T19:41:06.611Z,1720035666.611 [SBIT](INFO): Initialize SBIT Component. 2024-07-03T19:41:06.611Z,1720035666.611 [SBIT](IMPORTANT): git: 2024-06-03 2024-07-03T19:41:06.611Z,1720035666.611 [SBIT](INFO): git hash: 7fa19da3a148abf4baea4b65318d99ca26f8c915 2024-07-03T19:41:06.612Z,1720035666.612 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-07-03T19:41:06.613Z,1720035666.613 [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 2024-07-03T19:41:06.614Z,1720035666.614 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2024-07-03T19:41:06.615Z,1720035666.615 [IBIT](INFO): Initialize IBIT Component. 2024-07-03T19:41:06.615Z,1720035666.615 [CBIT](DEBUG): Initialize CBIT Component. 2024-07-03T19:41:06.616Z,1720035666.616 [logger ThreadHandler](INFO): Handler Thread ID is 2915 2024-07-03T19:41:06.626Z,1720035666.626 [CBIT](DEBUG): Initialized mux pins. 2024-07-03T19:41:06.627Z,1720035666.627 [CBIT](DEBUG): Initializing the watchdog timer. 2024-07-03T19:41:06.635Z,1720035666.635 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2916 2024-07-03T19:41:06.647Z,1720035666.647 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2917 2024-07-03T19:41:06.649Z,1720035666.649 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-07-03T19:41:06.651Z,1720035666.651 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-07-03T19:41:06.651Z,1720035666.651 [CBIT](DEBUG): Initializing heartbeat. 2024-07-03T19:41:06.659Z,1720035666.659 [Onboard ThreadHandler](INFO): Handler Thread ID is 2918 2024-07-03T19:41:06.677Z,1720035666.677 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2919 2024-07-03T19:41:06.695Z,1720035666.695 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2920 2024-07-03T19:41:06.696Z,1720035666.696 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-07-03T19:41:06.700Z,1720035666.700 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2922 2024-07-03T19:41:06.703Z,1720035666.703 [WetLabsBB2FL](INFO): Powering up 2024-07-03T19:41:06.704Z,1720035666.704 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2924 2024-07-03T19:41:06.707Z,1720035666.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-07-03T19:41:06.707Z,1720035666.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-07-03T19:41:06.707Z,1720035666.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-07-03T19:41:06.707Z,1720035666.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-07-03T19:41:06.707Z,1720035666.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-07-03T19:41:06.707Z,1720035666.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-07-03T19:41:06.708Z,1720035666.708 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-07-03T19:41:06.708Z,1720035666.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-07-03T19:41:06.723Z,1720035666.723 [CBIT](DEBUG): Deactivating GF circuits. 2024-07-03T19:41:06.723Z,1720035666.723 [CBIT](DEBUG): Deactivating emergency mode. 2024-07-03T19:41:06.762Z,1720035666.762 [CBIT](DEBUG): Backplane powered. 2024-07-03T19:41:06.763Z,1720035666.763 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-07-03T19:41:06.764Z,1720035666.764 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-07-03T19:41:06.764Z,1720035666.764 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-07-03T19:41:06.765Z,1720035666.765 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-07-03T19:41:06.808Z,1720035666.808 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-07-03T19:41:06.812Z,1720035666.812 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-07-03T19:41:06.844Z,1720035666.844 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-07-03T19:41:06.845Z,1720035666.845 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-07-03T19:41:06.845Z,1720035666.845 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-07-03T19:41:06.870Z,1720035666.870 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-07-03T19:41:07.083Z,1720035667.083 [Radio_Surface](INFO): Powering up 2024-07-03T19:41:07.167Z,1720035667.167 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-07-03T19:41:07.173Z,1720035667.173 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-07-03T19:41:07.174Z,1720035667.174 [Default:A.Wait](DEBUG): Construct Wait. 2024-07-03T19:41:07.197Z,1720035667.197 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-07-03T19:41:07.241Z,1720035667.241 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-07-03T19:41:07.244Z,1720035667.244 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-07-03T19:41:07.273Z,1720035667.273 [Default:E.Execute](DEBUG): Construct Execute. 2024-07-03T19:41:07.284Z,1720035667.284 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-07-03T19:41:07.289Z,1720035667.289 [controlThread](DEBUG): Component order: CycleStarter,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,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, 2024-07-03T19:41:07.342Z,1720035667.342 [Power24vConverter](INFO): Powering up. 2024-07-03T19:41:07.343Z,1720035667.343 [Sonardyne_Nano](INFO): Initializing. 2024-07-03T19:41:07.404Z,1720035667.404 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-07-03T19:41:07.411Z,1720035667.411 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-07-03T19:41:07.412Z,1720035667.412 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-07-03T19:41:07.419Z,1720035667.419 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-07-03T19:41:07.420Z,1720035667.420 [MassServo](DEBUG): Initializing EZServoServo. 2024-07-03T19:41:07.427Z,1720035667.427 [MassServo](DEBUG): Initializing MassServo. 2024-07-03T19:41:07.428Z,1720035667.428 [RudderServo](DEBUG): Initializing EZServoServo. 2024-07-03T19:41:07.435Z,1720035667.435 [RudderServo](DEBUG): Initializing RudderServo. 2024-07-03T19:41:07.436Z,1720035667.436 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-07-03T19:41:07.443Z,1720035667.443 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-07-03T19:41:07.740Z,1720035667.740 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-07-03T19:41:07.740Z,1720035667.740 [DropWeight] Hardware Fault, FailCount= 1 2024-07-03T19:41:07.740Z,1720035667.740 [DropWeight](ERROR): Hardware Fault 2024-07-03T19:41:07.773Z,1720035667.773 [CommandExec](FAULT): Scheduling is paused 2024-07-03T19:41:07.773Z,1720035667.773 [CBIT](INFO): Critical error at 20240703T194107 2024-07-03T19:41:07.773Z,1720035667.773 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-07-03T19:41:07.784Z,1720035667.784 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-07-03T19:41:07.784Z,1720035667.784 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-07-03T19:41:08.139Z,1720035668.139 [CBIT](INFO): Critical error at 20240703T194107 2024-07-03T19:41:08.811Z,1720035668.811 [WetLabsBB2FL](INFO): Powering down 2024-07-03T19:41:09.317Z,1720035669.317 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-07-03T19:41:10.531Z,1720035670.531 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2024-07-03T19:41:16.648Z,1720035676.648 [CBIT](CRITICAL): Environmental Failure. Press:14.814933 PSI. Humidity:49%. Temp:24 C. ABORTING MISSION 2024-07-03T19:41:17.041Z,1720035677.041 [CBIT](INFO): Critical error at 20240703T194116 2024-07-03T19:41:20.149Z,1720035680.149 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004739 2024-07-03T19:41:27.160Z,1720035687.160 [SBIT](IMPORTANT): Beginning Startup BIT 2024-07-03T19:41:27.168Z,1720035687.168 [CBIT](IMPORTANT): Beginning ground fault scan 2024-07-03T19:41:35.304Z,1720035695.304 [NAL9602](INFO): Powering up NAL9602 2024-07-03T19:41:38.194Z,1720035698.194 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005782 CHAN A1 (24V): -0.006259 CHAN A2 (12V): -0.004442 CHAN A3 (5V): -0.003105 CHAN B0 (3.3V): -0.001013 CHAN B1 (3.15aV): -0.001520 CHAN B2 (3.15bV): -0.000510 CHAN B3 (GND): -0.000847 OPEN: 0.003358 Full Scale: +/- 1 mA 2024-07-03T19:41:46.220Z,1720035706.220 [NAL9602](INFO): NAL9602 initialized 2024-07-03T19:41:56.788Z,1720035716.788 [CommandExec](IMPORTANT): got command strobe off 2024-07-03T19:41:56.788Z,1720035716.788 [CommandExec](IMPORTANT): Deactivating strobe 2024-07-03T19:42:21.048Z,1720035741.048 [SBIT](IMPORTANT): SBIT PASSED 2024-07-03T19:42:21.048Z,1720035741.048 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-07-03T19:42:21.049Z,1720035741.049 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2024-07-03T19:42:21.049Z,1720035741.049 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2024-07-03T19:42:21.049Z,1720035741.049 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-07-03T19:42:21.427Z,1720035741.427 [MissionManager](IMPORTANT): Started mission Startup 2024-07-03T19:42:21.427Z,1720035741.427 [Startup] Running Loop=1 2024-07-03T19:42:21.427Z,1720035741.427 [Startup](DEBUG): Aggregate::initialize Startup 2024-07-03T19:42:21.427Z,1720035741.427 [Startup:A.GoToSurface] Running Loop=1 2024-07-03T19:42:21.427Z,1720035741.427 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-07-03T19:42:21.428Z,1720035741.428 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-07-03T19:42:21.428Z,1720035741.428 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-07-03T19:42:21.428Z,1720035741.428 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-07-03T19:42:21.429Z,1720035741.429 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-07-03T19:42:21.429Z,1720035741.429 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-07-03T19:42:21.429Z,1720035741.429 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-07-03T19:42:21.455Z,1720035741.455 [Startup:StartupSatComms] Running Loop=1 2024-07-03T19:42:21.455Z,1720035741.455 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-07-03T19:42:21.455Z,1720035741.455 [Startup:StartupSatComms:A] Running Loop=1 2024-07-03T19:42:21.831Z,1720035741.831 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-07-03T19:43:06.626Z,1720035786.626 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2024-07-03T19:43:06.626Z,1720035786.626 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-07-03T19:43:06.626Z,1720035786.626 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-03T19:43:06.637Z,1720035786.637 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-03T19:43:07.041Z,1720035787.041 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-03T19:43:07.041Z,1720035787.041 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-07-03T19:43:21.633Z,1720035801.633 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-03T19:42:21.5Z 2024-07-03T19:43:21.633Z,1720035801.633 [Startup:StartupSatComms:A] Stopped 2024-07-03T19:43:21.633Z,1720035801.633 [Startup:StartupSatComms:B] Running Loop=1 2024-07-03T19:43:22.048Z,1720035802.048 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-07-03T19:43:30.618Z,1720035810.618 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240703T190151/Courier0007.lzma 2024-07-03T19:43:31.620Z,1720035811.620 [DataOverHttps](INFO): Moved sent file to Logs/20240703T190151/Courier0007.lzma.bak 2024-07-03T19:43:31.621Z,1720035811.621 [DataOverHttps](INFO): SBD MOMSN=21321646 2024-07-03T19:43:48.555Z,1720035828.555 [DataOverHttps](INFO): Sending 319 bytes from file Logs/20240703T194056/Courier0000.lzma 2024-07-03T19:43:49.016Z,1720035829.016 [DataOverHttps](INFO): Moved sent file to Logs/20240703T194056/Courier0000.lzma.bak 2024-07-03T19:43:49.016Z,1720035829.016 [DataOverHttps](INFO): SBD MOMSN=21321648 2024-07-03T19:44:11.711Z,1720035851.711 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20240703T183612/Express0014.lzma 2024-07-03T19:44:12.713Z,1720035852.713 [DataOverHttps](INFO): Moved sent file to Logs/20240703T183612/Express0014.lzma.bak 2024-07-03T19:44:12.713Z,1720035852.713 [DataOverHttps](INFO): SBD MOMSN=21321654 2024-07-03T19:44:21.807Z,1720035861.807 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-03T19:43:21.6Z 2024-07-03T19:44:21.807Z,1720035861.807 [Startup:StartupSatComms:B] Stopped 2024-07-03T19:44:21.807Z,1720035861.807 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-07-03T19:44:21.807Z,1720035861.807 [Startup:StartupSatComms] Stopped 2024-07-03T19:44:21.808Z,1720035861.808 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-07-03T19:44:21.808Z,1720035861.808 [Startup](INFO): Completed Startup 2024-07-03T19:44:21.809Z,1720035861.809 [MissionManager](INFO): Startup is completed. 2024-07-03T19:44:21.809Z,1720035861.809 [MissionManager](INFO): Uninitializing Mission Startup 2024-07-03T19:44:21.809Z,1720035861.809 [Startup] Stopped 2024-07-03T19:44:21.809Z,1720035861.809 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-07-03T19:44:21.809Z,1720035861.809 [Startup:A.GoToSurface] Stopped 2024-07-03T19:44:21.809Z,1720035861.809 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-07-03T19:44:22.203Z,1720035862.203 [MissionManager](IMPORTANT): Started mission Default 2024-07-03T19:44:22.203Z,1720035862.203 [Default] Running Loop=1 2024-07-03T19:44:22.203Z,1720035862.203 [Default](DEBUG): Aggregate::initialize Default 2024-07-03T19:44:22.203Z,1720035862.203 [Default:B.GoToSurface] Running Loop=1 2024-07-03T19:44:22.203Z,1720035862.203 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-07-03T19:44:22.204Z,1720035862.204 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-07-03T19:44:22.204Z,1720035862.204 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-07-03T19:44:22.204Z,1720035862.204 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-07-03T19:44:22.204Z,1720035862.204 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-07-03T19:44:22.205Z,1720035862.205 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-07-03T19:44:22.205Z,1720035862.205 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-07-03T19:44:22.205Z,1720035862.205 [Default:A.Wait] Running Loop=1 2024-07-03T19:44:22.205Z,1720035862.205 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-07-03T19:44:32.955Z,1720035872.955 [DataOverHttps](INFO): Sending 858 bytes from file Logs/20240703T190151/Express0001.lzma 2024-07-03T19:44:33.956Z,1720035873.956 [DataOverHttps](INFO): Moved sent file to Logs/20240703T190151/Express0001.lzma.bak 2024-07-03T19:44:33.956Z,1720035873.956 [DataOverHttps](INFO): SBD MOMSN=21321660 2024-07-03T19:44:35.526Z,1720035875.526 [Default:A.Wait](INFO): Done Waiting. 2024-07-03T19:44:35.538Z,1720035875.538 [Default:A.Wait] Stopped 2024-07-03T19:44:35.539Z,1720035875.539 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-07-03T19:44:35.943Z,1720035875.943 [Default:CheckIn] Running Loop=1 2024-07-03T19:44:35.943Z,1720035875.943 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-03T19:44:35.944Z,1720035875.944 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-03T19:44:36.375Z,1720035876.375 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-07-03T19:45:07.446Z,1720035907.446 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2024-07-03T19:45:07.446Z,1720035907.446 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-07-03T19:45:07.446Z,1720035907.446 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-03T19:45:07.456Z,1720035907.456 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-03T19:45:07.872Z,1720035907.872 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-03T19:45:07.872Z,1720035907.872 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-07-03T19:46:06.423Z,1720035966.423 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-03T19:46:12.876Z,1720035972.876 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-03T19:46:21.360Z,1720035981.360 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-03T19:46:29.844Z,1720035989.844 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-03T19:46:36.713Z,1720035996.713 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-03T19:46:46.408Z,1720036006.408 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-03T19:46:50.048Z,1720036010.048 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-07-03T19:46:52.526Z,1720036012.526 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-03T19:46:54.177Z,1720036014.177 [CommandExec](IMPORTANT): got command gfscan 2024-07-03T19:46:54.566Z,1720036014.566 [CBIT](IMPORTANT): Beginning ground fault scan 2024-07-03T19:47:05.045Z,1720036025.045 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-03T19:47:05.506Z,1720036025.506 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006105 CHAN A1 (24V): -0.006906 CHAN A2 (12V): -0.004466 CHAN A3 (5V): -0.002648 CHAN B0 (3.3V): -0.000808 CHAN B1 (3.15aV): -0.001176 CHAN B2 (3.15bV): -0.000891 CHAN B3 (GND): -0.000136 OPEN: 0.003616 Full Scale: +/- 1 mA 2024-07-03T19:47:07.875Z,1720036027.875 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-03T19:47:08.320Z,1720036028.320 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2024-07-03T19:47:08.320Z,1720036028.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-07-03T19:47:08.321Z,1720036028.321 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-03T19:47:08.332Z,1720036028.332 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-03T19:47:08.734Z,1720036028.734 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-03T19:47:08.734Z,1720036028.734 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-07-03T19:47:11.588Z,1720036031.588 [CommandExec](IMPORTANT): got command quit 2024-07-03T19:47:12.592Z,1720036032.592 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:12.592Z,1720036032.592 [CommandExec](INFO): Uninitializing the command executive. 2024-07-03T19:47:12.592Z,1720036032.592 [CommandExec](INFO): Uninitializing the command scheduler. 2024-07-03T19:47:12.592Z,1720036032.592 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:12.716Z,1720036032.716 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-07-03T19:47:12.716Z,1720036032.716 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-07-03T19:47:12.716Z,1720036032.716 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:12.717Z,1720036032.717 [NavChartDb](INFO): Join timeout helper Thread ID is 2946 2024-07-03T19:47:12.956Z,1720036032.956 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:12.956Z,1720036032.956 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:12.976Z,1720036032.976 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-07-03T19:47:12.976Z,1720036032.976 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:12.976Z,1720036032.976 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2947 2024-07-03T19:47:13.420Z,1720036033.420 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:13.420Z,1720036033.420 [WetLabsBB2FL](INFO): Powering down 2024-07-03T19:47:13.421Z,1720036033.421 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:13.436Z,1720036033.436 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-07-03T19:47:13.436Z,1720036033.436 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:13.437Z,1720036033.437 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2948 2024-07-03T19:47:13.952Z,1720036033.952 [CTD_Seabird](INFO): Powering down 2024-07-03T19:47:13.972Z,1720036033.972 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:13.972Z,1720036033.972 [CTD_Seabird](INFO): Powering down 2024-07-03T19:47:13.984Z,1720036033.984 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:13.992Z,1720036033.992 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-07-03T19:47:13.992Z,1720036033.992 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:13.992Z,1720036033.992 [Radio_Surface](INFO): Join timeout helper Thread ID is 2949 2024-07-03T19:47:13.000Z,1720036034.000 [Radio_Surface](INFO): Powering down 2024-07-03T19:47:14.001Z,1720036034.001 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:14.001Z,1720036034.001 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:14.012Z,1720036034.012 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-07-03T19:47:14.012Z,1720036034.012 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:14.012Z,1720036034.012 [Onboard](INFO): Join timeout helper Thread ID is 2950 2024-07-03T19:47:14.684Z,1720036034.684 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-07-03T19:47:17.680Z,1720036037.680 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:17.680Z,1720036037.680 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.685Z,1720036037.685 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-07-03T19:47:17.685Z,1720036037.685 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.685Z,1720036037.685 [DataOverHttps](INFO): Join timeout helper Thread ID is 2951 2024-07-03T19:47:17.696Z,1720036037.696 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:17.696Z,1720036037.696 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.704Z,1720036037.704 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-07-03T19:47:17.704Z,1720036037.704 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.705Z,1720036037.705 [BackseatComponent](INFO): Join timeout helper Thread ID is 2952 2024-07-03T19:47:17.716Z,1720036037.716 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:17.716Z,1720036037.716 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.729Z,1720036037.729 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-07-03T19:47:17.729Z,1720036037.729 [logger ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.729Z,1720036037.729 [logger](INFO): Join timeout helper Thread ID is 2953 2024-07-03T19:47:17.763Z,1720036037.763 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:17.764Z,1720036037.764 [logger ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.772Z,1720036037.772 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-07-03T19:47:17.772Z,1720036037.772 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.773Z,1720036037.773 [CommandLine](INFO): Join timeout helper Thread ID is 2954 2024-07-03T19:47:17.800Z,1720036037.800 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:17.800Z,1720036037.800 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.812Z,1720036037.812 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-07-03T19:47:17.812Z,1720036037.812 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.813Z,1720036037.813 [CommandExec](INFO): Join timeout helper Thread ID is 2955 2024-07-03T19:47:17.814Z,1720036037.814 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-07-03T19:47:17.814Z,1720036037.814 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:17.814Z,1720036037.814 [controlThread](INFO): Join timeout helper Thread ID is 2956 2024-07-03T19:47:17.976Z,1720036037.976 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-03T19:47:17.976Z,1720036037.976 [controlThread](DEBUG): Uninitializing ControlThread 2024-07-03T19:47:17.978Z,1720036037.978 [NAL9602](INFO): Powering down 2024-07-03T19:47:17.979Z,1720036037.979 [Sonardyne_Nano](INFO): Powering down 2024-07-03T19:47:18.101Z,1720036038.101 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-07-03T19:47:18.101Z,1720036038.101 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-07-03T19:47:18.102Z,1720036038.102 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-07-03T19:47:18.102Z,1720036038.102 [MissionManager](INFO): Uninitializing Mission Default 2024-07-03T19:47:18.102Z,1720036038.102 [Default] Stopped 2024-07-03T19:47:18.102Z,1720036038.102 [Default](DEBUG): Aggregate::uninitialize Default 2024-07-03T19:47:18.103Z,1720036038.103 [Default:B.GoToSurface] Stopped 2024-07-03T19:47:18.103Z,1720036038.103 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-07-03T19:47:18.103Z,1720036038.103 [Default:CheckIn] Stopped 2024-07-03T19:47:18.103Z,1720036038.103 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-03T19:47:18.103Z,1720036038.103 [Default:CheckIn:Read_GPS] Stopped 2024-07-03T19:47:18.106Z,1720036038.106 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-07-03T19:47:18.106Z,1720036038.106 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-07-03T19:47:18.106Z,1720036038.106 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-07-03T19:47:18.106Z,1720036038.106 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-07-03T19:47:18.107Z,1720036038.107 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-07-03T19:47:18.107Z,1720036038.107 [BuoyancyServo](INFO): Powering down 2024-07-03T19:47:18.120Z,1720036038.120 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-07-03T19:47:18.120Z,1720036038.120 [ElevatorServo](INFO): Powering down 2024-07-03T19:47:18.121Z,1720036038.121 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-07-03T19:47:18.121Z,1720036038.121 [MassServo](INFO): Powering down 2024-07-03T19:47:18.122Z,1720036038.122 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-07-03T19:47:18.122Z,1720036038.122 [RudderServo](INFO): Powering down 2024-07-03T19:47:18.123Z,1720036038.123 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-07-03T19:47:18.123Z,1720036038.123 [ThrusterHE](INFO): Powering down 2024-07-03T19:47:18.123Z,1720036038.123 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-07-03T19:47:18.124Z,1720036038.124 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-07-03T19:47:18.124Z,1720036038.124 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-07-03T19:47:18.124Z,1720036038.124 [CBIT](DEBUG): Powering off loads. 2024-07-03T19:47:18.136Z,1720036038.136 [CBIT](DEBUG): Disabling WDT. 2024-07-03T19:47:18.148Z,1720036038.148 [CBIT](DEBUG): Opening all GF detection circuits. 2024-07-03T19:47:18.149Z,1720036038.149 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:18.193Z,1720036038.193 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:18.195Z,1720036038.195 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:18.201Z,1720036038.201 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:18.244Z,1720036038.244 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:18.280Z,1720036038.280 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:18.285Z,1720036038.285 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:18.317Z,1720036038.317 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-03T19:47:18.379Z,1720036038.379 [logger ThreadHandler](INFO): Thread cancelled.