2022-11-03T23:45:06.516Z,1667519106.516 [Supervisor](DEBUG): Initializing supervisor. 2022-11-03T23:45:06.520Z,1667519106.520 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-11-03T23:45:06.520Z,1667519106.520 [SyncHandler](INFO): Protected caller Thread ID is 5480 2022-11-03T23:45:06.521Z,1667519106.521 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-11-03T23:45:06.522Z,1667519106.522 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-11-03T23:45:06.522Z,1667519106.522 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5481 2022-11-03T23:45:06.526Z,1667519106.526 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-11-03T23:45:06.543Z,1667519106.543 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-11-03T23:45:06.544Z,1667519106.544 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-11-03T23:45:06.545Z,1667519106.545 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5482 2022-11-03T23:45:06.549Z,1667519106.549 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-11-03T23:45:06.550Z,1667519106.550 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-11-03T23:45:06.550Z,1667519106.550 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5483 2022-11-03T23:45:06.552Z,1667519106.552 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-11-03T23:45:06.553Z,1667519106.553 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-11-03T23:45:06.554Z,1667519106.554 [logger ThreadHandler](INFO): Protected caller Thread ID is 5484 2022-11-03T23:45:06.557Z,1667519106.557 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-11-03T23:45:06.557Z,1667519106.557 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-11-03T23:45:06.559Z,1667519106.559 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-11-03T23:45:06.969Z,1667519106.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-11-03T23:45:06.970Z,1667519106.970 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-11-03T23:45:07.189Z,1667519107.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-11-03T23:45:07.189Z,1667519107.189 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-11-03T23:45:07.364Z,1667519107.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-11-03T23:45:07.364Z,1667519107.364 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-11-03T23:45:07.468Z,1667519107.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-11-03T23:45:07.468Z,1667519107.468 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-11-03T23:45:07.818Z,1667519107.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-11-03T23:45:07.819Z,1667519107.819 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-11-03T23:45:08.125Z,1667519108.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-11-03T23:45:08.125Z,1667519108.125 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-11-03T23:45:08.218Z,1667519108.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-11-03T23:45:08.219Z,1667519108.219 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-11-03T23:45:08.428Z,1667519108.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-11-03T23:45:08.429Z,1667519108.429 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-11-03T23:45:08.918Z,1667519108.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-11-03T23:45:08.918Z,1667519108.918 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-11-03T23:45:09.258Z,1667519109.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-11-03T23:45:09.258Z,1667519109.258 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-11-03T23:45:09.336Z,1667519109.336 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-11-03T23:45:09.538Z,1667519109.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-11-03T23:45:09.538Z,1667519109.538 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-11-03T23:45:09.670Z,1667519109.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-11-03T23:45:09.671Z,1667519109.671 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-11-03T23:45:10.373Z,1667519110.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-11-03T23:45:10.374Z,1667519110.374 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-11-03T23:45:10.451Z,1667519110.451 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-11-03T23:45:10.793Z,1667519110.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-11-03T23:45:10.856Z,1667519110.856 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2022-11-03T23:45:10.857Z,1667519110.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2022-11-03T23:45:11.040Z,1667519111.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2022-11-03T23:45:11.130Z,1667519111.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2022-11-03T23:45:11.239Z,1667519111.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2022-11-03T23:45:11.336Z,1667519111.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2022-11-03T23:45:11.417Z,1667519111.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2022-11-03T23:45:11.500Z,1667519111.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2022-11-03T23:45:11.609Z,1667519111.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2022-11-03T23:45:11.704Z,1667519111.704 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2022-11-03T23:45:11.853Z,1667519111.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2022-11-03T23:45:12.110Z,1667519112.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-11-03T23:45:12.111Z,1667519112.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2022-11-03T23:45:12.223Z,1667519112.223 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-11-03T23:45:12.240Z,1667519112.240 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-11-03T23:45:12.970Z,1667519112.970 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-11-03T23:45:12.971Z,1667519112.971 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-11-03T23:45:13.132Z,1667519113.132 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-11-03T23:45:13.132Z,1667519113.132 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-11-03T23:45:13.170Z,1667519113.170 [DeadReckonUsingSpeedCalculator] Loaded 2022-11-03T23:45:13.170Z,1667519113.170 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2022-11-03T23:45:13.183Z,1667519113.183 [NavChart] Loaded 2022-11-03T23:45:13.184Z,1667519113.184 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-11-03T23:45:13.189Z,1667519113.189 [UniversalFixResidualReporter] Loaded 2022-11-03T23:45:13.189Z,1667519113.189 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-11-03T23:45:13.190Z,1667519113.190 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-11-03T23:45:13.190Z,1667519113.190 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-11-03T23:45:13.200Z,1667519113.200 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-11-03T23:45:13.201Z,1667519113.201 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-11-03T23:45:13.332Z,1667519113.332 [PAR_Licor] Loaded 2022-11-03T23:45:13.332Z,1667519113.332 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-11-03T23:45:13.363Z,1667519113.363 [WetLabsBB2FL] Loaded 2022-11-03T23:45:13.363Z,1667519113.363 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-11-03T23:45:13.364Z,1667519113.364 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065A4E0 2022-11-03T23:45:13.365Z,1667519113.365 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5566 2022-11-03T23:45:13.366Z,1667519113.366 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-11-03T23:45:13.366Z,1667519113.366 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-11-03T23:45:13.416Z,1667519113.416 [VerticalControl](DEBUG): Construct VerticalControl. 2022-11-03T23:45:13.475Z,1667519113.475 [VerticalControl] Loaded 2022-11-03T23:45:13.475Z,1667519113.475 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-11-03T23:45:13.478Z,1667519113.478 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-11-03T23:45:13.518Z,1667519113.518 [HorizontalControl] Loaded 2022-11-03T23:45:13.518Z,1667519113.518 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-11-03T23:45:13.521Z,1667519113.521 [SpeedControl](DEBUG): Construct SpeedControl. 2022-11-03T23:45:13.524Z,1667519113.524 [SpeedControl] Loaded 2022-11-03T23:45:13.524Z,1667519113.524 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-11-03T23:45:13.527Z,1667519113.527 [LoopControl](DEBUG): Construct LoopControl. 2022-11-03T23:45:13.527Z,1667519113.527 [LoopControl] Loaded 2022-11-03T23:45:13.528Z,1667519113.528 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-11-03T23:45:13.528Z,1667519113.528 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-11-03T23:45:13.528Z,1667519113.528 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-11-03T23:45:13.577Z,1667519113.577 [DepthRateCalculator] Loaded 2022-11-03T23:45:13.578Z,1667519113.578 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-11-03T23:45:13.583Z,1667519113.583 [PitchRateCalculator] Loaded 2022-11-03T23:45:13.583Z,1667519113.583 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-11-03T23:45:13.592Z,1667519113.592 [SpeedCalculator] Loaded 2022-11-03T23:45:13.592Z,1667519113.592 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-11-03T23:45:13.596Z,1667519113.596 [YawRateCalculator] Loaded 2022-11-03T23:45:13.597Z,1667519113.597 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-11-03T23:45:13.614Z,1667519113.614 [ElevatorOffsetCalculator] Loaded 2022-11-03T23:45:13.614Z,1667519113.614 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-11-03T23:45:13.615Z,1667519113.615 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-11-03T23:45:13.615Z,1667519113.615 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-11-03T23:45:13.707Z,1667519113.707 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-11-03T23:45:13.708Z,1667519113.708 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-11-03T23:45:13.765Z,1667519113.765 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-11-03T23:45:13.766Z,1667519113.766 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-11-03T23:45:13.887Z,1667519113.887 [BuoyancyServo] Loaded 2022-11-03T23:45:13.887Z,1667519113.887 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-11-03T23:45:13.908Z,1667519113.908 [ElevatorServo] Loaded 2022-11-03T23:45:13.908Z,1667519113.908 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-11-03T23:45:13.927Z,1667519113.927 [MassServo] Loaded 2022-11-03T23:45:13.927Z,1667519113.927 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-11-03T23:45:13.945Z,1667519113.945 [RudderServo] Loaded 2022-11-03T23:45:13.946Z,1667519113.946 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-11-03T23:45:13.960Z,1667519113.960 [ThrusterHE] Loaded 2022-11-03T23:45:13.960Z,1667519113.960 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-11-03T23:45:13.961Z,1667519113.961 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-11-03T23:45:13.961Z,1667519113.961 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-11-03T23:45:14.112Z,1667519114.112 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-11-03T23:45:14.112Z,1667519114.112 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-11-03T23:45:14.507Z,1667519114.507 [AHRS_M2] Loaded 2022-11-03T23:45:14.507Z,1667519114.507 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-11-03T23:45:14.542Z,1667519114.542 [BackseatComponent] Loaded 2022-11-03T23:45:14.542Z,1667519114.542 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-11-03T23:45:14.543Z,1667519114.543 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0 2022-11-03T23:45:14.544Z,1667519114.544 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5567 2022-11-03T23:45:14.547Z,1667519114.547 [LcmUniversalReporter] Loaded 2022-11-03T23:45:14.547Z,1667519114.547 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-11-03T23:45:15.358Z,1667519115.358 [BPC1] Loaded 2022-11-03T23:45:15.358Z,1667519115.358 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-11-03T23:45:15.431Z,1667519115.431 [DataOverHttps] Loaded 2022-11-03T23:45:15.432Z,1667519115.432 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-11-03T23:45:15.433Z,1667519115.433 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0 2022-11-03T23:45:15.433Z,1667519115.433 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5568 2022-11-03T23:45:15.453Z,1667519115.453 [Depth_Keller] Loaded 2022-11-03T23:45:15.454Z,1667519115.454 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-11-03T23:45:15.459Z,1667519115.459 [DropWeight] Loaded 2022-11-03T23:45:15.459Z,1667519115.459 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-11-03T23:45:15.476Z,1667519115.476 [MultiRay] Loaded 2022-11-03T23:45:15.476Z,1667519115.476 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2022-11-03T23:45:15.538Z,1667519115.538 [NAL9602] Loaded 2022-11-03T23:45:15.538Z,1667519115.538 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-11-03T23:45:15.568Z,1667519115.568 [Onboard] Loaded 2022-11-03T23:45:15.568Z,1667519115.568 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-11-03T23:45:15.569Z,1667519115.569 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0 2022-11-03T23:45:15.570Z,1667519115.570 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5569 2022-11-03T23:45:15.576Z,1667519115.576 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2022-11-03T23:45:15.587Z,1667519115.587 [PowerOnly] Loaded 2022-11-03T23:45:15.587Z,1667519115.587 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2022-11-03T23:45:15.599Z,1667519115.599 [Power24vConverter] Loaded 2022-11-03T23:45:15.599Z,1667519115.599 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-11-03T23:45:15.612Z,1667519115.612 [Radio_Surface] Loaded 2022-11-03T23:45:15.613Z,1667519115.613 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-11-03T23:45:15.614Z,1667519115.614 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0 2022-11-03T23:45:15.614Z,1667519115.614 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5570 2022-11-03T23:45:15.705Z,1667519115.705 [DAT] Loaded 2022-11-03T23:45:15.706Z,1667519115.706 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2022-11-03T23:45:15.707Z,1667519115.707 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0 2022-11-03T23:45:15.707Z,1667519115.707 [DAT ThreadHandler](INFO): Protected caller Thread ID is 5571 2022-11-03T23:45:15.708Z,1667519115.708 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-11-03T23:45:15.708Z,1667519115.708 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-11-03T23:45:16.148Z,1667519116.148 [SBIT](DEBUG): Construct Startup Built In Test. 2022-11-03T23:45:16.157Z,1667519116.157 [SBIT] Loaded 2022-11-03T23:45:16.157Z,1667519116.157 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-11-03T23:45:16.160Z,1667519116.160 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-11-03T23:45:16.174Z,1667519116.174 [IBIT] Loaded 2022-11-03T23:45:16.174Z,1667519116.174 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-11-03T23:45:16.180Z,1667519116.180 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-11-03T23:45:16.281Z,1667519116.281 [CBIT] Loaded 2022-11-03T23:45:16.282Z,1667519116.282 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-11-03T23:45:16.282Z,1667519116.282 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-11-03T23:45:16.283Z,1667519116.283 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-11-03T23:45:16.309Z,1667519116.309 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-11-03T23:45:16.315Z,1667519116.315 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-11-03T23:45:16.318Z,1667519116.318 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-11-03T23:45:16.329Z,1667519116.329 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-11-03T23:45:16.330Z,1667519116.330 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA34E0 2022-11-03T23:45:16.330Z,1667519116.330 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5572 2022-11-03T23:45:16.335Z,1667519116.335 [Supervisor](INFO): Main Thread ID is 5479 2022-11-03T23:45:16.335Z,1667519116.335 [Supervisor](DEBUG): Running supervisor. 2022-11-03T23:45:16.335Z,1667519116.335 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5573 2022-11-03T23:45:16.336Z,1667519116.336 [CommandExec](INFO): Initializing the command executive. 2022-11-03T23:45:16.337Z,1667519116.337 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5574 2022-11-03T23:45:16.340Z,1667519116.340 [controlThread ThreadHandler](INFO): Handler Thread ID is 5575 2022-11-03T23:45:16.340Z,1667519116.340 [controlThread](DEBUG): Initializing ControlThread 2022-11-03T23:45:16.341Z,1667519116.341 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-03T23:45:16.342Z,1667519116.342 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-11-03T23:45:16.342Z,1667519116.342 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-11-03T23:45:16.344Z,1667519116.344 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-11-03T23:45:16.345Z,1667519116.345 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-11-03T23:45:16.346Z,1667519116.346 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-11-03T23:45:16.346Z,1667519116.346 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-11-03T23:45:16.347Z,1667519116.347 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-11-03T23:45:16.347Z,1667519116.347 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-11-03T23:45:16.347Z,1667519116.347 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-11-03T23:45:16.348Z,1667519116.348 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-11-03T23:45:16.348Z,1667519116.348 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-11-03T23:45:16.354Z,1667519116.354 [SBIT](INFO): Initialize SBIT Component. 2022-11-03T23:45:16.354Z,1667519116.354 [SBIT](IMPORTANT): git: 2022-10-11-37-g3067145cf 2022-11-03T23:45:16.354Z,1667519116.354 [SBIT](INFO): git hash: 3067145cfe0a71a9d335ed48764d88f81a84ec20 2022-11-03T23:45:16.355Z,1667519116.355 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-11-03T23:45:16.356Z,1667519116.356 [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 2022-11-03T23:45:16.357Z,1667519116.357 [SBIT](INFO): Beginning SBIT in 109.000000 seconds. 2022-11-03T23:45:16.358Z,1667519116.358 [IBIT](INFO): Initialize IBIT Component. 2022-11-03T23:45:16.358Z,1667519116.358 [CBIT](DEBUG): Initialize CBIT Component. 2022-11-03T23:45:16.359Z,1667519116.359 [logger ThreadHandler](INFO): Handler Thread ID is 5576 2022-11-03T23:45:16.371Z,1667519116.371 [CBIT](DEBUG): Initialized mux pins. 2022-11-03T23:45:16.372Z,1667519116.372 [CBIT](DEBUG): Initializing the watchdog timer. 2022-11-03T23:45:16.380Z,1667519116.380 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5577 2022-11-03T23:45:16.384Z,1667519116.384 [WetLabsBB2FL](INFO): Powering up 2022-11-03T23:45:16.386Z,1667519116.386 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5579 2022-11-03T23:45:16.392Z,1667519116.392 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5580 2022-11-03T23:45:16.393Z,1667519116.393 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-11-03T23:45:16.396Z,1667519116.396 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-11-03T23:45:16.396Z,1667519116.396 [CBIT](DEBUG): Initializing heartbeat. 2022-11-03T23:45:16.404Z,1667519116.404 [Onboard ThreadHandler](INFO): Handler Thread ID is 5581 2022-11-03T23:45:16.422Z,1667519116.422 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5582 2022-11-03T23:45:16.444Z,1667519116.444 [DAT ThreadHandler](INFO): Handler Thread ID is 5583 2022-11-03T23:45:16.445Z,1667519116.445 [DAT](INFO): Powering up 2022-11-03T23:45:16.445Z,1667519116.445 [DAT](DEBUG): Initializing DAT. 2022-11-03T23:45:16.449Z,1667519116.449 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5585 2022-11-03T23:45:16.451Z,1667519116.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-11-03T23:45:16.452Z,1667519116.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-11-03T23:45:16.452Z,1667519116.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-11-03T23:45:16.452Z,1667519116.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-11-03T23:45:16.452Z,1667519116.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-11-03T23:45:16.452Z,1667519116.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-11-03T23:45:16.452Z,1667519116.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-11-03T23:45:16.453Z,1667519116.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-11-03T23:45:16.467Z,1667519116.467 [CBIT](DEBUG): Deactivating GF circuits. 2022-11-03T23:45:16.468Z,1667519116.468 [CBIT](DEBUG): Deactivating emergency mode. 2022-11-03T23:45:16.503Z,1667519116.503 [CBIT](DEBUG): Backplane powered. 2022-11-03T23:45:16.504Z,1667519116.504 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-11-03T23:45:16.517Z,1667519116.517 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-11-03T23:45:16.545Z,1667519116.545 [MissionManager](DEBUG): 2022-11-03T23:45:16.545Z,1667519116.545 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-11-03T23:45:16.615Z,1667519116.615 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-11-03T23:45:16.617Z,1667519116.617 [Default:A.Wait](DEBUG): Construct Wait. 2022-11-03T23:45:16.619Z,1667519116.619 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-11-03T23:45:16.666Z,1667519116.666 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-11-03T23:45:16.669Z,1667519116.669 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-11-03T23:45:16.695Z,1667519116.695 [Default:E.Execute](DEBUG): Construct Execute. 2022-11-03T23:45:16.710Z,1667519116.710 [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 2022-11-03T23:45:16.718Z,1667519116.718 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-03T23:45:16.738Z,1667519116.738 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-11-03T23:45:16.804Z,1667519116.804 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar 2022-11-03T23:45:16.805Z,1667519116.805 [MultiRay](INFO): Powering up MultiRay Lights 2022-11-03T23:45:16.827Z,1667519116.827 [Radio_Surface](INFO): Powering up 2022-11-03T23:45:16.848Z,1667519116.848 [Power24vConverter](INFO): Powering up. 2022-11-03T23:45:16.856Z,1667519116.856 [DepthRateCalculator](ERROR): Depth measurement is not active 2022-11-03T23:45:16.878Z,1667519116.878 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-11-03T23:45:16.884Z,1667519116.884 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-11-03T23:45:16.885Z,1667519116.885 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-11-03T23:45:16.892Z,1667519116.892 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-11-03T23:45:16.893Z,1667519116.893 [MassServo](DEBUG): Initializing EZServoServo. 2022-11-03T23:45:16.900Z,1667519116.900 [MassServo](DEBUG): Initializing MassServo. 2022-11-03T23:45:16.901Z,1667519116.901 [RudderServo](DEBUG): Initializing EZServoServo. 2022-11-03T23:45:16.908Z,1667519116.908 [RudderServo](DEBUG): Initializing RudderServo. 2022-11-03T23:45:16.909Z,1667519116.909 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-11-03T23:45:16.924Z,1667519116.924 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-11-03T23:45:17.171Z,1667519117.171 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-11-03T23:45:17.171Z,1667519117.171 [DropWeight] Hardware Fault, FailCount= 1 2022-11-03T23:45:17.171Z,1667519117.171 [DropWeight](ERROR): Hardware Fault 2022-11-03T23:45:17.205Z,1667519117.205 [MultiRay](IMPORTANT): All lights off 2022-11-03T23:45:17.240Z,1667519117.240 [CommandExec](FAULT): Scheduling is paused 2022-11-03T23:45:17.240Z,1667519117.240 [CBIT](INFO): Critical error at 20221103T234517 2022-11-03T23:45:17.241Z,1667519117.241 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-11-03T23:45:17.247Z,1667519117.247 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-11-03T23:45:17.248Z,1667519117.248 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-11-03T23:45:17.915Z,1667519117.915 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-11-03T23:45:17.916Z,1667519117.916 [RudderServo](FAULT): Rudder failed to initialize 2022-11-03T23:45:17.916Z,1667519117.916 [RudderServo] Communications Fault, FailCount= 1 2022-11-03T23:45:17.916Z,1667519117.916 [RudderServo](ERROR): Communications Fault 2022-11-03T23:45:17.918Z,1667519117.918 [CBIT](INFO): Critical error at 20221103T234517 2022-11-03T23:45:17.920Z,1667519117.920 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-11-03T23:45:18.110Z,1667519118.110 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-11-03T23:45:18.110Z,1667519118.110 [RudderServo](INFO): Powering down 2022-11-03T23:45:18.761Z,1667519118.761 [RudderServo](DEBUG): Initializing EZServoServo. 2022-11-03T23:45:18.880Z,1667519118.880 [RudderServo](DEBUG): Initializing RudderServo. 2022-11-03T23:45:18.884Z,1667519118.884 [CBIT](INFO): Clearing failed state for component RudderServo 2022-11-03T23:45:18.885Z,1667519118.885 [RudderServo] No Fault, FailCount= 1 2022-11-03T23:45:18.996Z,1667519118.996 [WetLabsBB2FL](INFO): Powering down 2022-11-03T23:45:22.740Z,1667519122.740 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-11-03T23:45:30.298Z,1667519130.298 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004610 2022-11-03T23:45:31.566Z,1667519131.566 [DAT](INFO): commRate: 800 2022-11-03T23:45:33.631Z,1667519133.631 [DAT](INFO): entering command mode 2022-11-03T23:45:33.833Z,1667519133.833 [DAT](INFO): setting verbose to 3 2022-11-03T23:45:34.085Z,1667519134.085 [DAT](INFO): set verbose to 3 2022-11-03T23:45:34.086Z,1667519134.086 [DAT](INFO): setting DatVerbose to 27440 2022-11-03T23:45:34.337Z,1667519134.337 [DAT](INFO): set DatVerbose to 27440 2022-11-03T23:45:34.337Z,1667519134.337 [DAT](INFO): setting transmit power to 8 2022-11-03T23:45:34.590Z,1667519134.590 [DAT](INFO): set transmit power to 8 2022-11-03T23:45:34.590Z,1667519134.590 [DAT](INFO): setting local address to 10 2022-11-03T23:45:34.841Z,1667519134.841 [DAT](INFO): set local address to 10 2022-11-03T23:45:34.842Z,1667519134.842 [DAT](INFO): Setting time to: 23:45:34 And date to:11/3/2022 2022-11-03T23:45:35.093Z,1667519135.093 [DAT](INFO): Local DAT time set to Thu Nov 3, 2022 23:45:34 2022-11-03T23:45:45.853Z,1667519145.853 [NAL9602](INFO): Powering up NAL9602 2022-11-03T23:45:56.765Z,1667519156.765 [NAL9602](INFO): NAL9602 initialized 2022-11-03T23:47:05.873Z,1667519225.873 [SBIT](IMPORTANT): Beginning Startup BIT 2022-11-03T23:47:05.878Z,1667519225.878 [CBIT](IMPORTANT): Beginning ground fault scan 2022-11-03T23:47:16.820Z,1667519236.820 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.012288 CHAN A1 (24V): 0.000842 CHAN A2 (12V): -0.005952 CHAN A3 (5V): -0.002951 CHAN B0 (3.3V): -0.001026 CHAN B1 (3.15aV): -0.001147 CHAN B2 (3.15bV): -0.001900 CHAN B3 (GND): -0.000498 OPEN: 0.004649 Full Scale: +/- 1 mA 2022-11-03T23:48:00.024Z,1667519280.024 [SBIT](IMPORTANT): SBIT PASSED 2022-11-03T23:48:00.024Z,1667519280.024 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-11-03T23:48:00.025Z,1667519280.025 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool; 2022-11-03T23:48:00.025Z,1667519280.025 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none; 2022-11-03T23:48:00.025Z,1667519280.025 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere; 2022-11-03T23:48:00.025Z,1667519280.025 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour; 2022-11-03T23:48:00.025Z,1667519280.025 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2022-11-03T23:48:00.025Z,1667519280.025 [SBIT](IMPORTANT): DAT.surfaceThreshold=-1 meter; 2022-11-03T23:48:00.025Z,1667519280.025 [SBIT](IMPORTANT): Express none _.ayeris_disk_space; 2022-11-03T23:48:00.025Z,1667519280.025 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 250.000000 count; 2022-11-03T23:48:00.026Z,1667519280.026 [SBIT](IMPORTANT): Express none _.ayeris_particle_size; 2022-11-03T23:48:00.026Z,1667519280.026 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour; 2022-11-03T23:48:00.026Z,1667519280.026 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt; 2022-11-03T23:48:00.026Z,1667519280.026 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=210.367112 cubic_centimeter; 2022-11-03T23:48:00.026Z,1667519280.026 [SBIT](IMPORTANT): VerticalControl.massDefault=22.333859 millimeter; 2022-11-03T23:48:00.414Z,1667519280.414 [MissionManager](IMPORTANT): Started mission Startup 2022-11-03T23:48:00.414Z,1667519280.414 [Startup] Running Loop=1 2022-11-03T23:48:00.414Z,1667519280.414 [Startup](DEBUG): Aggregate::initialize Startup 2022-11-03T23:48:00.414Z,1667519280.414 [Startup:A.GoToSurface] Running Loop=1 2022-11-03T23:48:00.414Z,1667519280.414 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-03T23:48:00.415Z,1667519280.415 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-03T23:48:00.415Z,1667519280.415 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-03T23:48:00.416Z,1667519280.416 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-03T23:48:00.416Z,1667519280.416 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-03T23:48:00.416Z,1667519280.416 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-03T23:48:00.418Z,1667519280.418 [Startup:StartupSatComms] Running Loop=1 2022-11-03T23:48:00.418Z,1667519280.418 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-11-03T23:48:00.419Z,1667519280.419 [Startup:StartupSatComms:A] Running Loop=1 2022-11-03T23:48:00.811Z,1667519280.811 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-11-03T23:48:16.588Z,1667519296.588 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-11-03T23:48:16.588Z,1667519296.588 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-03T23:48:16.589Z,1667519296.589 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2022-11-03T23:48:16.589Z,1667519296.589 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-03T23:48:16.600Z,1667519296.600 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-03T23:48:16.600Z,1667519296.600 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-03T23:48:16.985Z,1667519296.985 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-03T23:48:16.985Z,1667519296.985 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-11-03T23:48:16.986Z,1667519296.986 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-03T23:48:16.986Z,1667519296.986 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2022-11-03T23:48:17.372Z,1667519297.372 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-03T23:49:00.601Z,1667519340.601 [Startup:StartupSatComms:A](INFO): Timed out from 2022-11-03T23:48:00.4Z 2022-11-03T23:49:00.601Z,1667519340.601 [Startup:StartupSatComms:A] Stopped 2022-11-03T23:49:00.601Z,1667519340.601 [Startup:StartupSatComms:B] Running Loop=1 2022-11-03T23:49:00.991Z,1667519340.991 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-11-03T23:49:01.896Z,1667519341.896 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-03T23:49:01.905Z,1667519341.905 [DAT](INFO): setting remote address to 1 2022-11-03T23:49:02.157Z,1667519342.157 [DAT](INFO): set remote address to 1 2022-11-03T23:49:02.158Z,1667519342.158 [DAT](INFO): entering online mode 2022-11-03T23:49:02.410Z,1667519342.410 [DAT](INFO): commRate: 800 2022-11-03T23:49:02.410Z,1667519342.410 [DAT](INFO): online mode acknowledged 2022-11-03T23:49:02.410Z,1667519342.410 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-03T23:49:05.685Z,1667519345.685 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-03T23:49:08.644Z,1667519348.644 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20221102T211232/Courier0000.lzma 2022-11-03T23:49:09.645Z,1667519349.645 [DataOverHttps](INFO): Moved sent file to Logs/20221102T211232/Courier0000.lzma.bak 2022-11-03T23:49:09.646Z,1667519349.646 [DataOverHttps](INFO): SBD MOMSN=17269961 2022-11-03T23:49:24.856Z,1667519364.856 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-03T23:49:26.346Z,1667519366.346 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-03T23:49:26.407Z,1667519366.407 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-03T23:49:28.387Z,1667519368.387 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20221102T211627/Courier0000.lzma 2022-11-03T23:49:29.389Z,1667519369.389 [DataOverHttps](INFO): Moved sent file to Logs/20221102T211627/Courier0000.lzma.bak 2022-11-03T23:49:29.389Z,1667519369.389 [DataOverHttps](INFO): SBD MOMSN=17269968 2022-11-03T23:49:29.625Z,1667519369.625 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-03T23:49:44.407Z,1667519384.407 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221102T211627/Courier0004.lzma 2022-11-03T23:49:45.401Z,1667519385.401 [DataOverHttps](INFO): Moved sent file to Logs/20221102T211627/Courier0004.lzma.bak 2022-11-03T23:49:45.401Z,1667519385.401 [DataOverHttps](INFO): SBD MOMSN=17269973 2022-11-03T23:49:46.764Z,1667519386.764 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-03T23:49:47.811Z,1667519387.811 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-03T23:49:47.869Z,1667519387.869 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-03T23:49:51.089Z,1667519391.089 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-03T23:50:00.887Z,1667519400.887 [Startup:StartupSatComms:B](INFO): Timed out from 2022-11-03T23:49:00.6Z 2022-11-03T23:50:00.887Z,1667519400.887 [Startup:StartupSatComms:B] Stopped 2022-11-03T23:50:00.887Z,1667519400.887 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-11-03T23:50:00.887Z,1667519400.887 [Startup:StartupSatComms] Stopped 2022-11-03T23:50:00.888Z,1667519400.888 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-11-03T23:50:00.888Z,1667519400.888 [Startup](INFO): Completed Startup 2022-11-03T23:50:00.888Z,1667519400.888 [MissionManager](INFO): Startup is completed. 2022-11-03T23:50:00.889Z,1667519400.889 [MissionManager](INFO): Uninitializing Mission Startup 2022-11-03T23:50:00.889Z,1667519400.889 [Startup] Stopped 2022-11-03T23:50:00.889Z,1667519400.889 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-11-03T23:50:00.889Z,1667519400.889 [Startup:A.GoToSurface] Stopped 2022-11-03T23:50:00.889Z,1667519400.889 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-03T23:50:01.289Z,1667519401.289 [MissionManager](IMPORTANT): Started mission Default 2022-11-03T23:50:01.290Z,1667519401.290 [Default] Running Loop=1 2022-11-03T23:50:01.290Z,1667519401.290 [Default](DEBUG): Aggregate::initialize Default 2022-11-03T23:50:01.290Z,1667519401.290 [Default:B.GoToSurface] Running Loop=1 2022-11-03T23:50:01.290Z,1667519401.290 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-03T23:50:01.290Z,1667519401.290 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-03T23:50:01.291Z,1667519401.291 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-03T23:50:01.291Z,1667519401.291 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-03T23:50:01.308Z,1667519401.308 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-03T23:50:01.308Z,1667519401.308 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-03T23:50:01.309Z,1667519401.309 [Default:A.Wait] Running Loop=1 2022-11-03T23:50:01.309Z,1667519401.309 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-03T23:50:01.491Z,1667519401.491 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20221102T212440/Courier0000.lzma 2022-11-03T23:50:02.493Z,1667519402.493 [DataOverHttps](INFO): Moved sent file to Logs/20221102T212440/Courier0000.lzma.bak 2022-11-03T23:50:02.493Z,1667519402.493 [DataOverHttps](INFO): SBD MOMSN=17269975 2022-11-03T23:50:08.252Z,1667519408.252 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-03T23:50:08.504Z,1667519408.504 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-03T23:50:08.756Z,1667519408.756 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-03T23:50:12.037Z,1667519412.037 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-03T23:50:12.564Z,1667519412.564 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-11-03T23:50:12.564Z,1667519412.564 [AHRS_M2] Data Fault, FailCount= 1 2022-11-03T23:50:12.564Z,1667519412.564 [AHRS_M2](ERROR): Data Fault 2022-11-03T23:50:12.609Z,1667519412.609 [CBIT](ERROR): Data Fault in component: AHRS_M2 2022-11-03T23:50:12.954Z,1667519412.954 [AHRS_M2](INFO): Powering down 2022-11-03T23:50:13.361Z,1667519413.361 [AHRS_M2](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-11-03T23:50:13.361Z,1667519413.361 [AHRS_M2] Hardware Fault, FailCount= 1 2022-11-03T23:50:13.361Z,1667519413.361 [AHRS_M2](ERROR): Hardware Fault 2022-11-03T23:50:13.826Z,1667519413.826 [CBIT](INFO): Clearing failed state for component AHRS_M2 2022-11-03T23:50:13.826Z,1667519413.826 [AHRS_M2] No Fault, FailCount= 1 2022-11-03T23:50:14.168Z,1667519414.168 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-11-03T23:50:14.600Z,1667519414.600 [Default:A.Wait](INFO): Done Waiting. 2022-11-03T23:50:14.600Z,1667519414.600 [Default:A.Wait] Stopped 2022-11-03T23:50:14.600Z,1667519414.600 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-03T23:50:15.009Z,1667519415.009 [Default:CheckIn] Running Loop=1 2022-11-03T23:50:15.009Z,1667519415.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-03T23:50:15.009Z,1667519415.009 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-03T23:50:15.413Z,1667519415.413 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-11-03T23:50:29.180Z,1667519429.180 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2022-11-03T23:50:29.180Z,1667519429.180 [DAT](ERROR): Ack receipt timeout failure. 2022-11-03T23:50:29.432Z,1667519429.432 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER 2022-11-03T23:50:29.684Z,1667519429.684 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-03T23:50:29.936Z,1667519429.936 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-03T23:50:33.217Z,1667519433.217 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-03T23:50:50.372Z,1667519450.372 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-03T23:50:50.624Z,1667519450.624 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-03T23:50:50.876Z,1667519450.876 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-03T23:50:54.153Z,1667519454.153 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-03T23:50:59.826Z,1667519459.826 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-11-03T23:51:11.312Z,1667519471.312 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-03T23:51:11.564Z,1667519471.564 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-03T23:51:11.816Z,1667519471.816 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-03T23:51:17.636Z,1667519477.636 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-11-03T23:51:17.636Z,1667519477.636 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-03T23:51:17.637Z,1667519477.637 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2022-11-03T23:51:17.637Z,1667519477.637 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-03T23:51:17.684Z,1667519477.684 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-03T23:51:17.684Z,1667519477.684 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-03T23:51:18.058Z,1667519478.058 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-03T23:51:18.058Z,1667519478.058 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-11-03T23:51:18.058Z,1667519478.058 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-03T23:51:18.059Z,1667519478.059 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2022-11-03T23:51:18.451Z,1667519478.451 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-03T23:51:21.904Z,1667519481.904 [DAT](FAULT): Buffer send receipt timeout failure. 2022-11-03T23:51:21.904Z,1667519481.904 [DAT](DEBUG): In sendingTransmitVerify, timeout so go online and set commsState_ = SENDING_FILL_BUFFER 2022-11-03T23:51:22.160Z,1667519482.160 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-03T23:51:22.413Z,1667519482.413 [DAT](INFO): entering online mode 2022-11-03T23:51:22.665Z,1667519482.665 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:22.916Z,1667519482.916 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:23.168Z,1667519483.168 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:23.420Z,1667519483.420 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:23.672Z,1667519483.672 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:23.924Z,1667519483.924 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:24.176Z,1667519484.176 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:24.428Z,1667519484.428 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:24.680Z,1667519484.680 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:24.932Z,1667519484.932 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:25.184Z,1667519485.184 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:25.436Z,1667519485.436 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:25.688Z,1667519485.688 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:25.940Z,1667519485.940 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:26.192Z,1667519486.192 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:26.444Z,1667519486.444 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:26.696Z,1667519486.696 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:26.948Z,1667519486.948 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:27.200Z,1667519487.200 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:27.452Z,1667519487.452 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:27.704Z,1667519487.704 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:27.956Z,1667519487.956 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:28.208Z,1667519488.208 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:28.460Z,1667519488.460 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:28.712Z,1667519488.712 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:28.964Z,1667519488.964 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:29.216Z,1667519489.216 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:29.468Z,1667519489.468 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:29.720Z,1667519489.720 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:29.972Z,1667519489.972 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:30.232Z,1667519490.232 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:30.484Z,1667519490.484 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:30.736Z,1667519490.736 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:30.988Z,1667519490.988 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:31.240Z,1667519491.240 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:31.492Z,1667519491.492 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:31.744Z,1667519491.744 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:31.996Z,1667519491.996 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:32.248Z,1667519492.248 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:32.501Z,1667519492.501 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:32.752Z,1667519492.752 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:33.004Z,1667519493.004 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:33.256Z,1667519493.256 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:33.508Z,1667519493.508 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:33.760Z,1667519493.760 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:34.012Z,1667519494.012 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:34.264Z,1667519494.264 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:34.516Z,1667519494.516 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:34.769Z,1667519494.769 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:35.020Z,1667519495.020 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:35.272Z,1667519495.272 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:35.524Z,1667519495.524 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:35.776Z,1667519495.776 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:36.028Z,1667519496.028 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:36.281Z,1667519496.281 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:36.532Z,1667519496.532 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:36.784Z,1667519496.784 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:37.036Z,1667519497.036 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:37.288Z,1667519497.288 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:37.540Z,1667519497.540 [DAT](DEBUG): checking for online mode acknowledgment 2022-11-03T23:51:37.540Z,1667519497.540 [DAT](FAULT): failed to enter online mode 2022-11-03T23:51:37.540Z,1667519497.540 [DAT](FAULT): Failure returning to online mode 2022-11-03T23:51:37.540Z,1667519497.540 [DAT] Communications Fault, FailCount= 1 2022-11-03T23:51:37.540Z,1667519497.540 [DAT](ERROR): Communications Fault 2022-11-03T23:51:37.860Z,1667519497.860 [CBIT](ERROR): Communications Fault in component: DAT 2022-11-03T23:51:37.944Z,1667519497.944 [DAT](INFO): Powering down 2022-11-03T23:51:39.103Z,1667519499.103 [CBIT](INFO): Clearing failed state for component DAT 2022-11-03T23:51:39.103Z,1667519499.103 [DAT] No Fault, FailCount= 1 2022-11-03T23:51:41.172Z,1667519501.172 [DAT](INFO): Powering up 2022-11-03T23:51:41.172Z,1667519501.172 [DAT](DEBUG): Initializing DAT. 2022-11-03T23:51:53.017Z,1667519513.017 [DAT](INFO): unknown deviceResponse_: ies 2022-11-03T23:52:41.404Z,1667519561.404 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: Nov 3 2022 23:51:49 , available: Features enabled ®P 2022-11-03T23:52:41.404Z,1667519561.404 [DAT] Communications Fault, FailCount= 2 2022-11-03T23:52:41.404Z,1667519561.404 [DAT](ERROR): Communications Fault 2022-11-03T23:52:41.675Z,1667519561.675 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-11-03T23:52:41.678Z,1667519561.678 [BPC1](INFO): Received data from all battery sticks. 2022-11-03T23:52:41.720Z,1667519561.720 [CBIT](ERROR): Communications Fault in component: DAT 2022-11-03T23:52:41.808Z,1667519561.808 [DAT](INFO): Powering down 2022-11-03T23:52:42.927Z,1667519562.927 [CBIT](INFO): Clearing failed state for component DAT 2022-11-03T23:52:42.927Z,1667519562.927 [DAT] No Fault, FailCount= 2 2022-11-03T23:52:44.856Z,1667519564.856 [DAT](INFO): Powering up 2022-11-03T23:52:44.856Z,1667519564.856 [DAT](DEBUG): Initializing DAT. 2022-11-03T23:52:59.801Z,1667519579.801 [CommandExec](IMPORTANT): got command show variable propomega 2022-11-03T23:52:59.931Z,1667519579.931 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction (radian_per_second) 2022-11-03T23:53:45.100Z,1667519625.100 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2022-11-03T23:53:45.100Z,1667519625.100 [DAT] Communications Fault, FailCount= 3 2022-11-03T23:53:45.100Z,1667519625.100 [DAT](ERROR): Communications Fault 2022-11-03T23:53:45.117Z,1667519625.117 [CBIT](ERROR): Communications Fault in component: DAT 2022-11-03T23:53:45.504Z,1667519625.504 [DAT](INFO): Powering down 2022-11-03T23:53:45.980Z,1667519625.980 [DAT](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-11-03T23:53:45.980Z,1667519625.980 [DAT] Hardware Fault, FailCount= 3 2022-11-03T23:53:45.980Z,1667519625.980 [DAT](ERROR): Hardware Fault 2022-11-03T23:53:46.773Z,1667519626.773 [CBIT](INFO): Clearing failed state for component DAT 2022-11-03T23:53:46.773Z,1667519626.773 [DAT] No Fault, FailCount= 3 2022-11-03T23:53:48.560Z,1667519628.560 [DAT](INFO): Powering up 2022-11-03T23:53:48.560Z,1667519628.560 [DAT](DEBUG): Initializing DAT. 2022-11-03T23:54:18.644Z,1667519658.644 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2022-11-03T23:54:18.644Z,1667519658.644 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-03T23:54:18.646Z,1667519658.646 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2022-11-03T23:54:18.646Z,1667519658.646 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-03T23:54:18.692Z,1667519658.692 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-03T23:54:18.693Z,1667519658.693 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-03T23:54:19.056Z,1667519659.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-03T23:54:19.056Z,1667519659.056 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2022-11-03T23:54:19.057Z,1667519659.057 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-03T23:54:19.057Z,1667519659.057 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2022-11-03T23:54:19.456Z,1667519659.456 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-03T23:54:48.564Z,1667519688.564 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2022-11-03T23:54:48.564Z,1667519688.564 [DAT] Communications Fault, FailCount= 4 2022-11-03T23:54:48.564Z,1667519688.564 [DAT](ERROR): Communications Fault 2022-11-03T23:54:48.566Z,1667519688.566 [CBIT](ERROR): Communications Fault in component: DAT 2022-11-03T23:54:48.968Z,1667519688.968 [DAT](INFO): Powering down 2022-11-03T23:54:50.218Z,1667519690.218 [CBIT](INFO): Clearing failed state for component DAT 2022-11-03T23:54:50.218Z,1667519690.218 [DAT] No Fault, FailCount= 4 2022-11-03T23:54:52.016Z,1667519692.016 [DAT](INFO): Powering up 2022-11-03T23:54:52.016Z,1667519692.016 [DAT](DEBUG): Initializing DAT. 2022-11-03T23:55:15.218Z,1667519715.218 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-03T23:50:15.0Z 2022-11-03T23:55:15.218Z,1667519715.218 [Default:CheckIn:Read_GPS] Stopped 2022-11-03T23:55:15.218Z,1667519715.218 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-11-03T23:55:15.626Z,1667519715.626 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-11-03T23:55:22.323Z,1667519722.323 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20221102T212727/Courier0000.lzma 2022-11-03T23:55:23.325Z,1667519723.325 [DataOverHttps](INFO): Moved sent file to Logs/20221102T212727/Courier0000.lzma.bak 2022-11-03T23:55:23.325Z,1667519723.325 [DataOverHttps](INFO): SBD MOMSN=17269980 2022-11-03T23:55:39.056Z,1667519739.056 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20221102T213251/Courier0000.lzma 2022-11-03T23:55:40.058Z,1667519740.058 [DataOverHttps](INFO): Moved sent file to Logs/20221102T213251/Courier0000.lzma.bak 2022-11-03T23:55:40.058Z,1667519740.058 [DataOverHttps](INFO): SBD MOMSN=17269985 2022-11-03T23:55:52.044Z,1667519752.044 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2022-11-03T23:55:52.044Z,1667519752.044 [DAT] Communications Fault, FailCount= 5 2022-11-03T23:55:52.044Z,1667519752.044 [DAT](ERROR): Communications Fault 2022-11-03T23:55:52.447Z,1667519752.447 [DAT](INFO): Powering down 2022-11-03T23:55:52.491Z,1667519752.491 [CBIT](ERROR): Communications Fault in component: DAT 2022-11-03T23:55:53.666Z,1667519753.666 [CBIT](INFO): Clearing failed state for component DAT 2022-11-03T23:55:53.666Z,1667519753.666 [DAT] No Fault, FailCount= 5 2022-11-03T23:55:55.496Z,1667519755.496 [DAT](INFO): Powering up 2022-11-03T23:55:55.496Z,1667519755.496 [DAT](DEBUG): Initializing DAT. 2022-11-03T23:55:56.703Z,1667519756.703 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221102T213251/Courier0004.lzma 2022-11-03T23:55:57.654Z,1667519757.654 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-11-03T23:55:57.655Z,1667519757.655 [NAL9602] Data Fault, FailCount= 1 2022-11-03T23:55:57.655Z,1667519757.655 [NAL9602](ERROR): Data Fault 2022-11-03T23:55:57.705Z,1667519757.705 [DataOverHttps](INFO): Moved sent file to Logs/20221102T213251/Courier0004.lzma.bak 2022-11-03T23:55:57.706Z,1667519757.706 [DataOverHttps](INFO): SBD MOMSN=17269990 2022-11-03T23:55:57.749Z,1667519757.749 [CBIT](ERROR): Data Fault in component: NAL9602 2022-11-03T23:55:58.065Z,1667519758.065 [NAL9602](INFO): Powering down 2022-11-03T23:55:58.955Z,1667519758.955 [CBIT](INFO): Clearing failed state for component NAL9602 2022-11-03T23:55:58.955Z,1667519758.955 [NAL9602] No Fault, FailCount= 1 2022-11-03T23:56:14.331Z,1667519774.331 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20221103T234506/Courier0000.lzma 2022-11-03T23:56:15.334Z,1667519775.334 [DataOverHttps](INFO): Moved sent file to Logs/20221103T234506/Courier0000.lzma.bak 2022-11-03T23:56:15.334Z,1667519775.334 [DataOverHttps](INFO): SBD MOMSN=17269992 2022-11-03T23:56:17.707Z,1667519777.707 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute 2022-11-03T23:56:17.710Z,1667519777.710 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2022-11-03T23:56:17.883Z,1667519777.883 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-03T23:56:28.357Z,1667519788.357 [NAL9602](INFO): Powering up NAL9602 2022-11-03T23:56:32.887Z,1667519792.887 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20221103T234506/Courier0004.lzma 2022-11-03T23:56:33.889Z,1667519793.889 [DataOverHttps](INFO): Moved sent file to Logs/20221103T234506/Courier0004.lzma.bak 2022-11-03T23:56:33.889Z,1667519793.889 [DataOverHttps](INFO): SBD MOMSN=17269997 2022-11-03T23:56:39.277Z,1667519799.277 [NAL9602](INFO): NAL9602 initialized 2022-11-03T23:56:51.010Z,1667519811.010 [DataOverHttps](INFO): Sending 369 bytes from file Logs/20221026T180640/Express0020.lzma 2022-11-03T23:56:52.009Z,1667519812.009 [DataOverHttps](INFO): Moved sent file to Logs/20221026T180640/Express0020.lzma.bak 2022-11-03T23:56:52.010Z,1667519812.010 [DataOverHttps](INFO): SBD MOMSN=17269999 2022-11-03T23:56:52.593Z,1667519812.593 [CommandExec](IMPORTANT): got command maintain clear 2022-11-03T23:56:52.699Z,1667519812.699 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-03T23:56:55.732Z,1667519815.732 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2022-11-03T23:56:55.732Z,1667519815.732 [DAT] Communications Fault, FailCount= 6 2022-11-03T23:56:55.732Z,1667519815.732 [DAT](ERROR): Communications Fault 2022-11-03T23:56:55.895Z,1667519815.895 [CBIT](ERROR): Communications Fault in component: DAT 2022-11-03T23:56:56.136Z,1667519816.136 [DAT](INFO): Powering down 2022-11-03T23:56:57.099Z,1667519817.099 [CBIT](INFO): Clearing failed state for component DAT 2022-11-03T23:56:57.099Z,1667519817.099 [DAT] No Fault, FailCount= 6 2022-11-03T23:56:59.180Z,1667519819.180 [DAT](INFO): Powering up 2022-11-03T23:56:59.180Z,1667519819.180 [DAT](DEBUG): Initializing DAT. 2022-11-03T23:57:07.789Z,1667519827.789 [DataOverHttps](INFO): Sending 415 bytes from file Logs/20221026T180640/Express0023.lzma 2022-11-03T23:57:08.789Z,1667519828.789 [DataOverHttps](INFO): Moved sent file to Logs/20221026T180640/Express0023.lzma.bak 2022-11-03T23:57:08.790Z,1667519828.790 [DataOverHttps](INFO): SBD MOMSN=17270021 2022-11-03T23:57:19.673Z,1667519839.673 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2022-11-03T23:57:19.673Z,1667519839.673 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-03T23:57:19.674Z,1667519839.674 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2022-11-03T23:57:19.674Z,1667519839.674 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-03T23:57:19.709Z,1667519839.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-03T23:57:19.709Z,1667519839.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-03T23:57:20.089Z,1667519840.089 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-03T23:57:20.089Z,1667519840.089 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2022-11-03T23:57:20.089Z,1667519840.089 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-03T23:57:20.090Z,1667519840.090 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2022-11-03T23:57:20.500Z,1667519840.500 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-03T23:57:26.833Z,1667519846.833 [DataOverHttps](INFO): Sending 531 bytes from file Logs/20221026T180640/Express0026.lzma 2022-11-03T23:57:27.833Z,1667519847.833 [DataOverHttps](INFO): Moved sent file to Logs/20221026T180640/Express0026.lzma.bak 2022-11-03T23:57:27.833Z,1667519847.833 [DataOverHttps](INFO): SBD MOMSN=17270049 2022-11-03T23:57:44.617Z,1667519864.617 [DataOverHttps](INFO): Sending 497 bytes from file Logs/20221026T180640/Express0029.lzma 2022-11-03T23:57:45.617Z,1667519865.617 [DataOverHttps](INFO): Moved sent file to Logs/20221026T180640/Express0029.lzma.bak 2022-11-03T23:57:45.617Z,1667519865.617 [DataOverHttps](INFO): SBD MOMSN=17270081 2022-11-03T23:57:59.424Z,1667519879.424 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2022-11-03T23:57:59.424Z,1667519879.424 [DAT] Communications Fault, FailCount= 7 2022-11-03T23:57:59.424Z,1667519879.424 [DAT](ERROR): Communications Fault 2022-11-03T23:57:59.695Z,1667519879.695 [CBIT](ERROR): Communications Fault in component: DAT 2022-11-03T23:57:59.828Z,1667519879.828 [DAT](INFO): Powering down 2022-11-03T23:58:00.935Z,1667519880.935 [CBIT](INFO): Clearing failed state for component DAT 2022-11-03T23:58:00.935Z,1667519880.935 [DAT] No Fault, FailCount= 7 2022-11-03T23:58:02.889Z,1667519882.889 [DAT](INFO): Powering up 2022-11-03T23:58:02.889Z,1667519882.889 [DAT](DEBUG): Initializing DAT. 2022-11-03T23:58:03.507Z,1667519883.507 [DataOverHttps](INFO): Sending 1120 bytes from file Logs/20221031T172428/Express0001.lzma 2022-11-03T23:58:04.501Z,1667519884.501 [DataOverHttps](INFO): Moved sent file to Logs/20221031T172428/Express0001.lzma.bak 2022-11-03T23:58:04.501Z,1667519884.501 [DataOverHttps](INFO): SBD MOMSN=17270109 2022-11-03T23:58:07.675Z,1667519887.675 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter 2022-11-03T23:58:07.679Z,1667519887.679 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2022-11-03T23:58:07.828Z,1667519887.828 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-03T23:58:22.707Z,1667519902.707 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221102T160538/Express0001.lzma 2022-11-03T23:58:23.709Z,1667519903.709 [DataOverHttps](INFO): Moved sent file to Logs/20221102T160538/Express0001.lzma.bak 2022-11-03T23:58:23.709Z,1667519903.709 [DataOverHttps](INFO): SBD MOMSN=17270141 2022-11-03T23:58:41.255Z,1667519921.255 [DataOverHttps](INFO): Sending 1194 bytes from file Logs/20221102T161009/Express0001.lzma 2022-11-03T23:58:42.253Z,1667519922.253 [DataOverHttps](INFO): Moved sent file to Logs/20221102T161009/Express0001.lzma.bak 2022-11-03T23:58:42.253Z,1667519922.253 [DataOverHttps](INFO): SBD MOMSN=17270143 2022-11-03T23:58:59.190Z,1667519939.190 [DataOverHttps](INFO): Sending 1076 bytes from file Logs/20221102T161555/Express0001.lzma 2022-11-03T23:59:00.189Z,1667519940.189 [DataOverHttps](INFO): Moved sent file to Logs/20221102T161555/Express0001.lzma.bak 2022-11-03T23:59:00.189Z,1667519940.189 [DataOverHttps](INFO): SBD MOMSN=17270179 2022-11-03T23:59:02.920Z,1667519942.920 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2022-11-03T23:59:02.920Z,1667519942.920 [DAT] Communications Fault, FailCount= 8 2022-11-03T23:59:02.920Z,1667519942.920 [DAT](ERROR): Communications Fault 2022-11-03T23:59:03.125Z,1667519943.125 [CBIT](ERROR): Communications Fault in component: DAT 2022-11-03T23:59:03.125Z,1667519943.125 [CBIT](FAULT): Communications Fault in component: DAT 2022-11-03T23:59:03.324Z,1667519943.324 [DAT](INFO): Powering down 2022-11-03T23:59:15.898Z,1667519955.898 [CommandExec](IMPORTANT): got command get platform_buoyancy_position 2022-11-03T23:59:15.899Z,1667519955.899 [CommandExec](IMPORTANT): platform_buoyancy_position 208.137726 cc 2022-11-03T23:59:18.532Z,1667519958.532 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20221102T161555/Express0005.lzma 2022-11-03T23:59:19.437Z,1667519959.437 [CommandExec](IMPORTANT): got command maintain clear 2022-11-03T23:59:19.533Z,1667519959.533 [DataOverHttps](INFO): Moved sent file to Logs/20221102T161555/Express0005.lzma.bak 2022-11-03T23:59:19.533Z,1667519959.533 [DataOverHttps](INFO): SBD MOMSN=17270207 2022-11-03T23:59:19.704Z,1667519959.704 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-03T23:59:36.737Z,1667519976.737 [DataOverHttps](INFO): Sending 630 bytes from file Logs/20221102T162223/Express0001.lzma 2022-11-03T23:59:37.737Z,1667519977.737 [DataOverHttps](INFO): Moved sent file to Logs/20221102T162223/Express0001.lzma.bak 2022-11-03T23:59:37.737Z,1667519977.737 [DataOverHttps](INFO): SBD MOMSN=17270210 2022-11-03T23:59:55.965Z,1667519995.965 [DataOverHttps](INFO): Sending 375 bytes from file Logs/20221102T162835/Express0001.lzma 2022-11-03T23:59:56.965Z,1667519996.965 [DataOverHttps](INFO): Moved sent file to Logs/20221102T162835/Express0001.lzma.bak 2022-11-03T23:59:56.965Z,1667519996.965 [DataOverHttps](INFO): SBD MOMSN=17270221 2022-11-04T00:00:07.700Z,1667520007.700 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter 2022-11-04T00:00:07.701Z,1667520007.701 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2022-11-04T00:00:07.768Z,1667520007.768 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-04T00:00:14.689Z,1667520014.689 [CommandExec](IMPORTANT): got command maintain clear 2022-11-04T00:00:14.961Z,1667520014.961 [DataOverHttps](INFO): Sending 376 bytes from file Logs/20221102T163155/Express0001.lzma 2022-11-04T00:00:15.110Z,1667520015.110 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-04T00:00:15.961Z,1667520015.961 [DataOverHttps](INFO): Moved sent file to Logs/20221102T163155/Express0001.lzma.bak 2022-11-04T00:00:15.961Z,1667520015.961 [DataOverHttps](INFO): SBD MOMSN=17270229 2022-11-04T00:00:17.497Z,1667520017.497 [CBIT](INFO): Clearing failed state for component DropWeight 2022-11-04T00:00:17.497Z,1667520017.497 [DropWeight] No Fault, FailCount= 1 2022-11-04T00:00:20.692Z,1667520020.692 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2022-11-04T00:00:20.692Z,1667520020.692 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-04T00:00:20.693Z,1667520020.693 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2022-11-04T00:00:20.693Z,1667520020.693 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-04T00:00:20.744Z,1667520020.744 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-04T00:00:20.745Z,1667520020.745 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-04T00:00:21.120Z,1667520021.120 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-04T00:00:21.120Z,1667520021.120 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2022-11-04T00:00:21.121Z,1667520021.121 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-04T00:00:21.121Z,1667520021.121 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2022-11-04T00:00:21.499Z,1667520021.499 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-04T00:00:30.034Z,1667520030.034 [CommandExec](IMPORTANT): got command get platform_buoyancy_position 2022-11-04T00:00:30.034Z,1667520030.034 [CommandExec](IMPORTANT): platform_buoyancy_position 948.399414 cc 2022-11-04T00:00:35.098Z,1667520035.098 [DataOverHttps](INFO): Sending 1119 bytes from file Logs/20221102T163427/Express0001.lzma 2022-11-04T00:00:36.097Z,1667520036.097 [DataOverHttps](INFO): Moved sent file to Logs/20221102T163427/Express0001.lzma.bak 2022-11-04T00:00:36.097Z,1667520036.097 [DataOverHttps](INFO): SBD MOMSN=17270237 2022-11-04T00:00:45.024Z,1667520045.024 [CommandExec](IMPORTANT): got command get platform_mass_position 2022-11-04T00:00:45.025Z,1667520045.025 [CommandExec](IMPORTANT): platform_mass_position 0.022334 m 2022-11-04T00:00:54.167Z,1667520054.167 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221102T163427/Express0005.lzma 2022-11-04T00:00:55.169Z,1667520055.169 [DataOverHttps](INFO): Moved sent file to Logs/20221102T163427/Express0005.lzma.bak 2022-11-04T00:00:55.169Z,1667520055.169 [DataOverHttps](INFO): SBD MOMSN=17270268 2022-11-04T00:01:01.400Z,1667520061.400 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2022-11-04T00:01:01.401Z,1667520061.401 [CommandExec](IMPORTANT): platform_mass_position 2.233372 cm 2022-11-04T00:01:12.757Z,1667520072.757 [DataOverHttps](INFO): Sending 715 bytes from file Logs/20221102T164101/Express0001.lzma 2022-11-04T00:01:13.757Z,1667520073.757 [DataOverHttps](INFO): Moved sent file to Logs/20221102T164101/Express0001.lzma.bak 2022-11-04T00:01:13.757Z,1667520073.757 [DataOverHttps](INFO): SBD MOMSN=17270270 2022-11-04T00:01:32.506Z,1667520092.506 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionLimitFwd 2022-11-04T00:01:32.507Z,1667520092.507 [CommandExec](FAULT): Incomplete syntax. Try: help maintain 2022-11-04T00:01:38.449Z,1667520098.449 [DataOverHttps](INFO): Sending 386 bytes from file Logs/20221102T165126/Express0001.lzma 2022-11-04T00:01:38.715Z,1667520098.715 [Power24vConverter](INFO): Powering down. 2022-11-04T00:01:39.122Z,1667520099.122 [Power24vConverter](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2022-11-04T00:01:39.122Z,1667520099.122 [Power24vConverter] Hardware Fault, FailCount= 1 2022-11-04T00:01:39.123Z,1667520099.123 [Power24vConverter](ERROR): Hardware Fault 2022-11-04T00:01:39.191Z,1667520099.191 [CBIT](ERROR): Hardware Fault in component: Power24vConverter 2022-11-04T00:01:39.449Z,1667520099.449 [DataOverHttps](INFO): Moved sent file to Logs/20221102T165126/Express0001.lzma.bak 2022-11-04T00:01:39.449Z,1667520099.449 [DataOverHttps](INFO): SBD MOMSN=17270287 2022-11-04T00:01:39.536Z,1667520099.536 [Power24vConverter](INFO): Powering down. 2022-11-04T00:01:40.371Z,1667520100.371 [CBIT](INFO): Clearing failed state for component Power24vConverter 2022-11-04T00:01:40.371Z,1667520100.371 [Power24vConverter] No Fault, FailCount= 1 2022-11-04T00:01:40.734Z,1667520100.734 [Power24vConverter](INFO): Powering up. 2022-11-04T00:02:01.578Z,1667520121.578 [DataOverHttps](INFO): Sending 1040 bytes from file Logs/20221102T165731/Express0001.lzma 2022-11-04T00:02:02.577Z,1667520122.577 [DataOverHttps](INFO): Moved sent file to Logs/20221102T165731/Express0001.lzma.bak 2022-11-04T00:02:02.577Z,1667520122.577 [DataOverHttps](INFO): SBD MOMSN=17270295 2022-11-04T00:02:06.299Z,1667520126.299 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitFwd 2022-11-04T00:02:06.308Z,1667520126.308 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd 37.000000 mm 2022-11-04T00:02:21.850Z,1667520141.850 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221102T170240/Express0001.lzma 2022-11-04T00:02:22.053Z,1667520142.053 [DataOverHttps](INFO): Moved sent file to Logs/20221102T170240/Express0001.lzma.bak 2022-11-04T00:02:22.054Z,1667520142.054 [DataOverHttps](INFO): SBD MOMSN=17270323 2022-11-04T00:02:23.409Z,1667520143.409 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitAft 2022-11-04T00:02:23.410Z,1667520143.410 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft -27.000000 mm 2022-11-04T00:02:39.785Z,1667520159.785 [DataOverHttps](INFO): Sending 721 bytes from file Logs/20221102T170721/Express0001.lzma 2022-11-04T00:02:40.785Z,1667520160.785 [DataOverHttps](INFO): Moved sent file to Logs/20221102T170721/Express0001.lzma.bak 2022-11-04T00:02:40.785Z,1667520160.785 [DataOverHttps](INFO): SBD MOMSN=17270325 2022-11-04T00:02:55.363Z,1667520175.363 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 30 millimeter 2022-11-04T00:02:55.371Z,1667520175.371 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2022-11-04T00:02:55.554Z,1667520175.554 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-04T00:03:00.499Z,1667520180.499 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221102T170721/Express0005.lzma 2022-11-04T00:03:01.501Z,1667520181.501 [DataOverHttps](INFO): Moved sent file to Logs/20221102T170721/Express0005.lzma.bak 2022-11-04T00:03:01.501Z,1667520181.501 [DataOverHttps](INFO): SBD MOMSN=17270339 2022-11-04T00:03:21.756Z,1667520201.756 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2022-11-04T00:03:21.756Z,1667520201.756 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-04T00:03:21.757Z,1667520201.757 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2022-11-04T00:03:21.757Z,1667520201.757 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-04T00:03:21.768Z,1667520201.768 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-04T00:03:21.769Z,1667520201.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-04T00:03:22.175Z,1667520202.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-04T00:03:22.175Z,1667520202.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2022-11-04T00:03:22.175Z,1667520202.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-04T00:03:22.176Z,1667520202.176 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2022-11-04T00:03:22.588Z,1667520202.588 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-04T00:03:22.661Z,1667520202.661 [DataOverHttps](INFO): Sending 764 bytes from file Logs/20221102T171214/Express0001.lzma 2022-11-04T00:03:23.661Z,1667520203.661 [DataOverHttps](INFO): Moved sent file to Logs/20221102T171214/Express0001.lzma.bak 2022-11-04T00:03:23.661Z,1667520203.661 [DataOverHttps](INFO): SBD MOMSN=17270341 2022-11-04T00:03:26.753Z,1667520206.753 [CommandExec](IMPORTANT): got command get platform_mass_position 2022-11-04T00:03:26.753Z,1667520206.753 [CommandExec](IMPORTANT): platform_mass_position 0.030000 m 2022-11-04T00:03:41.585Z,1667520221.585 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20221102T171214/Express0005.lzma 2022-11-04T00:03:42.213Z,1667520222.213 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -22 millimeter 2022-11-04T00:03:42.585Z,1667520222.585 [DataOverHttps](INFO): Moved sent file to Logs/20221102T171214/Express0005.lzma.bak 2022-11-04T00:03:42.585Z,1667520222.585 [DataOverHttps](INFO): SBD MOMSN=17270359 2022-11-04T00:04:03.000Z,1667520243.000 [CBIT](INFO): Clearing failed state for component DAT 2022-11-04T00:04:03.000Z,1667520243.000 [DAT] No Fault, FailCount= 8 2022-11-04T00:04:03.232Z,1667520243.232 [DAT](INFO): Powering up 2022-11-04T00:04:03.232Z,1667520243.232 [DAT](DEBUG): Initializing DAT. 2022-11-04T00:04:18.366Z,1667520258.366 [DAT](INFO): commRate: 800 2022-11-04T00:04:20.436Z,1667520260.436 [DAT](INFO): entering command mode 2022-11-04T00:04:20.636Z,1667520260.636 [DAT](INFO): setting verbose to 3 2022-11-04T00:04:20.889Z,1667520260.889 [DAT](INFO): set verbose to 3 2022-11-04T00:04:20.889Z,1667520260.889 [DAT](INFO): setting DatVerbose to 27440 2022-11-04T00:04:21.141Z,1667520261.141 [DAT](INFO): set DatVerbose to 27440 2022-11-04T00:04:21.141Z,1667520261.141 [DAT](INFO): setting transmit power to 8 2022-11-04T00:04:21.393Z,1667520261.393 [DAT](INFO): set transmit power to 8 2022-11-04T00:04:21.393Z,1667520261.393 [DAT](INFO): setting local address to 10 2022-11-04T00:04:21.645Z,1667520261.645 [DAT](INFO): set local address to 10 2022-11-04T00:04:21.646Z,1667520261.646 [DAT](INFO): Setting time to: 0:4:21 And date to:11/4/2022 2022-11-04T00:04:21.897Z,1667520261.897 [DAT](INFO): Local DAT time set to Fri Nov 4, 2022 00:04:21 2022-11-04T00:04:21.898Z,1667520261.898 [DAT](INFO): setting remote address to 1 2022-11-04T00:04:22.149Z,1667520262.149 [DAT](INFO): set remote address to 1 2022-11-04T00:04:22.149Z,1667520262.149 [DAT](INFO): entering online mode 2022-11-04T00:04:22.402Z,1667520262.402 [DAT](INFO): commRate: 800 2022-11-04T00:04:22.402Z,1667520262.402 [DAT](INFO): online mode acknowledged 2022-11-04T00:04:22.403Z,1667520262.403 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-04T00:04:25.685Z,1667520265.685 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-04T00:04:38.154Z,1667520278.154 [CommandExec](IMPORTANT): got command get platform_orientation 2022-11-04T00:04:38.155Z,1667520278.155 [CommandExec](IMPORTANT): platform_orientation 4.953978 rad 2022-11-04T00:04:42.824Z,1667520282.824 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-04T00:04:44.350Z,1667520284.350 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-04T00:04:44.356Z,1667520284.356 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-04T00:04:44.413Z,1667520284.413 [CommandExec](IMPORTANT): got command get platform_orientation 2022-11-04T00:04:44.413Z,1667520284.413 [CommandExec](FAULT): Incomplete syntax. Try: help get 2022-11-04T00:04:44.598Z,1667520284.598 [Default:CheckIn:Read_Iridium] Stopped 2022-11-04T00:04:44.598Z,1667520284.598 [Default:CheckIn:C.Wait] Running Loop=1 2022-11-04T00:04:44.598Z,1667520284.598 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-11-04T00:04:47.633Z,1667520287.633 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-04T00:04:48.565Z,1667520288.565 [CommandExec](IMPORTANT): got command get platform_orientation degree 2022-11-04T00:04:48.566Z,1667520288.566 [CommandExec](IMPORTANT): platform_orientation 283.835696 arcdeg 2022-11-04T00:04:52.460Z,1667520292.460 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247314 2022-11-04T00:05:04.772Z,1667520304.772 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-04T00:05:05.024Z,1667520305.024 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-04T00:05:05.072Z,1667520305.072 [CommandExec](IMPORTANT): got command get platform_mass_position 2022-11-04T00:05:05.072Z,1667520305.072 [CommandExec](IMPORTANT): platform_mass_position -0.022000 m 2022-11-04T00:05:05.276Z,1667520305.276 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-04T00:05:08.569Z,1667520308.569 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-04T00:05:10.897Z,1667520310.897 [CommandExec](IMPORTANT): got command maintain clear 2022-11-04T00:05:11.269Z,1667520311.269 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-04T00:05:23.584Z,1667520323.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-11-04T00:05:25.728Z,1667520325.728 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-04T00:05:25.980Z,1667520325.980 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-04T00:05:26.232Z,1667520326.232 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-04T00:05:29.509Z,1667520329.509 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-04T00:05:45.378Z,1667520345.378 [CommandExec](IMPORTANT): got command get platform_mass_position 2022-11-04T00:05:45.378Z,1667520345.378 [CommandExec](IMPORTANT): platform_mass_position 0.002995 m 2022-11-04T00:05:46.644Z,1667520346.644 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2022-11-04T00:05:46.644Z,1667520346.644 [DAT](ERROR): Ack receipt timeout failure. 2022-11-04T00:05:46.896Z,1667520346.896 [DAT](INFO): Failure count cleared after critical for DAT 2022-11-04T00:05:46.896Z,1667520346.896 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER 2022-11-04T00:05:47.148Z,1667520347.148 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-04T00:05:47.400Z,1667520347.400 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-04T00:05:50.677Z,1667520350.677 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-04T00:05:55.845Z,1667520355.845 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2022-11-04T00:05:55.845Z,1667520355.845 [CommandExec](IMPORTANT): Onboard.Pressure 59949.621094 Pa 2022-11-04T00:05:58.757Z,1667520358.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-11-04T00:06:05.149Z,1667520365.149 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2022-11-04T00:06:05.150Z,1667520365.150 [CommandExec](IMPORTANT): Onboard.Humidity 18.703455 % 2022-11-04T00:06:07.836Z,1667520367.836 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-04T00:06:08.088Z,1667520368.088 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-04T00:06:08.340Z,1667520368.340 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-04T00:06:11.324Z,1667520371.324 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002884 2022-11-04T00:06:11.617Z,1667520371.617 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-04T00:06:22.742Z,1667520382.742 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2022-11-04T00:06:22.742Z,1667520382.742 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-04T00:06:22.744Z,1667520382.744 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2022-11-04T00:06:22.744Z,1667520382.744 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-04T00:06:22.755Z,1667520382.755 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-04T00:06:22.755Z,1667520382.755 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-04T00:06:23.167Z,1667520383.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-04T00:06:23.167Z,1667520383.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2022-11-04T00:06:23.168Z,1667520383.168 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-04T00:06:23.168Z,1667520383.168 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2022-11-04T00:06:23.551Z,1667520383.551 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-04T00:06:28.768Z,1667520388.768 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-04T00:06:29.020Z,1667520389.020 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-04T00:06:29.272Z,1667520389.272 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-04T00:06:32.561Z,1667520392.561 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-04T00:06:40.107Z,1667520400.107 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-11-04T00:06:40.107Z,1667520400.107 [NAL9602] Data Fault, FailCount= 2 2022-11-04T00:06:40.107Z,1667520400.107 [NAL9602](ERROR): Data Fault 2022-11-04T00:06:40.156Z,1667520400.156 [CBIT](ERROR): Data Fault in component: NAL9602 2022-11-04T00:06:40.513Z,1667520400.513 [NAL9602](INFO): Powering down 2022-11-04T00:06:41.338Z,1667520401.338 [CBIT](INFO): Clearing failed state for component NAL9602 2022-11-04T00:06:41.338Z,1667520401.338 [NAL9602] No Fault, FailCount= 2 2022-11-04T00:06:49.696Z,1667520409.696 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2022-11-04T00:06:49.948Z,1667520409.948 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2022-11-04T00:06:50.203Z,1667520410.203 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2022-11-04T00:06:53.497Z,1667520413.497 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2022-11-04T00:07:08.340Z,1667520428.340 [CommandExec](IMPORTANT): got command failComponent 2022-11-04T00:07:08.340Z,1667520428.340 [CommandExec](IMPORTANT): Failed components: 2022-11-04T00:07:08.340Z,1667520428.340 [CommandExec](IMPORTANT): No failed Components. 2022-11-04T00:07:10.644Z,1667520430.644 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2022-11-04T00:07:10.644Z,1667520430.644 [DAT](ERROR): Ack receipt timeout failure. 2022-11-04T00:07:10.813Z,1667520430.813 [NAL9602](INFO): Powering up NAL9602 2022-11-04T00:07:10.896Z,1667520430.896 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER 2022-11-04T00:07:11.052Z,1667520431.052 [CommandExec](IMPORTANT): got command gfscan 2022-11-04T00:07:11.236Z,1667520431.236 [CBIT](IMPORTANT): Beginning ground fault scan 2022-11-04T00:07:21.718Z,1667520441.718 [NAL9602](INFO): NAL9602 initialized 2022-11-04T00:07:22.162Z,1667520442.162 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.013112 CHAN A1 (24V): 0.000893 CHAN A2 (12V): -0.005643 CHAN A3 (5V): -0.003646 CHAN B0 (3.3V): -0.001201 CHAN B1 (3.15aV): -0.001565 CHAN B2 (3.15bV): -0.001199 CHAN B3 (GND): -0.000525 OPEN: 0.004446 Full Scale: +/- 1 mA 2022-11-04T00:08:14.924Z,1667520494.924 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree 2022-11-04T00:08:14.928Z,1667520494.928 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread. 2022-11-04T00:08:15.182Z,1667520495.182 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-04T00:09:23.800Z,1667520563.800 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2022-11-04T00:09:23.800Z,1667520563.800 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-04T00:09:23.801Z,1667520563.801 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2022-11-04T00:09:23.801Z,1667520563.801 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-04T00:09:23.812Z,1667520563.812 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-04T00:09:23.813Z,1667520563.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-04T00:09:24.232Z,1667520564.232 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-04T00:09:24.232Z,1667520564.232 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2022-11-04T00:09:24.233Z,1667520564.233 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-04T00:09:24.233Z,1667520564.233 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2022-11-04T00:09:24.582Z,1667520564.582 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-04T00:09:45.183Z,1667520585.183 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-11-04T00:09:45.183Z,1667520585.183 [Default:CheckIn:C.Wait] Stopped 2022-11-04T00:09:45.183Z,1667520585.183 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-11-04T00:09:45.183Z,1667520585.183 [Default:CheckIn:D] Running Loop=1 2022-11-04T00:09:45.592Z,1667520585.592 [Default:CheckIn:D] Stopped 2022-11-04T00:09:45.592Z,1667520585.592 [Default:CheckIn:E] Running Loop=1 2022-11-04T00:09:46.008Z,1667520586.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.738377 min 2022-11-04T00:09:46.008Z,1667520586.008 [Default:CheckIn:E] Stopped 2022-11-04T00:09:46.008Z,1667520586.008 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-11-04T00:09:46.008Z,1667520586.008 [Default:CheckIn] Stopped 2022-11-04T00:09:46.008Z,1667520586.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-04T00:09:46.008Z,1667520586.008 [Default:CheckIn](INFO): Running loop #2 2022-11-04T00:09:46.009Z,1667520586.009 [Default:CheckIn] Running Loop=2 2022-11-04T00:09:46.009Z,1667520586.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-04T00:09:46.009Z,1667520586.009 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-04T00:10:32.984Z,1667520632.984 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree 2022-11-04T00:10:41.952Z,1667520641.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-11-04T00:10:47.976Z,1667520647.976 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.258999 2022-11-04T00:10:48.205Z,1667520648.205 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree 2022-11-04T00:10:55.697Z,1667520655.697 [CommandExec](IMPORTANT): got command maintain clear 2022-11-04T00:10:55.918Z,1667520655.918 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-04T00:11:52.232Z,1667520712.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-11-04T00:11:59.417Z,1667520719.417 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree 2022-11-04T00:11:59.421Z,1667520719.421 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread. 2022-11-04T00:11:59.736Z,1667520719.736 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-04T00:12:13.894Z,1667520733.894 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree 2022-11-04T00:12:24.758Z,1667520744.758 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2022-11-04T00:12:24.758Z,1667520744.758 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-04T00:12:24.759Z,1667520744.759 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2022-11-04T00:12:24.759Z,1667520744.759 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-11-04T00:12:24.770Z,1667520744.770 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-04T00:12:24.770Z,1667520744.770 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-11-04T00:12:25.191Z,1667520745.191 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-04T00:12:25.191Z,1667520745.191 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2022-11-04T00:12:25.192Z,1667520745.192 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-11-04T00:12:25.192Z,1667520745.192 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2022-11-04T00:12:25.600Z,1667520745.600 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-11-04T00:12:27.380Z,1667520747.380 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-11-04T00:12:28.685Z,1667520748.685 [CommandExec](IMPORTANT): got command maintain clear 2022-11-04T00:12:28.849Z,1667520748.849 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-04T00:13:02.524Z,1667520782.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-11-04T00:13:05.104Z,1667520785.104 [CommandExec](IMPORTANT): got command quit 2022-11-04T00:13:06.107Z,1667520786.107 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:06.108Z,1667520786.108 [CommandExec](INFO): Uninitializing the command executive. 2022-11-04T00:13:06.108Z,1667520786.108 [CommandExec](INFO): Uninitializing the command scheduler. 2022-11-04T00:13:06.108Z,1667520786.108 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:06.263Z,1667520786.263 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-11-04T00:13:06.264Z,1667520786.264 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-11-04T00:13:06.264Z,1667520786.264 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:06.264Z,1667520786.264 [NavChartDb](INFO): Join timeout helper Thread ID is 5653 2022-11-04T00:13:06.544Z,1667520786.544 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:06.544Z,1667520786.544 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:06.564Z,1667520786.564 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2022-11-04T00:13:06.564Z,1667520786.564 [DAT ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:06.564Z,1667520786.564 [DAT](INFO): Join timeout helper Thread ID is 5654 2022-11-04T00:13:06.576Z,1667520786.576 [DAT](INFO): Powering down 2022-11-04T00:13:06.645Z,1667520786.645 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:06.645Z,1667520786.645 [DAT](INFO): Powering down 2022-11-04T00:13:06.646Z,1667520786.646 [DAT ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:06.664Z,1667520786.664 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-11-04T00:13:06.664Z,1667520786.664 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:06.664Z,1667520786.664 [Radio_Surface](INFO): Join timeout helper Thread ID is 5655 2022-11-04T00:13:06.916Z,1667520786.916 [Radio_Surface](INFO): Powering down 2022-11-04T00:13:06.916Z,1667520786.916 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:06.917Z,1667520786.917 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:06.927Z,1667520786.927 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-11-04T00:13:06.928Z,1667520786.928 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:06.928Z,1667520786.928 [Onboard](INFO): Join timeout helper Thread ID is 5656 2022-11-04T00:13:07.174Z,1667520787.174 [Power24vConverter](INFO): Powering down. 2022-11-04T00:13:07.544Z,1667520787.544 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-11-04T00:13:07.763Z,1667520787.763 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:07.764Z,1667520787.764 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:07.771Z,1667520787.771 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-11-04T00:13:07.771Z,1667520787.771 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:07.772Z,1667520787.772 [DataOverHttps](INFO): Join timeout helper Thread ID is 5657 2022-11-04T00:13:08.548Z,1667520788.548 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:08.548Z,1667520788.548 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.568Z,1667520788.568 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2022-11-04T00:13:08.568Z,1667520788.568 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.569Z,1667520788.569 [BackseatComponent](INFO): Join timeout helper Thread ID is 5658 2022-11-04T00:13:08.584Z,1667520788.584 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:08.584Z,1667520788.584 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.596Z,1667520788.596 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-11-04T00:13:08.596Z,1667520788.596 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.597Z,1667520788.597 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5659 2022-11-04T00:13:08.824Z,1667520788.824 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:08.824Z,1667520788.824 [WetLabsBB2FL](INFO): Powering down 2022-11-04T00:13:08.825Z,1667520788.825 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.828Z,1667520788.828 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-11-04T00:13:08.828Z,1667520788.828 [logger ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.829Z,1667520788.829 [logger](INFO): Join timeout helper Thread ID is 5660 2022-11-04T00:13:08.848Z,1667520788.848 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:08.848Z,1667520788.848 [logger ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.868Z,1667520788.868 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-11-04T00:13:08.868Z,1667520788.868 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.869Z,1667520788.869 [CommandLine](INFO): Join timeout helper Thread ID is 5661 2022-11-04T00:13:08.936Z,1667520788.936 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:08.936Z,1667520788.936 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.948Z,1667520788.948 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-11-04T00:13:08.948Z,1667520788.948 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.949Z,1667520788.949 [CommandExec](INFO): Join timeout helper Thread ID is 5662 2022-11-04T00:13:08.950Z,1667520788.950 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-11-04T00:13:08.950Z,1667520788.950 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:08.950Z,1667520788.950 [controlThread](INFO): Join timeout helper Thread ID is 5663 2022-11-04T00:13:09.175Z,1667520789.175 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-04T00:13:09.176Z,1667520789.176 [controlThread](DEBUG): Uninitializing ControlThread 2022-11-04T00:13:09.176Z,1667520789.176 [AHRS_M2](INFO): Powering down 2022-11-04T00:13:09.488Z,1667520789.488 [MultiRay](INFO): Powering down. 2022-11-04T00:13:09.488Z,1667520789.488 [NAL9602](INFO): Powering down 2022-11-04T00:13:09.490Z,1667520789.490 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-11-04T00:13:09.491Z,1667520789.491 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-11-04T00:13:09.492Z,1667520789.492 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-11-04T00:13:09.492Z,1667520789.492 [MissionManager](INFO): Uninitializing Mission Default 2022-11-04T00:13:09.492Z,1667520789.492 [Default] Stopped 2022-11-04T00:13:09.493Z,1667520789.493 [Default](DEBUG): Aggregate::uninitialize Default 2022-11-04T00:13:09.493Z,1667520789.493 [Default:B.GoToSurface] Stopped 2022-11-04T00:13:09.493Z,1667520789.493 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-04T00:13:09.493Z,1667520789.493 [Default:CheckIn] Stopped 2022-11-04T00:13:09.493Z,1667520789.493 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-11-04T00:13:09.493Z,1667520789.493 [Default:CheckIn:Read_GPS] Stopped 2022-11-04T00:13:09.496Z,1667520789.496 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-11-04T00:13:09.496Z,1667520789.496 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-11-04T00:13:09.496Z,1667520789.496 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-11-04T00:13:09.497Z,1667520789.497 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-11-04T00:13:09.497Z,1667520789.497 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-11-04T00:13:09.497Z,1667520789.497 [BuoyancyServo](INFO): Powering down 2022-11-04T00:13:09.512Z,1667520789.512 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-11-04T00:13:09.512Z,1667520789.512 [ElevatorServo](INFO): Powering down 2022-11-04T00:13:09.513Z,1667520789.513 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-11-04T00:13:09.513Z,1667520789.513 [MassServo](INFO): Powering down 2022-11-04T00:13:09.513Z,1667520789.513 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-11-04T00:13:09.514Z,1667520789.514 [RudderServo](INFO): Powering down 2022-11-04T00:13:09.514Z,1667520789.514 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2022-11-04T00:13:09.514Z,1667520789.514 [ThrusterHE](INFO): Powering down 2022-11-04T00:13:09.515Z,1667520789.515 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-11-04T00:13:09.516Z,1667520789.516 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-11-04T00:13:09.516Z,1667520789.516 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-11-04T00:13:09.516Z,1667520789.516 [CBIT](DEBUG): Powering off loads. 2022-11-04T00:13:09.527Z,1667520789.527 [CBIT](DEBUG): Disabling WDT. 2022-11-04T00:13:09.539Z,1667520789.539 [CBIT](DEBUG): Opening all GF detection circuits. 2022-11-04T00:13:09.540Z,1667520789.540 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:09.570Z,1667520789.570 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:09.616Z,1667520789.616 [DAT ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:09.620Z,1667520789.620 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:09.623Z,1667520789.623 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:09.629Z,1667520789.629 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:09.673Z,1667520789.673 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:09.714Z,1667520789.714 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-11-04T00:13:09.772Z,1667520789.772 [logger ThreadHandler](INFO): Thread cancelled.