2019-08-05T19:51:10.179Z,1565034670.179 [Supervisor](DEBUG): Initializing supervisor.
2019-08-05T19:51:10.181Z,1565034670.181 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-08-05T19:51:10.182Z,1565034670.182 [SyncHandler](INFO): Protected caller Thread ID is 6506
2019-08-05T19:51:10.182Z,1565034670.182 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-08-05T19:51:10.183Z,1565034670.183 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-08-05T19:51:10.183Z,1565034670.183 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6507
2019-08-05T19:51:10.186Z,1565034670.186 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-08-05T19:51:10.197Z,1565034670.197 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-08-05T19:51:10.198Z,1565034670.198 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-08-05T19:51:10.199Z,1565034670.199 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6508
2019-08-05T19:51:10.200Z,1565034670.200 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-08-05T19:51:10.200Z,1565034670.200 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-08-05T19:51:10.201Z,1565034670.201 [logger ThreadHandler](INFO): Protected caller Thread ID is 6509
2019-08-05T19:51:10.203Z,1565034670.203 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-08-05T19:51:10.203Z,1565034670.203 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-08-05T19:51:10.205Z,1565034670.205 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-08-05T19:51:10.404Z,1565034670.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-08-05T19:51:10.405Z,1565034670.405 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-08-05T19:51:10.485Z,1565034670.485 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-08-05T19:51:10.914Z,1565034670.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-08-05T19:51:10.915Z,1565034670.915 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-08-05T19:51:11.247Z,1565034671.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-08-05T19:51:11.248Z,1565034671.248 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-08-05T19:51:11.344Z,1565034671.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-08-05T19:51:11.345Z,1565034671.345 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-08-05T19:51:11.641Z,1565034671.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-08-05T19:51:11.642Z,1565034671.642 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-08-05T19:51:11.836Z,1565034671.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-08-05T19:51:11.837Z,1565034671.837 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-08-05T19:51:12.285Z,1565034672.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-08-05T19:51:12.285Z,1565034672.285 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-08-05T19:51:12.391Z,1565034672.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-08-05T19:51:12.392Z,1565034672.392 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-08-05T19:51:12.492Z,1565034672.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-08-05T19:51:12.493Z,1565034672.493 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-08-05T19:51:13.086Z,1565034673.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-08-05T19:51:13.086Z,1565034673.086 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-08-05T19:51:13.479Z,1565034673.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-08-05T19:51:13.480Z,1565034673.480 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-08-05T19:51:13.673Z,1565034673.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-08-05T19:51:13.673Z,1565034673.673 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-08-05T19:51:13.818Z,1565034673.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-08-05T19:51:13.819Z,1565034673.819 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-08-05T19:51:13.968Z,1565034673.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-08-05T19:51:13.970Z,1565034673.970 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-08-05T19:51:13.971Z,1565034673.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-08-05T19:51:14.063Z,1565034674.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-08-05T19:51:14.151Z,1565034674.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-08-05T19:51:14.255Z,1565034674.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-08-05T19:51:14.339Z,1565034674.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-08-05T19:51:14.436Z,1565034674.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-08-05T19:51:14.538Z,1565034674.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-08-05T19:51:14.822Z,1565034674.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-08-05T19:51:14.947Z,1565034674.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-08-05T19:51:15.274Z,1565034675.274 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-08-05T19:51:15.470Z,1565034675.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-08-05T19:51:15.611Z,1565034675.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-08-05T19:51:16.091Z,1565034676.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-08-05T19:51:16.092Z,1565034676.092 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-08-05T19:51:16.112Z,1565034676.112 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-08-05T19:51:16.176Z,1565034676.176 [VerticalControl](DEBUG): Construct VerticalControl.
2019-08-05T19:51:16.284Z,1565034676.284 [VerticalControl] Loaded
2019-08-05T19:51:16.284Z,1565034676.284 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-08-05T19:51:16.285Z,1565034676.285 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-08-05T19:51:16.351Z,1565034676.351 [HorizontalControl] Loaded
2019-08-05T19:51:16.351Z,1565034676.351 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-08-05T19:51:16.352Z,1565034676.352 [SpeedControl](DEBUG): Construct SpeedControl.
2019-08-05T19:51:16.357Z,1565034676.357 [SpeedControl] Loaded
2019-08-05T19:51:16.358Z,1565034676.358 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-08-05T19:51:16.358Z,1565034676.358 [LoopControl](DEBUG): Construct LoopControl.
2019-08-05T19:51:16.359Z,1565034676.359 [LoopControl] Loaded
2019-08-05T19:51:16.359Z,1565034676.359 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-08-05T19:51:16.360Z,1565034676.360 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-08-05T19:51:16.360Z,1565034676.360 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-08-05T19:51:16.373Z,1565034676.373 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-08-05T19:51:16.374Z,1565034676.374 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-08-05T19:51:16.467Z,1565034676.467 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-08-05T19:51:16.468Z,1565034676.468 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-08-05T19:51:16.565Z,1565034676.565 [BuoyancyServo] Loaded
2019-08-05T19:51:16.565Z,1565034676.565 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-08-05T19:51:16.576Z,1565034676.576 [ElevatorServo] Loaded
2019-08-05T19:51:16.576Z,1565034676.576 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-08-05T19:51:16.587Z,1565034676.587 [MassServo] Loaded
2019-08-05T19:51:16.587Z,1565034676.587 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-08-05T19:51:16.598Z,1565034676.598 [RudderServo] Loaded
2019-08-05T19:51:16.598Z,1565034676.598 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-08-05T19:51:16.609Z,1565034676.609 [ThrusterServo] Loaded
2019-08-05T19:51:16.609Z,1565034676.609 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-08-05T19:51:16.610Z,1565034676.610 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-08-05T19:51:16.610Z,1565034676.610 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-08-05T19:51:16.714Z,1565034676.714 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-08-05T19:51:16.714Z,1565034676.714 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-08-05T19:51:16.735Z,1565034676.735 [NavChart] Loaded
2019-08-05T19:51:16.735Z,1565034676.735 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-08-05T19:51:16.739Z,1565034676.739 [UniversalFixResidualReporter] Loaded
2019-08-05T19:51:16.739Z,1565034676.739 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-08-05T19:51:16.739Z,1565034676.739 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-08-05T19:51:16.740Z,1565034676.740 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-08-05T19:51:16.807Z,1565034676.807 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-08-05T19:51:16.808Z,1565034676.808 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-08-05T19:51:17.125Z,1565034677.125 [DataOverHttps] Loaded
2019-08-05T19:51:17.126Z,1565034677.126 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-08-05T19:51:17.127Z,1565034677.127 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0
2019-08-05T19:51:17.127Z,1565034677.127 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6592
2019-08-05T19:51:17.140Z,1565034677.140 [Depth_Keller] Loaded
2019-08-05T19:51:17.140Z,1565034677.140 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-08-05T19:51:17.234Z,1565034677.234 [NAL9602] Loaded
2019-08-05T19:51:17.234Z,1565034677.234 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-08-05T19:51:17.250Z,1565034677.250 [Onboard] Loaded
2019-08-05T19:51:17.250Z,1565034677.250 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-08-05T19:51:17.254Z,1565034677.254 [Radio_Surface] Loaded
2019-08-05T19:51:17.254Z,1565034677.254 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-08-05T19:51:17.255Z,1565034677.255 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0
2019-08-05T19:51:17.255Z,1565034677.255 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6593
2019-08-05T19:51:18.702Z,1565034678.702 [BPC1] Loaded
2019-08-05T19:51:18.702Z,1565034678.702 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-08-05T19:51:18.703Z,1565034678.703 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-08-05T19:51:18.703Z,1565034678.703 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-08-05T19:51:18.742Z,1565034678.742 [DepthRateCalculator] Loaded
2019-08-05T19:51:18.743Z,1565034678.743 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-08-05T19:51:18.748Z,1565034678.748 [PitchRateCalculator] Loaded
2019-08-05T19:51:18.748Z,1565034678.748 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-08-05T19:51:18.760Z,1565034678.760 [SpeedCalculator] Loaded
2019-08-05T19:51:18.760Z,1565034678.760 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-08-05T19:51:18.780Z,1565034678.780 [TempGradientCalculator] Loaded
2019-08-05T19:51:18.781Z,1565034678.781 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-08-05T19:51:18.786Z,1565034678.786 [YawRateCalculator] Loaded
2019-08-05T19:51:18.786Z,1565034678.786 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-08-05T19:51:18.815Z,1565034678.815 [ElevatorOffsetCalculator] Loaded
2019-08-05T19:51:18.815Z,1565034678.815 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-08-05T19:51:18.816Z,1565034678.816 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-08-05T19:51:18.816Z,1565034678.816 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-08-05T19:51:19.002Z,1565034679.002 [ESPComponent] Loaded
2019-08-05T19:51:19.002Z,1565034679.002 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-08-05T19:51:19.016Z,1565034679.016 [PAR_Licor] Loaded
2019-08-05T19:51:19.016Z,1565034679.016 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-08-05T19:51:19.017Z,1565034679.017 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-08-05T19:51:19.018Z,1565034679.018 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-08-05T19:51:19.049Z,1565034679.049 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-08-05T19:51:19.049Z,1565034679.049 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-08-05T19:51:19.324Z,1565034679.324 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-08-05T19:51:19.324Z,1565034679.324 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-08-05T19:51:19.450Z,1565034679.450 [SBIT](DEBUG): Construct Startup Built In Test.
2019-08-05T19:51:19.461Z,1565034679.461 [SBIT] Loaded
2019-08-05T19:51:19.462Z,1565034679.462 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-08-05T19:51:19.462Z,1565034679.462 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-08-05T19:51:19.474Z,1565034679.474 [IBIT] Loaded
2019-08-05T19:51:19.474Z,1565034679.474 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-08-05T19:51:19.477Z,1565034679.477 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-08-05T19:51:19.609Z,1565034679.609 [CBIT] Loaded
2019-08-05T19:51:19.609Z,1565034679.609 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-08-05T19:51:19.610Z,1565034679.610 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-08-05T19:51:19.613Z,1565034679.613 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-08-05T19:51:19.614Z,1565034679.614 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-08-05T19:51:19.621Z,1565034679.621 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-08-05T19:51:19.622Z,1565034679.622 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2019-08-05T19:51:19.622Z,1565034679.622 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6594
2019-08-05T19:51:19.626Z,1565034679.626 [Supervisor](INFO): Main Thread ID is 6505
2019-08-05T19:51:19.627Z,1565034679.627 [Supervisor](DEBUG): Running supervisor.
2019-08-05T19:51:19.627Z,1565034679.627 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6595
2019-08-05T19:51:19.630Z,1565034679.630 [controlThread ThreadHandler](INFO): Handler Thread ID is 6596
2019-08-05T19:51:19.630Z,1565034679.630 [controlThread](DEBUG): Initializing ControlThread
2019-08-05T19:51:19.631Z,1565034679.631 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-08-05T19:51:19.633Z,1565034679.633 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-08-05T19:51:19.633Z,1565034679.633 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-08-05T19:51:19.634Z,1565034679.634 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-08-05T19:51:19.636Z,1565034679.636 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-08-05T19:51:19.636Z,1565034679.636 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-08-05T19:51:19.640Z,1565034679.640 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-08-05T19:51:19.640Z,1565034679.640 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-08-05T19:51:19.641Z,1565034679.641 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-08-05T19:51:19.641Z,1565034679.641 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-08-05T19:51:19.642Z,1565034679.642 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-08-05T19:51:19.642Z,1565034679.642 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-08-05T19:51:19.647Z,1565034679.647 [SBIT](INFO): Initialize SBIT Component.
2019-08-05T19:51:19.648Z,1565034679.648 [SBIT](IMPORTANT): git: 2019-07-26-1-g6ad825a
2019-08-05T19:51:19.648Z,1565034679.648 [SBIT](INFO): git hash: 6ad825a153e96bf2505e39e5df4d606652139ef6
2019-08-05T19:51:19.648Z,1565034679.648 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-08-05T19:51:19.649Z,1565034679.649 [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-05T19:51:19.650Z,1565034679.650 [SBIT](INFO): Beginning SBIT in 29.000000 seconds.
2019-08-05T19:51:19.651Z,1565034679.651 [IBIT](INFO): Initialize IBIT Component.
2019-08-05T19:51:19.652Z,1565034679.652 [CBIT](DEBUG): Initialize CBIT Component.
2019-08-05T19:51:19.653Z,1565034679.653 [logger ThreadHandler](INFO): Handler Thread ID is 6597
2019-08-05T19:51:19.663Z,1565034679.663 [CBIT](DEBUG): Initialized mux pins.
2019-08-05T19:51:19.663Z,1565034679.663 [CBIT](DEBUG): Initializing the watchdog timer.
2019-08-05T19:51:19.671Z,1565034679.671 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6598
2019-08-05T19:51:19.672Z,1565034679.672 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-08-05T19:51:19.683Z,1565034679.683 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6599
2019-08-05T19:51:19.687Z,1565034679.687 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-08-05T19:51:19.687Z,1565034679.687 [CBIT](DEBUG): Initializing heartbeat.
2019-08-05T19:51:19.695Z,1565034679.695 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6600
2019-08-05T19:51:19.698Z,1565034679.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-08-05T19:51:19.699Z,1565034679.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-08-05T19:51:19.699Z,1565034679.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-08-05T19:51:19.699Z,1565034679.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-08-05T19:51:19.699Z,1565034679.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-08-05T19:51:19.699Z,1565034679.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-08-05T19:51:19.700Z,1565034679.700 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-08-05T19:51:19.700Z,1565034679.700 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-08-05T19:51:19.700Z,1565034679.700 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-08-05T19:51:19.700Z,1565034679.700 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-08-05T19:51:19.700Z,1565034679.700 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-08-05T19:51:19.701Z,1565034679.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-08-05T19:51:19.701Z,1565034679.701 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-08-05T19:51:19.701Z,1565034679.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-08-05T19:51:19.701Z,1565034679.701 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-08-05T19:51:19.701Z,1565034679.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-08-05T19:51:19.759Z,1565034679.759 [CBIT](DEBUG): Deactivating GF circuits.
2019-08-05T19:51:19.759Z,1565034679.759 [CBIT](DEBUG): Deactivating emergency mode.
2019-08-05T19:51:19.795Z,1565034679.795 [CBIT](DEBUG): Backplane powered.
2019-08-05T19:51:19.796Z,1565034679.796 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-08-05T19:51:19.806Z,1565034679.806 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-05T19:51:19.817Z,1565034679.817 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-08-05T19:51:19.817Z,1565034679.817 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-08-05T19:51:19.890Z,1565034679.890 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-08-05T19:51:19.908Z,1565034679.908 [Default:A.Wait](DEBUG): Construct Wait.
2019-08-05T19:51:19.909Z,1565034679.909 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-05T19:51:19.948Z,1565034679.948 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-08-05T19:51:19.951Z,1565034679.951 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-08-05T19:51:19.972Z,1565034679.972 [Default:E.Execute](DEBUG): Construct Execute.
2019-08-05T19:51:19.976Z,1565034679.976 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-08-05T19:51:19.980Z,1565034679.980 [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-05T19:51:20.016Z,1565034680.016 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-08-05T19:51:20.087Z,1565034680.087 [Radio_Surface](INFO): Powering up
2019-08-05T19:51:20.150Z,1565034680.150 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-08-05T19:51:20.205Z,1565034680.205 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-08-05T19:51:20.211Z,1565034680.211 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-08-05T19:51:20.212Z,1565034680.212 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-08-05T19:51:20.219Z,1565034680.219 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-08-05T19:51:20.220Z,1565034680.220 [MassServo](DEBUG): Initializing EZServoServo.
2019-08-05T19:51:20.227Z,1565034680.227 [MassServo](DEBUG): Initializing MassServo.
2019-08-05T19:51:20.228Z,1565034680.228 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-05T19:51:20.235Z,1565034680.235 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-05T19:51:20.236Z,1565034680.236 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-08-05T19:51:20.247Z,1565034680.247 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-08-05T19:51:21.147Z,1565034681.147 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-08-05T19:51:21.147Z,1565034681.147 [RudderServo](FAULT): Rudder failed to initialize
2019-08-05T19:51:21.147Z,1565034681.147 [RudderServo] Communications Fault, FailCount= 1
2019-08-05T19:51:21.147Z,1565034681.147 [RudderServo](ERROR): Communications Fault
2019-08-05T19:51:21.258Z,1565034681.258 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-08-05T19:51:21.440Z,1565034681.440 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-05T19:51:21.440Z,1565034681.440 [RudderServo](INFO): Powering down
2019-08-05T19:51:22.138Z,1565034682.138 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-05T19:51:22.256Z,1565034682.256 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-05T19:51:22.260Z,1565034682.260 [CBIT](INFO): Clearing failed state for component RudderServo
2019-08-05T19:51:22.260Z,1565034682.260 [RudderServo] No Fault, FailCount= 1
2019-08-05T19:51:29.896Z,1565034689.896 [CBIT](CRITICAL): Environmental Failure. Press:14.731917 PSI. Humidity:37%. Temp:25 C. ABORTING MISSION
2019-08-05T19:51:29.896Z,1565034689.896 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-08-05T19:51:30.257Z,1565034690.257 [CommandLine](FAULT): Scheduling is paused
2019-08-05T19:51:30.258Z,1565034690.258 [CBIT](INFO): Critical error at 20190805T195129
2019-08-05T19:51:32.348Z,1565034692.348 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004497
2019-08-05T19:51:47.528Z,1565034707.528 [NAL9602](INFO): Powering up NAL9602
2019-08-05T19:51:49.248Z,1565034709.248 [SBIT](IMPORTANT): Beginning Startup BIT
2019-08-05T19:51:49.252Z,1565034709.252 [CBIT](IMPORTANT): Beginning ground fault scan
2019-08-05T19:51:58.396Z,1565034718.396 [NAL9602](INFO): NAL9602 initialized
2019-08-05T19:51:59.227Z,1565034719.227 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:52:00.183Z,1565034720.183 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009663
CHAN A1 (24V): -0.017220
CHAN A2 (12V): -0.002398
CHAN A3 (5V): -0.001774
CHAN B0 (3.3V): 0.000043
CHAN B1 (3.15aV): 0.000104
CHAN B2 (3.15bV): -0.000107
CHAN B3 (GND): -0.000257
OPEN: -0.000577
Full Scale Calc: 4.765 mA, -1.589 mA
2019-08-05T19:52:43.116Z,1565034763.116 [SBIT](IMPORTANT): SBIT PASSED
2019-08-05T19:52:43.152Z,1565034763.152 [CommandLine](IMPORTANT): got command configSet list
2019-08-05T19:52:43.152Z,1565034763.152 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-08-05T19:52:43.153Z,1565034763.153 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-08-05T19:52:43.153Z,1565034763.153 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-08-05T19:52:43.153Z,1565034763.153 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-08-05T19:52:43.153Z,1565034763.153 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=6 hour;

2019-08-05T19:52:43.153Z,1565034763.153 [CommandLine](IMPORTANT): CBIT.abortDepth=296 meter;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): CBIT.stopDepth=288 meter;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): DAT.ignoreElevationAngle=1 bool;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-08-05T19:52:43.154Z,1565034763.154 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2019-08-05T19:52:43.155Z,1565034763.155 [CommandLine](IMPORTANT): Express linearApproximation DAT.range 5.000000 meter;

