2021-05-10T16:44:16.442Z,1620665056.442 [Supervisor](DEBUG): Initializing supervisor. 2021-05-10T16:44:16.446Z,1620665056.446 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-05-10T16:44:16.447Z,1620665056.447 [SyncHandler](INFO): Protected caller Thread ID is 835 2021-05-10T16:44:16.447Z,1620665056.447 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-05-10T16:44:16.448Z,1620665056.448 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-05-10T16:44:16.449Z,1620665056.449 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836 2021-05-10T16:44:16.453Z,1620665056.453 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-05-10T16:44:16.471Z,1620665056.471 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-05-10T16:44:16.472Z,1620665056.472 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-05-10T16:44:16.473Z,1620665056.473 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837 2021-05-10T16:44:16.475Z,1620665056.475 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-05-10T16:44:16.476Z,1620665056.476 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-05-10T16:44:16.476Z,1620665056.476 [logger ThreadHandler](INFO): Protected caller Thread ID is 838 2021-05-10T16:44:16.480Z,1620665056.480 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-05-10T16:44:16.480Z,1620665056.480 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-05-10T16:44:16.484Z,1620665056.484 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-05-10T16:44:16.910Z,1620665056.910 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-05-10T16:44:16.912Z,1620665056.912 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-05-10T16:44:17.058Z,1620665057.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-05-10T16:44:17.060Z,1620665057.060 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-05-10T16:44:17.143Z,1620665057.143 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-05-10T16:44:17.360Z,1620665057.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-05-10T16:44:17.362Z,1620665057.362 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-05-10T16:44:17.442Z,1620665057.442 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-05-10T16:44:17.539Z,1620665057.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-05-10T16:44:17.539Z,1620665057.539 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-05-10T16:44:17.906Z,1620665057.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-05-10T16:44:17.908Z,1620665057.908 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-05-10T16:44:18.034Z,1620665058.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-05-10T16:44:18.034Z,1620665058.034 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-05-10T16:44:18.585Z,1620665058.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-05-10T16:44:18.587Z,1620665058.587 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-05-10T16:44:18.782Z,1620665058.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-05-10T16:44:18.782Z,1620665058.782 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-05-10T16:44:19.176Z,1620665059.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-05-10T16:44:19.177Z,1620665059.177 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-05-10T16:44:19.432Z,1620665059.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-05-10T16:44:19.434Z,1620665059.434 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-05-10T16:44:19.536Z,1620665059.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-05-10T16:44:19.537Z,1620665059.537 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-05-10T16:44:20.395Z,1620665060.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-05-10T16:44:20.396Z,1620665060.396 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-05-10T16:44:20.944Z,1620665060.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-05-10T16:44:20.945Z,1620665060.945 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-05-10T16:44:21.156Z,1620665061.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-05-10T16:44:21.158Z,1620665061.158 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2021-05-10T16:44:21.162Z,1620665061.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2021-05-10T16:44:21.363Z,1620665061.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2021-05-10T16:44:21.447Z,1620665061.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2021-05-10T16:44:21.589Z,1620665061.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2021-05-10T16:44:21.675Z,1620665061.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2021-05-10T16:44:21.937Z,1620665061.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-05-10T16:44:21.937Z,1620665061.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2021-05-10T16:44:22.046Z,1620665062.046 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2021-05-10T16:44:22.157Z,1620665062.157 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2021-05-10T16:44:22.252Z,1620665062.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2021-05-10T16:44:22.403Z,1620665062.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2021-05-10T16:44:22.503Z,1620665062.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2021-05-10T16:44:22.604Z,1620665062.604 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-05-10T16:44:22.609Z,1620665062.609 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-05-10T16:44:22.626Z,1620665062.626 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-05-10T16:44:22.627Z,1620665062.627 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-05-10T16:44:22.733Z,1620665062.733 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-05-10T16:44:22.735Z,1620665062.735 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-05-10T16:44:22.803Z,1620665062.803 [VerticalControl](DEBUG): Construct VerticalControl. 2021-05-10T16:44:22.864Z,1620665062.864 [VerticalControl] Loaded 2021-05-10T16:44:22.864Z,1620665062.864 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-05-10T16:44:22.867Z,1620665062.867 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-05-10T16:44:22.905Z,1620665062.905 [HorizontalControl] Loaded 2021-05-10T16:44:22.905Z,1620665062.905 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-05-10T16:44:22.907Z,1620665062.907 [SpeedControl](DEBUG): Construct SpeedControl. 2021-05-10T16:44:22.910Z,1620665062.910 [SpeedControl] Loaded 2021-05-10T16:44:22.910Z,1620665062.910 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-05-10T16:44:22.913Z,1620665062.913 [LoopControl](DEBUG): Construct LoopControl. 2021-05-10T16:44:22.913Z,1620665062.913 [LoopControl] Loaded 2021-05-10T16:44:22.914Z,1620665062.914 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-05-10T16:44:22.914Z,1620665062.914 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-05-10T16:44:22.915Z,1620665062.915 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-05-10T16:44:23.067Z,1620665063.067 [BuoyancyServo] Loaded 2021-05-10T16:44:23.067Z,1620665063.067 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-05-10T16:44:23.086Z,1620665063.086 [ElevatorServo] Loaded 2021-05-10T16:44:23.086Z,1620665063.086 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-05-10T16:44:23.104Z,1620665063.104 [MassServo] Loaded 2021-05-10T16:44:23.104Z,1620665063.104 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-05-10T16:44:23.121Z,1620665063.121 [RudderServo] Loaded 2021-05-10T16:44:23.122Z,1620665063.122 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-05-10T16:44:23.139Z,1620665063.139 [ThrusterServo] Loaded 2021-05-10T16:44:23.139Z,1620665063.139 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-05-10T16:44:23.139Z,1620665063.139 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-05-10T16:44:23.140Z,1620665063.140 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-05-10T16:44:23.197Z,1620665063.197 [DepthRateCalculator] Loaded 2021-05-10T16:44:23.197Z,1620665063.197 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-05-10T16:44:23.202Z,1620665063.202 [PitchRateCalculator] Loaded 2021-05-10T16:44:23.202Z,1620665063.202 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-05-10T16:44:23.211Z,1620665063.211 [SpeedCalculator] Loaded 2021-05-10T16:44:23.212Z,1620665063.212 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-05-10T16:44:23.225Z,1620665063.225 [TempGradientCalculator] Loaded 2021-05-10T16:44:23.225Z,1620665063.225 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-05-10T16:44:23.229Z,1620665063.229 [YawRateCalculator] Loaded 2021-05-10T16:44:23.229Z,1620665063.229 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-05-10T16:44:23.246Z,1620665063.246 [ElevatorOffsetCalculator] Loaded 2021-05-10T16:44:23.246Z,1620665063.246 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-05-10T16:44:23.247Z,1620665063.247 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-05-10T16:44:23.247Z,1620665063.247 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-05-10T16:44:23.343Z,1620665063.343 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-05-10T16:44:23.344Z,1620665063.344 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-05-10T16:44:23.357Z,1620665063.357 [NavChart] Loaded 2021-05-10T16:44:23.358Z,1620665063.358 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-05-10T16:44:23.363Z,1620665063.363 [UniversalFixResidualReporter] Loaded 2021-05-10T16:44:23.364Z,1620665063.364 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-05-10T16:44:23.364Z,1620665063.364 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-05-10T16:44:23.365Z,1620665063.365 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-05-10T16:44:24.015Z,1620665064.015 [AHRS_M2] Loaded 2021-05-10T16:44:24.016Z,1620665064.016 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-05-10T16:44:24.260Z,1620665064.260 [BackseatComponent] Loaded 2021-05-10T16:44:24.260Z,1620665064.260 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2021-05-10T16:44:24.261Z,1620665064.261 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085D4E0 2021-05-10T16:44:24.261Z,1620665064.261 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921 2021-05-10T16:44:24.264Z,1620665064.264 [LcmUniversalReporter] Loaded 2021-05-10T16:44:24.264Z,1620665064.264 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2021-05-10T16:44:25.043Z,1620665065.043 [BPC1] Loaded 2021-05-10T16:44:25.044Z,1620665065.044 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-05-10T16:44:25.190Z,1620665065.190 [DataOverHttps] Loaded 2021-05-10T16:44:25.190Z,1620665065.190 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-05-10T16:44:25.191Z,1620665065.191 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4088D4E0 2021-05-10T16:44:25.192Z,1620665065.192 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922 2021-05-10T16:44:25.211Z,1620665065.211 [Depth_Keller] Loaded 2021-05-10T16:44:25.211Z,1620665065.211 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-05-10T16:44:25.216Z,1620665065.216 [DropWeight] Loaded 2021-05-10T16:44:25.216Z,1620665065.216 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-05-10T16:44:25.273Z,1620665065.273 [NAL9602] Loaded 2021-05-10T16:44:25.273Z,1620665065.273 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-05-10T16:44:25.301Z,1620665065.301 [Onboard] Loaded 2021-05-10T16:44:25.301Z,1620665065.301 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-05-10T16:44:25.302Z,1620665065.302 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0 2021-05-10T16:44:25.303Z,1620665065.303 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923 2021-05-10T16:44:25.315Z,1620665065.315 [Power24vConverter] Loaded 2021-05-10T16:44:25.315Z,1620665065.315 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-05-10T16:44:25.328Z,1620665065.328 [Radio_Surface] Loaded 2021-05-10T16:44:25.328Z,1620665065.328 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-05-10T16:44:25.329Z,1620665065.329 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0 2021-05-10T16:44:25.329Z,1620665065.329 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924 2021-05-10T16:44:25.411Z,1620665065.411 [DAT] Loaded 2021-05-10T16:44:25.411Z,1620665065.411 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-05-10T16:44:25.411Z,1620665065.411 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-05-10T16:44:25.412Z,1620665065.412 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-05-10T16:44:25.666Z,1620665065.666 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-05-10T16:44:25.667Z,1620665065.667 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-05-10T16:44:26.316Z,1620665066.316 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-05-10T16:44:26.316Z,1620665066.316 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-05-10T16:44:26.359Z,1620665066.359 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-05-10T16:44:26.359Z,1620665066.359 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-05-10T16:44:26.575Z,1620665066.575 [CTD_Seabird] Loaded 2021-05-10T16:44:26.576Z,1620665066.576 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-05-10T16:44:26.577Z,1620665066.577 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A0C4E0 2021-05-10T16:44:26.577Z,1620665066.577 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 925 2021-05-10T16:44:26.596Z,1620665066.596 [PAR_Licor] Loaded 2021-05-10T16:44:26.597Z,1620665066.597 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-05-10T16:44:26.627Z,1620665066.627 [WetLabsBB2FL] Loaded 2021-05-10T16:44:26.627Z,1620665066.627 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-05-10T16:44:26.628Z,1620665066.628 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A3C4E0 2021-05-10T16:44:26.629Z,1620665066.629 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 926 2021-05-10T16:44:26.630Z,1620665066.630 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-05-10T16:44:26.630Z,1620665066.630 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-05-10T16:44:26.991Z,1620665066.991 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-05-10T16:44:26.992Z,1620665066.992 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-05-10T16:44:27.158Z,1620665067.158 [SBIT](DEBUG): Construct Startup Built In Test. 2021-05-10T16:44:27.167Z,1620665067.167 [SBIT] Loaded 2021-05-10T16:44:27.167Z,1620665067.167 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-05-10T16:44:27.170Z,1620665067.170 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-05-10T16:44:27.183Z,1620665067.183 [IBIT] Loaded 2021-05-10T16:44:27.183Z,1620665067.183 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-05-10T16:44:27.189Z,1620665067.189 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-05-10T16:44:27.288Z,1620665067.288 [CBIT] Loaded 2021-05-10T16:44:27.289Z,1620665067.289 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-05-10T16:44:27.289Z,1620665067.289 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-05-10T16:44:27.295Z,1620665067.295 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-05-10T16:44:27.298Z,1620665067.298 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-05-10T16:44:27.308Z,1620665067.308 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-05-10T16:44:27.309Z,1620665067.309 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B214E0 2021-05-10T16:44:27.310Z,1620665067.310 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927 2021-05-10T16:44:27.314Z,1620665067.314 [Supervisor](INFO): Main Thread ID is 831 2021-05-10T16:44:27.314Z,1620665067.314 [Supervisor](DEBUG): Running supervisor. 2021-05-10T16:44:27.315Z,1620665067.315 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928 2021-05-10T16:44:27.317Z,1620665067.317 [controlThread ThreadHandler](INFO): Handler Thread ID is 929 2021-05-10T16:44:27.318Z,1620665067.318 [controlThread](DEBUG): Initializing ControlThread 2021-05-10T16:44:27.318Z,1620665067.318 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-05-10T16:44:27.320Z,1620665067.320 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-05-10T16:44:27.321Z,1620665067.321 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-05-10T16:44:27.321Z,1620665067.321 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-05-10T16:44:27.323Z,1620665067.323 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-05-10T16:44:27.323Z,1620665067.323 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-05-10T16:44:27.323Z,1620665067.323 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-05-10T16:44:27.324Z,1620665067.324 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-05-10T16:44:27.324Z,1620665067.324 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-05-10T16:44:27.325Z,1620665067.325 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-05-10T16:44:27.326Z,1620665067.326 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-05-10T16:44:27.326Z,1620665067.326 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-05-10T16:44:27.336Z,1620665067.336 [SBIT](INFO): Initialize SBIT Component. 2021-05-10T16:44:27.337Z,1620665067.337 [SBIT](IMPORTANT): git: 2021-05-10 2021-05-10T16:44:27.337Z,1620665067.337 [SBIT](INFO): git hash: 03112e3e75aba085c15f5826cf8e252bb5a4e5aa 2021-05-10T16:44:27.337Z,1620665067.337 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-05-10T16:44:27.338Z,1620665067.338 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020 2021-05-10T16:44:27.339Z,1620665067.339 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2021-05-10T16:44:27.340Z,1620665067.340 [IBIT](INFO): Initialize IBIT Component. 2021-05-10T16:44:27.341Z,1620665067.341 [CBIT](DEBUG): Initialize CBIT Component. 2021-05-10T16:44:27.342Z,1620665067.342 [logger ThreadHandler](INFO): Handler Thread ID is 930 2021-05-10T16:44:27.352Z,1620665067.352 [CBIT](DEBUG): Initialized mux pins. 2021-05-10T16:44:27.352Z,1620665067.352 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2021-05-10T16:44:27.352Z,1620665067.352 [CBIT](DEBUG): Initializing the watchdog timer. 2021-05-10T16:44:27.360Z,1620665067.360 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 931 2021-05-10T16:44:27.372Z,1620665067.372 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 932 2021-05-10T16:44:27.373Z,1620665067.373 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-05-10T16:44:27.376Z,1620665067.376 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-05-10T16:44:27.376Z,1620665067.376 [CBIT](DEBUG): Initializing heartbeat. 2021-05-10T16:44:27.384Z,1620665067.384 [Onboard ThreadHandler](INFO): Handler Thread ID is 933 2021-05-10T16:44:27.402Z,1620665067.402 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 934 2021-05-10T16:44:27.420Z,1620665067.420 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 935 2021-05-10T16:44:27.421Z,1620665067.421 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-05-10T16:44:27.425Z,1620665067.425 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 937 2021-05-10T16:44:27.428Z,1620665067.428 [WetLabsBB2FL](INFO): Powering up 2021-05-10T16:44:27.430Z,1620665067.430 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 939 2021-05-10T16:44:27.438Z,1620665067.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-05-10T16:44:27.438Z,1620665067.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-05-10T16:44:27.438Z,1620665067.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-05-10T16:44:27.438Z,1620665067.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-05-10T16:44:27.439Z,1620665067.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-05-10T16:44:27.439Z,1620665067.439 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-05-10T16:44:27.439Z,1620665067.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-05-10T16:44:27.439Z,1620665067.439 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-05-10T16:44:27.439Z,1620665067.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-05-10T16:44:27.440Z,1620665067.440 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-05-10T16:44:27.440Z,1620665067.440 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-05-10T16:44:27.440Z,1620665067.440 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-05-10T16:44:27.440Z,1620665067.440 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-05-10T16:44:27.441Z,1620665067.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-05-10T16:44:27.441Z,1620665067.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-05-10T16:44:27.441Z,1620665067.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-05-10T16:44:27.448Z,1620665067.448 [CBIT](DEBUG): Deactivating GF circuits. 2021-05-10T16:44:27.448Z,1620665067.448 [CBIT](DEBUG): Deactivating emergency mode. 2021-05-10T16:44:27.484Z,1620665067.484 [CBIT](DEBUG): Backplane powered. 2021-05-10T16:44:27.485Z,1620665067.485 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-05-10T16:44:27.520Z,1620665067.520 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-05-10T16:44:27.548Z,1620665067.548 [MissionManager](DEBUG): 2021-05-10T16:44:27.549Z,1620665067.549 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-05-10T16:44:27.634Z,1620665067.634 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-05-10T16:44:27.635Z,1620665067.635 [Default:A.Wait](DEBUG): Construct Wait. 2021-05-10T16:44:27.637Z,1620665067.637 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-05-10T16:44:27.664Z,1620665067.664 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-05-10T16:44:27.667Z,1620665067.667 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-05-10T16:44:27.693Z,1620665067.693 [Default:E.Execute](DEBUG): Construct Execute. 2021-05-10T16:44:27.713Z,1620665067.713 [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 2021-05-10T16:44:27.718Z,1620665067.718 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2021-05-10T16:44:27.736Z,1620665067.736 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-05-10T16:44:27.808Z,1620665067.808 [Radio_Surface](INFO): Powering up 2021-05-10T16:44:27.877Z,1620665067.877 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2021-05-10T16:44:27.878Z,1620665067.878 [Power24vConverter](INFO): Powering down. 2021-05-10T16:44:27.878Z,1620665067.878 [DAT](INFO): Powering up 2021-05-10T16:44:27.879Z,1620665067.879 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:44:27.902Z,1620665067.902 [DepthRateCalculator](ERROR): Depth measurement is not active 2021-05-10T16:44:27.937Z,1620665067.937 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-05-10T16:44:27.940Z,1620665067.940 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-05-10T16:44:27.941Z,1620665067.941 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-05-10T16:44:27.948Z,1620665067.948 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-05-10T16:44:27.949Z,1620665067.949 [MassServo](DEBUG): Initializing EZServoServo. 2021-05-10T16:44:27.956Z,1620665067.956 [MassServo](DEBUG): Initializing MassServo. 2021-05-10T16:44:27.957Z,1620665067.957 [RudderServo](DEBUG): Initializing EZServoServo. 2021-05-10T16:44:27.964Z,1620665067.964 [RudderServo](DEBUG): Initializing RudderServo. 2021-05-10T16:44:27.965Z,1620665067.965 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-05-10T16:44:27.972Z,1620665067.972 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-05-10T16:44:28.533Z,1620665068.533 [Power24vConverter](INFO): Powering up. 2021-05-10T16:44:28.868Z,1620665068.868 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2021-05-10T16:44:28.868Z,1620665068.868 [RudderServo](FAULT): Rudder failed to initialize 2021-05-10T16:44:28.868Z,1620665068.868 [RudderServo] Communications Fault, FailCount= 1 2021-05-10T16:44:28.868Z,1620665068.868 [RudderServo](ERROR): Communications Fault 2021-05-10T16:44:28.969Z,1620665068.969 [CBIT](ERROR): Communications Fault in component: RudderServo 2021-05-10T16:44:29.157Z,1620665069.157 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-05-10T16:44:29.157Z,1620665069.157 [RudderServo](INFO): Powering down 2021-05-10T16:44:29.536Z,1620665069.536 [WetLabsBB2FL](INFO): Powering down 2021-05-10T16:44:29.816Z,1620665069.816 [RudderServo](DEBUG): Initializing EZServoServo. 2021-05-10T16:44:29.937Z,1620665069.937 [RudderServo](DEBUG): Initializing RudderServo. 2021-05-10T16:44:29.941Z,1620665069.941 [CBIT](INFO): Clearing failed state for component RudderServo 2021-05-10T16:44:29.941Z,1620665069.941 [RudderServo] No Fault, FailCount= 1 2021-05-10T16:44:47.999Z,1620665087.999 [SBIT](IMPORTANT): Beginning Startup BIT 2021-05-10T16:44:48.003Z,1620665088.003 [CBIT](IMPORTANT): Beginning ground fault scan 2021-05-10T16:44:55.646Z,1620665095.646 [NAL9602](INFO): Powering up NAL9602 2021-05-10T16:44:58.928Z,1620665098.928 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.002673 CHAN A1 (24V): -0.160757 CHAN A2 (12V): -0.004252 CHAN A3 (5V): -0.002189 CHAN B0 (3.3V): -0.000471 CHAN B1 (3.15aV): -0.001197 CHAN B2 (3.15bV): -0.001208 CHAN B3 (GND): 0.000615 OPEN: 0.006219 Full Scale Calc: 4.765 mA, -1.589 mA 2021-05-10T16:45:06.566Z,1620665106.566 [NAL9602](INFO): NAL9602 initialized 2021-05-10T16:45:27.974Z,1620665127.974 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:45:27.974Z,1620665127.974 [DAT] Communications Fault, FailCount= 1 2021-05-10T16:45:27.974Z,1620665127.974 [DAT](ERROR): Communications Fault 2021-05-10T16:45:28.018Z,1620665128.018 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:45:28.407Z,1620665128.407 [DAT](INFO): Powering down 2021-05-10T16:45:29.188Z,1620665129.188 [Power24vConverter](INFO): Powering down. 2021-05-10T16:45:29.206Z,1620665129.206 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:45:29.206Z,1620665129.206 [DAT] No Fault, FailCount= 1 2021-05-10T16:45:30.008Z,1620665130.008 [Power24vConverter](INFO): Powering up. 2021-05-10T16:45:31.610Z,1620665131.610 [DAT](INFO): Powering up 2021-05-10T16:45:31.610Z,1620665131.610 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:45:42.126Z,1620665142.126 [SBIT](IMPORTANT): SBIT PASSED 2021-05-10T16:45:42.126Z,1620665142.126 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-05-10T16:45:42.529Z,1620665142.529 [MissionManager](IMPORTANT): Started mission Startup 2021-05-10T16:45:42.529Z,1620665142.529 [Startup] Running Loop=1 2021-05-10T16:45:42.530Z,1620665142.530 [Startup](DEBUG): Aggregate::initialize Startup 2021-05-10T16:45:42.530Z,1620665142.530 [Startup:A.GoToSurface] Running Loop=1 2021-05-10T16:45:42.530Z,1620665142.530 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-05-10T16:45:42.530Z,1620665142.530 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-05-10T16:45:42.531Z,1620665142.531 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-05-10T16:45:42.531Z,1620665142.531 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-05-10T16:45:42.532Z,1620665142.532 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-05-10T16:45:42.533Z,1620665142.533 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-05-10T16:45:42.534Z,1620665142.534 [Startup:StartupSatComms] Running Loop=1 2021-05-10T16:45:42.534Z,1620665142.534 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-05-10T16:45:42.534Z,1620665142.534 [Startup:StartupSatComms:A] Running Loop=1 2021-05-10T16:45:42.955Z,1620665142.955 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-05-10T16:46:31.806Z,1620665191.806 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:46:31.806Z,1620665191.806 [DAT] Communications Fault, FailCount= 2 2021-05-10T16:46:31.806Z,1620665191.806 [DAT](ERROR): Communications Fault 2021-05-10T16:46:31.824Z,1620665191.824 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:46:32.210Z,1620665192.210 [DAT](INFO): Powering down 2021-05-10T16:46:33.011Z,1620665193.011 [Power24vConverter](INFO): Powering down. 2021-05-10T16:46:33.027Z,1620665193.027 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:46:33.027Z,1620665193.027 [DAT] No Fault, FailCount= 2 2021-05-10T16:46:33.826Z,1620665193.826 [Power24vConverter](INFO): Powering up. 2021-05-10T16:46:35.512Z,1620665195.512 [DAT](INFO): Powering up 2021-05-10T16:46:35.513Z,1620665195.513 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:46:42.726Z,1620665202.726 [Startup:StartupSatComms:A](INFO): Timed out from 2021-05-10T16:45:42.5Z 2021-05-10T16:46:42.726Z,1620665202.726 [Startup:StartupSatComms:A] Stopped 2021-05-10T16:46:42.726Z,1620665202.726 [Startup:StartupSatComms:B] Running Loop=1 2021-05-10T16:46:43.134Z,1620665203.134 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-05-10T16:47:13.274Z,1620665233.274 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004930 2021-05-10T16:47:22.201Z,1620665242.201 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210510T163733/Courier0004.lzma 2021-05-10T16:47:23.204Z,1620665243.204 [DataOverHttps](INFO): Moved sent file to Logs/20210510T163733/Courier0004.lzma.bak 2021-05-10T16:47:23.204Z,1620665243.204 [DataOverHttps](INFO): SBD MOMSN=15633776 2021-05-10T16:47:27.619Z,1620665247.619 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-05-10T16:47:27.620Z,1620665247.620 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-05-10T16:47:27.663Z,1620665247.663 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-05-10T16:47:28.076Z,1620665248.076 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-05-10T16:47:28.076Z,1620665248.076 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-05-10T16:47:35.676Z,1620665255.676 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:47:35.676Z,1620665255.676 [DAT] Communications Fault, FailCount= 3 2021-05-10T16:47:35.676Z,1620665255.676 [DAT](ERROR): Communications Fault 2021-05-10T16:47:35.748Z,1620665255.748 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:47:36.092Z,1620665256.092 [DAT](INFO): Powering down 2021-05-10T16:47:36.888Z,1620665256.888 [Power24vConverter](INFO): Powering down. 2021-05-10T16:47:36.946Z,1620665256.946 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:47:36.946Z,1620665256.946 [DAT] No Fault, FailCount= 3 2021-05-10T16:47:37.696Z,1620665257.696 [Power24vConverter](INFO): Powering up. 2021-05-10T16:47:38.714Z,1620665258.714 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210510T164416/Courier0000.lzma 2021-05-10T16:47:39.322Z,1620665259.322 [DAT](INFO): Powering up 2021-05-10T16:47:39.323Z,1620665259.323 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:47:39.716Z,1620665259.716 [DataOverHttps](INFO): Moved sent file to Logs/20210510T164416/Courier0000.lzma.bak 2021-05-10T16:47:39.716Z,1620665259.716 [DataOverHttps](INFO): SBD MOMSN=15633778 2021-05-10T16:47:42.972Z,1620665262.972 [Startup:StartupSatComms:B](INFO): Timed out from 2021-05-10T16:46:42.7Z 2021-05-10T16:47:42.972Z,1620665262.972 [Startup:StartupSatComms:B] Stopped 2021-05-10T16:47:42.972Z,1620665262.972 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-05-10T16:47:42.972Z,1620665262.972 [Startup:StartupSatComms] Stopped 2021-05-10T16:47:42.972Z,1620665262.972 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-05-10T16:47:42.973Z,1620665262.973 [Startup](INFO): Completed Startup 2021-05-10T16:47:42.973Z,1620665262.973 [MissionManager](INFO): Startup is completed. 2021-05-10T16:47:42.973Z,1620665262.973 [MissionManager](INFO): Uninitializing Mission Startup 2021-05-10T16:47:42.973Z,1620665262.973 [Startup] Stopped 2021-05-10T16:47:42.974Z,1620665262.974 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-05-10T16:47:42.974Z,1620665262.974 [Startup:A.GoToSurface] Stopped 2021-05-10T16:47:42.974Z,1620665262.974 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-05-10T16:47:43.387Z,1620665263.387 [MissionManager](IMPORTANT): Started mission Default 2021-05-10T16:47:43.387Z,1620665263.387 [Default] Running Loop=1 2021-05-10T16:47:43.388Z,1620665263.388 [Default](DEBUG): Aggregate::initialize Default 2021-05-10T16:47:43.388Z,1620665263.388 [Default:B.GoToSurface] Running Loop=1 2021-05-10T16:47:43.388Z,1620665263.388 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-05-10T16:47:43.388Z,1620665263.388 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-05-10T16:47:43.388Z,1620665263.388 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-05-10T16:47:43.389Z,1620665263.389 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-05-10T16:47:43.389Z,1620665263.389 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-05-10T16:47:43.389Z,1620665263.389 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-05-10T16:47:43.390Z,1620665263.390 [Default:A.Wait] Running Loop=1 2021-05-10T16:47:43.390Z,1620665263.390 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-05-10T16:47:50.116Z,1620665270.116 [CommandLine](IMPORTANT): got command burn on 2021-05-10T16:47:50.116Z,1620665270.116 [CommandLine](IMPORTANT): Activating dropweight wire 2021-05-10T16:47:55.229Z,1620665275.229 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210510T163733/Express0005.lzma 2021-05-10T16:47:56.232Z,1620665276.232 [DataOverHttps](INFO): Moved sent file to Logs/20210510T163733/Express0005.lzma.bak 2021-05-10T16:47:56.232Z,1620665276.232 [DataOverHttps](INFO): SBD MOMSN=15633781 2021-05-10T16:47:56.712Z,1620665276.712 [Default:A.Wait](INFO): Done Waiting. 2021-05-10T16:47:56.712Z,1620665276.712 [Default:A.Wait] Stopped 2021-05-10T16:47:56.712Z,1620665276.712 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-05-10T16:47:57.104Z,1620665277.104 [Default:CheckIn] Running Loop=1 2021-05-10T16:47:57.105Z,1620665277.105 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-10T16:47:57.105Z,1620665277.105 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-10T16:47:57.508Z,1620665277.508 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-05-10T16:48:39.568Z,1620665319.568 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:48:39.568Z,1620665319.568 [DAT] Communications Fault, FailCount= 4 2021-05-10T16:48:39.569Z,1620665319.569 [DAT](ERROR): Communications Fault 2021-05-10T16:48:39.584Z,1620665319.584 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:48:39.920Z,1620665319.920 [DAT](INFO): Powering down 2021-05-10T16:48:40.744Z,1620665320.744 [Power24vConverter](INFO): Powering down. 2021-05-10T16:48:40.800Z,1620665320.800 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:48:40.800Z,1620665320.800 [DAT] No Fault, FailCount= 4 2021-05-10T16:48:41.548Z,1620665321.548 [Power24vConverter](INFO): Powering up. 2021-05-10T16:48:43.158Z,1620665323.158 [DAT](INFO): Powering up 2021-05-10T16:48:43.159Z,1620665323.159 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:49:04.207Z,1620665344.207 [CommandLine](IMPORTANT): got command burn off 2021-05-10T16:49:04.207Z,1620665344.207 [CommandLine](IMPORTANT): Deactivating dropweight wire 2021-05-10T16:49:09.720Z,1620665349.720 [CommandLine](IMPORTANT): got command burn on 2021-05-10T16:49:09.720Z,1620665349.720 [CommandLine](IMPORTANT): Activating dropweight wire 2021-05-10T16:49:14.759Z,1620665354.759 [CommandLine](IMPORTANT): got command burn off 2021-05-10T16:49:14.759Z,1620665354.759 [CommandLine](IMPORTANT): Deactivating dropweight wire 2021-05-10T16:49:43.345Z,1620665383.345 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:49:43.345Z,1620665383.345 [DAT] Communications Fault, FailCount= 5 2021-05-10T16:49:43.345Z,1620665383.345 [DAT](ERROR): Communications Fault 2021-05-10T16:49:43.395Z,1620665383.395 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:49:43.748Z,1620665383.748 [DAT](INFO): Powering down 2021-05-10T16:49:44.556Z,1620665384.556 [Power24vConverter](INFO): Powering down. 2021-05-10T16:49:44.585Z,1620665384.585 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:49:44.585Z,1620665384.585 [DAT] No Fault, FailCount= 5 2021-05-10T16:49:45.374Z,1620665385.374 [Power24vConverter](INFO): Powering up. 2021-05-10T16:49:46.980Z,1620665386.980 [DAT](INFO): Powering up 2021-05-10T16:49:46.981Z,1620665386.981 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:49:47.790Z,1620665387.790 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-05-10T16:49:53.838Z,1620665393.838 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:50:07.399Z,1620665407.399 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.407665 2021-05-10T16:50:09.190Z,1620665409.190 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:50:09.615Z,1620665409.615 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-05-10T16:50:17.706Z,1620665417.706 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002837 2021-05-10T16:50:25.350Z,1620665425.350 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:50:28.616Z,1620665428.616 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-05-10T16:50:28.616Z,1620665428.616 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-05-10T16:50:28.626Z,1620665428.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-05-10T16:50:29.023Z,1620665429.023 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-05-10T16:50:29.023Z,1620665429.023 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-05-10T16:50:40.785Z,1620665440.785 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:50:47.191Z,1620665447.191 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:50:47.191Z,1620665447.191 [DAT] Communications Fault, FailCount= 6 2021-05-10T16:50:47.191Z,1620665447.191 [DAT](ERROR): Communications Fault 2021-05-10T16:50:47.273Z,1620665447.273 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:50:47.594Z,1620665447.594 [DAT](INFO): Powering down 2021-05-10T16:50:48.384Z,1620665448.384 [Power24vConverter](INFO): Powering down. 2021-05-10T16:50:48.411Z,1620665448.411 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:50:48.411Z,1620665448.411 [DAT] No Fault, FailCount= 6 2021-05-10T16:50:49.188Z,1620665449.188 [Power24vConverter](INFO): Powering up. 2021-05-10T16:50:50.808Z,1620665450.808 [DAT](INFO): Powering up 2021-05-10T16:50:50.808Z,1620665450.808 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:50:55.258Z,1620665455.258 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:51:09.789Z,1620665469.789 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:51:25.141Z,1620665485.141 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:51:41.303Z,1620665501.303 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:51:51.004Z,1620665511.004 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:51:51.004Z,1620665511.004 [DAT] Communications Fault, FailCount= 7 2021-05-10T16:51:51.004Z,1620665511.004 [DAT](ERROR): Communications Fault 2021-05-10T16:51:51.021Z,1620665511.021 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:51:51.409Z,1620665511.409 [DAT](INFO): Powering down 2021-05-10T16:51:52.226Z,1620665512.226 [Power24vConverter](INFO): Powering down. 2021-05-10T16:51:52.249Z,1620665512.249 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:51:52.249Z,1620665512.249 [DAT] No Fault, FailCount= 7 2021-05-10T16:51:53.038Z,1620665513.038 [Power24vConverter](INFO): Powering up. 2021-05-10T16:51:54.641Z,1620665514.641 [DAT](INFO): Powering up 2021-05-10T16:51:54.641Z,1620665514.641 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:51:56.654Z,1620665516.654 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:52:13.218Z,1620665533.218 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-05-10T16:52:54.916Z,1620665574.916 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:52:54.916Z,1620665574.916 [DAT] Communications Fault, FailCount= 8 2021-05-10T16:52:54.916Z,1620665574.916 [DAT](ERROR): Communications Fault 2021-05-10T16:52:54.961Z,1620665574.961 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:52:54.961Z,1620665574.961 [CBIT](FAULT): Communications Fault in component: DAT 2021-05-10T16:52:55.334Z,1620665575.334 [DAT](INFO): Powering down 2021-05-10T16:52:56.122Z,1620665576.122 [Power24vConverter](INFO): Powering down. 2021-05-10T16:52:57.342Z,1620665577.342 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-05-10T16:47:57.1Z 2021-05-10T16:52:57.342Z,1620665577.342 [Default:CheckIn:Read_GPS] Stopped 2021-05-10T16:52:57.342Z,1620665577.342 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-10T16:52:57.735Z,1620665577.735 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-05-10T16:53:04.777Z,1620665584.777 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210510T164416/Courier0004.lzma 2021-05-10T16:53:05.780Z,1620665585.780 [DataOverHttps](INFO): Moved sent file to Logs/20210510T164416/Courier0004.lzma.bak 2021-05-10T16:53:05.780Z,1620665585.780 [DataOverHttps](INFO): SBD MOMSN=15633811 2021-05-10T16:53:21.175Z,1620665601.175 [DataOverHttps](INFO): Sending 715 bytes from file Logs/20210510T164416/Express0001.lzma 2021-05-10T16:53:22.176Z,1620665602.176 [DataOverHttps](INFO): Moved sent file to Logs/20210510T164416/Express0001.lzma.bak 2021-05-10T16:53:22.176Z,1620665602.176 [DataOverHttps](INFO): SBD MOMSN=15633813 2021-05-10T16:53:29.659Z,1620665609.659 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-05-10T16:53:29.659Z,1620665609.659 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-05-10T16:53:29.669Z,1620665609.669 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-05-10T16:53:30.083Z,1620665610.083 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-05-10T16:53:30.083Z,1620665610.083 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-05-10T16:53:37.667Z,1620665617.667 [DataOverHttps](INFO): Sending 399 bytes from file Logs/20210510T164416/Express0005.lzma 2021-05-10T16:53:38.668Z,1620665618.668 [DataOverHttps](INFO): Moved sent file to Logs/20210510T164416/Express0005.lzma.bak 2021-05-10T16:53:38.668Z,1620665618.668 [DataOverHttps](INFO): SBD MOMSN=15633828 2021-05-10T16:53:39.759Z,1620665619.759 [Default:CheckIn:Read_Iridium] Stopped 2021-05-10T16:53:39.759Z,1620665619.759 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-10T16:53:39.759Z,1620665619.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-10T16:54:02.028Z,1620665642.028 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165400.00,A,3648.16648,N,12147.28013,W,0.000,0.00,100521,,,A*70 2021-05-10T16:54:02.031Z,1620665642.031 [NAL9602](INFO): GPS fix at 20210510T165400: (36.802775, -121.788002) 2021-05-10T16:54:34.307Z,1620665674.307 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-10T16:57:55.250Z,1620665875.250 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:57:55.250Z,1620665875.250 [DAT] No Fault, FailCount= 8 2021-05-10T16:57:55.577Z,1620665875.577 [DAT](INFO): Powering up 2021-05-10T16:57:55.577Z,1620665875.577 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:57:55.991Z,1620665875.991 [Power24vConverter](INFO): Powering up. 2021-05-10T16:58:40.423Z,1620665920.423 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-10T16:58:40.423Z,1620665920.423 [Default:CheckIn:C.Wait] Stopped 2021-05-10T16:58:40.423Z,1620665920.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-10T16:58:40.423Z,1620665920.423 [Default:CheckIn:D] Running Loop=1 2021-05-10T16:58:40.833Z,1620665920.833 [Default:CheckIn:D] Stopped 2021-05-10T16:58:40.833Z,1620665920.833 [Default:CheckIn:E] Running Loop=1 2021-05-10T16:58:41.253Z,1620665921.253 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.957425 min 2021-05-10T16:58:41.253Z,1620665921.253 [Default:CheckIn:E] Stopped 2021-05-10T16:58:41.253Z,1620665921.253 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-10T16:58:41.254Z,1620665921.254 [Default:CheckIn] Stopped 2021-05-10T16:58:41.254Z,1620665921.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-10T16:58:41.254Z,1620665921.254 [Default:CheckIn](INFO): Running loop #2 2021-05-10T16:58:41.254Z,1620665921.254 [Default:CheckIn] Running Loop=2 2021-05-10T16:58:41.254Z,1620665921.254 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-10T16:58:41.254Z,1620665921.254 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-10T16:58:43.245Z,1620665923.245 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165842.00,A,3648.11949,N,12147.23311,W,0.233,103.40,100521,,,D*78 2021-05-10T16:58:43.247Z,1620665923.247 [NAL9602](INFO): GPS fix at 20210510T165842: (36.801991, -121.787218) 2021-05-10T16:58:43.262Z,1620665923.262 [Default:CheckIn:Read_GPS] Stopped 2021-05-10T16:58:43.262Z,1620665923.262 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-10T16:58:44.725Z,1620665924.725 [CommandLine](IMPORTANT): got command failComponent 2021-05-10T16:58:44.738Z,1620665924.738 [CommandLine](IMPORTANT): Failed components: 2021-05-10T16:58:44.738Z,1620665924.738 [CommandLine](IMPORTANT): No failed Components. 2021-05-10T16:58:53.830Z,1620665933.830 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20210510T164416/Courier0007.lzma 2021-05-10T16:58:54.839Z,1620665934.839 [DataOverHttps](INFO): Moved sent file to Logs/20210510T164416/Courier0007.lzma.bak 2021-05-10T16:58:54.839Z,1620665934.839 [DataOverHttps](INFO): SBD MOMSN=15633847 2021-05-10T16:58:55.852Z,1620665935.852 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-05-10T16:58:55.852Z,1620665935.852 [DAT] Communications Fault, FailCount= 1 2021-05-10T16:58:55.852Z,1620665935.852 [DAT](ERROR): Communications Fault 2021-05-10T16:58:55.911Z,1620665935.911 [CBIT](ERROR): Communications Fault in component: DAT 2021-05-10T16:58:56.252Z,1620665936.252 [DAT](INFO): Powering down 2021-05-10T16:58:57.056Z,1620665937.056 [Power24vConverter](INFO): Powering down. 2021-05-10T16:58:57.116Z,1620665937.116 [CBIT](INFO): Clearing failed state for component DAT 2021-05-10T16:58:57.116Z,1620665937.116 [DAT] No Fault, FailCount= 1 2021-05-10T16:58:57.991Z,1620665937.991 [Power24vConverter](INFO): Powering up. 2021-05-10T16:58:59.482Z,1620665939.482 [DAT](INFO): Powering up 2021-05-10T16:58:59.482Z,1620665939.482 [DAT](DEBUG): Initializing DAT. 2021-05-10T16:59:10.742Z,1620665950.742 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20210510T164416/Express0008.lzma 2021-05-10T16:59:11.744Z,1620665951.744 [DataOverHttps](INFO): Moved sent file to Logs/20210510T164416/Express0008.lzma.bak 2021-05-10T16:59:11.744Z,1620665951.744 [DataOverHttps](INFO): SBD MOMSN=15633852 2021-05-10T16:59:12.829Z,1620665952.829 [Default:CheckIn:Read_Iridium] Stopped 2021-05-10T16:59:12.829Z,1620665952.829 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-10T16:59:12.830Z,1620665952.830 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-10T16:59:14.027Z,1620665954.027 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-05-10T16:59:14.106Z,1620665954.106 [NAL9602](ERROR): received: +CSQ:0 OK 2021-05-10T16:59:20.339Z,1620665960.339 [DataOverHttps](IMPORTANT): SBD MTMSN=20210510T165919 2021-05-10T16:59:27.758Z,1620665967.758 [DataOverHttps](INFO): Received command:restart sys 2021-05-10T16:59:27.763Z,1620665967.763 [CommandLine](IMPORTANT): got command restart system 2021-05-10T16:59:27.769Z,1620665967.769 [NAL9602](INFO): SBD MO Status=2, MOMSN=4209, MT Status=2, MTMSN=0 2021-05-10T16:59:27.769Z,1620665967.769 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-05-10T16:59:29.834Z,1620665969.834 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2021-05-10T16:59:29.834Z,1620665969.834 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-10T16:59:29.835Z,1620665969.835 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:29.954Z,1620665969.954 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-05-10T16:59:29.954Z,1620665969.954 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:29.955Z,1620665969.955 [CommandLine](INFO): Join timeout helper Thread ID is 1022 2021-05-10T16:59:29.955Z,1620665969.955 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-05-10T16:59:29.955Z,1620665969.955 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:29.956Z,1620665969.956 [NavChartDb](INFO): Join timeout helper Thread ID is 1023 2021-05-10T16:59:30.262Z,1620665970.262 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-10T16:59:30.262Z,1620665970.262 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:30.278Z,1620665970.278 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-05-10T16:59:30.278Z,1620665970.278 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:30.278Z,1620665970.278 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1024 2021-05-10T16:59:30.562Z,1620665970.562 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-10T16:59:30.562Z,1620665970.562 [WetLabsBB2FL](INFO): Powering down 2021-05-10T16:59:30.563Z,1620665970.563 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:30.590Z,1620665970.590 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-05-10T16:59:30.590Z,1620665970.590 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:30.590Z,1620665970.590 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1025 2021-05-10T16:59:31.014Z,1620665971.014 [CTD_Seabird](INFO): Powering down 2021-05-10T16:59:31.026Z,1620665971.026 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-10T16:59:31.026Z,1620665971.026 [CTD_Seabird](INFO): Powering down 2021-05-10T16:59:31.038Z,1620665971.038 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:31.054Z,1620665971.054 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-05-10T16:59:31.054Z,1620665971.054 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:31.055Z,1620665971.055 [Radio_Surface](INFO): Join timeout helper Thread ID is 1026 2021-05-10T16:59:31.126Z,1620665971.126 [Radio_Surface](INFO): Powering down 2021-05-10T16:59:31.127Z,1620665971.127 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-10T16:59:31.127Z,1620665971.127 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:31.135Z,1620665971.135 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-05-10T16:59:31.135Z,1620665971.135 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-05-10T16:59:31.135Z,1620665971.135 [Onboard](INFO): Join timeout helper Thread ID is 1027 2021-05-10T16:59:31.770Z,1620665971.770 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2021-05-10T16:59:34.632Z,1620665974.632 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-10T16:59:34.798Z,1620665974.798 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-10T16:59:34.799Z,