2021-08-19T17:24:36.452Z,1629393876.452 [Supervisor](DEBUG): Initializing supervisor. 2021-08-19T17:24:36.456Z,1629393876.456 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-08-19T17:24:36.457Z,1629393876.457 [SyncHandler](INFO): Protected caller Thread ID is 9364 2021-08-19T17:24:36.457Z,1629393876.457 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-08-19T17:24:36.458Z,1629393876.458 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-08-19T17:24:36.459Z,1629393876.459 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9365 2021-08-19T17:24:36.463Z,1629393876.463 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-08-19T17:24:36.482Z,1629393876.482 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-08-19T17:24:36.483Z,1629393876.483 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-08-19T17:24:36.484Z,1629393876.484 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9366 2021-08-19T17:24:36.486Z,1629393876.486 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-08-19T17:24:36.487Z,1629393876.487 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-08-19T17:24:36.487Z,1629393876.487 [logger ThreadHandler](INFO): Protected caller Thread ID is 9367 2021-08-19T17:24:36.491Z,1629393876.491 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-08-19T17:24:36.491Z,1629393876.491 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-08-19T17:24:36.493Z,1629393876.493 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-08-19T17:24:36.920Z,1629393876.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-08-19T17:24:36.921Z,1629393876.921 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-08-19T17:24:37.137Z,1629393877.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-08-19T17:24:37.137Z,1629393877.137 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-08-19T17:24:37.332Z,1629393877.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-08-19T17:24:37.332Z,1629393877.332 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-08-19T17:24:37.442Z,1629393877.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-08-19T17:24:37.443Z,1629393877.443 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-08-19T17:24:37.812Z,1629393877.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-08-19T17:24:37.813Z,1629393877.813 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-08-19T17:24:38.148Z,1629393878.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-08-19T17:24:38.149Z,1629393878.149 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-08-19T17:24:38.247Z,1629393878.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-08-19T17:24:38.248Z,1629393878.248 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-08-19T17:24:38.400Z,1629393878.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-08-19T17:24:38.401Z,1629393878.401 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-08-19T17:24:38.935Z,1629393878.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-08-19T17:24:38.935Z,1629393878.935 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-08-19T17:24:39.302Z,1629393879.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-08-19T17:24:39.302Z,1629393879.302 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-08-19T17:24:39.386Z,1629393879.386 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-08-19T17:24:39.594Z,1629393879.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-08-19T17:24:39.595Z,1629393879.595 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-08-19T17:24:39.723Z,1629393879.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-08-19T17:24:39.724Z,1629393879.724 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-08-19T17:24:40.389Z,1629393880.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-08-19T17:24:40.390Z,1629393880.390 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-08-19T17:24:40.472Z,1629393880.472 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-08-19T17:24:40.775Z,1629393880.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-08-19T17:24:40.777Z,1629393880.777 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2021-08-19T17:24:40.777Z,1629393880.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2021-08-19T17:24:41.146Z,1629393881.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2021-08-19T17:24:41.392Z,1629393881.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2021-08-19T17:24:41.595Z,1629393881.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2021-08-19T17:24:41.746Z,1629393881.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2021-08-19T17:24:41.833Z,1629393881.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2021-08-19T17:24:41.921Z,1629393881.921 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2021-08-19T17:24:42.036Z,1629393882.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2021-08-19T17:24:42.138Z,1629393882.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2021-08-19T17:24:42.283Z,1629393882.283 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2021-08-19T17:24:42.545Z,1629393882.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-08-19T17:24:42.545Z,1629393882.545 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2021-08-19T17:24:42.643Z,1629393882.643 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2021-08-19T17:24:42.643Z,1629393882.643 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2021-08-19T17:24:42.655Z,1629393882.655 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-08-19T17:24:42.982Z,1629393882.982 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-08-19T17:24:42.982Z,1629393882.982 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-08-19T17:24:43.064Z,1629393883.064 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-08-19T17:24:43.064Z,1629393883.064 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-08-19T17:24:43.099Z,1629393883.099 [DeadReckonUsingSpeedCalculator] Loaded 2021-08-19T17:24:43.099Z,1629393883.099 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2021-08-19T17:24:43.112Z,1629393883.112 [NavChart] Loaded 2021-08-19T17:24:43.112Z,1629393883.112 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-08-19T17:24:43.118Z,1629393883.118 [UniversalFixResidualReporter] Loaded 2021-08-19T17:24:43.118Z,1629393883.118 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-08-19T17:24:43.118Z,1629393883.118 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-08-19T17:24:43.119Z,1629393883.119 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-08-19T17:24:43.130Z,1629393883.130 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-08-19T17:24:43.131Z,1629393883.131 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-08-19T17:24:43.303Z,1629393883.303 [ESPComponent] Loaded 2021-08-19T17:24:43.303Z,1629393883.303 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2021-08-19T17:24:43.323Z,1629393883.323 [PAR_Licor] Loaded 2021-08-19T17:24:43.323Z,1629393883.323 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-08-19T17:24:43.324Z,1629393883.324 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-08-19T17:24:43.325Z,1629393883.325 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-08-19T17:24:43.377Z,1629393883.377 [VerticalControl](DEBUG): Construct VerticalControl. 2021-08-19T17:24:43.437Z,1629393883.437 [VerticalControl] Loaded 2021-08-19T17:24:43.437Z,1629393883.437 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-08-19T17:24:43.440Z,1629393883.440 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-08-19T17:24:43.479Z,1629393883.479 [HorizontalControl] Loaded 2021-08-19T17:24:43.479Z,1629393883.479 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-08-19T17:24:43.482Z,1629393883.482 [SpeedControl](DEBUG): Construct SpeedControl. 2021-08-19T17:24:43.485Z,1629393883.485 [SpeedControl] Loaded 2021-08-19T17:24:43.485Z,1629393883.485 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-08-19T17:24:43.488Z,1629393883.488 [LoopControl](DEBUG): Construct LoopControl. 2021-08-19T17:24:43.488Z,1629393883.488 [LoopControl] Loaded 2021-08-19T17:24:43.488Z,1629393883.488 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-08-19T17:24:43.489Z,1629393883.489 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-08-19T17:24:43.489Z,1629393883.489 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-08-19T17:24:43.532Z,1629393883.532 [DepthRateCalculator] Loaded 2021-08-19T17:24:43.532Z,1629393883.532 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-08-19T17:24:43.536Z,1629393883.536 [PitchRateCalculator] Loaded 2021-08-19T17:24:43.537Z,1629393883.537 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-08-19T17:24:43.546Z,1629393883.546 [SpeedCalculator] Loaded 2021-08-19T17:24:43.546Z,1629393883.546 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-08-19T17:24:43.560Z,1629393883.560 [TempGradientCalculator] Loaded 2021-08-19T17:24:43.560Z,1629393883.560 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-08-19T17:24:43.564Z,1629393883.564 [YawRateCalculator] Loaded 2021-08-19T17:24:43.565Z,1629393883.565 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-08-19T17:24:43.582Z,1629393883.582 [ElevatorOffsetCalculator] Loaded 2021-08-19T17:24:43.582Z,1629393883.582 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-08-19T17:24:43.582Z,1629393883.582 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-08-19T17:24:43.583Z,1629393883.583 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-08-19T17:24:43.679Z,1629393883.679 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-08-19T17:24:43.679Z,1629393883.679 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-08-19T17:24:43.738Z,1629393883.738 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-08-19T17:24:43.738Z,1629393883.738 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-08-19T17:24:43.860Z,1629393883.860 [BuoyancyServo] Loaded 2021-08-19T17:24:43.860Z,1629393883.860 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-08-19T17:24:43.879Z,1629393883.879 [ElevatorServo] Loaded 2021-08-19T17:24:43.879Z,1629393883.879 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-08-19T17:24:43.898Z,1629393883.898 [MassServo] Loaded 2021-08-19T17:24:43.898Z,1629393883.898 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-08-19T17:24:43.916Z,1629393883.916 [RudderServo] Loaded 2021-08-19T17:24:43.917Z,1629393883.917 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-08-19T17:24:43.934Z,1629393883.934 [ThrusterServo] Loaded 2021-08-19T17:24:43.934Z,1629393883.934 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-08-19T17:24:43.934Z,1629393883.934 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-08-19T17:24:43.935Z,1629393883.935 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-08-19T17:24:44.041Z,1629393884.041 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-08-19T17:24:44.041Z,1629393884.041 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-08-19T17:24:45.170Z,1629393885.170 [BPC1] Loaded 2021-08-19T17:24:45.170Z,1629393885.170 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-08-19T17:24:45.242Z,1629393885.242 [DataOverHttps] Loaded 2021-08-19T17:24:45.243Z,1629393885.243 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-08-19T17:24:45.244Z,1629393885.244 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409B34E0 2021-08-19T17:24:45.244Z,1629393885.244 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9449 2021-08-19T17:24:45.264Z,1629393885.264 [Depth_Keller] Loaded 2021-08-19T17:24:45.265Z,1629393885.265 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-08-19T17:24:45.325Z,1629393885.325 [NAL9602] Loaded 2021-08-19T17:24:45.325Z,1629393885.325 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-08-19T17:24:45.352Z,1629393885.352 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2021-08-19T17:24:45.353Z,1629393885.353 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2021-08-19T17:24:45.353Z,1629393885.353 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2021-08-19T17:24:45.354Z,1629393885.354 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2021-08-19T17:24:45.355Z,1629393885.355 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2021-08-19T17:24:45.355Z,1629393885.355 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2021-08-19T17:24:45.355Z,1629393885.355 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2021-08-19T17:24:45.356Z,1629393885.356 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2021-08-19T17:24:45.356Z,1629393885.356 [Onboard] Loaded 2021-08-19T17:24:45.356Z,1629393885.356 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-08-19T17:24:45.357Z,1629393885.357 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409E34E0 2021-08-19T17:24:45.358Z,1629393885.358 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9450 2021-08-19T17:24:45.370Z,1629393885.370 [Power24vConverter] Loaded 2021-08-19T17:24:45.371Z,1629393885.371 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-08-19T17:24:45.383Z,1629393885.383 [Radio_Surface] Loaded 2021-08-19T17:24:45.384Z,1629393885.384 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-08-19T17:24:45.385Z,1629393885.385 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A134E0 2021-08-19T17:24:45.385Z,1629393885.385 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9451 2021-08-19T17:24:45.470Z,1629393885.470 [DAT] Loaded 2021-08-19T17:24:45.470Z,1629393885.470 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-08-19T17:24:45.470Z,1629393885.470 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-08-19T17:24:45.471Z,1629393885.471 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-08-19T17:24:45.619Z,1629393885.619 [SBIT](DEBUG): Construct Startup Built In Test. 2021-08-19T17:24:45.628Z,1629393885.628 [SBIT] Loaded 2021-08-19T17:24:45.629Z,1629393885.629 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-08-19T17:24:45.631Z,1629393885.631 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-08-19T17:24:45.645Z,1629393885.645 [IBIT] Loaded 2021-08-19T17:24:45.645Z,1629393885.645 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-08-19T17:24:45.651Z,1629393885.651 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-08-19T17:24:45.751Z,1629393885.751 [CBIT] Loaded 2021-08-19T17:24:45.752Z,1629393885.752 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-08-19T17:24:45.752Z,1629393885.752 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-08-19T17:24:45.753Z,1629393885.753 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-08-19T17:24:45.811Z,1629393885.811 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-08-19T17:24:45.817Z,1629393885.817 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-08-19T17:24:45.819Z,1629393885.819 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-08-19T17:24:45.853Z,1629393885.853 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-08-19T17:24:45.854Z,1629393885.854 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9E4E0 2021-08-19T17:24:45.854Z,1629393885.854 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9452 2021-08-19T17:24:45.859Z,1629393885.859 [Supervisor](INFO): Main Thread ID is 9237 2021-08-19T17:24:45.859Z,1629393885.859 [Supervisor](DEBUG): Running supervisor. 2021-08-19T17:24:45.860Z,1629393885.860 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9453 2021-08-19T17:24:45.880Z,1629393885.880 [controlThread ThreadHandler](INFO): Handler Thread ID is 9454 2021-08-19T17:24:45.888Z,1629393885.888 [controlThread](DEBUG): Initializing ControlThread 2021-08-19T17:24:45.890Z,1629393885.890 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-08-19T17:24:45.890Z,1629393885.890 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-08-19T17:24:45.891Z,1629393885.891 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-08-19T17:24:45.893Z,1629393885.893 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-08-19T17:24:45.894Z,1629393885.894 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-08-19T17:24:45.895Z,1629393885.895 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-08-19T17:24:45.896Z,1629393885.896 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-08-19T17:24:45.896Z,1629393885.896 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-08-19T17:24:45.897Z,1629393885.897 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-08-19T17:24:45.897Z,1629393885.897 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-08-19T17:24:45.897Z,1629393885.897 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-08-19T17:24:45.898Z,1629393885.898 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-08-19T17:24:45.898Z,1629393885.898 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-08-19T17:24:45.901Z,1629393885.901 [SBIT](INFO): Initialize SBIT Component. 2021-08-19T17:24:45.902Z,1629393885.902 [SBIT](IMPORTANT): git: 2021-06-24-10-g1c68dac98 2021-08-19T17:24:45.902Z,1629393885.902 [SBIT](INFO): git hash: 1c68dac98e3954a039edc16fe17de653428a8fd0 2021-08-19T17:24:45.902Z,1629393885.902 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-08-19T17:24:45.904Z,1629393885.904 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2021-08-19T17:24:45.905Z,1629393885.905 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2021-08-19T17:24:45.906Z,1629393885.906 [IBIT](INFO): Initialize IBIT Component. 2021-08-19T17:24:45.906Z,1629393885.906 [CBIT](DEBUG): Initialize CBIT Component. 2021-08-19T17:24:45.913Z,1629393885.913 [logger ThreadHandler](INFO): Handler Thread ID is 9455 2021-08-19T17:24:45.928Z,1629393885.928 [CBIT](DEBUG): Initialized mux pins. 2021-08-19T17:24:45.929Z,1629393885.929 [CBIT](DEBUG): Initializing the watchdog timer. 2021-08-19T17:24:45.937Z,1629393885.937 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9456 2021-08-19T17:24:45.938Z,1629393885.938 [Onboard ThreadHandler](INFO): Handler Thread ID is 9457 2021-08-19T17:24:45.939Z,1629393885.939 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9458 2021-08-19T17:24:45.941Z,1629393885.941 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-08-19T17:24:45.953Z,1629393885.953 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-08-19T17:24:45.958Z,1629393885.958 [CBIT](DEBUG): Initializing heartbeat. 2021-08-19T17:24:45.992Z,1629393885.992 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9459 2021-08-19T17:24:45.996Z,1629393885.996 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-08-19T17:24:45.996Z,1629393885.996 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-08-19T17:24:45.996Z,1629393885.996 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-08-19T17:24:45.996Z,1629393885.996 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-08-19T17:24:45.997Z,1629393885.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-08-19T17:24:45.997Z,1629393885.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-08-19T17:24:45.997Z,1629393885.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-08-19T17:24:45.997Z,1629393885.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-08-19T17:24:45.997Z,1629393885.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-08-19T17:24:45.997Z,1629393885.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-08-19T17:24:45.998Z,1629393885.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-08-19T17:24:45.998Z,1629393885.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-08-19T17:24:45.998Z,1629393885.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-08-19T17:24:45.998Z,1629393885.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-08-19T17:24:45.998Z,1629393885.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-08-19T17:24:45.998Z,1629393885.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-08-19T17:24:46.028Z,1629393886.028 [CBIT](DEBUG): Deactivating GF circuits. 2021-08-19T17:24:46.028Z,1629393886.028 [CBIT](DEBUG): Deactivating emergency mode. 2021-08-19T17:24:46.064Z,1629393886.064 [CBIT](DEBUG): Backplane powered. 2021-08-19T17:24:46.065Z,1629393886.065 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2021-08-19T17:24:46.078Z,1629393886.078 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-08-19T17:24:46.091Z,1629393886.091 [MissionManager](DEBUG): 2021-08-19T17:24:46.092Z,1629393886.092 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2021-08-19T17:24:46.170Z,1629393886.170 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-08-19T17:24:46.172Z,1629393886.172 [Default:A.Wait](DEBUG): Construct Wait. 2021-08-19T17:24:46.174Z,1629393886.174 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-08-19T17:24:46.218Z,1629393886.218 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-08-19T17:24:46.221Z,1629393886.221 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-08-19T17:24:46.243Z,1629393886.243 [Default:E.Execute](DEBUG): Construct Execute. 2021-08-19T17:24:46.246Z,1629393886.246 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2021-08-19T17:24:46.265Z,1629393886.265 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,BPC1,Depth_Keller,NAL9602,Power24vConverter,DAT,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2021-08-19T17:24:46.321Z,1629393886.321 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar 2021-08-19T17:24:46.322Z,1629393886.322 [Power24vConverter](INFO): Powering up. 2021-08-19T17:24:46.323Z,1629393886.323 [DAT](INFO): Powering up 2021-08-19T17:24:46.323Z,1629393886.323 [DAT](DEBUG): Initializing DAT. 2021-08-19T17:24:46.340Z,1629393886.340 [Radio_Surface](INFO): Powering up 2021-08-19T17:24:46.353Z,1629393886.353 [DepthRateCalculator](ERROR): Depth measurement is not active 2021-08-19T17:24:46.389Z,1629393886.389 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-08-19T17:24:46.401Z,1629393886.401 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-08-19T17:24:46.402Z,1629393886.402 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-08-19T17:24:46.409Z,1629393886.409 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-08-19T17:24:46.410Z,1629393886.410 [MassServo](DEBUG): Initializing EZServoServo. 2021-08-19T17:24:46.417Z,1629393886.417 [MassServo](DEBUG): Initializing MassServo. 2021-08-19T17:24:46.418Z,1629393886.418 [RudderServo](DEBUG): Initializing EZServoServo. 2021-08-19T17:24:46.433Z,1629393886.433 [RudderServo](DEBUG): Initializing RudderServo. 2021-08-19T17:24:46.434Z,1629393886.434 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-08-19T17:24:46.441Z,1629393886.441 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-08-19T17:25:06.519Z,1629393906.519 [SBIT](IMPORTANT): Beginning Startup BIT 2021-08-19T17:25:06.523Z,1629393906.523 [CBIT](IMPORTANT): Beginning ground fault scan 2021-08-19T17:25:15.366Z,1629393915.366 [NAL9602](INFO): Powering up NAL9602 2021-08-19T17:25:17.457Z,1629393917.457 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.878235 CHAN A1 (24V): 0.100033 CHAN A2 (12V): 0.920585 CHAN A3 (5V): 0.937883 CHAN B0 (3.3V): 0.728575 CHAN B1 (3.15aV): 0.968509 CHAN B2 (3.15bV): 0.968518 CHAN B3 (GND): 0.014051 OPEN: 0.027274 Full Scale Calc: 4.765 mA, -1.589 mA 2021-08-19T17:25:26.274Z,1629393926.274 [NAL9602](INFO): NAL9602 initialized 2021-08-19T17:25:46.471Z,1629393946.471 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-08-19T17:25:46.471Z,1629393946.471 [DAT] Communications Fault, FailCount= 1 2021-08-19T17:25:46.471Z,1629393946.471 [DAT](ERROR): Communications Fault 2021-08-19T17:25:46.487Z,1629393946.487 [CBIT](ERROR): Communications Fault in component: DAT 2021-08-19T17:25:46.906Z,1629393946.906 [DAT](INFO): Powering down 2021-08-19T17:25:47.713Z,1629393947.713 [CBIT](INFO): Clearing failed state for component DAT 2021-08-19T17:25:47.713Z,1629393947.713 [DAT] No Fault, FailCount= 1 2021-08-19T17:25:50.125Z,1629393950.125 [DAT](INFO): Powering up 2021-08-19T17:25:50.125Z,1629393950.125 [DAT](DEBUG): Initializing DAT. 2021-08-19T17:26:00.239Z,1629393960.239 [SBIT](IMPORTANT): SBIT PASSED 2021-08-19T17:26:00.239Z,1629393960.239 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2021-08-19T17:26:00.240Z,1629393960.240 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2021-08-19T17:26:00.240Z,1629393960.240 [SBIT](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2021-08-19T17:26:00.240Z,1629393960.240 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2021-08-19T17:26:00.241Z,1629393960.241 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2021-08-19T17:26:00.241Z,1629393960.241 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2021-08-19T17:26:00.241Z,1629393960.241 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2021-08-19T17:26:00.241Z,1629393960.241 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour; 2021-08-19T17:26:00.241Z,1629393960.241 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt; 2021-08-19T17:26:00.241Z,1629393960.241 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool; 2021-08-19T17:26:00.241Z,1629393960.241 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2021-08-19T17:26:00.242Z,1629393960.242 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=235 cubic_centimeter; 2021-08-19T17:26:00.242Z,1629393960.242 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter; 2021-08-19T17:26:00.242Z,1629393960.242 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool; 2021-08-19T17:26:00.654Z,1629393960.654 [MissionManager](IMPORTANT): Started mission Startup 2021-08-19T17:26:00.654Z,1629393960.654 [Startup] Running Loop=1 2021-08-19T17:26:00.654Z,1629393960.654 [Startup](DEBUG): Aggregate::initialize Startup 2021-08-19T17:26:00.654Z,1629393960.654 [Startup:A.GoToSurface] Running Loop=1 2021-08-19T17:26:00.655Z,1629393960.655 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-08-19T17:26:00.655Z,1629393960.655 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-08-19T17:26:00.656Z,1629393960.656 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-08-19T17:26:00.656Z,1629393960.656 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-08-19T17:26:00.657Z,1629393960.657 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-08-19T17:26:00.657Z,1629393960.657 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-08-19T17:26:00.658Z,1629393960.658 [Startup:StartupSatComms] Running Loop=1 2021-08-19T17:26:00.659Z,1629393960.659 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-08-19T17:26:00.659Z,1629393960.659 [Startup:StartupSatComms:A] Running Loop=1 2021-08-19T17:26:01.053Z,1629393961.053 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-08-19T17:26:46.273Z,1629394006.273 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:26:46.274Z,1629394006.274 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-08-19T17:26:46.274Z,1629394006.274 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-08-19T17:26:46.275Z,1629394006.275 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:26:46.275Z,1629394006.275 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2021-08-19T17:26:46.275Z,1629394006.275 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-08-19T17:26:46.335Z,1629394006.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-08-19T17:26:46.335Z,1629394006.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-08-19T17:26:46.683Z,1629394006.683 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-08-19T17:26:46.684Z,1629394006.684 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-08-19T17:26:46.684Z,1629394006.684 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-08-19T17:26:46.684Z,1629394006.684 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2021-08-19T17:26:47.080Z,1629394007.080 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-08-19T17:26:50.309Z,1629394010.309 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-08-19T17:26:50.309Z,1629394010.309 [DAT] Communications Fault, FailCount= 2 2021-08-19T17:26:50.309Z,1629394010.309 [DAT](ERROR): Communications Fault 2021-08-19T17:26:50.326Z,1629394010.326 [CBIT](ERROR): Communications Fault in component: DAT 2021-08-19T17:26:50.714Z,1629394010.714 [DAT](INFO): Powering down 2021-08-19T17:26:51.537Z,1629394011.537 [CBIT](INFO): Clearing failed state for component DAT 2021-08-19T17:26:51.537Z,1629394011.537 [DAT] No Fault, FailCount= 2 2021-08-19T17:26:53.961Z,1629394013.961 [DAT](INFO): Powering up 2021-08-19T17:26:53.961Z,1629394013.961 [DAT](DEBUG): Initializing DAT. 2021-08-19T17:26:55.933Z,1629394015.933 [CommandLine](IMPORTANT): got command show stack 2021-08-19T17:26:55.933Z,1629394015.933 [CommandLine](IMPORTANT): Behavior Stack: 2021-08-19T17:26:55.934Z,1629394015.934 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface 2021-08-19T17:26:55.934Z,1629394015.934 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A 2021-08-19T17:27:00.824Z,1629394020.824 [Startup:StartupSatComms:A](INFO): Timed out from 2021-08-19T17:26:00.7Z 2021-08-19T17:27:00.824Z,1629394020.824 [Startup:StartupSatComms:A] Stopped 2021-08-19T17:27:00.824Z,1629394020.824 [Startup:StartupSatComms:B] Running Loop=1 2021-08-19T17:27:01.217Z,1629394021.217 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-08-19T17:27:13.675Z,1629394033.675 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004983 2021-08-19T17:27:22.245Z,1629394042.245 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20210803T210340/Express0001.lzma 2021-08-19T17:27:23.246Z,1629394043.246 [DataOverHttps](INFO): Moved sent file to Logs/20210803T210340/Express0001.lzma.bak 2021-08-19T17:27:23.246Z,1629394043.246 [DataOverHttps](INFO): SBD MOMSN=15895746 2021-08-19T17:27:39.686Z,1629394059.686 [DataOverHttps](INFO): Sending 776 bytes from file Logs/20210816T173051/Express0001.lzma 2021-08-19T17:27:40.686Z,1629394060.686 [DataOverHttps](INFO): Moved sent file to Logs/20210816T173051/Express0001.lzma.bak 2021-08-19T17:27:40.686Z,1629394060.686 [DataOverHttps](INFO): SBD MOMSN=15895755 2021-08-19T17:27:51.398Z,1629394071.398 [CommandLine](IMPORTANT): got command configSet PAR_Licor.adcCal 0.000009 microampere_per_count persist 2021-08-19T17:27:54.138Z,1629394074.138 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-08-19T17:27:54.138Z,1629394074.138 [DAT] Communications Fault, FailCount= 3 2021-08-19T17:27:54.139Z,1629394074.139 [DAT](ERROR): Communications Fault 2021-08-19T17:27:54.154Z,1629394074.154 [CBIT](ERROR): Communications Fault in component: DAT 2021-08-19T17:27:54.570Z,1629394074.570 [DAT](INFO): Powering down 2021-08-19T17:27:55.411Z,1629394075.411 [CBIT](INFO): Clearing failed state for component DAT 2021-08-19T17:27:55.411Z,1629394075.411 [DAT] No Fault, FailCount= 3 2021-08-19T17:27:57.781Z,1629394077.781 [DAT](INFO): Powering up 2021-08-19T17:27:57.781Z,1629394077.781 [DAT](DEBUG): Initializing DAT. 2021-08-19T17:27:58.582Z,1629394078.582 [DataOverHttps](INFO): Sending 1523 bytes from file Logs/20210819T172014/Express0001.lzma 2021-08-19T17:27:59.582Z,1629394079.582 [DataOverHttps](INFO): Moved sent file to Logs/20210819T172014/Express0001.lzma.bak 2021-08-19T17:27:59.582Z,1629394079.582 [DataOverHttps](INFO): SBD MOMSN=15895764 2021-08-19T17:28:01.026Z,1629394081.026 [Startup:StartupSatComms:B](INFO): Timed out from 2021-08-19T17:27:00.8Z 2021-08-19T17:28:01.026Z,1629394081.026 [Startup:StartupSatComms:B] Stopped 2021-08-19T17:28:01.026Z,1629394081.026 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-08-19T17:28:01.027Z,1629394081.027 [Startup:StartupSatComms] Stopped 2021-08-19T17:28:01.027Z,1629394081.027 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-08-19T17:28:01.028Z,1629394081.028 [Startup](INFO): Completed Startup 2021-08-19T17:28:01.028Z,1629394081.028 [MissionManager](INFO): Startup is completed. 2021-08-19T17:28:01.028Z,1629394081.028 [MissionManager](INFO): Uninitializing Mission Startup 2021-08-19T17:28:01.028Z,1629394081.028 [Startup] Stopped 2021-08-19T17:28:01.028Z,1629394081.028 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-08-19T17:28:01.028Z,1629394081.028 [Startup:A.GoToSurface] Stopped 2021-08-19T17:28:01.028Z,1629394081.028 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-08-19T17:28:01.450Z,1629394081.450 [MissionManager](IMPORTANT): Started mission Default 2021-08-19T17:28:01.450Z,1629394081.450 [Default] Running Loop=1 2021-08-19T17:28:01.450Z,1629394081.450 [Default](DEBUG): Aggregate::initialize Default 2021-08-19T17:28:01.450Z,1629394081.450 [Default:B.GoToSurface] Running Loop=1 2021-08-19T17:28:01.450Z,1629394081.450 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-08-19T17:28:01.451Z,1629394081.451 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-08-19T17:28:01.451Z,1629394081.451 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-08-19T17:28:01.451Z,1629394081.451 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-08-19T17:28:01.452Z,1629394081.452 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-08-19T17:28:01.452Z,1629394081.452 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-08-19T17:28:01.452Z,1629394081.452 [Default:A.Wait] Running Loop=1 2021-08-19T17:28:01.452Z,1629394081.452 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-08-19T17:28:13.918Z,1629394093.918 [CommandLine](IMPORTANT): got command configSet PAR_Licor.darkCount 4.000000 count persist 2021-08-19T17:28:14.757Z,1629394094.757 [Default:A.Wait](INFO): Done Waiting. 2021-08-19T17:28:14.757Z,1629394094.757 [Default:A.Wait] Stopped 2021-08-19T17:28:14.757Z,1629394094.757 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-08-19T17:28:15.159Z,1629394095.159 [Default:CheckIn] Running Loop=1 2021-08-19T17:28:15.159Z,1629394095.159 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-19T17:28:15.160Z,1629394095.160 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-19T17:28:15.563Z,1629394095.563 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-08-19T17:28:32.822Z,1629394112.822 [CommandLine](IMPORTANT): got command configSet PAR_Licor.multiplier 269.339996 micromole_per_second_per_square_meter_per_microampere persist 2021-08-19T17:28:43.525Z,1629394123.525 [DataOverHttps](INFO): Sending 744 bytes from file Logs/20210819T172014/Express0005.lzma 2021-08-19T17:28:44.526Z,1629394124.526 [DataOverHttps](INFO): Moved sent file to Logs/20210819T172014/Express0005.lzma.bak 2021-08-19T17:28:44.526Z,1629394124.526 [DataOverHttps](INFO): SBD MOMSN=15895866 2021-08-19T17:28:47.473Z,1629394127.473 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:28:47.473Z,1629394127.473 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-08-19T17:28:47.473Z,1629394127.473 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-08-19T17:28:47.475Z,1629394127.475 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:28:47.475Z,1629394127.475 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2021-08-19T17:28:47.475Z,1629394127.475 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-08-19T17:28:47.486Z,1629394127.486 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-08-19T17:28:47.486Z,1629394127.486 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-08-19T17:28:47.889Z,1629394127.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-08-19T17:28:47.889Z,1629394127.889 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-08-19T17:28:47.890Z,1629394127.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-08-19T17:28:47.890Z,1629394127.890 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2021-08-19T17:28:48.291Z,1629394128.291 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-08-19T17:28:52.533Z,1629394132.533 [CommandLine](IMPORTANT): got command configSet PAR_Licor.parCal 1.221000 none persist 2021-08-19T17:28:57.976Z,1629394137.976 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-08-19T17:28:57.977Z,1629394137.977 [DAT] Communications Fault, FailCount= 4 2021-08-19T17:28:57.977Z,1629394137.977 [DAT](ERROR): Communications Fault 2021-08-19T17:28:57.992Z,1629394137.992 [CBIT](ERROR): Communications Fault in component: DAT 2021-08-19T17:28:58.385Z,1629394138.385 [DAT](INFO): Powering down 2021-08-19T17:28:59.229Z,1629394139.229 [CBIT](INFO): Clearing failed state for component DAT 2021-08-19T17:28:59.229Z,1629394139.229 [DAT] No Fault, FailCount= 4 2021-08-19T17:29:01.614Z,1629394141.614 [DAT](INFO): Powering up 2021-08-19T17:29:01.614Z,1629394141.614 [DAT](DEBUG): Initializing DAT. 2021-08-19T17:29:02.646Z,1629394142.646 [CommandLine](IMPORTANT): got command failComponent hardware PAR_Licor 2021-08-19T17:29:02.646Z,1629394142.646 [PAR_Licor] Hardware Fault, FailCount= 1 2021-08-19T17:29:02.646Z,1629394142.646 [PAR_Licor](ERROR): Hardware Fault 2021-08-19T17:29:02.647Z,1629394142.647 [CommandLine](IMPORTANT): PAR_Licor failureMode is Hardware Fault 2021-08-19T17:29:02.835Z,1629394142.835 [CBIT](ERROR): Hardware Fault in component: PAR_Licor 2021-08-19T17:29:02.835Z,1629394142.835 [CBIT](INFO): Clearing failed state for component PAR_Licor 2021-08-19T17:29:02.835Z,1629394142.835 [PAR_Licor] No Fault, FailCount= 1 2021-08-19T17:29:16.405Z,1629394156.405 [CommandLine](IMPORTANT): got command show variable PAR 2021-08-19T17:29:16.416Z,1629394156.416 [CommandLine](IMPORTANT): PAR_Licor.loadControl (none) 2021-08-19T17:29:16.417Z,1629394156.417 [CommandLine](IMPORTANT): PAR_Licor.ad (none) 2021-08-19T17:29:16.417Z,1629394156.417 [CommandLine](IMPORTANT): PAR_Licor.adRes (bit) 2021-08-19T17:29:16.418Z,1629394156.418 [CommandLine](IMPORTANT): PAR_Licor.adTimeout (millisecond) 2021-08-19T17:29:16.418Z,1629394156.418 [CommandLine](IMPORTANT): PAR_Licor.adVref (volt) 2021-08-19T17:29:16.494Z,1629394156.494 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup (bool) 2021-08-19T17:29:16.495Z,1629394156.495 [CommandLine](IMPORTANT): PAR_Licor.simulateHardware (bool) 2021-08-19T17:29:16.495Z,1629394156.495 [CommandLine](IMPORTANT): PAR_Licor.adcCal (microampere_per_count) 2021-08-19T17:29:16.496Z,1629394156.496 [CommandLine](IMPORTANT): PAR_Licor.darkCount (count) 2021-08-19T17:29:16.496Z,1629394156.496 [CommandLine](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter) 2021-08-19T17:29:16.513Z,1629394156.513 [CommandLine](IMPORTANT): PAR_Licor.maxValidPitch (degree) 2021-08-19T17:29:16.513Z,1629394156.513 [CommandLine](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter) 2021-08-19T17:29:16.513Z,1629394156.513 [CommandLine](IMPORTANT): PAR_Licor.minValidPitch (degree) 2021-08-19T17:29:16.514Z,1629394156.514 [CommandLine](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere) 2021-08-19T17:29:16.514Z,1629394156.514 [CommandLine](IMPORTANT): PAR_Licor.parCal (none) 2021-08-19T17:29:16.515Z,1629394156.515 [CommandLine](IMPORTANT): PAR_Licor.serial (none) 2021-08-19T17:29:16.586Z,1629394156.586 [CommandLine](IMPORTANT): PAR_Licor.enableBroadcast (bool) 2021-08-19T17:29:16.586Z,1629394156.586 [CommandLine](IMPORTANT): PAR_Licor.component_voltage (volt) 2021-08-19T17:29:16.586Z,1629394156.586 [CommandLine](IMPORTANT): PAR_Licor.component_avgVoltage (volt) 2021-08-19T17:29:16.587Z,1629394156.587 [CommandLine](IMPORTANT): PAR_Licor.component_current (milliampere) 2021-08-19T17:29:16.587Z,1629394156.587 [CommandLine](IMPORTANT): PAR_Licor.component_avgCurrent (milliampere) 2021-08-19T17:29:16.588Z,1629394156.588 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter) 2021-08-19T17:29:16.588Z,1629394156.588 [CommandLine](IMPORTANT): PAR_Licor.adcCount (count) 2021-08-19T17:29:34.630Z,1629394174.630 [CommandLine](IMPORTANT): got command report touch PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2021-08-19T17:29:34.758Z,1629394174.758 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.321321 umol/s/m2 2021-08-19T17:29:35.163Z,1629394175.163 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.430860 umol/s/m2 2021-08-19T17:29:35.571Z,1629394175.571 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.626878 umol/s/m2 2021-08-19T17:29:35.994Z,1629394175.994 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.773892 umol/s/m2 2021-08-19T17:29:36.398Z,1629394176.398 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.854606 umol/s/m2 2021-08-19T17:29:36.789Z,1629394176.789 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.848840 umol/s/m2 2021-08-19T17:29:37.193Z,1629394177.193 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.909376 umol/s/m2 2021-08-19T17:29:37.595Z,1629394177.595 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.981441 umol/s/m2 2021-08-19T17:29:37.988Z,1629394177.988 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.978558 umol/s/m2 2021-08-19T17:29:38.393Z,1629394178.393 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 5.964146 umol/s/m2 2021-08-19T17:29:38.796Z,1629394178.796 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.093863 umol/s/m2 2021-08-19T17:29:39.209Z,1629394179.209 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.108276 umol/s/m2 2021-08-19T17:29:39.605Z,1629394179.605 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.114042 umol/s/m2 2021-08-19T17:29:40.009Z,1629394180.009 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.240877 umol/s/m2 2021-08-19T17:29:40.414Z,1629394180.414 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.301412 umol/s/m2 2021-08-19T17:29:40.824Z,1629394180.824 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.235112 umol/s/m2 2021-08-19T17:29:41.248Z,1629394181.248 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.174577 umol/s/m2 2021-08-19T17:29:41.654Z,1629394181.654 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.168811 umol/s/m2 2021-08-19T17:29:42.042Z,1629394182.042 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 6.050624 umol/s/m2 2021-08-19T17:29:42.453Z,1629394182.453 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 3.240067 umol/s/m2 2021-08-19T17:29:42.849Z,1629394182.849 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.386811 umol/s/m2 2021-08-19T17:29:43.241Z,1629394183.241 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.594360 umol/s/m2 2021-08-19T17:29:43.646Z,1629394183.646 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.516529 umol/s/m2 2021-08-19T17:29:44.099Z,1629394184.099 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.594360 umol/s/m2 2021-08-19T17:29:44.480Z,1629394184.480 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.542473 umol/s/m2 2021-08-19T17:29:44.877Z,1629394184.877 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.490586 umol/s/m2 2021-08-19T17:29:45.272Z,1629394185.272 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.582829 umol/s/m2 2021-08-19T17:29:45.666Z,1629394185.666 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.502116 umol/s/m2 2021-08-19T17:29:46.079Z,1629394186.079 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.513647 umol/s/m2 2021-08-19T17:29:46.494Z,1629394186.494 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.528060 umol/s/m2 2021-08-19T17:29:46.909Z,1629394186.909 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.519412 umol/s/m2 2021-08-19T17:29:47.300Z,1629394187.300 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.504999 umol/s/m2 2021-08-19T17:29:47.697Z,1629394187.697 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.536708 umol/s/m2 2021-08-19T17:29:48.086Z,1629394188.086 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.496351 umol/s/m2 2021-08-19T17:29:48.487Z,1629394188.487 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.496351 umol/s/m2 2021-08-19T17:29:48.898Z,1629394188.898 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.533825 umol/s/m2 2021-08-19T17:29:49.308Z,1629394189.308 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.421403 umol/s/m2 2021-08-19T17:29:49.718Z,1629394189.718 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.242680 umol/s/m2 2021-08-19T17:29:50.167Z,1629394190.167 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.130258 umol/s/m2 2021-08-19T17:29:50.524Z,1629394190.524 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.205206 umol/s/m2 2021-08-19T17:29:50.936Z,1629394190.936 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.210971 umol/s/m2 2021-08-19T17:29:51.348Z,1629394191.348 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.196558 umol/s/m2 2021-08-19T17:29:51.763Z,1629394191.763 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.404107 umol/s/m2 2021-08-19T17:29:52.173Z,1629394192.173 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.427168 umol/s/m2 2021-08-19T17:29:52.588Z,1629394192.588 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.424285 umol/s/m2 2021-08-19T17:29:52.964Z,1629394192.964 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.392576 umol/s/m2 2021-08-19T17:29:53.360Z,1629394193.360 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.406990 umol/s/m2 2021-08-19T17:29:53.745Z,1629394193.745 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.395459 umol/s/m2 2021-08-19T17:29:54.151Z,1629394194.151 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.418520 umol/s/m2 2021-08-19T17:29:54.562Z,1629394194.562 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.381046 umol/s/m2 2021-08-19T17:29:54.959Z,1629394194.959 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.435816 umol/s/m2 2021-08-19T17:29:55.361Z,1629394195.361 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.441581 umol/s/m2 2021-08-19T17:29:55.766Z,1629394195.766 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.528060 umol/s/m2 2021-08-19T17:29:56.178Z,1629394196.178 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.565534 umol/s/m2 2021-08-19T17:29:56.647Z,1629394196.647 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.518872 umol/s/m2 2021-08-19T17:29:57.033Z,1629394197.033 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1.337537 umol/s/m2 2021-08-19T17:29:57.409Z,1629394197.409 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 266.161163 umol/s/m2 2021-08-19T17:29:57.809Z,1629394197.809 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1502.209473 umol/s/m2 2021-08-19T17:29:58.189Z,1629394198.189 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1600.359863 umol/s/m2 2021-08-19T17:29:58.593Z,1629394198.593 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1601.835815 umol/s/m2 2021-08-19T17:29:58.998Z,1629394198.998 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1603.138550 umol/s/m2 2021-08-19T17:29:59.410Z,1629394199.410 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1599.333618 umol/s/m2 2021-08-19T17:29:59.805Z,1629394199.805 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1600.221313 umol/s/m2 2021-08-19T17:30:00.209Z,1629394200.209 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1599.595825 umol/s/m2 2021-08-19T17:30:00.610Z,1629394200.610 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1599.999390 umol/s/m2 2021-08-19T17:30:01.022Z,1629394201.022 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1600.097534 umol/s/m2 2021-08-19T17:30:01.435Z,1629394201.435 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1600.299316 umol/s/m2 2021-08-19T17:30:01.821Z,1629394201.821 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-08-19T17:30:01.821Z,1629394201.821 [DAT] Communications Fault, FailCount= 5 2021-08-19T17:30:01.821Z,1629394201.821 [DAT](ERROR): Communications Fault 2021-08-19T17:30:01.868Z,1629394201.868 [CBIT](ERROR): Communications Fault in component: DAT 2021-08-19T17:30:01.882Z,1629394201.882 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1600.051392 umol/s/m2 2021-08-19T17:30:02.225Z,1629394202.225 [DAT](INFO): Powering down 2021-08-19T17:30:02.372Z,1629394202.372 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1599.595825 umol/s/m2 2021-08-19T17:30:02.649Z,1629394202.649 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1599.567139 umol/s/m2 2021-08-19T17:30:03.059Z,1629394203.059 [CBIT](INFO): Clearing failed state for component DAT 2021-08-19T17:30:03.060Z,1629394203.060 [DAT] No Fault, FailCount= 5 2021-08-19T17:30:03.061Z,1629394203.061 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1599.985107 umol/s/m2 2021-08-19T17:30:03.442Z,1629394203.442 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1599.408569 umol/s/m2 2021-08-19T17:30:03.844Z,1629394203.844 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1599.650635 umol/s/m2 2021-08-19T17:30:04.251Z,1629394204.251 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1600.080200 umol/s/m2 2021-08-19T17:30:04.661Z,1629394204.661 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1214.739868 umol/s/m2 2021-08-19T17:30:05.056Z,1629394205.056 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 4.254750 umol/s/m2 2021-08-19T17:30:05.445Z,1629394205.445 [DAT](INFO): Powering up 2021-08-19T17:30:05.445Z,1629394205.445 [DAT](DEBUG): Initializing DAT. 2021-08-19T17:30:05.502Z,1629394205.502 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 3.280424 umol/s/m2 2021-08-19T17:30:05.866Z,1629394205.866 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 3.020988 umol/s/m2 2021-08-19T17:30:06.279Z,1629394206.279 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.718313 umol/s/m2 2021-08-19T17:30:06.673Z,1629394206.673 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.770200 umol/s/m2 2021-08-19T17:30:07.109Z,1629394207.109 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.542473 umol/s/m2 2021-08-19T17:30:07.493Z,1629394207.493 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.646247 umol/s/m2 2021-08-19T17:30:07.905Z,1629394207.905 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.464642 umol/s/m2 2021-08-19T17:30:08.309Z,1629394208.309 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.487703 umol/s/m2 2021-08-19T17:30:08.745Z,1629394208.745 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.568416 umol/s/m2 2021-08-19T17:30:09.106Z,1629394209.106 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.597243 umol/s/m2 2021-08-19T17:30:09.515Z,1629394209.515 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.600125 umol/s/m2 2021-08-19T17:30:09.928Z,1629394209.928 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.556886 umol/s/m2 2021-08-19T17:30:10.309Z,1629394210.309 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.591477 umol/s/m2 2021-08-19T17:30:10.711Z,1629394210.711 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.516529 umol/s/m2 2021-08-19T17:30:11.124Z,1629394211.124 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.582829 umol/s/m2 2021-08-19T17:30:11.535Z,1629394211.535 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.519412 umol/s/m2 2021-08-19T17:30:11.925Z,1629394211.925 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.528060 umol/s/m2 2021-08-19T17:30:12.341Z,1629394212.341 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.539590 umol/s/m2 2021-08-19T17:30:12.745Z,1629394212.745 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.516529 umol/s/m2 2021-08-19T17:30:13.153Z,1629394213.153 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.519412 umol/s/m2 2021-08-19T17:30:13.566Z,1629394213.566 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.536708 umol/s/m2 2021-08-19T17:30:13.945Z,1629394213.945 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.519412 umol/s/m2 2021-08-19T17:30:14.349Z,1629394214.349 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.528060 umol/s/m2 2021-08-19T17:30:14.762Z,1629394214.762 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.528060 umol/s/m2 2021-08-19T17:30:15.157Z,1629394215.157 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.542473 umol/s/m2 2021-08-19T17:30:15.560Z,1629394215.560 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.530942 umol/s/m2 2021-08-19T17:30:15.966Z,1629394215.966 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.496351 umol/s/m2 2021-08-19T17:30:16.378Z,1629394216.378 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.530942 umol/s/m2 2021-08-19T17:30:16.773Z,1629394216.773 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.536708 umol/s/m2 2021-08-19T17:30:17.177Z,1629394217.177 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.545355 umol/s/m2 2021-08-19T17:30:17.601Z,1629394217.601 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.530942 umol/s/m2 2021-08-19T17:30:17.997Z,1629394217.997 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.441581 umol/s/m2 2021-08-19T17:30:18.411Z,1629394218.411 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.245563 umol/s/m2 2021-08-19T17:30:18.826Z,1629394218.826 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.173497 umol/s/m2 2021-08-19T17:30:19.198Z,1629394219.198 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.259976 umol/s/m2 2021-08-19T17:30:19.610Z,1629394219.610 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.121610 umol/s/m2 2021-08-19T17:30:20.005Z,1629394220.005 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1.573912 umol/s/m2 2021-08-19T17:30:20.409Z,1629394220.409 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1.421133 umol/s/m2 2021-08-19T17:30:20.815Z,1629394220.815 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1.478785 umol/s/m2 2021-08-19T17:30:21.061Z,1629394221.061 [CommandLine](IMPORTANT): got command stop 2021-08-19T17:30:21.061Z,1629394221.061 [CommandLine](IMPORTANT): Scheduling is paused 2021-08-19T17:30:21.062Z,1629394221.062 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2021-08-19T17:30:21.237Z,1629394221.237 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1.398072 umol/s/m2 2021-08-19T17:30:21.690Z,1629394221.690 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 1.735339 umol/s/m2 2021-08-19T17:30:22.034Z,1629394222.034 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.196558 umol/s/m2 2021-08-19T17:30:22.449Z,1629394222.449 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.242680 umol/s/m2 2021-08-19T17:30:22.864Z,1629394222.864 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.179262 umol/s/m2 2021-08-19T17:30:23.259Z,1629394223.259 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.190793 umol/s/m2 2021-08-19T17:30:23.674Z,1629394223.674 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 2.187910 umol/s/m2 2021-08-19T17:30:23.909Z,1629394223.909 [CommandLine](IMPORTANT): got command report clear 2021-08-19T17:30:29.696Z,1629394229.696 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-08-19T17:30:48.423Z,1629394248.423 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:30:48.424Z,1629394248.424 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-08-19T17:30:48.424Z,1629394248.424 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-08-19T17:30:48.430Z,1629394248.430 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:30:48.430Z,1629394248.430 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2021-08-19T17:30:48.430Z,1629394248.430 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-08-19T17:30:48.446Z,1629394248.446 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-08-19T17:30:48.446Z,1629394248.446 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-08-19T17:30:48.770Z,1629394248.770 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-08-19T17:30:48.770Z,1629394248.770 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-08-19T17:30:48.770Z,1629394248.770 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-08-19T17:30:48.770Z,1629394248.770 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2021-08-19T17:30:49.136Z,1629394249.136 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-08-19T17:31:05.669Z,1629394265.669 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-08-19T17:31:05.669Z,1629394265.669 [DAT] Communications Fault, FailCount= 6 2021-08-19T17:31:05.669Z,1629394265.669 [DAT](ERROR): Communications Fault 2021-08-19T17:31:05.685Z,1629394265.685 [CBIT](ERROR): Communications Fault in component: DAT 2021-08-19T17:31:06.065Z,1629394266.065 [DAT](INFO): Powering down 2021-08-19T17:31:06.883Z,1629394266.883 [CBIT](INFO): Clearing failed state for component DAT 2021-08-19T17:31:06.883Z,1629394266.883 [DAT] No Fault, FailCount= 6 2021-08-19T17:31:09.292Z,1629394269.292 [DAT](INFO): Powering up 2021-08-19T17:31:09.292Z,1629394269.292 [DAT](DEBUG): Initializing DAT. 2021-08-19T17:32:09.518Z,1629394329.518 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-08-19T17:32:09.518Z,1629394329.518 [DAT] Communications Fault, FailCount= 7 2021-08-19T17:32:09.518Z,1629394329.518 [DAT](ERROR): Communications Fault 2021-08-19T17:32:09.533Z,1629394329.533 [CBIT](ERROR): Communications Fault in component: DAT 2021-08-19T17:32:09.905Z,1629394329.905 [DAT](INFO): Powering down 2021-08-19T17:32:10.725Z,1629394330.725 [CBIT](INFO): Clearing failed state for component DAT 2021-08-19T17:32:10.725Z,1629394330.725 [DAT] No Fault, FailCount= 7 2021-08-19T17:32:13.122Z,1629394333.122 [DAT](INFO): Powering up 2021-08-19T17:32:13.123Z,1629394333.123 [DAT](DEBUG): Initializing DAT. 2021-08-19T17:32:49.521Z,1629394369.521 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:32:49.521Z,1629394369.521 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2021-08-19T17:32:49.521Z,1629394369.521 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-08-19T17:32:49.533Z,1629394369.533 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:32:49.533Z,1629394369.533 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2021-08-19T17:32:49.533Z,1629394369.533 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-08-19T17:32:49.544Z,1629394369.544 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-08-19T17:32:49.544Z,1629394369.544 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-08-19T17:32:49.949Z,1629394369.949 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-08-19T17:32:49.949Z,1629394369.949 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2021-08-19T17:32:49.950Z,1629394369.950 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-08-19T17:32:49.950Z,1629394369.950 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2021-08-19T17:32:50.303Z,1629394370.303 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-08-19T17:33:13.337Z,1629394393.337 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-08-19T17:33:13.337Z,1629394393.337 [DAT] Communications Fault, FailCount= 8 2021-08-19T17:33:13.337Z,1629394393.337 [DAT](ERROR): Communications Fault 2021-08-19T17:33:13.359Z,1629394393.359 [CBIT](ERROR): Communications Fault in component: DAT 2021-08-19T17:33:13.359Z,1629394393.359 [CBIT](FAULT): Communications Fault in component: DAT 2021-08-19T17:33:13.737Z,1629394393.737 [DAT](INFO): Powering down 2021-08-19T17:33:15.374Z,1629394395.374 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-08-19T17:28:15.2Z 2021-08-19T17:33:15.374Z,1629394395.374 [Default:CheckIn:Read_GPS] Stopped 2021-08-19T17:33:15.374Z,1629394395.374 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-19T17:33:15.775Z,1629394395.775 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-08-19T17:33:23.890Z,1629394403.890 [DataOverHttps](INFO): Sending 1079 bytes from file Logs/20210819T172436/Express0001.lzma 2021-08-19T17:33:24.890Z,1629394404.890 [DataOverHttps](INFO): Moved sent file to Logs/20210819T172436/Express0001.lzma.bak 2021-08-19T17:33:24.891Z,1629394404.891 [DataOverHttps](INFO): SBD MOMSN=15895930 2021-08-19T17:33:41.506Z,1629394421.506 [DataOverHttps](INFO): Sending 970 bytes from file Logs/20210819T172436/Express0005.lzma 2021-08-19T17:33:42.506Z,1629394422.506 [DataOverHttps](INFO): Moved sent file to Logs/20210819T172436/Express0005.lzma.bak 2021-08-19T17:33:42.506Z,1629394422.506 [DataOverHttps](INFO): SBD MOMSN=15895963 2021-08-19T17:33:45.266Z,1629394425.266 [Default:CheckIn:Read_Iridium] Stopped 2021-08-19T17:33:45.266Z,1629394425.266 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-19T17:33:45.267Z,1629394425.267 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-19T17:34:50.688Z,1629394490.688 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:34:50.688Z,1629394490.688 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2021-08-19T17:34:50.688Z,1629394490.688 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-08-19T17:34:50.690Z,1629394490.690 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:34:50.690Z,1629394490.690 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2021-08-19T17:34:50.690Z,1629394490.690 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-08-19T17:34:50.701Z,1629394490.701 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-08-19T17:34:50.701Z,1629394490.701 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-08-19T17:34:51.104Z,1629394491.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-08-19T17:34:51.104Z,1629394491.104 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2021-08-19T17:34:51.105Z,1629394491.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-08-19T17:34:51.105Z,1629394491.105 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2021-08-19T17:34:51.498Z,1629394491.498 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-08-19T17:35:27.488Z,1629394527.488 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-08-19T17:35:27.488Z,1629394527.488 [NAL9602] Data Fault, FailCount= 1 2021-08-19T17:35:27.488Z,1629394527.488 [NAL9602](ERROR): Data Fault 2021-08-19T17:35:27.504Z,1629394527.504 [CBIT](ERROR): Data Fault in component: NAL9602 2021-08-19T17:35:27.894Z,1629394527.894 [NAL9602](INFO): Powering down 2021-08-19T17:35:28.751Z,1629394528.751 [CBIT](INFO): Clearing failed state for component NAL9602 2021-08-19T17:35:28.751Z,1629394528.751 [NAL9602] No Fault, FailCount= 1 2021-08-19T17:35:47.515Z,1629394547.515 [CommandLine](IMPORTANT): got command failComponent 2021-08-19T17:35:47.515Z,1629394547.515 [CommandLine](IMPORTANT): Failed components: 2021-08-19T17:35:47.516Z,1629394547.516 [CommandLine](IMPORTANT): DAT: Communications Fault 2021-08-19T17:35:48.091Z,1629394548.091 [Power24vConverter](INFO): Powering down. 2021-08-19T17:35:58.191Z,1629394558.191 [NAL9602](INFO): Powering up NAL9602 2021-08-19T17:36:09.157Z,1629394569.157 [NAL9602](INFO): NAL9602 initialized 2021-08-19T17:36:18.115Z,1629394578.115 [CommandLine](IMPORTANT): got command get RDI_Pathfinder.loadAtStartup 2021-08-19T17:36:18.115Z,1629394578.115 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup 0 bool 2021-08-19T17:36:51.538Z,1629394611.538 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:36:51.539Z,1629394611.539 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2021-08-19T17:36:51.539Z,1629394611.539 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-08-19T17:36:51.540Z,1629394611.540 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-08-19T17:36:51.541Z,1629394611.541 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2021-08-19T17:36:51.541Z,1629394611.541 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-08-19T17:36:51.552Z,1629394611.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-08-19T17:36:51.552Z,1629394611.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-08-19T17:36:51.965Z,1629394611.965 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-08-19T17:36:51.966Z,1629394611.966 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2021-08-19T17:36:51.966Z,1629394611.966 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-08-19T17:36:51.966Z,1629394611.966 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2021-08-19T17:36:52.360Z,1629394612.360 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-08-19T17:36:54.450Z,1629394614.450 [CommandLine](IMPORTANT): got command get RDI_Pathfinder.simulateHardware 2021-08-19T17:36:54.451Z,1629394614.451 [CommandLine](IMPORTANT): RDI_Pathfinder.simulateHardware 0 bool 2021-08-19T17:37:25.877Z,1629394645.877 [CommandLine](IMPORTANT): got command configSet RDI_Pathfinder.loadAtStartup 1.000000 bool persist 2021-08-19T17:37:25.877Z,1629394645.877 [CommandLine](IMPORTANT): configSet RDI_Pathfinder.loadAtStartup requires a restart to take effect. 2021-08-19T17:37:25.880Z,1629394645.880 [CommandLine](IMPORTANT): got command restart application 2021-08-19T17:37:26.888Z,1629394646.888 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-19T17:37:26.889Z,1629394646.889 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:26.940Z,1629394646.940 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-08-19T17:37:26.940Z,1629394646.940 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:26.941Z,1629394646.941 [CommandLine](INFO): Join timeout helper Thread ID is 9508 2021-08-19T17:37:26.942Z,1629394646.942 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-08-19T17:37:26.942Z,1629394646.942 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:26.942Z,1629394646.942 [NavChartDb](INFO): Join timeout helper Thread ID is 9509 2021-08-19T17:37:27.205Z,1629394647.205 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-19T17:37:27.205Z,1629394647.205 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:27.208Z,1629394647.208 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-08-19T17:37:27.209Z,1629394647.209 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:27.209Z,1629394647.209 [Radio_Surface](INFO): Join timeout helper Thread ID is 9510 2021-08-19T17:37:27.604Z,1629394647.604 [Radio_Surface](INFO): Powering down 2021-08-19T17:37:27.605Z,1629394647.605 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-19T17:37:27.606Z,1629394647.606 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:27.612Z,1629394647.612 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-08-19T17:37:27.613Z,1629394647.613 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:27.613Z,1629394647.613 [Onboard](INFO): Join timeout helper Thread ID is 9511 2021-08-19T17:37:31.553Z,1629394651.553 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-19T17:37:31.553Z,1629394651.553 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:31.556Z,1629394651.556 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-08-19T17:37:31.556Z,1629394651.556 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:31.557Z,1629394651.557 [DataOverHttps](INFO): Join timeout helper Thread ID is 9512 2021-08-19T17:37:32.276Z,1629394652.276 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-19T17:37:32.279Z,1629394652.279 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.281Z,1629394652.281 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-08-19T17:37:32.281Z,1629394652.281 [logger ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.282Z,1629394652.282 [logger](INFO): Join timeout helper Thread ID is 9513 2021-08-19T17:37:32.308Z,1629394652.308 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-19T17:37:32.309Z,1629394652.309 [logger ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.321Z,1629394652.321 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-08-19T17:37:32.321Z,1629394652.321 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.321Z,1629394652.321 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-08-19T17:37:32.322Z,1629394652.322 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.322Z,1629394652.322 [controlThread](INFO): Join timeout helper Thread ID is 9514 2021-08-19T17:37:32.340Z,1629394652.340 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-19T17:37:32.340Z,1629394652.340 [controlThread](DEBUG): Uninitializing ControlThread 2021-08-19T17:37:32.343Z,1629394652.343 [NAL9602](INFO): Powering down 2021-08-19T17:37:32.345Z,1629394652.345 [DAT](INFO): Powering down 2021-08-19T17:37:32.346Z,1629394652.346 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-08-19T17:37:32.347Z,1629394652.347 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-08-19T17:37:32.347Z,1629394652.347 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-08-19T17:37:32.348Z,1629394652.348 [MissionManager](INFO): Uninitializing Mission Default 2021-08-19T17:37:32.348Z,1629394652.348 [Default] Stopped 2021-08-19T17:37:32.348Z,1629394652.348 [Default](DEBUG): Aggregate::uninitialize Default 2021-08-19T17:37:32.349Z,1629394652.349 [Default:B.GoToSurface] Stopped 2021-08-19T17:37:32.349Z,1629394652.349 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-08-19T17:37:32.349Z,1629394652.349 [Default:CheckIn] Stopped 2021-08-19T17:37:32.349Z,1629394652.349 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-19T17:37:32.349Z,1629394652.349 [Default:CheckIn:C.Wait] Stopped 2021-08-19T17:37:32.349Z,1629394652.349 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-19T17:37:32.352Z,1629394652.352 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-08-19T17:37:32.353Z,1629394652.353 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-08-19T17:37:32.353Z,1629394652.353 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-08-19T17:37:32.353Z,1629394652.353 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-08-19T17:37:32.353Z,1629394652.353 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-08-19T17:37:32.354Z,1629394652.354 [BuoyancyServo](INFO): Powering down 2021-08-19T17:37:32.368Z,1629394652.368 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-08-19T17:37:32.369Z,1629394652.369 [ElevatorServo](INFO): Powering down 2021-08-19T17:37:32.369Z,1629394652.369 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-08-19T17:37:32.370Z,1629394652.370 [MassServo](INFO): Powering down 2021-08-19T17:37:32.370Z,1629394652.370 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-08-19T17:37:32.371Z,1629394652.371 [RudderServo](INFO): Powering down 2021-08-19T17:37:32.371Z,1629394652.371 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-08-19T17:37:32.372Z,1629394652.372 [ThrusterServo](INFO): Powering down 2021-08-19T17:37:32.373Z,1629394652.373 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-08-19T17:37:32.373Z,1629394652.373 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-08-19T17:37:32.373Z,1629394652.373 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-08-19T17:37:32.373Z,1629394652.373 [CBIT](DEBUG): Powering off loads. 2021-08-19T17:37:32.384Z,1629394652.384 [CBIT](DEBUG): Disabling WDT. 2021-08-19T17:37:32.396Z,1629394652.396 [CBIT](DEBUG): Opening all GF detection circuits. 2021-08-19T17:37:32.397Z,1629394652.397 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.474Z,1629394652.474 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.477Z,1629394652.477 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.483Z,1629394652.483 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.560Z,1629394652.560 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-19T17:37:32.620Z,1629394652.620 [logger ThreadHandler](INFO): Thread cancelled.