2019-08-06T13:56:56.734Z,1565099816.734 [Supervisor](DEBUG): Initializing supervisor.
2019-08-06T13:56:56.737Z,1565099816.737 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-08-06T13:56:56.737Z,1565099816.737 [SyncHandler](INFO): Protected caller Thread ID is 5018
2019-08-06T13:56:56.737Z,1565099816.737 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-08-06T13:56:56.738Z,1565099816.738 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-08-06T13:56:56.739Z,1565099816.739 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5019
2019-08-06T13:56:56.741Z,1565099816.741 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-08-06T13:56:56.753Z,1565099816.753 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-08-06T13:56:56.754Z,1565099816.754 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-08-06T13:56:56.754Z,1565099816.754 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5020
2019-08-06T13:56:56.755Z,1565099816.755 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-08-06T13:56:56.756Z,1565099816.756 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-08-06T13:56:56.756Z,1565099816.756 [logger ThreadHandler](INFO): Protected caller Thread ID is 5021
2019-08-06T13:56:56.758Z,1565099816.758 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-08-06T13:56:56.758Z,1565099816.758 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-08-06T13:56:56.760Z,1565099816.760 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-08-06T13:56:56.959Z,1565099816.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-08-06T13:56:56.960Z,1565099816.960 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-08-06T13:56:57.041Z,1565099817.041 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-08-06T13:56:57.469Z,1565099817.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-08-06T13:56:57.469Z,1565099817.469 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-08-06T13:56:57.801Z,1565099817.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-08-06T13:56:57.802Z,1565099817.802 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-08-06T13:56:57.899Z,1565099817.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-08-06T13:56:57.900Z,1565099817.900 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-08-06T13:56:58.197Z,1565099818.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-08-06T13:56:58.197Z,1565099818.197 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-08-06T13:56:58.392Z,1565099818.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-08-06T13:56:58.392Z,1565099818.392 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-08-06T13:56:58.839Z,1565099818.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-08-06T13:56:58.840Z,1565099818.840 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-08-06T13:56:58.945Z,1565099818.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-08-06T13:56:58.946Z,1565099818.946 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-08-06T13:56:59.048Z,1565099819.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-08-06T13:56:59.048Z,1565099819.048 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-08-06T13:56:59.712Z,1565099819.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-08-06T13:56:59.713Z,1565099819.713 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-08-06T13:57:00.107Z,1565099820.107 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-08-06T13:57:00.108Z,1565099820.108 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-08-06T13:57:00.301Z,1565099820.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-08-06T13:57:00.302Z,1565099820.302 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-08-06T13:57:00.448Z,1565099820.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-08-06T13:57:00.448Z,1565099820.448 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-08-06T13:57:00.597Z,1565099820.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-08-06T13:57:00.600Z,1565099820.600 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-08-06T13:57:00.600Z,1565099820.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-08-06T13:57:00.692Z,1565099820.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-08-06T13:57:00.780Z,1565099820.780 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-08-06T13:57:00.884Z,1565099820.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-08-06T13:57:00.969Z,1565099820.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-08-06T13:57:01.066Z,1565099821.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-08-06T13:57:01.168Z,1565099821.168 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-08-06T13:57:01.377Z,1565099821.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-08-06T13:57:01.456Z,1565099821.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-08-06T13:57:01.624Z,1565099821.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-08-06T13:57:01.773Z,1565099821.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-08-06T13:57:02.084Z,1565099822.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-08-06T13:57:02.743Z,1565099822.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-08-06T13:57:02.827Z,1565099822.827 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-08-06T13:57:02.827Z,1565099822.827 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-08-06T13:57:02.848Z,1565099822.848 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-08-06T13:57:02.913Z,1565099822.913 [VerticalControl](DEBUG): Construct VerticalControl.
2019-08-06T13:57:03.020Z,1565099823.020 [VerticalControl] Loaded
2019-08-06T13:57:03.020Z,1565099823.020 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-08-06T13:57:03.021Z,1565099823.021 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-08-06T13:57:03.087Z,1565099823.087 [HorizontalControl] Loaded
2019-08-06T13:57:03.088Z,1565099823.088 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-08-06T13:57:03.088Z,1565099823.088 [SpeedControl](DEBUG): Construct SpeedControl.
2019-08-06T13:57:03.094Z,1565099823.094 [SpeedControl] Loaded
2019-08-06T13:57:03.094Z,1565099823.094 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-08-06T13:57:03.095Z,1565099823.095 [LoopControl](DEBUG): Construct LoopControl.
2019-08-06T13:57:03.095Z,1565099823.095 [LoopControl] Loaded
2019-08-06T13:57:03.095Z,1565099823.095 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-08-06T13:57:03.096Z,1565099823.096 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-08-06T13:57:03.096Z,1565099823.096 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-08-06T13:57:03.109Z,1565099823.109 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-08-06T13:57:03.110Z,1565099823.110 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-08-06T13:57:03.204Z,1565099823.204 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-08-06T13:57:03.204Z,1565099823.204 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-08-06T13:57:03.302Z,1565099823.302 [BuoyancyServo] Loaded
2019-08-06T13:57:03.302Z,1565099823.302 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-08-06T13:57:03.313Z,1565099823.313 [ElevatorServo] Loaded
2019-08-06T13:57:03.313Z,1565099823.313 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-08-06T13:57:03.324Z,1565099823.324 [MassServo] Loaded
2019-08-06T13:57:03.324Z,1565099823.324 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-08-06T13:57:03.335Z,1565099823.335 [RudderServo] Loaded
2019-08-06T13:57:03.335Z,1565099823.335 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-08-06T13:57:03.346Z,1565099823.346 [ThrusterServo] Loaded
2019-08-06T13:57:03.346Z,1565099823.346 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-08-06T13:57:03.346Z,1565099823.346 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-08-06T13:57:03.347Z,1565099823.347 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-08-06T13:57:03.450Z,1565099823.450 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-08-06T13:57:03.451Z,1565099823.451 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-08-06T13:57:03.471Z,1565099823.471 [NavChart] Loaded
2019-08-06T13:57:03.471Z,1565099823.471 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-08-06T13:57:03.475Z,1565099823.475 [UniversalFixResidualReporter] Loaded
2019-08-06T13:57:03.475Z,1565099823.475 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-08-06T13:57:03.476Z,1565099823.476 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-08-06T13:57:03.476Z,1565099823.476 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-08-06T13:57:03.544Z,1565099823.544 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-08-06T13:57:03.544Z,1565099823.544 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-08-06T13:57:03.861Z,1565099823.861 [DataOverHttps] Loaded
2019-08-06T13:57:03.861Z,1565099823.861 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-08-06T13:57:03.862Z,1565099823.862 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0
2019-08-06T13:57:03.862Z,1565099823.862 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5104
2019-08-06T13:57:03.875Z,1565099823.875 [Depth_Keller] Loaded
2019-08-06T13:57:03.876Z,1565099823.876 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-08-06T13:57:03.969Z,1565099823.969 [NAL9602] Loaded
2019-08-06T13:57:03.969Z,1565099823.969 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-08-06T13:57:03.985Z,1565099823.985 [Onboard] Loaded
2019-08-06T13:57:03.985Z,1565099823.985 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-08-06T13:57:03.988Z,1565099823.988 [Radio_Surface] Loaded
2019-08-06T13:57:03.989Z,1565099823.989 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-08-06T13:57:03.990Z,1565099823.990 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0
2019-08-06T13:57:03.990Z,1565099823.990 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5105
2019-08-06T13:57:05.434Z,1565099825.434 [BPC1] Loaded
2019-08-06T13:57:05.434Z,1565099825.434 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-08-06T13:57:05.434Z,1565099825.434 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-08-06T13:57:05.435Z,1565099825.435 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-08-06T13:57:05.474Z,1565099825.474 [DepthRateCalculator] Loaded
2019-08-06T13:57:05.474Z,1565099825.474 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-08-06T13:57:05.480Z,1565099825.480 [PitchRateCalculator] Loaded
2019-08-06T13:57:05.480Z,1565099825.480 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-08-06T13:57:05.491Z,1565099825.491 [SpeedCalculator] Loaded
2019-08-06T13:57:05.492Z,1565099825.492 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-08-06T13:57:05.512Z,1565099825.512 [TempGradientCalculator] Loaded
2019-08-06T13:57:05.513Z,1565099825.513 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-08-06T13:57:05.518Z,1565099825.518 [YawRateCalculator] Loaded
2019-08-06T13:57:05.518Z,1565099825.518 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-08-06T13:57:05.547Z,1565099825.547 [ElevatorOffsetCalculator] Loaded
2019-08-06T13:57:05.547Z,1565099825.547 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-08-06T13:57:05.547Z,1565099825.547 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-08-06T13:57:05.548Z,1565099825.548 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-08-06T13:57:05.733Z,1565099825.733 [ESPComponent] Loaded
2019-08-06T13:57:05.733Z,1565099825.733 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-08-06T13:57:05.748Z,1565099825.748 [PAR_Licor] Loaded
2019-08-06T13:57:05.748Z,1565099825.748 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-08-06T13:57:05.749Z,1565099825.749 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-08-06T13:57:05.749Z,1565099825.749 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-08-06T13:57:05.780Z,1565099825.780 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-08-06T13:57:05.781Z,1565099825.781 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-08-06T13:57:06.056Z,1565099826.056 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-08-06T13:57:06.056Z,1565099826.056 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-08-06T13:57:06.182Z,1565099826.182 [SBIT](DEBUG): Construct Startup Built In Test.
2019-08-06T13:57:06.193Z,1565099826.193 [SBIT] Loaded
2019-08-06T13:57:06.194Z,1565099826.194 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-08-06T13:57:06.194Z,1565099826.194 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-08-06T13:57:06.206Z,1565099826.206 [IBIT] Loaded
2019-08-06T13:57:06.206Z,1565099826.206 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-08-06T13:57:06.209Z,1565099826.209 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-08-06T13:57:06.342Z,1565099826.342 [CBIT] Loaded
2019-08-06T13:57:06.342Z,1565099826.342 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-08-06T13:57:06.342Z,1565099826.342 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-08-06T13:57:06.346Z,1565099826.346 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-08-06T13:57:06.347Z,1565099826.347 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-08-06T13:57:06.353Z,1565099826.353 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-08-06T13:57:06.354Z,1565099826.354 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2019-08-06T13:57:06.355Z,1565099826.355 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5106
2019-08-06T13:57:06.359Z,1565099826.359 [Supervisor](INFO): Main Thread ID is 5017
2019-08-06T13:57:06.359Z,1565099826.359 [Supervisor](DEBUG): Running supervisor.
2019-08-06T13:57:06.360Z,1565099826.360 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5107
2019-08-06T13:57:06.362Z,1565099826.362 [controlThread ThreadHandler](INFO): Handler Thread ID is 5108
2019-08-06T13:57:06.363Z,1565099826.363 [controlThread](DEBUG): Initializing ControlThread
2019-08-06T13:57:06.364Z,1565099826.364 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-08-06T13:57:06.365Z,1565099826.365 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-08-06T13:57:06.366Z,1565099826.366 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-08-06T13:57:06.366Z,1565099826.366 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-08-06T13:57:06.368Z,1565099826.368 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-08-06T13:57:06.369Z,1565099826.369 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-08-06T13:57:06.372Z,1565099826.372 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-08-06T13:57:06.373Z,1565099826.373 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-08-06T13:57:06.373Z,1565099826.373 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-08-06T13:57:06.373Z,1565099826.373 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-08-06T13:57:06.374Z,1565099826.374 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-08-06T13:57:06.374Z,1565099826.374 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-08-06T13:57:06.379Z,1565099826.379 [SBIT](INFO): Initialize SBIT Component.
2019-08-06T13:57:06.380Z,1565099826.380 [SBIT](IMPORTANT): git: 2019-07-26-1-g6ad825a
2019-08-06T13:57:06.380Z,1565099826.380 [SBIT](INFO): git hash: 6ad825a153e96bf2505e39e5df4d606652139ef6
2019-08-06T13:57:06.380Z,1565099826.380 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-08-06T13:57:06.381Z,1565099826.381 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-08-06T13:57:06.382Z,1565099826.382 [SBIT](INFO): Beginning SBIT in 29.000000 seconds.
2019-08-06T13:57:06.383Z,1565099826.383 [IBIT](INFO): Initialize IBIT Component.
2019-08-06T13:57:06.384Z,1565099826.384 [CBIT](DEBUG): Initialize CBIT Component.
2019-08-06T13:57:06.385Z,1565099826.385 [logger ThreadHandler](INFO): Handler Thread ID is 5109
2019-08-06T13:57:06.395Z,1565099826.395 [CBIT](DEBUG): Initialized mux pins.
2019-08-06T13:57:06.395Z,1565099826.395 [CBIT](DEBUG): Initializing the watchdog timer.
2019-08-06T13:57:06.403Z,1565099826.403 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5110
2019-08-06T13:57:06.404Z,1565099826.404 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-08-06T13:57:06.415Z,1565099826.415 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5111
2019-08-06T13:57:06.419Z,1565099826.419 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-08-06T13:57:06.419Z,1565099826.419 [CBIT](DEBUG): Initializing heartbeat.
2019-08-06T13:57:06.427Z,1565099826.427 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5112
2019-08-06T13:57:06.430Z,1565099826.430 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-08-06T13:57:06.431Z,1565099826.431 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-08-06T13:57:06.431Z,1565099826.431 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-08-06T13:57:06.431Z,1565099826.431 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-08-06T13:57:06.431Z,1565099826.431 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-08-06T13:57:06.431Z,1565099826.431 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-08-06T13:57:06.432Z,1565099826.432 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-08-06T13:57:06.432Z,1565099826.432 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-08-06T13:57:06.432Z,1565099826.432 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-08-06T13:57:06.432Z,1565099826.432 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-08-06T13:57:06.432Z,1565099826.432 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-08-06T13:57:06.433Z,1565099826.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-08-06T13:57:06.433Z,1565099826.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-08-06T13:57:06.433Z,1565099826.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-08-06T13:57:06.433Z,1565099826.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-08-06T13:57:06.433Z,1565099826.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-08-06T13:57:06.491Z,1565099826.491 [CBIT](DEBUG): Deactivating GF circuits.
2019-08-06T13:57:06.491Z,1565099826.491 [CBIT](DEBUG): Deactivating emergency mode.
2019-08-06T13:57:06.527Z,1565099826.527 [CBIT](DEBUG): Backplane powered.
2019-08-06T13:57:06.528Z,1565099826.528 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-08-06T13:57:06.538Z,1565099826.538 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-06T13:57:06.549Z,1565099826.549 [MissionManager](DEBUG):
2019-08-06T13:57:06.550Z,1565099826.550 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-08-06T13:57:06.621Z,1565099826.621 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-08-06T13:57:06.622Z,1565099826.622 [Default:A.Wait](DEBUG): Construct Wait.
2019-08-06T13:57:06.640Z,1565099826.640 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-06T13:57:06.663Z,1565099826.663 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-08-06T13:57:06.665Z,1565099826.665 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-08-06T13:57:06.687Z,1565099826.687 [Default:E.Execute](DEBUG): Construct Execute.
2019-08-06T13:57:06.690Z,1565099826.690 [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
2019-08-06T13:57:06.707Z,1565099826.707 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-08-06T13:57:06.713Z,1565099826.713 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-08-06T13:57:06.819Z,1565099826.819 [Radio_Surface](INFO): Powering up
2019-08-06T13:57:06.896Z,1565099826.896 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-08-06T13:57:06.954Z,1565099826.954 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-08-06T13:57:06.987Z,1565099826.987 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-08-06T13:57:06.988Z,1565099826.988 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-08-06T13:57:07.006Z,1565099827.006 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-08-06T13:57:07.007Z,1565099827.007 [MassServo](DEBUG): Initializing EZServoServo.
2019-08-06T13:57:07.066Z,1565099827.066 [MassServo](DEBUG): Initializing MassServo.
2019-08-06T13:57:07.066Z,1565099827.066 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-06T13:57:07.075Z,1565099827.075 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-06T13:57:07.076Z,1565099827.076 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-08-06T13:57:07.080Z,1565099827.080 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-08-06T13:57:07.895Z,1565099827.895 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-08-06T13:57:07.895Z,1565099827.895 [RudderServo](FAULT): Rudder failed to initialize
2019-08-06T13:57:07.895Z,1565099827.895 [RudderServo] Communications Fault, FailCount= 1
2019-08-06T13:57:07.895Z,1565099827.895 [RudderServo](ERROR): Communications Fault
2019-08-06T13:57:08.066Z,1565099828.066 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-08-06T13:57:08.248Z,1565099828.248 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-06T13:57:08.248Z,1565099828.248 [RudderServo](INFO): Powering down
2019-08-06T13:57:08.937Z,1565099828.937 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-06T13:57:09.056Z,1565099829.056 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-06T13:57:09.060Z,1565099829.060 [CBIT](INFO): Clearing failed state for component RudderServo
2019-08-06T13:57:09.060Z,1565099829.060 [RudderServo] No Fault, FailCount= 1
2019-08-06T13:57:18.901Z,1565099838.901 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004733
2019-08-06T13:57:33.980Z,1565099853.980 [NAL9602](INFO): Powering up NAL9602
2019-08-06T13:57:36.082Z,1565099856.082 [SBIT](IMPORTANT): Beginning Startup BIT
2019-08-06T13:57:36.098Z,1565099856.098 [CBIT](IMPORTANT): Beginning ground fault scan
2019-08-06T13:57:40.372Z,1565099860.372 [CommandLine](IMPORTANT): got command report mod Onboard.Humidity
2019-08-06T13:57:40.716Z,1565099860.716 [Reporter](INFO): Onboard.Humidity 6.934440 %
2019-08-06T13:57:41.057Z,1565099861.057 [Reporter](INFO): Onboard.Humidity 6.910023 %
2019-08-06T13:57:44.093Z,1565099864.093 [Reporter](INFO): Onboard.Humidity 6.934440 %
2019-08-06T13:57:44.804Z,1565099864.804 [NAL9602](INFO): NAL9602 initialized
2019-08-06T13:57:44.941Z,1565099864.941 [Reporter](INFO): Onboard.Humidity 6.910023 %
2019-08-06T13:57:45.619Z,1565099865.619 [NAL9602](DEBUG): Fix Requested
2019-08-06T13:57:45.788Z,1565099865.788 [Reporter](INFO): Onboard.Humidity 6.934440 %
2019-08-06T13:57:47.411Z,1565099867.411 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.010205
CHAN A1 (24V): -0.198405
CHAN A2 (12V): -0.002238
CHAN A3 (5V): -0.001038
CHAN B0 (3.3V): 0.000300
CHAN B1 (3.15aV): 0.000030
CHAN B2 (3.15bV): -0.000189
CHAN B3 (GND): 0.000377
OPEN: -0.000374
Full Scale Calc: 4.765 mA, -1.589 mA
2019-08-06T13:57:47.412Z,1565099867.412 [Reporter](INFO): Onboard.Humidity 6.910023 %
2019-08-06T13:57:55.669Z,1565099875.669 [CommandLine](IMPORTANT): got command report clear
2019-08-06T13:58:24.873Z,1565099904.873 [CommandLine](IMPORTANT): got command report mod Onboard.Pressure
2019-08-06T13:58:25.117Z,1565099905.117 [Reporter](INFO): Onboard.Pressure 62112.406250 Pa
2019-08-06T13:58:25.524Z,1565099905.524 [Reporter](INFO): Onboard.Pressure 61574.921875 Pa
2019-08-06T13:58:25.913Z,1565099905.913 [Reporter](INFO): Onboard.Pressure 61716.585938 Pa
2019-08-06T13:58:26.301Z,1565099906.301 [Reporter](INFO): Onboard.Pressure 61603.867188 Pa
2019-08-06T13:58:26.709Z,1565099906.709 [Reporter](INFO): Onboard.Pressure 61433.246094 Pa
2019-08-06T13:58:27.109Z,1565099907.109 [Reporter](INFO): Onboard.Pressure 61687.593750 Pa
2019-08-06T13:58:27.604Z,1565099907.604 [Reporter](INFO): Onboard.Pressure 62029.066406 Pa
2019-08-06T13:58:27.889Z,1565099907.889 [Reporter](INFO): Onboard.Pressure 61545.988281 Pa
2019-08-06T13:58:28.306Z,1565099908.306 [Reporter](INFO): Onboard.Pressure 61574.921875 Pa
2019-08-06T13:58:28.500Z,1565099908.500 [CommandLine](IMPORTANT): got command quit
2019-08-06T13:58:29.094Z,1565099909.094 [Reporter](INFO): Onboard.Pressure 61716.585938 Pa
2019-08-06T13:58:29.503Z,1565099909.503 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-08-06T13:58:29.503Z,1565099909.503 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T13:58:29.504Z,1565099909.504 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:29.512Z,1565099909.512 [Reporter](INFO): Onboard.Pressure 61603.867188 Pa
2019-08-06T13:58:29.699Z,1565099909.699 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-08-06T13:58:29.699Z,1565099909.699 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:29.700Z,1565099909.700 [CommandLine](INFO): Join timeout helper Thread ID is 5127
2019-08-06T13:58:29.700Z,1565099909.700 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-08-06T13:58:29.700Z,1565099909.700 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:29.701Z,1565099909.701 [NavChartDb](INFO): Join timeout helper Thread ID is 5128
2019-08-06T13:58:29.909Z,1565099909.909 [SBIT](IMPORTANT): SBIT PASSED
2019-08-06T13:58:29.931Z,1565099909.931 [Reporter](INFO): Onboard.Pressure 61716.585938 Pa
2019-08-06T13:58:29.959Z,1565099909.959 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T13:58:29.959Z,1565099909.959 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:29.979Z,1565099909.979 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-08-06T13:58:29.979Z,1565099909.979 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:29.979Z,1565099909.979 [Radio_Surface](INFO): Join timeout helper Thread ID is 5129
2019-08-06T13:58:30.043Z,1565099910.043 [Radio_Surface](INFO): Powering down
2019-08-06T13:58:30.044Z,1565099910.044 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T13:58:30.045Z,1565099910.045 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.059Z,1565099910.059 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-08-06T13:58:30.059Z,1565099910.059 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.060Z,1565099910.060 [DataOverHttps](INFO): Join timeout helper Thread ID is 5130
2019-08-06T13:58:30.167Z,1565099910.167 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T13:58:30.167Z,1565099910.167 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.187Z,1565099910.187 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-08-06T13:58:30.187Z,1565099910.187 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.187Z,1565099910.187 [logger](INFO): Join timeout helper Thread ID is 5131
2019-08-06T13:58:30.259Z,1565099910.259 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T13:58:30.259Z,1565099910.259 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.268Z,1565099910.268 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-08-06T13:58:30.268Z,1565099910.268 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.268Z,1565099910.268 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-08-06T13:58:30.268Z,1565099910.268 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.269Z,1565099910.269 [controlThread](INFO): Join timeout helper Thread ID is 5132
2019-08-06T13:58:30.312Z,1565099910.312 [MissionManager](IMPORTANT): Started mission Startup
2019-08-06T13:58:30.312Z,1565099910.312 [Startup] Running Loop=1
2019-08-06T13:58:30.313Z,1565099910.313 [Startup](DEBUG): Aggregate::initialize Startup
2019-08-06T13:58:30.313Z,1565099910.313 [Startup:A.GoToSurface] Running Loop=1
2019-08-06T13:58:30.313Z,1565099910.313 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-06T13:58:30.313Z,1565099910.313 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-06T13:58:30.314Z,1565099910.314 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-06T13:58:30.314Z,1565099910.314 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-06T13:58:30.314Z,1565099910.314 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-06T13:58:30.315Z,1565099910.315 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-06T13:58:30.316Z,1565099910.316 [Startup:StartupSatComms] Running Loop=1
2019-08-06T13:58:30.316Z,1565099910.316 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-08-06T13:58:30.316Z,1565099910.316 [Startup:StartupSatComms:A] Running Loop=1
2019-08-06T13:58:30.326Z,1565099910.326 [Reporter](INFO): Onboard.Pressure 61745.593750 Pa
2019-08-06T13:58:30.326Z,1565099910.326 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T13:58:30.327Z,1565099910.327 [controlThread](DEBUG): Uninitializing ControlThread
2019-08-06T13:58:30.327Z,1565099910.327 [NAL9602](INFO): Powering down
2019-08-06T13:58:30.349Z,1565099910.349 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-08-06T13:58:30.349Z,1565099910.349 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-08-06T13:58:30.350Z,1565099910.350 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-08-06T13:58:30.350Z,1565099910.350 [MissionManager](INFO): Uninitializing Mission Default
2019-08-06T13:58:30.353Z,1565099910.353 [MissionManager](INFO): Uninitializing Mission Startup
2019-08-06T13:58:30.353Z,1565099910.353 [Startup] Stopped
2019-08-06T13:58:30.353Z,1565099910.353 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-08-06T13:58:30.353Z,1565099910.353 [Startup:A.GoToSurface] Stopped
2019-08-06T13:58:30.353Z,1565099910.353 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-06T13:58:30.353Z,1565099910.353 [Startup:StartupSatComms] Stopped
2019-08-06T13:58:30.353Z,1565099910.353 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-08-06T13:58:30.353Z,1565099910.353 [Startup:StartupSatComms:A] Stopped
2019-08-06T13:58:30.354Z,1565099910.354 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-08-06T13:58:30.355Z,1565099910.355 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-08-06T13:58:30.355Z,1565099910.355 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-08-06T13:58:30.355Z,1565099910.355 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-08-06T13:58:30.356Z,1565099910.356 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-08-06T13:58:30.356Z,1565099910.356 [BuoyancyServo](INFO): Powering down
2019-08-06T13:58:30.367Z,1565099910.367 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-08-06T13:58:30.367Z,1565099910.367 [ElevatorServo](INFO): Powering down
2019-08-06T13:58:30.368Z,1565099910.368 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-08-06T13:58:30.368Z,1565099910.368 [MassServo](INFO): Powering down
2019-08-06T13:58:30.369Z,1565099910.369 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-06T13:58:30.369Z,1565099910.369 [RudderServo](INFO): Powering down
2019-08-06T13:58:30.370Z,1565099910.370 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-08-06T13:58:30.370Z,1565099910.370 [ThrusterServo](INFO): Powering down
2019-08-06T13:58:30.370Z,1565099910.370 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-08-06T13:58:30.371Z,1565099910.371 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-08-06T13:58:30.371Z,1565099910.371 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-08-06T13:58:30.371Z,1565099910.371 [CBIT](DEBUG): Powering off loads.
2019-08-06T13:58:30.383Z,1565099910.383 [CBIT](DEBUG): Disabling WDT.
2019-08-06T13:58:30.395Z,1565099910.395 [CBIT](DEBUG): Opening all GF detection circuits.
2019-08-06T13:58:30.396Z,1565099910.396 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.477Z,1565099910.477 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.483Z,1565099910.483 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.542Z,1565099910.542 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-06T13:58:30.596Z,1565099910.596 [logger ThreadHandler](INFO): Thread cancelled.