2023-05-02T18:43:55.744Z,1683053035.744 [Supervisor](DEBUG): Initializing supervisor. 2023-05-02T18:43:55.748Z,1683053035.748 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-05-02T18:43:55.749Z,1683053035.749 [SyncHandler](INFO): Protected caller Thread ID is 1986 2023-05-02T18:43:55.749Z,1683053035.749 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-05-02T18:43:55.750Z,1683053035.750 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-05-02T18:43:55.751Z,1683053035.751 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1987 2023-05-02T18:43:55.755Z,1683053035.755 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-05-02T18:43:55.772Z,1683053035.772 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-05-02T18:43:55.773Z,1683053035.773 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-05-02T18:43:55.773Z,1683053035.773 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1988 2023-05-02T18:43:55.777Z,1683053035.777 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-05-02T18:43:55.778Z,1683053035.778 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-05-02T18:43:55.779Z,1683053035.779 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1989 2023-05-02T18:43:55.781Z,1683053035.781 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-05-02T18:43:55.782Z,1683053035.782 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-05-02T18:43:55.782Z,1683053035.782 [logger ThreadHandler](INFO): Protected caller Thread ID is 1990 2023-05-02T18:43:55.786Z,1683053035.786 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-05-02T18:43:55.786Z,1683053035.786 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-05-02T18:43:55.788Z,1683053035.788 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-05-02T18:43:56.002Z,1683053036.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-05-02T18:43:56.003Z,1683053036.003 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-05-02T18:43:56.209Z,1683053036.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-05-02T18:43:56.209Z,1683053036.209 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-05-02T18:43:56.300Z,1683053036.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-05-02T18:43:56.301Z,1683053036.301 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-05-02T18:43:56.475Z,1683053036.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-05-02T18:43:56.475Z,1683053036.475 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-05-02T18:43:56.909Z,1683053036.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-05-02T18:43:56.910Z,1683053036.910 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-05-02T18:43:57.013Z,1683053037.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-05-02T18:43:57.013Z,1683053037.013 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-05-02T18:43:57.273Z,1683053037.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-05-02T18:43:57.274Z,1683053037.274 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-05-02T18:43:57.465Z,1683053037.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-05-02T18:43:57.465Z,1683053037.465 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-05-02T18:43:57.931Z,1683053037.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-05-02T18:43:57.931Z,1683053037.931 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-05-02T18:43:58.319Z,1683053038.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-05-02T18:43:58.319Z,1683053038.319 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-05-02T18:43:58.395Z,1683053038.395 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-05-02T18:43:58.736Z,1683053038.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-05-02T18:43:58.736Z,1683053038.736 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-05-02T18:43:58.868Z,1683053038.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-05-02T18:43:58.868Z,1683053038.868 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-05-02T18:43:59.575Z,1683053039.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-05-02T18:43:59.576Z,1683053039.576 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-05-02T18:43:59.893Z,1683053039.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-05-02T18:43:59.894Z,1683053039.894 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-05-02T18:43:59.974Z,1683053039.974 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/ 2023-05-02T18:43:59.975Z,1683053039.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg 2023-05-02T18:44:00.054Z,1683053040.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg 2023-05-02T18:44:00.154Z,1683053040.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg 2023-05-02T18:44:00.308Z,1683053040.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg 2023-05-02T18:44:00.408Z,1683053040.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg 2023-05-02T18:44:00.508Z,1683053040.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg 2023-05-02T18:44:00.600Z,1683053040.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg 2023-05-02T18:44:00.706Z,1683053040.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg 2023-05-02T18:44:00.828Z,1683053040.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg 2023-05-02T18:44:00.981Z,1683053040.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg 2023-05-02T18:44:01.074Z,1683053041.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg 2023-05-02T18:44:01.333Z,1683053041.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-05-02T18:44:01.333Z,1683053041.333 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-05-02T18:44:01.338Z,1683053041.338 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-05-02T18:44:01.705Z,1683053041.705 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-05-02T18:44:01.705Z,1683053041.705 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-05-02T18:44:02.123Z,1683053042.123 [AHRS_M2] Loaded 2023-05-02T18:44:02.123Z,1683053042.123 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-05-02T18:44:02.926Z,1683053042.926 [BPC1] Loaded 2023-05-02T18:44:02.926Z,1683053042.926 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-05-02T18:44:02.998Z,1683053042.998 [DataOverHttps] Loaded 2023-05-02T18:44:02.999Z,1683053042.999 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-05-02T18:44:02.000Z,1683053043.000 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408684E0 2023-05-02T18:44:03.000Z,1683053043.000 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2072 2023-05-02T18:44:03.046Z,1683053043.046 [Depth_Keller] Loaded 2023-05-02T18:44:03.047Z,1683053043.047 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-05-02T18:44:03.051Z,1683053043.051 [DropWeight] Loaded 2023-05-02T18:44:03.052Z,1683053043.052 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-05-02T18:44:03.070Z,1683053043.070 [MultiRay] Loaded 2023-05-02T18:44:03.070Z,1683053043.070 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2023-05-02T18:44:03.131Z,1683053043.131 [NAL9602] Loaded 2023-05-02T18:44:03.131Z,1683053043.131 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-05-02T18:44:03.160Z,1683053043.160 [Onboard] Loaded 2023-05-02T18:44:03.160Z,1683053043.160 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-05-02T18:44:03.161Z,1683053043.161 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408984E0 2023-05-02T18:44:03.162Z,1683053043.162 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2073 2023-05-02T18:44:03.175Z,1683053043.175 [Power24vConverter] Loaded 2023-05-02T18:44:03.175Z,1683053043.175 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-05-02T18:44:03.247Z,1683053043.247 [PNI_TCM] Loaded 2023-05-02T18:44:03.248Z,1683053043.248 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2023-05-02T18:44:03.262Z,1683053043.262 [Radio_Surface] Loaded 2023-05-02T18:44:03.262Z,1683053043.262 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-05-02T18:44:03.263Z,1683053043.263 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408C84E0 2023-05-02T18:44:03.263Z,1683053043.263 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2074 2023-05-02T18:44:03.352Z,1683053043.352 [DAT] Loaded 2023-05-02T18:44:03.353Z,1683053043.353 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-05-02T18:44:03.354Z,1683053043.354 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408F84E0 2023-05-02T18:44:03.354Z,1683053043.354 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2075 2023-05-02T18:44:03.355Z,1683053043.355 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-05-02T18:44:03.355Z,1683053043.355 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-05-02T18:44:03.457Z,1683053043.457 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-05-02T18:44:03.457Z,1683053043.457 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-05-02T18:44:03.509Z,1683053043.509 [VerticalControl](DEBUG): Construct VerticalControl. 2023-05-02T18:44:03.568Z,1683053043.568 [VerticalControl] Loaded 2023-05-02T18:44:03.568Z,1683053043.568 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-05-02T18:44:03.571Z,1683053043.571 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-05-02T18:44:03.612Z,1683053043.612 [HorizontalControl] Loaded 2023-05-02T18:44:03.612Z,1683053043.612 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-05-02T18:44:03.614Z,1683053043.614 [SpeedControl](DEBUG): Construct SpeedControl. 2023-05-02T18:44:03.618Z,1683053043.618 [SpeedControl] Loaded 2023-05-02T18:44:03.618Z,1683053043.618 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-05-02T18:44:03.621Z,1683053043.621 [LoopControl](DEBUG): Construct LoopControl. 2023-05-02T18:44:03.621Z,1683053043.621 [LoopControl] Loaded 2023-05-02T18:44:03.621Z,1683053043.621 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-05-02T18:44:03.622Z,1683053043.622 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-05-02T18:44:03.622Z,1683053043.622 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-05-02T18:44:03.633Z,1683053043.633 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-05-02T18:44:03.633Z,1683053043.633 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-05-02T18:44:03.660Z,1683053043.660 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-05-02T18:44:03.661Z,1683053043.661 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-05-02T18:44:03.774Z,1683053043.774 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-05-02T18:44:03.775Z,1683053043.775 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-05-02T18:44:03.817Z,1683053043.817 [DeadReckonUsingSpeedCalculator] Loaded 2023-05-02T18:44:03.817Z,1683053043.817 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-05-02T18:44:03.858Z,1683053043.858 [DeadReckonWithRespectToSeafloor] Loaded 2023-05-02T18:44:03.859Z,1683053043.859 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2023-05-02T18:44:03.872Z,1683053043.872 [NavChart] Loaded 2023-05-02T18:44:03.872Z,1683053043.872 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-05-02T18:44:03.878Z,1683053043.878 [UniversalFixResidualReporter] Loaded 2023-05-02T18:44:03.878Z,1683053043.878 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-05-02T18:44:03.879Z,1683053043.879 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-05-02T18:44:03.879Z,1683053043.879 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-05-02T18:44:04.021Z,1683053044.021 [SBIT](DEBUG): Construct Startup Built In Test. 2023-05-02T18:44:04.030Z,1683053044.030 [SBIT] Loaded 2023-05-02T18:44:04.030Z,1683053044.030 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-05-02T18:44:04.033Z,1683053044.033 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-05-02T18:44:04.054Z,1683053044.054 [IBIT] Loaded 2023-05-02T18:44:04.054Z,1683053044.054 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-05-02T18:44:04.068Z,1683053044.068 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-05-02T18:44:04.207Z,1683053044.207 [CBIT] Loaded 2023-05-02T18:44:04.207Z,1683053044.207 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-05-02T18:44:04.207Z,1683053044.207 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-05-02T18:44:04.208Z,1683053044.208 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-05-02T18:44:04.357Z,1683053044.357 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-05-02T18:44:04.357Z,1683053044.357 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-05-02T18:44:04.488Z,1683053044.488 [BuoyancyServo] Loaded 2023-05-02T18:44:04.488Z,1683053044.488 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-05-02T18:44:04.509Z,1683053044.509 [ElevatorServo] Loaded 2023-05-02T18:44:04.509Z,1683053044.509 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-05-02T18:44:04.528Z,1683053044.528 [MassServo] Loaded 2023-05-02T18:44:04.529Z,1683053044.529 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-05-02T18:44:04.547Z,1683053044.547 [RudderServo] Loaded 2023-05-02T18:44:04.548Z,1683053044.548 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-05-02T18:44:04.562Z,1683053044.562 [ThrusterHE] Loaded 2023-05-02T18:44:04.563Z,1683053044.563 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-05-02T18:44:04.563Z,1683053044.563 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-05-02T18:44:04.564Z,1683053044.564 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-05-02T18:44:04.699Z,1683053044.699 [Aanderaa_O2] Loaded 2023-05-02T18:44:04.700Z,1683053044.700 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2023-05-02T18:44:04.760Z,1683053044.760 [CTD_Seabird] Loaded 2023-05-02T18:44:04.760Z,1683053044.760 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-05-02T18:44:04.761Z,1683053044.761 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AFC4E0 2023-05-02T18:44:04.762Z,1683053044.762 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2076 2023-05-02T18:44:04.795Z,1683053044.795 [ESPComponent] Loaded 2023-05-02T18:44:04.795Z,1683053044.795 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-05-02T18:44:04.815Z,1683053044.815 [PAR_Licor] Loaded 2023-05-02T18:44:04.815Z,1683053044.815 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-05-02T18:44:04.846Z,1683053044.846 [WetLabsBB2FL] Loaded 2023-05-02T18:44:04.847Z,1683053044.847 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-05-02T18:44:04.848Z,1683053044.848 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B2C4E0 2023-05-02T18:44:04.848Z,1683053044.848 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2077 2023-05-02T18:44:04.849Z,1683053044.849 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-05-02T18:44:04.849Z,1683053044.849 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-05-02T18:44:04.898Z,1683053044.898 [DepthRateCalculator] Loaded 2023-05-02T18:44:04.899Z,1683053044.899 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-05-02T18:44:04.904Z,1683053044.904 [PitchRateCalculator] Loaded 2023-05-02T18:44:04.904Z,1683053044.904 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-05-02T18:44:04.913Z,1683053044.913 [SpeedCalculator] Loaded 2023-05-02T18:44:04.913Z,1683053044.913 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-05-02T18:44:04.918Z,1683053044.918 [YawRateCalculator] Loaded 2023-05-02T18:44:04.918Z,1683053044.918 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-05-02T18:44:04.937Z,1683053044.937 [ElevatorOffsetCalculator] Loaded 2023-05-02T18:44:04.937Z,1683053044.937 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-05-02T18:44:04.938Z,1683053044.938 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-05-02T18:44:04.938Z,1683053044.938 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-05-02T18:44:05.009Z,1683053045.009 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-05-02T18:44:05.015Z,1683053045.015 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-05-02T18:44:05.018Z,1683053045.018 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-05-02T18:44:05.029Z,1683053045.029 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-05-02T18:44:05.030Z,1683053045.030 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BAE4E0 2023-05-02T18:44:05.030Z,1683053045.030 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2078 2023-05-02T18:44:05.035Z,1683053045.035 [Supervisor](INFO): Main Thread ID is 1985 2023-05-02T18:44:05.035Z,1683053045.035 [Supervisor](DEBUG): Running supervisor. 2023-05-02T18:44:05.036Z,1683053045.036 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2079 2023-05-02T18:44:05.036Z,1683053045.036 [CommandExec](INFO): Initializing the command executive. 2023-05-02T18:44:05.038Z,1683053045.038 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2080 2023-05-02T18:44:05.040Z,1683053045.040 [controlThread ThreadHandler](INFO): Handler Thread ID is 2081 2023-05-02T18:44:05.040Z,1683053045.040 [controlThread](DEBUG): Initializing ControlThread 2023-05-02T18:44:05.046Z,1683053045.046 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-05-02T18:44:05.048Z,1683053045.048 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-05-02T18:44:05.048Z,1683053045.048 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-05-02T18:44:05.049Z,1683053045.049 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-05-02T18:44:05.050Z,1683053045.050 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-05-02T18:44:05.050Z,1683053045.050 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-05-02T18:44:05.051Z,1683053045.051 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-05-02T18:44:05.051Z,1683053045.051 [SBIT](INFO): Initialize SBIT Component. 2023-05-02T18:44:05.051Z,1683053045.051 [SBIT](IMPORTANT): git: 2023-02-15A-21-g3d3976af4 2023-05-02T18:44:05.052Z,1683053045.052 [SBIT](INFO): git hash: 3d3976af40c3333a6154de93af21df77ac8c6f9b 2023-05-02T18:44:05.052Z,1683053045.052 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-05-02T18:44:05.053Z,1683053045.053 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-05-02T18:44:05.054Z,1683053045.054 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2023-05-02T18:44:05.055Z,1683053045.055 [IBIT](INFO): Initialize IBIT Component. 2023-05-02T18:44:05.056Z,1683053045.056 [CBIT](DEBUG): Initialize CBIT Component. 2023-05-02T18:44:05.057Z,1683053045.057 [logger ThreadHandler](INFO): Handler Thread ID is 2082 2023-05-02T18:44:05.068Z,1683053045.068 [CBIT](DEBUG): Initialized mux pins. 2023-05-02T18:44:05.068Z,1683053045.068 [CBIT](DEBUG): Initializing the watchdog timer. 2023-05-02T18:44:05.076Z,1683053045.076 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2083 2023-05-02T18:44:05.077Z,1683053045.077 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-05-02T18:44:05.088Z,1683053045.088 [Onboard ThreadHandler](INFO): Handler Thread ID is 2084 2023-05-02T18:44:05.092Z,1683053045.092 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-05-02T18:44:05.093Z,1683053045.093 [CBIT](DEBUG): Initializing heartbeat. 2023-05-02T18:44:05.109Z,1683053045.109 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2085 2023-05-02T18:44:05.124Z,1683053045.124 [DAT ThreadHandler](INFO): Handler Thread ID is 2086 2023-05-02T18:44:05.125Z,1683053045.125 [DAT](INFO): Powering up 2023-05-02T18:44:05.125Z,1683053045.125 [DAT](DEBUG): Initializing DAT. 2023-05-02T18:44:05.129Z,1683053045.129 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2088 2023-05-02T18:44:05.130Z,1683053045.130 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:44:05.133Z,1683053045.133 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2090 2023-05-02T18:44:05.136Z,1683053045.136 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:44:05.138Z,1683053045.138 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2092 2023-05-02T18:44:05.141Z,1683053045.141 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2023-05-02T18:44:05.141Z,1683053045.141 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2023-05-02T18:44:05.141Z,1683053045.141 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2023-05-02T18:44:05.142Z,1683053045.142 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2023-05-02T18:44:05.142Z,1683053045.142 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-05-02T18:44:05.142Z,1683053045.142 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-05-02T18:44:05.142Z,1683053045.142 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-05-02T18:44:05.142Z,1683053045.142 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-05-02T18:44:05.142Z,1683053045.142 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2023-05-02T18:44:05.143Z,1683053045.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2023-05-02T18:44:05.143Z,1683053045.143 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2023-05-02T18:44:05.143Z,1683053045.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2023-05-02T18:44:05.164Z,1683053045.164 [CBIT](DEBUG): Deactivating GF circuits. 2023-05-02T18:44:05.164Z,1683053045.164 [CBIT](DEBUG): Deactivating emergency mode. 2023-05-02T18:44:05.200Z,1683053045.200 [CBIT](DEBUG): Backplane powered. 2023-05-02T18:44:05.204Z,1683053045.204 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-05-02T18:44:05.205Z,1683053045.205 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-05-02T18:44:05.205Z,1683053045.205 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-05-02T18:44:05.205Z,1683053045.205 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-05-02T18:44:05.206Z,1683053045.206 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-05-02T18:44:05.207Z,1683053045.207 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-05-02T18:44:05.221Z,1683053045.221 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-02T18:44:05.253Z,1683053045.253 [MissionManager](DEBUG): 2023-05-02T18:44:05.253Z,1683053045.253 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-05-02T18:44:05.309Z,1683053045.309 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-05-02T18:44:05.310Z,1683053045.310 [Default:A.Wait](DEBUG): Construct Wait. 2023-05-02T18:44:05.312Z,1683053045.312 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-02T18:44:05.346Z,1683053045.346 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-05-02T18:44:05.357Z,1683053045.357 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-05-02T18:44:05.363Z,1683053045.363 [Default:E.Execute](DEBUG): Construct Execute. 2023-05-02T18:44:05.366Z,1683053045.366 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-05-02T18:44:05.375Z,1683053045.375 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2023-05-02T18:44:05.388Z,1683053045.388 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-05-02T18:44:05.439Z,1683053045.439 [MultiRay](INFO): Powering up MultiRay Lights 2023-05-02T18:44:05.464Z,1683053045.464 [Power24vConverter](INFO): Powering up. 2023-05-02T18:44:05.506Z,1683053045.506 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:44:05.512Z,1683053045.512 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-05-02T18:44:05.513Z,1683053045.513 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:44:05.516Z,1683053045.516 [Radio_Surface](INFO): Powering up 2023-05-02T18:44:05.520Z,1683053045.520 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-05-02T18:44:05.521Z,1683053045.521 [MassServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:44:05.528Z,1683053045.528 [MassServo](DEBUG): Initializing MassServo. 2023-05-02T18:44:05.529Z,1683053045.529 [RudderServo](DEBUG): Initializing EZServoServo. 2023-05-02T18:44:05.536Z,1683053045.536 [RudderServo](DEBUG): Initializing RudderServo. 2023-05-02T18:44:05.537Z,1683053045.537 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-05-02T18:44:05.544Z,1683053045.544 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-05-02T18:44:05.837Z,1683053045.837 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-05-02T18:44:05.838Z,1683053045.838 [DropWeight] Hardware Fault, FailCount= 1 2023-05-02T18:44:05.838Z,1683053045.838 [DropWeight](ERROR): Hardware Fault 2023-05-02T18:44:05.939Z,1683053045.939 [CommandExec](FAULT): Scheduling is paused 2023-05-02T18:44:05.940Z,1683053045.940 [CBIT](INFO): Critical error at 20230502T184405 2023-05-02T18:44:05.940Z,1683053045.940 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-05-02T18:44:05.942Z,1683053045.942 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-05-02T18:44:05.943Z,1683053045.943 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-05-02T18:44:06.269Z,1683053046.269 [CBIT](INFO): Critical error at 20230502T184405 2023-05-02T18:44:08.610Z,1683053048.610 [AHRS_M2](DEBUG): boresightMatrix m[ decimal 0 0 2 2 f0.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f0.0 ]m set drop 2023-05-02T18:44:09.013Z,1683053049.013 [AHRS_M2](DEBUG): chan0Format 2 set drop 2023-05-02T18:44:09.417Z,1683053049.417 [AHRS_M2](DEBUG): chan0Trigger 5 set drop 2023-05-02T18:44:09.821Z,1683053049.821 [AHRS_M2](DEBUG): chan0Enables array[ 0 15 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 ]array set drop 2023-05-02T18:44:09.821Z,1683053049.821 [AHRS_M2](DEBUG): chan0EnableBit pitch dvid@ set drop 2023-05-02T18:44:10.225Z,1683053050.225 [AHRS_M2](DEBUG): chan0EnableBit roll dvid@ set drop 2023-05-02T18:44:10.424Z,1683053050.424 [WetLabsBB2FL] Communications Fault, FailCount= 1 2023-05-02T18:44:10.424Z,1683053050.424 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:44:10.629Z,1683053050.629 [AHRS_M2](DEBUG): chan0EnableBit yaw dvid@ set drop 2023-05-02T18:44:10.687Z,1683053050.687 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:44:10.924Z,1683053050.924 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:44:11.033Z,1683053051.033 [AHRS_M2](DEBUG): chan0EnableBit magp dvid@ set drop 2023-05-02T18:44:11.088Z,1683053051.088 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-05-02T18:44:11.437Z,1683053051.437 [AHRS_M2](DEBUG): chan0EnableBit accelp dvid@ set drop 2023-05-02T18:44:11.841Z,1683053051.841 [AHRS_M2](DEBUG): chan0EnableBit gyrop dvid@ set drop 2023-05-02T18:44:11.909Z,1683053051.909 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:44:11.909Z,1683053051.909 [WetLabsBB2FL] No Fault, FailCount= 1 2023-05-02T18:44:12.144Z,1683053052.144 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:44:12.245Z,1683053052.245 [AHRS_M2](DEBUG): chan0EnableBit yawErrEst dvid@ set drop 2023-05-02T18:44:12.649Z,1683053052.649 [AHRS_M2](DEBUG): chan0EnableBit temperature dvid@ set drop 2023-05-02T18:44:12.654Z,1683053052.654 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:44:12.654Z,1683053052.654 [PNI_TCM] Data Fault, FailCount= 1 2023-05-02T18:44:12.654Z,1683053052.654 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:44:12.726Z,1683053052.726 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:44:13.053Z,1683053053.053 [AHRS_M2](DEBUG): chan0EnableBit magBufferActiveIndex dvid@ set drop 2023-05-02T18:44:13.057Z,1683053053.057 [PNI_TCM](INFO): Powering down 2023-05-02T18:44:13.457Z,1683053053.457 [AHRS_M2](DEBUG): chan0TriggerDivisor 40 set drop 2023-05-02T18:44:14.319Z,1683053054.319 [CBIT](INFO): Clearing failed state for component PNI_TCM 2023-05-02T18:44:14.319Z,1683053054.319 [PNI_TCM] No Fault, FailCount= 1 2023-05-02T18:44:15.148Z,1683053055.148 [CBIT](CRITICAL): Environmental Failure. Press:14.552866 PSI. Humidity:38%. Temp:26 C. ABORTING MISSION 2023-05-02T18:44:15.494Z,1683053055.494 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:44:15.494Z,1683053055.494 [Aanderaa_O2] Communications Fault, FailCount= 1 2023-05-02T18:44:15.494Z,1683053055.494 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:44:15.583Z,1683053055.583 [CBIT](INFO): Critical error at 20230502T184415 2023-05-02T18:44:15.585Z,1683053055.585 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:44:16.012Z,1683053056.012 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:44:16.731Z,1683053056.731 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2023-05-02T18:44:16.731Z,1683053056.731 [Aanderaa_O2] No Fault, FailCount= 1 2023-05-02T18:44:17.432Z,1683053057.432 [WetLabsBB2FL] Communications Fault, FailCount= 2 2023-05-02T18:44:17.432Z,1683053057.432 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:44:17.531Z,1683053057.531 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:44:17.936Z,1683053057.936 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:44:18.180Z,1683053058.180 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:44:18.180Z,1683053058.180 [CTD_Seabird] Communications Fault, FailCount= 1 2023-05-02T18:44:18.180Z,1683053058.180 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:44:18.330Z,1683053058.330 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:44:18.585Z,1683053058.585 [CTD_Seabird](INFO): Powering down 2023-05-02T18:44:19.135Z,1683053059.135 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:44:19.135Z,1683053059.135 [WetLabsBB2FL] No Fault, FailCount= 2 2023-05-02T18:44:19.153Z,1683053059.153 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:44:19.542Z,1683053059.542 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-05-02T18:44:19.542Z,1683053059.542 [CTD_Seabird] No Fault, FailCount= 1 2023-05-02T18:44:19.924Z,1683053059.924 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:44:21.946Z,1683053061.946 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:44:21.946Z,1683053061.946 [PNI_TCM] Data Fault, FailCount= 2 2023-05-02T18:44:21.946Z,1683053061.946 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:44:21.988Z,1683053061.988 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:44:22.354Z,1683053062.354 [PNI_TCM](INFO): Powering down 2023-05-02T18:44:23.557Z,1683053063.557 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2023-05-02T18:44:23.557Z,1683053063.557 [AHRS_M2] Data Fault, FailCount= 1 2023-05-02T18:44:23.557Z,1683053063.557 [AHRS_M2](ERROR): Data Fault 2023-05-02T18:44:23.593Z,1683053063.593 [CBIT](ERROR): Data Fault in component: AHRS_M2 2023-05-02T18:44:23.594Z,1683053063.594 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2023-05-02T18:44:23.594Z,1683053063.594 [CBIT](INFO): Clearing failed state for component PNI_TCM 2023-05-02T18:44:23.594Z,1683053063.594 [PNI_TCM] No Fault, FailCount= 2 2023-05-02T18:44:23.993Z,1683053063.993 [CBIT](INFO): Critical error at 20230502T184423 2023-05-02T18:44:24.444Z,1683053064.444 [WetLabsBB2FL] Communications Fault, FailCount= 3 2023-05-02T18:44:24.444Z,1683053064.444 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:44:24.810Z,1683053064.810 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:44:24.944Z,1683053064.944 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:44:25.606Z,1683053065.606 [SBIT](IMPORTANT): Beginning Startup BIT 2023-05-02T18:44:25.610Z,1683053065.610 [CBIT](IMPORTANT): Beginning ground fault scan 2023-05-02T18:44:26.078Z,1683053066.078 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:44:26.078Z,1683053066.078 [WetLabsBB2FL] No Fault, FailCount= 3 2023-05-02T18:44:26.157Z,1683053066.157 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:44:27.202Z,1683053067.202 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:44:27.202Z,1683053067.202 [Aanderaa_O2] Communications Fault, FailCount= 2 2023-05-02T18:44:27.202Z,1683053067.202 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:44:27.337Z,1683053067.337 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:44:27.724Z,1683053067.724 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:44:28.519Z,1683053068.519 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2023-05-02T18:44:28.519Z,1683053068.519 [Aanderaa_O2] No Fault, FailCount= 2 2023-05-02T18:44:31.235Z,1683053071.235 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:44:31.235Z,1683053071.235 [PNI_TCM] Data Fault, FailCount= 3 2023-05-02T18:44:31.235Z,1683053071.235 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:44:31.282Z,1683053071.282 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:44:31.456Z,1683053071.456 [WetLabsBB2FL] Communications Fault, FailCount= 4 2023-05-02T18:44:31.456Z,1683053071.456 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:44:31.641Z,1683053071.641 [PNI_TCM](INFO): Powering down 2023-05-02T18:44:31.778Z,1683053071.778 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:44:31.956Z,1683053071.956 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:44:32.909Z,1683053072.909 [CBIT](INFO): Clearing failed state for component PNI_TCM 2023-05-02T18:44:32.909Z,1683053072.909 [PNI_TCM] No Fault, FailCount= 3 2023-05-02T18:44:32.909Z,1683053072.909 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:44:32.910Z,1683053072.910 [WetLabsBB2FL] No Fault, FailCount= 4 2023-05-02T18:44:32.976Z,1683053072.976 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:44:32.976Z,1683053072.976 [CTD_Seabird] Communications Fault, FailCount= 2 2023-05-02T18:44:32.976Z,1683053072.976 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:44:33.169Z,1683053073.169 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:44:33.262Z,1683053073.262 [NAL9602](INFO): Powering up NAL9602 2023-05-02T18:44:33.315Z,1683053073.315 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:44:33.380Z,1683053073.380 [CTD_Seabird](INFO): Powering down 2023-05-02T18:44:34.525Z,1683053074.525 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-05-02T18:44:34.525Z,1683053074.525 [CTD_Seabird] No Fault, FailCount= 2 2023-05-02T18:44:34.724Z,1683053074.724 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:44:36.507Z,1683053076.507 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.008789 CHAN A1 (24V): -0.006590 CHAN A2 (12V): -0.000023 CHAN A3 (5V): -0.001503 CHAN B0 (3.3V): -0.001066 CHAN B1 (3.15aV): -0.000926 CHAN B2 (3.15bV): -0.001365 CHAN B3 (GND): -0.001006 OPEN: 0.003726 Full Scale: +/- 1 mA 2023-05-02T18:44:38.460Z,1683053078.460 [WetLabsBB2FL] Communications Fault, FailCount= 5 2023-05-02T18:44:38.460Z,1683053078.460 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:44:38.532Z,1683053078.532 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:44:38.532Z,1683053078.532 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:44:38.914Z,1683053078.914 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:44:38.914Z,1683053078.914 [Aanderaa_O2] Communications Fault, FailCount= 3 2023-05-02T18:44:38.914Z,1683053078.914 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:44:38.949Z,1683053078.949 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:44:38.949Z,1683053078.949 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2023-05-02T18:44:38.960Z,1683053078.960 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:44:39.436Z,1683053079.436 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:44:40.534Z,1683053080.534 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:44:40.534Z,1683053080.534 [PNI_TCM] Data Fault, FailCount= 4 2023-05-02T18:44:40.534Z,1683053080.534 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:44:40.600Z,1683053080.600 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:44:40.938Z,1683053080.938 [PNI_TCM](INFO): Powering down 2023-05-02T18:44:42.245Z,1683053082.245 [CBIT](INFO): Clearing failed state for component PNI_TCM 2023-05-02T18:44:42.245Z,1683053082.245 [PNI_TCM] No Fault, FailCount= 4 2023-05-02T18:44:44.170Z,1683053084.170 [NAL9602](INFO): NAL9602 initialized 2023-05-02T18:44:47.776Z,1683053087.776 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:44:47.776Z,1683053087.776 [CTD_Seabird] Communications Fault, FailCount= 3 2023-05-02T18:44:47.776Z,1683053087.776 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:44:47.885Z,1683053087.885 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:44:47.885Z,1683053087.885 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2023-05-02T18:44:48.180Z,1683053088.180 [CTD_Seabird](INFO): Powering down 2023-05-02T18:44:49.822Z,1683053089.822 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2023-05-02T18:44:49.822Z,1683053089.822 [PNI_TCM] Data Fault, FailCount= 5 2023-05-02T18:44:49.822Z,1683053089.822 [PNI_TCM](ERROR): Data Fault 2023-05-02T18:44:49.898Z,1683053089.898 [CBIT](ERROR): Data Fault in component: PNI_TCM 2023-05-02T18:44:49.898Z,1683053089.898 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2023-05-02T18:44:50.230Z,1683053090.230 [PNI_TCM](INFO): Powering down 2023-05-02T18:44:50.366Z,1683053090.366 [CBIT](INFO): Critical error at 20230502T184449 2023-05-02T18:45:05.368Z,1683053105.368 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-02T18:45:05.368Z,1683053105.368 [DAT] Communications Fault, FailCount= 1 2023-05-02T18:45:05.368Z,1683053105.368 [DAT](ERROR): Communications Fault 2023-05-02T18:45:05.594Z,1683053105.594 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-02T18:45:05.772Z,1683053105.772 [DAT](INFO): Powering down 2023-05-02T18:45:06.824Z,1683053106.824 [CBIT](INFO): Clearing failed state for component DAT 2023-05-02T18:45:06.824Z,1683053106.824 [DAT] No Fault, FailCount= 1 2023-05-02T18:45:08.820Z,1683053108.820 [DAT](INFO): Powering up 2023-05-02T18:45:08.820Z,1683053108.820 [DAT](DEBUG): Initializing DAT. 2023-05-02T18:45:19.742Z,1683053119.742 [SBIT](IMPORTANT): SBIT PASSED 2023-05-02T18:45:19.743Z,1683053119.743 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-05-02T18:45:19.744Z,1683053119.744 [SBIT](IMPORTANT): VerticalControl.buoyancyDefault=915 cubic_centimeter; 2023-05-02T18:45:19.744Z,1683053119.744 [SBIT](IMPORTANT): VerticalControl.buoyancyLimitLoCC=0 cubic_centimeter; 2023-05-02T18:45:20.133Z,1683053120.133 [MissionManager](IMPORTANT): Started mission Startup 2023-05-02T18:45:20.134Z,1683053120.134 [Startup] Running Loop=1 2023-05-02T18:45:20.134Z,1683053120.134 [Startup](DEBUG): Aggregate::initialize Startup 2023-05-02T18:45:20.134Z,1683053120.134 [Startup:A.GoToSurface] Running Loop=1 2023-05-02T18:45:20.134Z,1683053120.134 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-02T18:45:20.134Z,1683053120.134 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-02T18:45:20.135Z,1683053120.135 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-02T18:45:20.135Z,1683053120.135 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-02T18:45:20.136Z,1683053120.136 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-02T18:45:20.136Z,1683053120.136 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-02T18:45:20.137Z,1683053120.137 [Startup:StartupSatComms] Running Loop=1 2023-05-02T18:45:20.137Z,1683053120.137 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-05-02T18:45:20.137Z,1683053120.137 [Startup:StartupSatComms:A] Running Loop=1 2023-05-02T18:45:20.534Z,1683053120.534 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-05-02T18:46:05.400Z,1683053165.400 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-05-02T18:46:05.400Z,1683053165.400 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-05-02T18:46:05.400Z,1683053165.400 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-02T18:46:05.402Z,1683053165.402 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-05-02T18:46:05.402Z,1683053165.402 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-05-02T18:46:05.402Z,1683053165.402 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-05-02T18:46:05.403Z,1683053165.403 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-05-02T18:46:05.404Z,1683053165.404 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-05-02T18:46:05.404Z,1683053165.404 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-05-02T18:46:05.515Z,1683053165.515 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-02T18:46:05.515Z,1683053165.515 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-05-02T18:46:05.515Z,1683053165.515 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-05-02T18:46:05.865Z,1683053165.865 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-02T18:46:05.865Z,1683053165.865 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-05-02T18:46:05.865Z,1683053165.865 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-05-02T18:46:05.866Z,1683053165.866 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-05-02T18:46:05.866Z,1683053165.866 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-05-02T18:46:05.866Z,1683053165.866 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-05-02T18:46:06.260Z,1683053166.260 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-05-02T18:46:08.832Z,1683053168.832 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-02T18:46:08.832Z,1683053168.832 [DAT] Communications Fault, FailCount= 2 2023-05-02T18:46:08.832Z,1683053168.832 [DAT](ERROR): Communications Fault 2023-05-02T18:46:09.093Z,1683053169.093 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-02T18:46:09.236Z,1683053169.236 [DAT](INFO): Powering down 2023-05-02T18:46:10.302Z,1683053170.302 [CBIT](INFO): Clearing failed state for component DAT 2023-05-02T18:46:10.302Z,1683053170.302 [DAT] No Fault, FailCount= 2 2023-05-02T18:46:12.285Z,1683053172.285 [DAT](INFO): Powering up 2023-05-02T18:46:12.286Z,1683053172.286 [DAT](DEBUG): Initializing DAT. 2023-05-02T18:46:20.354Z,1683053180.354 [Startup:StartupSatComms:A](INFO): Timed out from 2023-05-02T18:45:20.1Z 2023-05-02T18:46:20.354Z,1683053180.354 [Startup:StartupSatComms:A] Stopped 2023-05-02T18:46:20.354Z,1683053180.354 [Startup:StartupSatComms:B] Running Loop=1 2023-05-02T18:46:20.764Z,1683053180.764 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-05-02T18:46:47.958Z,1683053207.958 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004912 2023-05-02T18:46:55.143Z,1683053215.143 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20230502T153941/Courier0055.lzma 2023-05-02T18:46:56.146Z,1683053216.146 [DataOverHttps](INFO): Moved sent file to Logs/20230502T153941/Courier0055.lzma.bak 2023-05-02T18:46:56.146Z,1683053216.146 [DataOverHttps](INFO): SBD MOMSN=18278074 2023-05-02T18:47:08.523Z,1683053228.523 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:47:08.523Z,1683053228.523 [WetLabsBB2FL] No Fault, FailCount= 5 2023-05-02T18:47:08.917Z,1683053228.917 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:47:09.297Z,1683053229.297 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2023-05-02T18:47:09.297Z,1683053229.297 [Aanderaa_O2] No Fault, FailCount= 3 2023-05-02T18:47:11.932Z,1683053231.932 [DataOverHttps](INFO): Sending 345 bytes from file Logs/20230502T184355/Courier0000.lzma 2023-05-02T18:47:12.344Z,1683053232.344 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-02T18:47:12.344Z,1683053232.344 [DAT] Communications Fault, FailCount= 3 2023-05-02T18:47:12.344Z,1683053232.344 [DAT](ERROR): Communications Fault 2023-05-02T18:47:12.513Z,1683053232.513 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-02T18:47:12.748Z,1683053232.748 [DAT](INFO): Powering down 2023-05-02T18:47:12.934Z,1683053232.934 [DataOverHttps](INFO): Moved sent file to Logs/20230502T184355/Courier0000.lzma.bak 2023-05-02T18:47:12.934Z,1683053232.934 [DataOverHttps](INFO): SBD MOMSN=18278079 2023-05-02T18:47:13.853Z,1683053233.853 [CBIT](INFO): Clearing failed state for component DAT 2023-05-02T18:47:13.853Z,1683053233.853 [DAT] No Fault, FailCount= 3 2023-05-02T18:47:14.208Z,1683053234.208 [WetLabsBB2FL] Communications Fault, FailCount= 1 2023-05-02T18:47:14.208Z,1683053234.208 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:47:14.569Z,1683053234.569 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:47:14.708Z,1683053234.708 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:47:15.741Z,1683053235.741 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:47:15.741Z,1683053235.741 [WetLabsBB2FL] No Fault, FailCount= 1 2023-05-02T18:47:15.796Z,1683053235.796 [DAT](INFO): Powering up 2023-05-02T18:47:15.796Z,1683053235.796 [DAT](DEBUG): Initializing DAT. 2023-05-02T18:47:15.929Z,1683053235.929 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:47:18.189Z,1683053238.189 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-05-02T18:47:18.189Z,1683053238.189 [CTD_Seabird] No Fault, FailCount= 3 2023-05-02T18:47:18.216Z,1683053238.216 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:47:19.717Z,1683053239.717 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:47:19.717Z,1683053239.717 [Aanderaa_O2] Communications Fault, FailCount= 1 2023-05-02T18:47:19.717Z,1683053239.717 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:47:19.801Z,1683053239.801 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:47:20.232Z,1683053240.232 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:47:20.526Z,1683053240.526 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-02T18:46:20.4Z 2023-05-02T18:47:20.526Z,1683053240.526 [Startup:StartupSatComms:B] Stopped 2023-05-02T18:47:20.526Z,1683053240.526 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-05-02T18:47:20.526Z,1683053240.526 [Startup:StartupSatComms] Stopped 2023-05-02T18:47:20.526Z,1683053240.526 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-05-02T18:47:20.527Z,1683053240.527 [Startup](INFO): Completed Startup 2023-05-02T18:47:20.527Z,1683053240.527 [MissionManager](INFO): Startup is completed. 2023-05-02T18:47:20.527Z,1683053240.527 [MissionManager](INFO): Uninitializing Mission Startup 2023-05-02T18:47:20.527Z,1683053240.527 [Startup] Stopped 2023-05-02T18:47:20.527Z,1683053240.527 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-05-02T18:47:20.527Z,1683053240.527 [Startup:A.GoToSurface] Stopped 2023-05-02T18:47:20.527Z,1683053240.527 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-02T18:47:20.930Z,1683053240.930 [MissionManager](IMPORTANT): Started mission Default 2023-05-02T18:47:20.930Z,1683053240.930 [Default] Running Loop=1 2023-05-02T18:47:20.930Z,1683053240.930 [Default](DEBUG): Aggregate::initialize Default 2023-05-02T18:47:20.930Z,1683053240.930 [Default:B.GoToSurface] Running Loop=1 2023-05-02T18:47:20.930Z,1683053240.930 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-02T18:47:20.930Z,1683053240.930 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-02T18:47:20.931Z,1683053240.931 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-02T18:47:20.931Z,1683053240.931 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-02T18:47:20.931Z,1683053240.931 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-02T18:47:20.932Z,1683053240.932 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-02T18:47:20.932Z,1683053240.932 [Default:A.Wait] Running Loop=1 2023-05-02T18:47:20.932Z,1683053240.932 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-05-02T18:47:20.994Z,1683053240.994 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2023-05-02T18:47:20.994Z,1683053240.994 [Aanderaa_O2] No Fault, FailCount= 1 2023-05-02T18:47:21.220Z,1683053241.220 [WetLabsBB2FL] Communications Fault, FailCount= 2 2023-05-02T18:47:21.220Z,1683053241.220 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:47:21.405Z,1683053241.405 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:47:21.721Z,1683053241.721 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:47:22.609Z,1683053242.609 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:47:22.609Z,1683053242.609 [WetLabsBB2FL] No Fault, FailCount= 2 2023-05-02T18:47:22.933Z,1683053242.933 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:47:28.224Z,1683053248.224 [WetLabsBB2FL] Communications Fault, FailCount= 3 2023-05-02T18:47:28.224Z,1683053248.224 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:47:28.309Z,1683053248.309 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:47:28.667Z,1683053248.667 [DataOverHttps](INFO): Sending 513 bytes from file Logs/20230502T153941/Express0056.lzma 2023-05-02T18:47:28.724Z,1683053248.724 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:47:29.658Z,1683053249.658 [DataOverHttps](INFO): Moved sent file to Logs/20230502T153941/Express0056.lzma.bak 2023-05-02T18:47:29.658Z,1683053249.658 [DataOverHttps](INFO): SBD MOMSN=18278087 2023-05-02T18:47:29.899Z,1683053249.899 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:47:29.899Z,1683053249.899 [WetLabsBB2FL] No Fault, FailCount= 3 2023-05-02T18:47:29.937Z,1683053249.937 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:47:31.300Z,1683053251.300 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:47:31.300Z,1683053251.300 [CTD_Seabird] Communications Fault, FailCount= 1 2023-05-02T18:47:31.300Z,1683053251.300 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:47:31.423Z,1683053251.423 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:47:31.423Z,1683053251.423 [Aanderaa_O2] Communications Fault, FailCount= 2 2023-05-02T18:47:31.423Z,1683053251.423 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:47:31.510Z,1683053251.510 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:47:31.510Z,1683053251.510 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:47:31.705Z,1683053251.705 [CTD_Seabird](INFO): Powering down 2023-05-02T18:47:31.948Z,1683053251.948 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:47:32.710Z,1683053252.710 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2023-05-02T18:47:32.710Z,1683053252.710 [Aanderaa_O2] No Fault, FailCount= 2 2023-05-02T18:47:32.711Z,1683053252.711 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-05-02T18:47:32.711Z,1683053252.711 [CTD_Seabird] No Fault, FailCount= 1 2023-05-02T18:47:33.052Z,1683053253.052 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:47:34.290Z,1683053254.290 [Default:A.Wait](INFO): Done Waiting. 2023-05-02T18:47:34.290Z,1683053254.290 [Default:A.Wait] Stopped 2023-05-02T18:47:34.290Z,1683053254.290 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-05-02T18:47:34.681Z,1683053254.681 [Default:CheckIn] Running Loop=1 2023-05-02T18:47:34.681Z,1683053254.681 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-02T18:47:34.681Z,1683053254.681 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-02T18:47:35.084Z,1683053255.084 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-05-02T18:47:35.236Z,1683053255.236 [WetLabsBB2FL] Communications Fault, FailCount= 4 2023-05-02T18:47:35.236Z,1683053255.236 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:47:35.557Z,1683053255.557 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:47:35.736Z,1683053255.736 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:47:36.753Z,1683053256.753 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-05-02T18:47:36.753Z,1683053256.753 [WetLabsBB2FL] No Fault, FailCount= 4 2023-05-02T18:47:36.949Z,1683053256.949 [WetLabsBB2FL](INFO): Powering up 2023-05-02T18:47:42.240Z,1683053262.240 [WetLabsBB2FL] Communications Fault, FailCount= 5 2023-05-02T18:47:42.240Z,1683053262.240 [WetLabsBB2FL](ERROR): Communications Fault 2023-05-02T18:47:42.397Z,1683053262.397 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:47:42.397Z,1683053262.397 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2023-05-02T18:47:42.740Z,1683053262.740 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:47:43.142Z,1683053263.142 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2023-05-02T18:47:43.142Z,1683053263.142 [Aanderaa_O2] Communications Fault, FailCount= 3 2023-05-02T18:47:43.142Z,1683053263.142 [Aanderaa_O2](ERROR): Communications Fault 2023-05-02T18:47:43.214Z,1683053263.214 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2023-05-02T18:47:43.215Z,1683053263.215 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2023-05-02T18:47:43.660Z,1683053263.660 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:47:46.112Z,1683053266.112 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:47:46.112Z,1683053266.112 [CTD_Seabird] Communications Fault, FailCount= 2 2023-05-02T18:47:46.112Z,1683053266.112 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:47:46.437Z,1683053266.437 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:47:46.516Z,1683053266.516 [CTD_Seabird](INFO): Powering down 2023-05-02T18:47:47.649Z,1683053267.649 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-05-02T18:47:47.649Z,1683053267.649 [CTD_Seabird] No Fault, FailCount= 2 2023-05-02T18:47:47.856Z,1683053267.856 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-02T18:48:00.908Z,1683053280.908 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-05-02T18:48:00.908Z,1683053280.908 [CTD_Seabird] Communications Fault, FailCount= 3 2023-05-02T18:48:00.908Z,1683053280.908 [CTD_Seabird](ERROR): Communications Fault 2023-05-02T18:48:01.001Z,1683053281.001 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-05-02T18:48:01.001Z,1683053281.001 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2023-05-02T18:48:01.312Z,1683053281.312 [CTD_Seabird](INFO): Powering down 2023-05-02T18:48:06.601Z,1683053286.601 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-05-02T18:48:06.601Z,1683053286.601 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-05-02T18:48:06.601Z,1683053286.601 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-02T18:48:06.602Z,1683053286.602 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-05-02T18:48:06.602Z,1683053286.602 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-05-02T18:48:06.602Z,1683053286.602 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-05-02T18:48:06.604Z,1683053286.604 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-05-02T18:48:06.604Z,1683053286.604 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-05-02T18:48:06.604Z,1683053286.604 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-05-02T18:48:06.675Z,1683053286.675 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-02T18:48:06.675Z,1683053286.675 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-05-02T18:48:06.676Z,1683053286.676 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-05-02T18:48:07.058Z,1683053287.058 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-02T18:48:07.058Z,1683053287.058 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-05-02T18:48:07.059Z,1683053287.059 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-05-02T18:48:07.059Z,1683053287.059 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-05-02T18:48:07.059Z,1683053287.059 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-05-02T18:48:07.059Z,1683053287.059 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-05-02T18:48:07.499Z,1683053287.499 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-05-02T18:48:15.828Z,1683053295.828 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-02T18:48:15.828Z,1683053295.828 [DAT] Communications Fault, FailCount= 4 2023-05-02T18:48:15.828Z,1683053295.828 [DAT](ERROR): Communications Fault 2023-05-02T18:48:15.957Z,1683053295.957 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-02T18:48:16.232Z,1683053296.232 [DAT](INFO): Powering down 2023-05-02T18:48:17.166Z,1683053297.166 [CBIT](INFO): Clearing failed state for component DAT 2023-05-02T18:48:17.166Z,1683053297.166 [DAT] No Fault, FailCount= 4 2023-05-02T18:48:19.296Z,1683053299.296 [DAT](INFO): Powering up 2023-05-02T18:48:19.296Z,1683053299.296 [DAT](DEBUG): Initializing DAT. 2023-05-02T18:49:19.304Z,1683053359.304 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2023-05-02T18:49:19.304Z,1683053359.304 [DAT] Communications Fault, FailCount= 5 2023-05-02T18:49:19.304Z,1683053359.304 [DAT](ERROR): Communications Fault 2023-05-02T18:49:19.401Z,1683053359.401 [CBIT](ERROR): Communications Fault in component: DAT 2023-05-02T18:49:19.708Z,1683053359.708 [DAT](INFO): Powering down 2023-05-02T18:49:20.985Z,1683053360.985 [CBIT](INFO): Clearing failed state for component DAT 2023-05-02T18:49:20.985Z,1683053360.985 [DAT] No Fault, FailCount= 5 2023-05-02T18:49:22.756Z,1683053362.756 [DAT](INFO): Powering up 2023-05-02T18:49:22.757Z,1683053362.757 [DAT](DEBUG): Initializing DAT. 2023-05-02T18:49:27.365Z,1683053367.365 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-05-02T18:49:28.577Z,1683053368.577 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-05-02T18:49:31.621Z,1683053371.621 [CommandExec](IMPORTANT): got command quit 2023-05-02T18:49:32.624Z,1683053372.624 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:32.624Z,1683053372.624 [CommandExec](INFO): Uninitializing the command executive. 2023-05-02T18:49:32.624Z,1683053372.624 [CommandExec](INFO): Uninitializing the command scheduler. 2023-05-02T18:49:32.624Z,1683053372.624 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:32.716Z,1683053372.716 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-05-02T18:49:32.716Z,1683053372.716 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-05-02T18:49:32.716Z,1683053372.716 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:32.717Z,1683053372.717 [NavChartDb](INFO): Join timeout helper Thread ID is 2140 2023-05-02T18:49:32.892Z,1683053372.892 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:32.892Z,1683053372.892 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:32.896Z,1683053372.896 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-05-02T18:49:32.896Z,1683053372.896 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:32.896Z,1683053372.896 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2141 2023-05-02T18:49:33.148Z,1683053373.148 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:33.148Z,1683053373.148 [WetLabsBB2FL](INFO): Powering down 2023-05-02T18:49:33.149Z,1683053373.149 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:33.160Z,1683053373.160 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-05-02T18:49:33.160Z,1683053373.160 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:33.160Z,1683053373.160 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2142 2023-05-02T18:49:33.556Z,1683053373.556 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:33.556Z,1683053373.556 [CTD_Seabird](INFO): Powering down 2023-05-02T18:49:33.568Z,1683053373.568 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:33.580Z,1683053373.580 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-05-02T18:49:33.580Z,1683053373.580 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:33.580Z,1683053373.580 [DAT](INFO): Join timeout helper Thread ID is 2143 2023-05-02T18:49:33.604Z,1683053373.604 [DAT](INFO): Powering down 2023-05-02T18:49:33.676Z,1683053373.676 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:33.676Z,1683053373.676 [DAT](INFO): Powering down 2023-05-02T18:49:33.677Z,1683053373.677 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:33.684Z,1683053373.684 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-05-02T18:49:33.684Z,1683053373.684 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:33.684Z,1683053373.684 [Radio_Surface](INFO): Join timeout helper Thread ID is 2144 2023-05-02T18:49:34.004Z,1683053374.004 [Radio_Surface](INFO): Powering down 2023-05-02T18:49:34.005Z,1683053374.005 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:34.005Z,1683053374.005 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:34.025Z,1683053374.025 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-05-02T18:49:34.025Z,1683053374.025 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:34.025Z,1683053374.025 [Onboard](INFO): Join timeout helper Thread ID is 2145 2023-05-02T18:49:34.064Z,1683053374.064 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-05-02T18:49:34.233Z,1683053374.233 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-05-02T18:49:35.356Z,1683053375.356 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:35.356Z,1683053375.356 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:35.364Z,1683053375.364 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-05-02T18:49:35.365Z,1683053375.365 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:35.365Z,1683053375.365 [DataOverHttps](INFO): Join timeout helper Thread ID is 2146 2023-05-02T18:49:36.072Z,1683053376.072 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:36.072Z,1683053376.072 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.077Z,1683053376.077 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-05-02T18:49:36.077Z,1683053376.077 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.077Z,1683053376.077 [logger](INFO): Join timeout helper Thread ID is 2147 2023-05-02T18:49:36.100Z,1683053376.100 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:36.100Z,1683053376.100 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.116Z,1683053376.116 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-05-02T18:49:36.117Z,1683053376.117 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.117Z,1683053376.117 [CommandLine](INFO): Join timeout helper Thread ID is 2148 2023-05-02T18:49:36.156Z,1683053376.156 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:36.156Z,1683053376.156 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.176Z,1683053376.176 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-05-02T18:49:36.177Z,1683053376.177 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.177Z,1683053376.177 [CommandExec](INFO): Join timeout helper Thread ID is 2149 2023-05-02T18:49:36.178Z,1683053376.178 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-05-02T18:49:36.178Z,1683053376.178 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.179Z,1683053376.179 [controlThread](INFO): Join timeout helper Thread ID is 2150 2023-05-02T18:49:36.248Z,1683053376.248 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-02T18:49:36.248Z,1683053376.248 [controlThread](DEBUG): Uninitializing ControlThread 2023-05-02T18:49:36.250Z,1683053376.250 [MultiRay](INFO): Powering down. 2023-05-02T18:49:36.251Z,1683053376.251 [NAL9602](INFO): Powering down 2023-05-02T18:49:36.252Z,1683053376.252 [PNI_TCM](INFO): Powering down 2023-05-02T18:49:36.253Z,1683053376.253 [Aanderaa_O2](INFO): Powering down 2023-05-02T18:49:36.255Z,1683053376.255 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-05-02T18:49:36.256Z,1683053376.256 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-05-02T18:49:36.256Z,1683053376.256 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-05-02T18:49:36.257Z,1683053376.257 [MissionManager](INFO): Uninitializing Mission Default 2023-05-02T18:49:36.257Z,1683053376.257 [Default] Stopped 2023-05-02T18:49:36.257Z,1683053376.257 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-02T18:49:36.257Z,1683053376.257 [Default:B.GoToSurface] Stopped 2023-05-02T18:49:36.257Z,1683053376.257 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-02T18:49:36.257Z,1683053376.257 [Default:CheckIn] Stopped 2023-05-02T18:49:36.257Z,1683053376.257 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-02T18:49:36.257Z,1683053376.257 [Default:CheckIn:Read_GPS] Stopped 2023-05-02T18:49:36.260Z,1683053376.260 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-05-02T18:49:36.260Z,1683053376.260 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-05-02T18:49:36.260Z,1683053376.260 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-05-02T18:49:36.261Z,1683053376.261 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-05-02T18:49:36.261Z,1683053376.261 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-05-02T18:49:36.261Z,1683053376.261 [BuoyancyServo](INFO): Powering down 2023-05-02T18:49:36.392Z,1683053376.392 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-05-02T18:49:36.392Z,1683053376.392 [ElevatorServo](INFO): Powering down 2023-05-02T18:49:36.393Z,1683053376.393 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-05-02T18:49:36.393Z,1683053376.393 [MassServo](INFO): Powering down 2023-05-02T18:49:36.394Z,1683053376.394 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-02T18:49:36.394Z,1683053376.394 [RudderServo](INFO): Powering down 2023-05-02T18:49:36.395Z,1683053376.395 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-05-02T18:49:36.395Z,1683053376.395 [ThrusterHE](INFO): Powering down 2023-05-02T18:49:36.396Z,1683053376.396 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-05-02T18:49:36.396Z,1683053376.396 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-05-02T18:49:36.397Z,1683053376.397 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-05-02T18:49:36.397Z,1683053376.397 [CBIT](DEBUG): Powering off loads. 2023-05-02T18:49:36.408Z,1683053376.408 [CBIT](DEBUG): Disabling WDT. 2023-05-02T18:49:36.420Z,1683053376.420 [CBIT](DEBUG): Opening all GF detection circuits. 2023-05-02T18:49:36.421Z,1683053376.421 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.451Z,1683053376.451 [DAT ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.456Z,1683053376.456 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.459Z,1683053376.459 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.466Z,1683053376.466 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.590Z,1683053376.590 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.594Z,1683053376.594 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.613Z,1683053376.613 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-02T18:49:36.657Z,1683053376.657 [logger ThreadHandler](INFO): Thread cancelled.