2019-08-05T19:52:43.155Z,1565034763.155 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2019-08-05T19:52:43.155Z,1565034763.155 [CommandLine](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2019-08-05T19:52:43.155Z,1565034763.155 [CommandLine](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2019-08-05T19:52:43.155Z,1565034763.155 [CommandLine](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2019-08-05T19:52:43.155Z,1565034763.155 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-08-05T19:52:43.155Z,1565034763.155 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=274 cubic_centimeter;

2019-08-05T19:52:43.155Z,1565034763.155 [CommandLine](IMPORTANT): VerticalControl.massDefault=-2.3 millimeter;

2019-08-05T19:52:43.156Z,1565034763.156 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-31 millimeter;

2019-08-05T19:52:43.156Z,1565034763.156 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter;

2019-08-05T19:52:43.156Z,1565034763.156 [CommandLine](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2019-08-05T19:52:43.444Z,1565034763.444 [MissionManager](IMPORTANT): Started mission Startup
2019-08-05T19:52:43.444Z,1565034763.444 [Startup] Running Loop=1
2019-08-05T19:52:43.444Z,1565034763.444 [Startup](DEBUG): Aggregate::initialize Startup
2019-08-05T19:52:43.445Z,1565034763.445 [Startup:A.GoToSurface] Running Loop=1
2019-08-05T19:52:43.445Z,1565034763.445 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-05T19:52:43.445Z,1565034763.445 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-05T19:52:43.446Z,1565034763.446 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-05T19:52:43.446Z,1565034763.446 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-05T19:52:43.446Z,1565034763.446 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-05T19:52:43.447Z,1565034763.447 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-05T19:52:43.449Z,1565034763.449 [Startup:StartupSatComms] Running Loop=1
2019-08-05T19:52:43.449Z,1565034763.449 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-08-05T19:52:43.449Z,1565034763.449 [Startup:StartupSatComms:A] Running Loop=1
2019-08-05T19:52:43.852Z,1565034763.852 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-08-05T19:53:19.810Z,1565034799.810 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T19:53:19.810Z,1565034799.810 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-05T19:53:19.810Z,1565034799.810 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T19:53:19.821Z,1565034799.821 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T19:53:20.220Z,1565034800.220 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T19:53:20.220Z,1565034800.220 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-05T19:53:43.630Z,1565034823.630 [Startup:StartupSatComms:A](INFO): Timed out from 2019-08-05T19:52:43.4Z
2019-08-05T19:53:43.630Z,1565034823.630 [Startup:StartupSatComms:A] Stopped
2019-08-05T19:53:43.630Z,1565034823.630 [Startup:StartupSatComms:B] Running Loop=1
2019-08-05T19:53:44.030Z,1565034824.030 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-05T19:53:50.654Z,1565034830.654 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190805T192505/Courier0010.lzma
2019-08-05T19:53:51.461Z,1565034831.461 [DataOverHttps](INFO): Moved sent file to Logs/20190805T192505/Courier0010.lzma.bak
2019-08-05T19:53:51.461Z,1565034831.461 [DataOverHttps](INFO): SBD MOMSN=11522314
2019-08-05T19:54:04.410Z,1565034844.410 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20190805T195110/Courier0000.lzma
2019-08-05T19:54:05.216Z,1565034845.216 [DataOverHttps](INFO): Moved sent file to Logs/20190805T195110/Courier0000.lzma.bak
2019-08-05T19:54:05.217Z,1565034845.217 [DataOverHttps](INFO): SBD MOMSN=11522316
2019-08-05T19:54:18.919Z,1565034858.919 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20190805T192505/Express0011.lzma
2019-08-05T19:54:19.724Z,1565034859.724 [DataOverHttps](INFO): Moved sent file to Logs/20190805T192505/Express0011.lzma.bak
2019-08-05T19:54:19.724Z,1565034859.724 [DataOverHttps](INFO): SBD MOMSN=11522320
2019-08-05T19:54:33.280Z,1565034873.280 [DataOverHttps](INFO): Sending 1177 bytes from file Logs/20190805T195110/Express0001.lzma
2019-08-05T19:54:34.084Z,1565034874.084 [DataOverHttps](INFO): Moved sent file to Logs/20190805T195110/Express0001.lzma.bak
2019-08-05T19:54:34.084Z,1565034874.084 [DataOverHttps](INFO): SBD MOMSN=11522324
2019-08-05T19:54:35.744Z,1565034875.744 [Startup:StartupSatComms:B] Stopped
2019-08-05T19:54:35.745Z,1565034875.745 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-08-05T19:54:35.745Z,1565034875.745 [Startup:StartupSatComms] Stopped
2019-08-05T19:54:35.745Z,1565034875.745 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-08-05T19:54:35.745Z,1565034875.745 [Startup](INFO): Completed Startup
2019-08-05T19:54:35.746Z,1565034875.746 [MissionManager](INFO): Startup is completed.
2019-08-05T19:54:35.746Z,1565034875.746 [MissionManager](INFO): Uninitializing Mission Startup
2019-08-05T19:54:35.746Z,1565034875.746 [Startup] Stopped
2019-08-05T19:54:35.746Z,1565034875.746 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-08-05T19:54:35.746Z,1565034875.746 [Startup:A.GoToSurface] Stopped
2019-08-05T19:54:35.746Z,1565034875.746 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-05T19:54:36.173Z,1565034876.173 [MissionManager](IMPORTANT): Started mission Default
2019-08-05T19:54:36.173Z,1565034876.173 [Default] Running Loop=1
2019-08-05T19:54:36.173Z,1565034876.173 [Default](DEBUG): Aggregate::initialize Default
2019-08-05T19:54:36.173Z,1565034876.173 [Default:B.GoToSurface] Running Loop=1
2019-08-05T19:54:36.174Z,1565034876.174 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-05T19:54:36.174Z,1565034876.174 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-05T19:54:36.174Z,1565034876.174 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-05T19:54:36.174Z,1565034876.174 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-05T19:54:36.179Z,1565034876.179 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-05T19:54:36.179Z,1565034876.179 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-05T19:54:36.180Z,1565034876.180 [Default:A.Wait] Running Loop=1
2019-08-05T19:54:36.180Z,1565034876.180 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-05T19:54:49.493Z,1565034889.493 [Default:A.Wait](INFO): Done Waiting.
2019-08-05T19:54:49.493Z,1565034889.493 [Default:A.Wait] Stopped
2019-08-05T19:54:49.493Z,1565034889.493 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-05T19:54:49.900Z,1565034889.900 [Default:CheckIn] Running Loop=1
2019-08-05T19:54:49.901Z,1565034889.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-05T19:54:49.901Z,1565034889.901 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-05T19:54:50.305Z,1565034890.305 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-08-05T19:55:21.069Z,1565034921.069 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T19:55:21.069Z,1565034921.069 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-05T19:55:21.069Z,1565034921.069 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T19:55:21.080Z,1565034921.080 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T19:55:21.445Z,1565034921.445 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T19:55:21.445Z,1565034921.445 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-05T19:55:45.630Z,1565034945.630 [CommandLine](IMPORTANT): got command failComponent
2019-08-05T19:55:45.630Z,1565034945.630 [CommandLine](IMPORTANT): Failed components:
2019-08-05T19:55:45.631Z,1565034945.631 [CommandLine](IMPORTANT): No failed Components.
2019-08-05T19:57:01.968Z,1565035021.968 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-05T19:57:02.791Z,1565035022.791 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:07.635Z,1565035027.635 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:10.467Z,1565035030.467 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:13.695Z,1565035033.695 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:16.523Z,1565035036.523 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:19.355Z,1565035039.355 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:21.375Z,1565035041.375 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:22.228Z,1565035042.228 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T19:57:22.228Z,1565035042.228 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-05T19:57:22.228Z,1565035042.228 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T19:57:22.238Z,1565035042.238 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T19:57:22.620Z,1565035042.620 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T19:57:22.620Z,1565035042.620 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-05T19:57:23.395Z,1565035043.395 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:25.411Z,1565035045.411 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:28.647Z,1565035048.647 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:31.475Z,1565035051.475 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:34.707Z,1565035054.707 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:37.535Z,1565035057.535 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:40.767Z,1565035060.767 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:43.595Z,1565035063.595 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:46.439Z,1565035066.439 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:48.439Z,1565035068.439 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:51.675Z,1565035071.675 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:54.499Z,1565035074.499 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:57:57.735Z,1565035077.735 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:00.563Z,1565035080.563 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:03.795Z,1565035083.795 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:06.622Z,1565035086.622 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:09.447Z,1565035089.447 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:11.471Z,1565035091.471 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:13.487Z,1565035093.487 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:16.722Z,1565035096.722 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:19.547Z,1565035099.547 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:22.779Z,1565035102.779 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:25.607Z,1565035105.607 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:28.839Z,1565035108.839 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:31.671Z,1565035111.671 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:34.495Z,1565035114.495 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:36.515Z,1565035116.515 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:39.747Z,1565035119.747 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:42.579Z,1565035122.579 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:45.807Z,1565035125.807 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:48.247Z,1565035128.247 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-08-05T19:58:48.250Z,1565035128.250 [BPC1](INFO): Received data from all battery sticks.
2019-08-05T19:58:48.635Z,1565035128.635 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:51.867Z,1565035131.867 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:54.699Z,1565035134.699 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:57.526Z,1565035137.526 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:58:59.547Z,1565035139.547 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:02.775Z,1565035142.775 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:05.607Z,1565035145.607 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:08.835Z,1565035148.835 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:11.667Z,1565035151.667 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:14.895Z,1565035154.895 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:17.727Z,1565035157.727 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:20.551Z,1565035160.551 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:22.575Z,1565035162.575 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:23.007Z,1565035163.007 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T19:59:23.007Z,1565035163.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-08-05T19:59:23.007Z,1565035163.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T19:59:23.018Z,1565035163.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T19:59:23.415Z,1565035163.415 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T19:59:23.416Z,1565035163.416 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-08-05T19:59:24.591Z,1565035164.591 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:27.826Z,1565035167.826 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:30.655Z,1565035170.655 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:33.883Z,1565035173.883 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:36.711Z,1565035176.711 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:39.947Z,1565035179.947 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:42.771Z,1565035182.771 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:45.603Z,1565035185.603 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:48.831Z,1565035188.831 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:50.060Z,1565035190.060 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-08-05T19:54:49.9Z
2019-08-05T19:59:50.060Z,1565035190.060 [Default:CheckIn:Read_GPS] Stopped
2019-08-05T19:59:50.061Z,1565035190.061 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-05T19:59:50.468Z,1565035190.468 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-05T19:59:51.659Z,1565035191.659 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:54.891Z,1565035194.891 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:57.435Z,1565035197.435 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190805T195110/Courier0004.lzma
2019-08-05T19:59:57.719Z,1565035197.719 [NAL9602](DEBUG): Fix Requested
2019-08-05T19:59:58.240Z,1565035198.240 [DataOverHttps](INFO): Moved sent file to Logs/20190805T195110/Courier0004.lzma.bak
2019-08-05T19:59:58.241Z,1565035198.241 [DataOverHttps](INFO): SBD MOMSN=11522431
2019-08-05T20:00:00.172Z,1565035200.172 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2019-08-05T20:00:00.172Z,1565035200.172 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 30.000000 mm
2019-08-05T20:00:00.951Z,1565035200.951 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:03.779Z,1565035203.779 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:07.011Z,1565035207.011 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:09.839Z,1565035209.839 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:11.991Z,1565035211.991 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20190805T195110/Express0005.lzma
2019-08-05T20:00:12.667Z,1565035212.667 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:12.804Z,1565035212.804 [DataOverHttps](INFO): Moved sent file to Logs/20190805T195110/Express0005.lzma.bak
2019-08-05T20:00:12.805Z,1565035212.805 [DataOverHttps](INFO): SBD MOMSN=11522433
2019-08-05T20:00:15.135Z,1565035215.135 [Default:CheckIn:Read_Iridium] Stopped
2019-08-05T20:00:15.135Z,1565035215.135 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-05T20:00:15.135Z,1565035215.135 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-05T20:00:15.899Z,1565035215.899 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:17.269Z,1565035217.269 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2019-08-05T20:00:17.270Z,1565035217.270 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -31.000000 mm
2019-08-05T20:00:18.730Z,1565035218.730 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:21.959Z,1565035221.959 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:24.787Z,1565035224.787 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:28.019Z,1565035228.019 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:30.847Z,1565035230.847 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:33.675Z,1565035233.675 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:35.695Z,1565035235.695 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:38.927Z,1565035238.927 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:41.758Z,1565035241.758 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:44.066Z,1565035244.066 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 25.000000 millimeter
2019-08-05T20:00:44.068Z,1565035244.068 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2019-08-05T20:00:44.208Z,1565035244.208 [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,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-08-05T20:00:44.987Z,1565035244.987 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:47.835Z,1565035247.835 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:51.071Z,1565035251.071 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:53.895Z,1565035253.895 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:56.723Z,1565035256.723 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:00:58.743Z,1565035258.743 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:01.975Z,1565035261.975 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:04.803Z,1565035264.803 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:08.039Z,1565035268.039 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:10.863Z,1565035270.863 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:14.095Z,1565035274.095 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:16.923Z,1565035276.923 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:19.755Z,1565035279.755 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:21.771Z,1565035281.771 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:23.800Z,1565035283.800 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T20:01:23.800Z,1565035283.800 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-08-05T20:01:23.800Z,1565035283.800 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T20:01:23.810Z,1565035283.810 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T20:01:24.220Z,1565035284.220 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T20:01:24.220Z,1565035284.220 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-08-05T20:01:25.007Z,1565035285.007 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:27.831Z,1565035287.831 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:31.063Z,1565035291.063 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:33.891Z,1565035293.891 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:35.296Z,1565035295.296 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 30.000000 millimeter
2019-08-05T20:01:37.127Z,1565035297.127 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:39.999Z,1565035299.999 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:42.823Z,1565035302.823 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:46.059Z,1565035306.059 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:48.883Z,1565035308.883 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:51.055Z,1565035311.055 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -29.000000 millimeter
2019-08-05T20:01:52.119Z,1565035312.119 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:54.987Z,1565035314.987 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:57.815Z,1565035317.815 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:01:59.824Z,1565035319.824 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-08-05T20:01:59.824Z,1565035319.824 [NAL9602] Data Fault, FailCount= 1
2019-08-05T20:01:59.824Z,1565035319.824 [NAL9602](ERROR): Data Fault
2019-08-05T20:01:59.936Z,1565035319.936 [CBIT](ERROR): Data Fault in component: NAL9602
2019-08-05T20:02:00.228Z,1565035320.228 [NAL9602](INFO): Powering down
2019-08-05T20:02:01.131Z,1565035321.131 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-05T20:02:01.131Z,1565035321.131 [NAL9602] No Fault, FailCount= 1
2019-08-05T20:02:30.528Z,1565035350.528 [NAL9602](INFO): Powering up NAL9602
2019-08-05T20:02:41.440Z,1565035361.440 [NAL9602](INFO): NAL9602 initialized
2019-08-05T20:02:42.259Z,1565035362.259 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:02:45.087Z,1565035365.087 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:02:47.915Z,1565035367.915 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:02:51.147Z,1565035371.147 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:02:53.971Z,1565035373.971 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:02:57.203Z,1565035377.203 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:00.036Z,1565035380.036 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:03.263Z,1565035383.263 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:06.091Z,1565035386.091 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:08.919Z,1565035388.919 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:10.939Z,1565035390.939 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:14.171Z,1565035394.171 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:17.003Z,1565035397.003 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:20.231Z,1565035400.231 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:21.345Z,1565035401.345 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -31.000000 millimeter
2019-08-05T20:03:23.059Z,1565035403.059 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:24.771Z,1565035404.771 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T20:03:24.771Z,1565035404.771 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-08-05T20:03:24.771Z,1565035404.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T20:03:24.856Z,1565035404.856 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T20:03:25.231Z,1565035405.231 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T20:03:25.231Z,1565035405.231 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-08-05T20:03:25.967Z,1565035405.967 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:29.199Z,1565035409.199 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:32.027Z,1565035412.027 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:34.976Z,1565035414.976 [CommandLine](IMPORTANT): got command maintain clear
2019-08-05T20:03:35.259Z,1565035415.259 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:35.297Z,1565035415.297 [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-05T20:03:38.135Z,1565035418.135 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:40.963Z,1565035420.963 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:42.987Z,1565035422.987 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:45.007Z,1565035425.007 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:48.239Z,1565035428.239 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:51.063Z,1565035431.063 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:54.295Z,1565035434.295 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:03:57.127Z,1565035437.127 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:00.355Z,1565035440.355 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:03.187Z,1565035443.187 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:06.011Z,1565035446.011 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:08.031Z,1565035448.031 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:11.263Z,1565035451.263 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:14.095Z,1565035454.095 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:17.323Z,1565035457.323 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:20.155Z,1565035460.155 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:23.387Z,1565035463.387 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:26.211Z,1565035466.211 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:29.047Z,1565035469.047 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:31.059Z,1565035471.059 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:33.087Z,1565035473.087 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:36.315Z,1565035476.315 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:39.143Z,1565035479.143 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:42.375Z,1565035482.375 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:45.199Z,1565035485.199 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:48.435Z,1565035488.435 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:51.263Z,1565035491.263 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:54.091Z,1565035494.091 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:56.111Z,1565035496.111 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:04:59.343Z,1565035499.343 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:02.167Z,1565035502.167 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:05.403Z,1565035505.403 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:08.231Z,1565035508.231 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:11.463Z,1565035511.463 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:14.291Z,1565035514.291 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:15.916Z,1565035515.916 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-05T20:05:15.916Z,1565035515.916 [Default:CheckIn:C.Wait] Stopped
2019-08-05T20:05:15.917Z,1565035515.917 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-05T20:05:15.917Z,1565035515.917 [Default:CheckIn:D] Running Loop=1
2019-08-05T20:05:16.324Z,1565035516.324 [Default:CheckIn:D] Stopped
2019-08-05T20:05:16.324Z,1565035516.324 [Default:CheckIn:E] Running Loop=1
2019-08-05T20:05:16.725Z,1565035516.725 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.669183 min 
2019-08-05T20:05:16.725Z,1565035516.725 [Default:CheckIn:E] Stopped
2019-08-05T20:05:16.726Z,1565035516.726 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-05T20:05:16.726Z,1565035516.726 [Default:CheckIn] Stopped
2019-08-05T20:05:16.726Z,1565035516.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-05T20:05:16.726Z,1565035516.726 [Default:CheckIn](INFO): Running loop #2
2019-08-05T20:05:16.726Z,1565035516.726 [Default:CheckIn] Running Loop=2
2019-08-05T20:05:16.726Z,1565035516.726 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-05T20:05:16.726Z,1565035516.726 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-05T20:05:17.115Z,1565035517.115 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:19.139Z,1565035519.139 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:22.371Z,1565035522.371 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:25.199Z,1565035525.199 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:25.626Z,1565035525.626 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T20:05:25.626Z,1565035525.626 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-08-05T20:05:25.626Z,1565035525.626 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T20:05:25.677Z,1565035525.677 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T20:05:26.040Z,1565035526.040 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T20:05:26.040Z,1565035526.040 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-08-05T20:05:28.431Z,1565035528.431 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:31.255Z,1565035531.255 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:34.495Z,1565035534.495 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:37.319Z,1565035537.319 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:40.143Z,1565035540.143 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:42.167Z,1565035542.167 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:44.187Z,1565035544.187 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:47.415Z,1565035547.415 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:50.247Z,1565035550.247 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:53.475Z,1565035553.475 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:56.307Z,1565035556.307 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:05:59.539Z,1565035559.539 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:02.363Z,1565035562.363 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:05.199Z,1565035565.199 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:07.215Z,1565035567.215 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:10.443Z,1565035570.443 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:13.275Z,1565035573.275 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:16.503Z,1565035576.503 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:19.335Z,1565035579.335 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:22.563Z,1565035582.563 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:25.395Z,1565035585.395 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:28.223Z,1565035588.223 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:30.243Z,1565035590.243 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:32.259Z,1565035592.259 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:35.495Z,1565035595.495 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:38.319Z,1565035598.319 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:41.555Z,1565035601.555 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:44.383Z,1565035604.383 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:47.615Z,1565035607.615 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:50.443Z,1565035610.443 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:53.275Z,1565035613.275 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:55.287Z,1565035615.287 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:06:58.523Z,1565035618.523 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:01.347Z,1565035621.347 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:04.583Z,1565035624.583 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:07.407Z,1565035627.407 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:10.643Z,1565035630.643 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:13.467Z,1565035633.467 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:16.299Z,1565035636.299 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:18.319Z,1565035638.319 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:20.339Z,1565035640.339 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:23.571Z,1565035643.571 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:26.395Z,1565035646.395 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:26.827Z,1565035646.827 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T20:07:26.827Z,1565035646.827 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-08-05T20:07:26.827Z,1565035646.827 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T20:07:26.837Z,1565035646.837 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T20:07:27.240Z,1565035647.240 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T20:07:27.240Z,1565035647.240 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-08-05T20:07:29.631Z,1565035649.631 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:32.455Z,1565035652.455 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:35.691Z,1565035655.691 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:38.519Z,1565035658.519 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:41.343Z,1565035661.343 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:43.367Z,1565035663.367 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:46.599Z,1565035666.599 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:49.423Z,1565035669.423 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:52.659Z,1565035672.659 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:55.487Z,1565035675.487 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:07:58.719Z,1565035678.719 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:01.543Z,1565035681.543 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:04.371Z,1565035684.371 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:06.395Z,1565035686.395 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:08.415Z,1565035688.415 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:11.643Z,1565035691.643 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:14.475Z,1565035694.475 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:17.707Z,1565035697.707 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:20.535Z,1565035700.535 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:23.763Z,1565035703.763 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:26.591Z,1565035706.591 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:29.419Z,1565035709.419 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:31.439Z,1565035711.439 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:34.671Z,1565035714.671 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:37.503Z,1565035717.503 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:40.731Z,1565035720.731 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:43.563Z,1565035723.563 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:46.791Z,1565035726.791 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:49.623Z,1565035729.623 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:52.451Z,1565035732.451 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:54.471Z,1565035734.471 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:56.487Z,1565035736.487 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:08:59.719Z,1565035739.719 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:02.551Z,1565035742.551 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:05.779Z,1565035745.779 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:08.611Z,1565035748.611 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:11.839Z,1565035751.839 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:14.667Z,1565035754.667 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:17.499Z,1565035757.499 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:19.519Z,1565035759.519 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:22.751Z,1565035762.751 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:25.575Z,1565035765.575 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:28.015Z,1565035768.015 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T20:09:28.015Z,1565035768.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-08-05T20:09:28.015Z,1565035768.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T20:09:28.025Z,1565035768.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T20:09:28.428Z,1565035768.428 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T20:09:28.428Z,1565035768.428 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-08-05T20:09:28.811Z,1565035768.811 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:31.635Z,1565035771.635 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:34.871Z,1565035774.871 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:37.695Z,1565035777.695 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:40.527Z,1565035780.527 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:42.543Z,1565035782.543 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:45.779Z,1565035785.779 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:48.603Z,1565035788.603 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:51.839Z,1565035791.839 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:54.663Z,1565035794.663 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:09:57.895Z,1565035797.895 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:00.727Z,1565035800.727 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:03.551Z,1565035803.551 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:05.571Z,1565035805.571 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:07.595Z,1565035807.595 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:10.827Z,1565035810.827 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:13.275Z,1565035813.275 [CBIT](FAULT): WATER ALARM BOW.
2019-08-05T20:10:13.276Z,1565035813.276 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2019-08-05T20:10:13.659Z,1565035813.659 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:13.691Z,1565035813.691 [CBIT](FAULT): WATER ALARM BOW.
2019-08-05T20:10:13.693Z,1565035813.693 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2019-08-05T20:10:16.887Z,1565035816.887 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:16.913Z,1565035816.913 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-08-05T20:05:16.7Z
2019-08-05T20:10:16.913Z,1565035816.913 [Default:CheckIn:Read_GPS] Stopped
2019-08-05T20:10:16.913Z,1565035816.913 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-05T20:10:19.715Z,1565035819.715 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:20.971Z,1565035820.971 [CBIT](FAULT): WATER ALARM STERN.
2019-08-05T20:10:20.972Z,1565035820.972 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2019-08-05T20:10:21.390Z,1565035821.390 [CBIT](FAULT): WATER ALARM STERN.
2019-08-05T20:10:21.391Z,1565035821.391 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2019-08-05T20:10:21.782Z,1565035821.782 [CBIT](FAULT): WATER ALARM STERN.
2019-08-05T20:10:21.783Z,1565035821.783 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2019-08-05T20:10:22.187Z,1565035822.187 [CBIT](FAULT): WATER ALARM STERN.
2019-08-05T20:10:22.201Z,1565035822.201 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2019-08-05T20:10:22.208Z,1565035822.208 [CBIT](IMPORTANT): Powered off ESP with command: ! echo 0 > /dev/loadA6
2019-08-05T20:10:22.267Z,1565035822.267 [CommandLine](IMPORTANT): got command ! echo 0 > /dev/loadA6
2019-08-05T20:10:22.617Z,1565035822.617 [CBIT](INFO): Critical error at 20190805T201022
2019-08-05T20:10:22.943Z,1565035822.943 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:23.858Z,1565035823.858 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190805T195110/Courier0007.lzma
2019-08-05T20:10:24.676Z,1565035824.676 [DataOverHttps](INFO): Moved sent file to Logs/20190805T195110/Courier0007.lzma.bak
2019-08-05T20:10:24.677Z,1565035824.677 [DataOverHttps](INFO): SBD MOMSN=11522519
2019-08-05T20:10:25.771Z,1565035825.771 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:28.599Z,1565035828.599 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:30.619Z,1565035830.619 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:33.859Z,1565035833.859 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:36.679Z,1565035836.679 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:37.951Z,1565035837.951 [DataOverHttps](INFO): Sending 455 bytes from file Logs/20190805T195110/Express0008.lzma
2019-08-05T20:10:38.756Z,1565035838.756 [DataOverHttps](INFO): Moved sent file to Logs/20190805T195110/Express0008.lzma.bak
2019-08-05T20:10:38.757Z,1565035838.757 [DataOverHttps](INFO): SBD MOMSN=11522521
2019-08-05T20:10:39.911Z,1565035839.911 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:40.352Z,1565035840.352 [Default:CheckIn:Read_Iridium] Stopped
2019-08-05T20:10:40.352Z,1565035840.352 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-05T20:10:40.352Z,1565035840.352 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-05T20:10:42.743Z,1565035842.743 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:45.971Z,1565035845.971 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:48.803Z,1565035848.803 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:51.627Z,1565035851.627 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:53.647Z,1565035853.647 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:56.883Z,1565035856.883 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:10:59.707Z,1565035859.707 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:02.943Z,1565035862.943 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:05.771Z,1565035865.771 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:09.003Z,1565035869.003 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:11.830Z,1565035871.830 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:14.655Z,1565035874.655 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:16.675Z,1565035876.675 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:18.699Z,1565035878.699 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:21.927Z,1565035881.927 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:22.590Z,1565035882.590 [CommandLine](IMPORTANT): got command burn off
2019-08-05T20:11:22.591Z,1565035882.591 [CommandLine](IMPORTANT): Deactivating dropweight wire
2019-08-05T20:11:24.759Z,1565035884.759 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:27.987Z,1565035887.987 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:29.217Z,1565035889.217 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-05T20:11:29.218Z,1565035889.218 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-08-05T20:11:29.218Z,1565035889.218 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-05T20:11:29.228Z,1565035889.228 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-05T20:11:29.641Z,1565035889.641 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-05T20:11:29.641Z,1565035889.641 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-08-05T20:11:30.819Z,1565035890.819 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:34.047Z,1565035894.047 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:36.879Z,1565035896.879 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:39.707Z,1565035899.707 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:41.727Z,1565035901.727 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:43.743Z,1565035903.743 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:46.979Z,1565035906.979 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:49.803Z,1565035909.803 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:53.035Z,1565035913.035 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:55.871Z,1565035915.871 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:11:59.099Z,1565035919.099 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:12:01.923Z,1565035921.923 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:12:04.755Z,1565035924.755 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:12:06.775Z,1565035926.775 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:12:10.007Z,1565035930.007 [NAL9602](DEBUG): Fix Requested
2019-08-05T20:12:10.753Z,1565035930.753 [CommandLine](IMPORTANT): got command quit
2019-08-05T20:12:11.759Z,1565035931.759 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-05T20:12:11.759Z,1565035931.759 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:11.895Z,1565035931.895 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-08-05T20:12:11.895Z,1565035931.895 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:11.896Z,1565035931.896 [CommandLine](INFO): Join timeout helper Thread ID is 6649
2019-08-05T20:12:11.896Z,1565035931.896 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-08-05T20:12:11.896Z,1565035931.896 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:11.897Z,1565035931.897 [NavChartDb](INFO): Join timeout helper Thread ID is 6650
2019-08-05T20:12:11.947Z,1565035931.947 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-05T20:12:11.947Z,1565035931.947 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:11.955Z,1565035931.955 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-08-05T20:12:11.955Z,1565035931.955 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:11.955Z,1565035931.955 [Radio_Surface](INFO): Join timeout helper Thread ID is 6651
2019-08-05T20:12:12.279Z,1565035932.279 [Radio_Surface](INFO): Powering down
2019-08-05T20:12:12.280Z,1565035932.280 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-05T20:12:12.280Z,1565035932.280 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.295Z,1565035932.295 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-08-05T20:12:12.295Z,1565035932.295 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.296Z,1565035932.296 [DataOverHttps](INFO): Join timeout helper Thread ID is 6652
2019-08-05T20:12:12.439Z,1565035932.439 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-05T20:12:12.440Z,1565035932.440 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.455Z,1565035932.455 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-08-05T20:12:12.455Z,1565035932.455 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.455Z,1565035932.455 [logger](INFO): Join timeout helper Thread ID is 6653
2019-08-05T20:12:12.495Z,1565035932.495 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-05T20:12:12.495Z,1565035932.495 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.516Z,1565035932.516 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-08-05T20:12:12.516Z,1565035932.516 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.516Z,1565035932.516 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-08-05T20:12:12.516Z,1565035932.516 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.517Z,1565035932.517 [controlThread](INFO): Join timeout helper Thread ID is 6654
2019-08-05T20:12:12.811Z,1565035932.811 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-05T20:12:12.811Z,1565035932.811 [controlThread](DEBUG): Uninitializing ControlThread
2019-08-05T20:12:12.812Z,1565035932.812 [NAL9602](INFO): Powering down
2019-08-05T20:12:12.833Z,1565035932.833 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-08-05T20:12:12.834Z,1565035932.834 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-08-05T20:12:12.834Z,1565035932.834 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-08-05T20:12:12.835Z,1565035932.835 [MissionManager](INFO): Uninitializing Mission Default
2019-08-05T20:12:12.835Z,1565035932.835 [Default] Stopped
2019-08-05T20:12:12.835Z,1565035932.835 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-05T20:12:12.835Z,1565035932.835 [Default:B.GoToSurface] Stopped
2019-08-05T20:12:12.835Z,1565035932.835 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-05T20:12:12.835Z,1565035932.835 [Default:CheckIn] Stopped
2019-08-05T20:12:12.835Z,1565035932.835 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-05T20:12:12.835Z,1565035932.835 [Default:CheckIn:C.Wait] Stopped
2019-08-05T20:12:12.835Z,1565035932.835 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-05T20:12:12.838Z,1565035932.838 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-08-05T20:12:12.838Z,1565035932.838 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-08-05T20:12:12.838Z,1565035932.838 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-08-05T20:12:12.838Z,1565035932.838 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-08-05T20:12:12.839Z,1565035932.839 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-08-05T20:12:12.839Z,1565035932.839 [BuoyancyServo](INFO): Powering down
2019-08-05T20:12:12.851Z,1565035932.851 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-08-05T20:12:12.851Z,1565035932.851 [ElevatorServo](INFO): Powering down
2019-08-05T20:12:12.852Z,1565035932.852 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-08-05T20:12:12.852Z,1565035932.852 [MassServo](INFO): Powering down
2019-08-05T20:12:12.853Z,1565035932.853 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-05T20:12:12.853Z,1565035932.853 [RudderServo](INFO): Powering down
2019-08-05T20:12:12.854Z,1565035932.854 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-08-05T20:12:12.854Z,1565035932.854 [ThrusterServo](INFO): Powering down
2019-08-05T20:12:12.855Z,1565035932.855 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-08-05T20:12:12.855Z,1565035932.855 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-08-05T20:12:12.855Z,1565035932.855 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-08-05T20:12:12.855Z,1565035932.855 [CBIT](DEBUG): Powering off loads.
2019-08-05T20:12:12.867Z,1565035932.867 [CBIT](DEBUG): Disabling WDT.
2019-08-05T20:12:12.879Z,1565035932.879 [CBIT](DEBUG): Opening all GF detection circuits.
2019-08-05T20:12:12.880Z,1565035932.880 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.955Z,1565035932.955 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:12.961Z,1565035932.961 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:13.023Z,1565035933.023 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-05T20:12:13.078Z,1565035933.078 [logger ThreadHandler](INFO): Thread cancelled.