2019-08-06T20:25:56.077Z,1565123156.077 [Supervisor](DEBUG): Initializing supervisor.
2019-08-06T20:25:56.080Z,1565123156.080 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-08-06T20:25:56.080Z,1565123156.080 [SyncHandler](INFO): Protected caller Thread ID is 4452
2019-08-06T20:25:56.081Z,1565123156.081 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-08-06T20:25:56.082Z,1565123156.082 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-08-06T20:25:56.082Z,1565123156.082 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4453
2019-08-06T20:25:56.085Z,1565123156.085 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-08-06T20:25:56.096Z,1565123156.096 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-08-06T20:25:56.097Z,1565123156.097 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-08-06T20:25:56.097Z,1565123156.097 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4454
2019-08-06T20:25:56.098Z,1565123156.098 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-08-06T20:25:56.099Z,1565123156.099 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-08-06T20:25:56.099Z,1565123156.099 [logger ThreadHandler](INFO): Protected caller Thread ID is 4455
2019-08-06T20:25:56.101Z,1565123156.101 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-08-06T20:25:56.102Z,1565123156.102 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-08-06T20:25:56.103Z,1565123156.103 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-08-06T20:25:56.305Z,1565123156.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-08-06T20:25:56.305Z,1565123156.305 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-08-06T20:25:56.388Z,1565123156.388 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-08-06T20:25:56.818Z,1565123156.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-08-06T20:25:56.818Z,1565123156.818 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-08-06T20:25:57.152Z,1565123157.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-08-06T20:25:57.152Z,1565123157.152 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-08-06T20:25:57.252Z,1565123157.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-08-06T20:25:57.253Z,1565123157.253 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-08-06T20:25:57.550Z,1565123157.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-08-06T20:25:57.550Z,1565123157.550 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-08-06T20:25:57.746Z,1565123157.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-08-06T20:25:57.747Z,1565123157.747 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-08-06T20:25:58.195Z,1565123158.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-08-06T20:25:58.196Z,1565123158.196 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-08-06T20:25:58.303Z,1565123158.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-08-06T20:25:58.304Z,1565123158.304 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-08-06T20:25:58.406Z,1565123158.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-08-06T20:25:58.406Z,1565123158.406 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-08-06T20:25:59.001Z,1565123159.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-08-06T20:25:59.001Z,1565123159.001 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-08-06T20:25:59.393Z,1565123159.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-08-06T20:25:59.394Z,1565123159.394 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-08-06T20:25:59.601Z,1565123159.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-08-06T20:25:59.601Z,1565123159.601 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-08-06T20:25:59.748Z,1565123159.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-08-06T20:25:59.748Z,1565123159.748 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-08-06T20:25:59.899Z,1565123159.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-08-06T20:25:59.901Z,1565123159.901 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-08-06T20:25:59.902Z,1565123159.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-08-06T20:25:59.996Z,1565123159.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-08-06T20:26:00.085Z,1565123160.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-08-06T20:26:00.190Z,1565123160.190 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-08-06T20:26:00.284Z,1565123160.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-08-06T20:26:00.579Z,1565123160.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-08-06T20:26:00.877Z,1565123160.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-08-06T20:26:01.301Z,1565123161.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-08-06T20:26:01.383Z,1565123161.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-08-06T20:26:01.562Z,1565123161.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-08-06T20:26:01.699Z,1565123161.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-08-06T20:26:01.821Z,1565123161.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-08-06T20:26:02.049Z,1565123162.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-08-06T20:26:02.050Z,1565123162.050 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-08-06T20:26:02.051Z,1565123162.051 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-08-06T20:26:02.072Z,1565123162.072 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-08-06T20:26:02.135Z,1565123162.135 [VerticalControl](DEBUG): Construct VerticalControl.
2019-08-06T20:26:02.243Z,1565123162.243 [VerticalControl] Loaded
2019-08-06T20:26:02.244Z,1565123162.244 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-08-06T20:26:02.244Z,1565123162.244 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-08-06T20:26:02.310Z,1565123162.310 [HorizontalControl] Loaded
2019-08-06T20:26:02.310Z,1565123162.310 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-08-06T20:26:02.311Z,1565123162.311 [SpeedControl](DEBUG): Construct SpeedControl.
2019-08-06T20:26:02.316Z,1565123162.316 [SpeedControl] Loaded
2019-08-06T20:26:02.317Z,1565123162.317 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-08-06T20:26:02.318Z,1565123162.318 [LoopControl](DEBUG): Construct LoopControl.
2019-08-06T20:26:02.318Z,1565123162.318 [LoopControl] Loaded
2019-08-06T20:26:02.318Z,1565123162.318 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-08-06T20:26:02.319Z,1565123162.319 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-08-06T20:26:02.319Z,1565123162.319 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-08-06T20:26:02.332Z,1565123162.332 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-08-06T20:26:02.333Z,1565123162.333 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-08-06T20:26:02.425Z,1565123162.425 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-08-06T20:26:02.426Z,1565123162.426 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-08-06T20:26:02.549Z,1565123162.549 [BuoyancyServo] Loaded
2019-08-06T20:26:02.549Z,1565123162.549 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-08-06T20:26:02.560Z,1565123162.560 [ElevatorServo] Loaded
2019-08-06T20:26:02.560Z,1565123162.560 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-08-06T20:26:02.571Z,1565123162.571 [MassServo] Loaded
2019-08-06T20:26:02.571Z,1565123162.571 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-08-06T20:26:02.582Z,1565123162.582 [RudderServo] Loaded
2019-08-06T20:26:02.582Z,1565123162.582 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-08-06T20:26:02.593Z,1565123162.593 [ThrusterServo] Loaded
2019-08-06T20:26:02.593Z,1565123162.593 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-08-06T20:26:02.593Z,1565123162.593 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-08-06T20:26:02.594Z,1565123162.594 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-08-06T20:26:02.697Z,1565123162.697 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-08-06T20:26:02.697Z,1565123162.697 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-08-06T20:26:02.717Z,1565123162.717 [NavChart] Loaded
2019-08-06T20:26:02.718Z,1565123162.718 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-08-06T20:26:02.722Z,1565123162.722 [UniversalFixResidualReporter] Loaded
2019-08-06T20:26:02.722Z,1565123162.722 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-08-06T20:26:02.722Z,1565123162.722 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-08-06T20:26:02.723Z,1565123162.723 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-08-06T20:26:02.789Z,1565123162.789 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-08-06T20:26:02.790Z,1565123162.790 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-08-06T20:26:03.104Z,1565123163.104 [DataOverHttps] Loaded
2019-08-06T20:26:03.104Z,1565123163.104 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-08-06T20:26:03.106Z,1565123163.106 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0
2019-08-06T20:26:03.106Z,1565123163.106 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4537
2019-08-06T20:26:03.119Z,1565123163.119 [Depth_Keller] Loaded
2019-08-06T20:26:03.119Z,1565123163.119 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-08-06T20:26:03.212Z,1565123163.212 [NAL9602] Loaded
2019-08-06T20:26:03.212Z,1565123163.212 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-08-06T20:26:03.228Z,1565123163.228 [Onboard] Loaded
2019-08-06T20:26:03.228Z,1565123163.228 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-08-06T20:26:03.232Z,1565123163.232 [Radio_Surface] Loaded
2019-08-06T20:26:03.232Z,1565123163.232 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-08-06T20:26:03.233Z,1565123163.233 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0
2019-08-06T20:26:03.233Z,1565123163.233 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4538
2019-08-06T20:26:04.676Z,1565123164.676 [BPC1] Loaded
2019-08-06T20:26:04.676Z,1565123164.676 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-08-06T20:26:04.676Z,1565123164.676 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-08-06T20:26:04.677Z,1565123164.677 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-08-06T20:26:04.716Z,1565123164.716 [DepthRateCalculator] Loaded
2019-08-06T20:26:04.716Z,1565123164.716 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-08-06T20:26:04.721Z,1565123164.721 [PitchRateCalculator] Loaded
2019-08-06T20:26:04.722Z,1565123164.722 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-08-06T20:26:04.733Z,1565123164.733 [SpeedCalculator] Loaded
2019-08-06T20:26:04.733Z,1565123164.733 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-08-06T20:26:04.754Z,1565123164.754 [TempGradientCalculator] Loaded
2019-08-06T20:26:04.754Z,1565123164.754 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-08-06T20:26:04.759Z,1565123164.759 [YawRateCalculator] Loaded
2019-08-06T20:26:04.759Z,1565123164.759 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-08-06T20:26:04.788Z,1565123164.788 [ElevatorOffsetCalculator] Loaded
2019-08-06T20:26:04.788Z,1565123164.788 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-08-06T20:26:04.789Z,1565123164.789 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-08-06T20:26:04.789Z,1565123164.789 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-08-06T20:26:04.955Z,1565123164.955 [CANONSampler] Loaded
2019-08-06T20:26:04.955Z,1565123164.955 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2019-08-06T20:26:04.969Z,1565123164.969 [PAR_Licor] Loaded
2019-08-06T20:26:04.969Z,1565123164.969 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-08-06T20:26:04.970Z,1565123164.970 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-08-06T20:26:04.971Z,1565123164.971 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-08-06T20:26:05.001Z,1565123165.001 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-08-06T20:26:05.002Z,1565123165.002 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-08-06T20:26:05.276Z,1565123165.276 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-08-06T20:26:05.276Z,1565123165.276 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-08-06T20:26:05.533Z,1565123165.533 [SBIT](DEBUG): Construct Startup Built In Test.
2019-08-06T20:26:05.544Z,1565123165.544 [SBIT] Loaded
2019-08-06T20:26:05.544Z,1565123165.544 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-08-06T20:26:05.545Z,1565123165.545 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-08-06T20:26:05.556Z,1565123165.556 [IBIT] Loaded
2019-08-06T20:26:05.557Z,1565123165.557 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-08-06T20:26:05.560Z,1565123165.560 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-08-06T20:26:05.693Z,1565123165.693 [CBIT] Loaded
2019-08-06T20:26:05.694Z,1565123165.694 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-08-06T20:26:05.694Z,1565123165.694 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-08-06T20:26:05.698Z,1565123165.698 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-08-06T20:26:05.698Z,1565123165.698 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-08-06T20:26:05.705Z,1565123165.705 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-08-06T20:26:05.706Z,1565123165.706 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2019-08-06T20:26:05.706Z,1565123165.706 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4539
2019-08-06T20:26:05.711Z,1565123165.711 [Supervisor](INFO): Main Thread ID is 3901
2019-08-06T20:26:05.711Z,1565123165.711 [Supervisor](DEBUG): Running supervisor.
2019-08-06T20:26:05.712Z,1565123165.712 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4540
2019-08-06T20:26:05.715Z,1565123165.715 [controlThread ThreadHandler](INFO): Handler Thread ID is 4541
2019-08-06T20:26:05.716Z,1565123165.716 [controlThread](DEBUG): Initializing ControlThread
2019-08-06T20:26:05.716Z,1565123165.716 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-08-06T20:26:05.718Z,1565123165.718 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-08-06T20:26:05.719Z,1565123165.719 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-08-06T20:26:05.719Z,1565123165.719 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-08-06T20:26:05.721Z,1565123165.721 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-08-06T20:26:05.721Z,1565123165.721 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-08-06T20:26:05.724Z,1565123165.724 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-08-06T20:26:05.724Z,1565123165.724 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-08-06T20:26:05.725Z,1565123165.725 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-08-06T20:26:05.725Z,1565123165.725 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-08-06T20:26:05.725Z,1565123165.725 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-08-06T20:26:05.726Z,1565123165.726 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-08-06T20:26:05.727Z,1565123165.727 [SBIT](INFO): Initialize SBIT Component.
2019-08-06T20:26:05.728Z,1565123165.728 [SBIT](IMPORTANT): git: 2019-07-26-1-g6ad825a
2019-08-06T20:26:05.728Z,1565123165.728 [SBIT](INFO): git hash: 6ad825a153e96bf2505e39e5df4d606652139ef6
2019-08-06T20:26:05.728Z,1565123165.728 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-08-06T20:26:05.729Z,1565123165.729 [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-06T20:26:05.730Z,1565123165.730 [SBIT](INFO): Beginning SBIT in 32.000000 seconds.
2019-08-06T20:26:05.731Z,1565123165.731 [IBIT](INFO): Initialize IBIT Component.
2019-08-06T20:26:05.732Z,1565123165.732 [CBIT](DEBUG): Initialize CBIT Component.
2019-08-06T20:26:05.733Z,1565123165.733 [logger ThreadHandler](INFO): Handler Thread ID is 4542
2019-08-06T20:26:05.743Z,1565123165.743 [CBIT](DEBUG): Initialized mux pins.
2019-08-06T20:26:05.743Z,1565123165.743 [CBIT](DEBUG): Initializing the watchdog timer.
2019-08-06T20:26:05.751Z,1565123165.751 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4543
2019-08-06T20:26:05.752Z,1565123165.752 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-08-06T20:26:05.763Z,1565123165.763 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4544
2019-08-06T20:26:05.767Z,1565123165.767 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-08-06T20:26:05.767Z,1565123165.767 [CBIT](DEBUG): Initializing heartbeat.
2019-08-06T20:26:05.775Z,1565123165.775 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4545
2019-08-06T20:26:05.779Z,1565123165.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-08-06T20:26:05.779Z,1565123165.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-08-06T20:26:05.779Z,1565123165.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-08-06T20:26:05.779Z,1565123165.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-08-06T20:26:05.779Z,1565123165.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-08-06T20:26:05.780Z,1565123165.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-08-06T20:26:05.780Z,1565123165.780 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-08-06T20:26:05.780Z,1565123165.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-08-06T20:26:05.780Z,1565123165.780 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-08-06T20:26:05.780Z,1565123165.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-08-06T20:26:05.780Z,1565123165.780 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-08-06T20:26:05.781Z,1565123165.781 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-08-06T20:26:05.781Z,1565123165.781 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-08-06T20:26:05.781Z,1565123165.781 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-08-06T20:26:05.781Z,1565123165.781 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-08-06T20:26:05.781Z,1565123165.781 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-08-06T20:26:05.839Z,1565123165.839 [CBIT](DEBUG): Deactivating GF circuits.
2019-08-06T20:26:05.839Z,1565123165.839 [CBIT](DEBUG): Deactivating emergency mode.
2019-08-06T20:26:05.875Z,1565123165.875 [CBIT](DEBUG): Backplane powered.
2019-08-06T20:26:05.876Z,1565123165.876 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-08-06T20:26:05.886Z,1565123165.886 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-06T20:26:05.897Z,1565123165.897 [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-06T20:26:05.897Z,1565123165.897 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-08-06T20:26:05.964Z,1565123165.964 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-08-06T20:26:05.966Z,1565123165.966 [Default:A.Wait](DEBUG): Construct Wait.
2019-08-06T20:26:05.983Z,1565123165.983 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-06T20:26:06.010Z,1565123166.010 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-08-06T20:26:06.029Z,1565123166.029 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-08-06T20:26:06.034Z,1565123166.034 [Default:E.Execute](DEBUG): Construct Execute.
2019-08-06T20:26:06.053Z,1565123166.053 [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-06T20:26:06.058Z,1565123166.058 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,CANONSampler,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-06T20:26:06.112Z,1565123166.112 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-08-06T20:26:06.159Z,1565123166.159 [CANONSampler](INFO): Powering down
2019-08-06T20:26:06.167Z,1565123166.167 [Radio_Surface](INFO): Powering up
2019-08-06T20:26:06.333Z,1565123166.333 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-08-06T20:26:06.356Z,1565123166.356 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-08-06T20:26:06.363Z,1565123166.363 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-08-06T20:26:06.364Z,1565123166.364 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-08-06T20:26:06.371Z,1565123166.371 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-08-06T20:26:06.372Z,1565123166.372 [MassServo](DEBUG): Initializing EZServoServo.
2019-08-06T20:26:06.379Z,1565123166.379 [MassServo](DEBUG): Initializing MassServo.
2019-08-06T20:26:06.380Z,1565123166.380 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-06T20:26:06.387Z,1565123166.387 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-06T20:26:06.388Z,1565123166.388 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-08-06T20:26:06.395Z,1565123166.395 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-08-06T20:26:07.227Z,1565123167.227 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-08-06T20:26:07.227Z,1565123167.227 [RudderServo](FAULT): Rudder failed to initialize
2019-08-06T20:26:07.227Z,1565123167.227 [RudderServo] Communications Fault, FailCount= 1
2019-08-06T20:26:07.227Z,1565123167.227 [RudderServo](ERROR): Communications Fault
2019-08-06T20:26:07.362Z,1565123167.362 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-08-06T20:26:07.580Z,1565123167.580 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-06T20:26:07.580Z,1565123167.580 [RudderServo](INFO): Powering down
2019-08-06T20:26:08.249Z,1565123168.249 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-06T20:26:08.368Z,1565123168.368 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-06T20:26:08.372Z,1565123168.372 [CBIT](INFO): Clearing failed state for component RudderServo
2019-08-06T20:26:08.372Z,1565123168.372 [RudderServo] No Fault, FailCount= 1
2019-08-06T20:26:33.225Z,1565123193.225 [NAL9602](INFO): Powering up NAL9602
2019-08-06T20:26:38.196Z,1565123198.196 [SBIT](IMPORTANT): Beginning Startup BIT
2019-08-06T20:26:38.223Z,1565123198.223 [CBIT](IMPORTANT): Beginning ground fault scan
2019-08-06T20:26:44.264Z,1565123204.264 [NAL9602](INFO): NAL9602 initialized
2019-08-06T20:26:45.087Z,1565123205.087 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:26:48.795Z,1565123208.795 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006273
CHAN A1 (24V): 4.592929
CHAN A2 (12V): -0.002312
CHAN A3 (5V): -0.001436
CHAN B0 (3.3V): -0.000098
CHAN B1 (3.15aV): -0.000132
CHAN B2 (3.15bV): -0.000146
CHAN B3 (GND): 0.000070
OPEN: -0.000132
Full Scale Calc: 4.765 mA, -1.589 mA
2019-08-06T20:27:31.834Z,1565123251.834 [SBIT](IMPORTANT): SBIT PASSED
2019-08-06T20:27:31.924Z,1565123251.924 [CommandLine](IMPORTANT): got command configSet list
2019-08-06T20:27:31.925Z,1565123251.925 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-08-06T20:27:31.925Z,1565123251.925 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=6 hour;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): CBIT.abortDepth=296 meter;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): CBIT.stopDepth=288 meter;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-08-06T20:27:31.926Z,1565123251.926 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-08-06T20:27:31.927Z,1565123251.927 [CommandLine](IMPORTANT): DAT.ignoreElevationAngle=1 bool;

2019-08-06T20:27:31.927Z,1565123251.927 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-08-06T20:27:31.927Z,1565123251.927 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-08-06T20:27:31.927Z,1565123251.927 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-08-06T20:27:31.927Z,1565123251.927 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2019-08-06T20:27:31.927Z,1565123251.927 [CommandLine](IMPORTANT): Express linearApproximation DAT.range 5.000000 meter;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=274 cubic_centimeter;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): VerticalControl.massDefault=-3 millimeter;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-31 millimeter;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter;

2019-08-06T20:27:31.928Z,1565123251.928 [CommandLine](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2019-08-06T20:27:32.248Z,1565123252.248 [MissionManager](IMPORTANT): Started mission Startup
2019-08-06T20:27:32.248Z,1565123252.248 [Startup] Running Loop=1
2019-08-06T20:27:32.248Z,1565123252.248 [Startup](DEBUG): Aggregate::initialize Startup
2019-08-06T20:27:32.248Z,1565123252.248 [Startup:A.GoToSurface] Running Loop=1
2019-08-06T20:27:32.249Z,1565123252.249 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-06T20:27:32.249Z,1565123252.249 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-06T20:27:32.250Z,1565123252.250 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-06T20:27:32.250Z,1565123252.250 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-06T20:27:32.250Z,1565123252.250 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-06T20:27:32.255Z,1565123252.255 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-06T20:27:32.256Z,1565123252.256 [Startup:StartupSatComms] Running Loop=1
2019-08-06T20:27:32.256Z,1565123252.256 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-08-06T20:27:32.257Z,1565123252.257 [Startup:StartupSatComms:A] Running Loop=1
2019-08-06T20:27:32.648Z,1565123252.648 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-08-06T20:28:05.767Z,1565123285.767 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:28:05.767Z,1565123285.767 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T20:28:05.767Z,1565123285.767 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:28:05.777Z,1565123285.777 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:28:06.184Z,1565123286.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:28:06.184Z,1565123286.184 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T20:28:21.093Z,1565123301.093 [NAL9602](INFO): SBD MO Status=2, MOMSN=15847, MT Status=2, MTMSN=0
2019-08-06T20:28:21.094Z,1565123301.094 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T20:28:32.431Z,1565123312.431 [Startup:StartupSatComms:A](INFO): Timed out from 2019-08-06T20:27:32.3Z
2019-08-06T20:28:32.431Z,1565123312.431 [Startup:StartupSatComms:A] Stopped
2019-08-06T20:28:32.431Z,1565123312.431 [Startup:StartupSatComms:B] Running Loop=1
2019-08-06T20:28:32.837Z,1565123312.837 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-06T20:28:55.438Z,1565123335.438 [NAL9602](INFO): SBD MO Status=2, MOMSN=15847, MT Status=2, MTMSN=0
2019-08-06T20:28:55.438Z,1565123335.438 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T20:29:22.506Z,1565123362.506 [NAL9602](INFO): SBD MO Status=2, MOMSN=15847, MT Status=2, MTMSN=0
2019-08-06T20:29:22.506Z,1565123362.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T20:29:32.626Z,1565123372.626 [Startup:StartupSatComms:B](INFO): Timed out from 2019-08-06T20:28:32.4Z
2019-08-06T20:29:32.626Z,1565123372.626 [Startup:StartupSatComms:B] Stopped
2019-08-06T20:29:32.626Z,1565123372.626 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-08-06T20:29:32.626Z,1565123372.626 [Startup:StartupSatComms] Stopped
2019-08-06T20:29:32.626Z,1565123372.626 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-08-06T20:29:32.627Z,1565123372.627 [Startup](INFO): Completed Startup
2019-08-06T20:29:32.627Z,1565123372.627 [MissionManager](INFO): Startup is completed.
2019-08-06T20:29:32.627Z,1565123372.627 [MissionManager](INFO): Uninitializing Mission Startup
2019-08-06T20:29:32.627Z,1565123372.627 [Startup] Stopped
2019-08-06T20:29:32.627Z,1565123372.627 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-08-06T20:29:32.627Z,1565123372.627 [Startup:A.GoToSurface] Stopped
2019-08-06T20:29:32.627Z,1565123372.627 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-06T20:29:33.032Z,1565123373.032 [MissionManager](IMPORTANT): Started mission Default
2019-08-06T20:29:33.032Z,1565123373.032 [Default] Running Loop=1
2019-08-06T20:29:33.032Z,1565123373.032 [Default](DEBUG): Aggregate::initialize Default
2019-08-06T20:29:33.032Z,1565123373.032 [Default:B.GoToSurface] Running Loop=1
2019-08-06T20:29:33.032Z,1565123373.032 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-06T20:29:33.033Z,1565123373.033 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-06T20:29:33.033Z,1565123373.033 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-06T20:29:33.033Z,1565123373.033 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-06T20:29:33.034Z,1565123373.034 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-06T20:29:33.034Z,1565123373.034 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-06T20:29:33.034Z,1565123373.034 [Default:A.Wait] Running Loop=1
2019-08-06T20:29:33.034Z,1565123373.034 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-06T20:29:43.934Z,1565123383.934 [NAL9602](INFO): SBD MO Status=2, MOMSN=15847, MT Status=2, MTMSN=0
2019-08-06T20:29:43.934Z,1565123383.934 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T20:29:46.460Z,1565123386.460 [Default:A.Wait](INFO): Done Waiting.
2019-08-06T20:29:46.460Z,1565123386.460 [Default:A.Wait] Stopped
2019-08-06T20:29:46.460Z,1565123386.460 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T20:29:46.872Z,1565123386.872 [Default:CheckIn] Running Loop=1
2019-08-06T20:29:46.872Z,1565123386.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T20:29:46.872Z,1565123386.872 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T20:29:47.217Z,1565123387.217 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-08-06T20:29:48.337Z,1565123388.337 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004893
2019-08-06T20:29:48.405Z,1565123388.405 [NAL9602](INFO): SBD MO Status=2, MOMSN=15847, MT Status=2, MTMSN=0
2019-08-06T20:29:48.406Z,1565123388.406 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T20:30:06.605Z,1565123406.605 [NAL9602](INFO): SBD MO Status=0, MOMSN=15847, MT Status=0, MTMSN=0
2019-08-06T20:30:06.606Z,1565123406.606 [NAL9602](INFO): No messages in MT queue
2019-08-06T20:30:07.035Z,1565123407.035 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:30:07.035Z,1565123407.035 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T20:30:07.035Z,1565123407.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:30:07.045Z,1565123407.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:30:07.423Z,1565123407.423 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:07.516Z,1565123407.516 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:30:07.516Z,1565123407.516 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T20:30:10.255Z,1565123410.255 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:13.483Z,1565123413.483 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:16.319Z,1565123416.319 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:19.543Z,1565123419.543 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:22.371Z,1565123422.371 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:25.203Z,1565123425.203 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:28.431Z,1565123428.431 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:31.263Z,1565123431.263 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:34.495Z,1565123434.495 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:37.319Z,1565123437.319 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:40.086Z,1565123440.086 [CommandLine](INFO): End of History
2019-08-06T20:30:40.108Z,1565123440.108 [CommandLine](INFO): End of History
2019-08-06T20:30:40.142Z,1565123440.142 [CommandLine](INFO): End of History
2019-08-06T20:30:40.222Z,1565123440.222 [CommandLine](INFO): End of History
2019-08-06T20:30:40.245Z,1565123440.245 [CommandLine](INFO): End of History
2019-08-06T20:30:40.302Z,1565123440.302 [CommandLine](INFO): End of History
2019-08-06T20:30:40.326Z,1565123440.326 [CommandLine](INFO): End of History
2019-08-06T20:30:40.348Z,1565123440.348 [CommandLine](INFO): End of History
2019-08-06T20:30:40.397Z,1565123440.397 [CommandLine](INFO): End of History
2019-08-06T20:30:40.419Z,1565123440.419 [CommandLine](INFO): End of History
2019-08-06T20:30:40.441Z,1565123440.441 [CommandLine](INFO): End of History
2019-08-06T20:30:40.490Z,1565123440.490 [CommandLine](INFO): End of History
2019-08-06T20:30:40.512Z,1565123440.512 [CommandLine](INFO): End of History
2019-08-06T20:30:40.544Z,1565123440.544 [CommandLine](INFO): End of History
2019-08-06T20:30:40.551Z,1565123440.551 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:43.382Z,1565123443.382 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:46.211Z,1565123446.211 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:49.443Z,1565123449.443 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:51.247Z,1565123451.247 [CommandLine](IMPORTANT): got command configSet list
2019-08-06T20:30:51.248Z,1565123451.248 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-08-06T20:30:51.248Z,1565123451.248 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=6 hour;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): CBIT.abortDepth=296 meter;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): CBIT.stopDepth=288 meter;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-08-06T20:30:51.249Z,1565123451.249 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): DAT.ignoreElevationAngle=1 bool;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): Express linearApproximation DAT.range 5.000000 meter;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2019-08-06T20:30:51.250Z,1565123451.250 [CommandLine](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2019-08-06T20:30:51.251Z,1565123451.251 [CommandLine](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2019-08-06T20:30:51.251Z,1565123451.251 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-08-06T20:30:51.251Z,1565123451.251 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=274 cubic_centimeter;

2019-08-06T20:30:51.251Z,1565123451.251 [CommandLine](IMPORTANT): VerticalControl.massDefault=-3 millimeter;

2019-08-06T20:30:51.251Z,1565123451.251 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-31 millimeter;

2019-08-06T20:30:51.251Z,1565123451.251 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter;

2019-08-06T20:30:51.251Z,1565123451.251 [CommandLine](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2019-08-06T20:30:52.267Z,1565123452.267 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:55.495Z,1565123455.495 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:58.327Z,1565123458.327 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:30:58.387Z,1565123458.387 [CommandLine](INFO): End of History
2019-08-06T20:30:58.441Z,1565123458.441 [CommandLine](INFO): End of History
2019-08-06T20:30:58.464Z,1565123458.464 [CommandLine](INFO): End of History
2019-08-06T20:31:01.563Z,1565123461.563 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:01.822Z,1565123461.822 [CommandLine](INFO): End of History
2019-08-06T20:31:01.857Z,1565123461.857 [CommandLine](INFO): End of History
2019-08-06T20:31:01.879Z,1565123461.879 [CommandLine](INFO): End of History
2019-08-06T20:31:01.903Z,1565123461.903 [CommandLine](INFO): End of History
2019-08-06T20:31:01.926Z,1565123461.926 [CommandLine](INFO): End of History
2019-08-06T20:31:02.021Z,1565123462.021 [CommandLine](INFO): End of History
2019-08-06T20:31:02.086Z,1565123462.086 [CommandLine](INFO): End of History
2019-08-06T20:31:02.129Z,1565123462.129 [CommandLine](INFO): End of History
2019-08-06T20:31:02.190Z,1565123462.190 [CommandLine](INFO): End of History
2019-08-06T20:31:02.214Z,1565123462.214 [CommandLine](INFO): End of History
2019-08-06T20:31:02.236Z,1565123462.236 [CommandLine](INFO): End of History
2019-08-06T20:31:02.298Z,1565123462.298 [CommandLine](INFO): End of History
2019-08-06T20:31:02.320Z,1565123462.320 [CommandLine](INFO): End of History
2019-08-06T20:31:02.343Z,1565123462.343 [CommandLine](INFO): End of History
2019-08-06T20:31:02.439Z,1565123462.439 [CommandLine](INFO): End of History
2019-08-06T20:31:02.497Z,1565123462.497 [CommandLine](INFO): End of History
2019-08-06T20:31:02.550Z,1565123462.550 [CommandLine](INFO): End of History
2019-08-06T20:31:02.584Z,1565123462.584 [CommandLine](INFO): End of History
2019-08-06T20:31:02.607Z,1565123462.607 [CommandLine](INFO): End of History
2019-08-06T20:31:02.629Z,1565123462.629 [CommandLine](INFO): End of History
2019-08-06T20:31:02.651Z,1565123462.651 [CommandLine](INFO): End of History
2019-08-06T20:31:02.697Z,1565123462.697 [CommandLine](INFO): End of History
2019-08-06T20:31:02.729Z,1565123462.729 [CommandLine](INFO): End of History
2019-08-06T20:31:02.766Z,1565123462.766 [CommandLine](INFO): End of History
2019-08-06T20:31:02.848Z,1565123462.848 [CommandLine](INFO): End of History
2019-08-06T20:31:04.066Z,1565123464.066 [CommandLine](INFO): End of History
2019-08-06T20:31:04.091Z,1565123464.091 [CommandLine](INFO): End of History
2019-08-06T20:31:04.117Z,1565123464.117 [CommandLine](INFO): End of History
2019-08-06T20:31:04.179Z,1565123464.179 [CommandLine](INFO): End of History
2019-08-06T20:31:04.221Z,1565123464.221 [CommandLine](INFO): End of History
2019-08-06T20:31:04.254Z,1565123464.254 [CommandLine](INFO): End of History
2019-08-06T20:31:04.276Z,1565123464.276 [CommandLine](INFO): End of History
2019-08-06T20:31:04.299Z,1565123464.299 [CommandLine](INFO): End of History
2019-08-06T20:31:04.321Z,1565123464.321 [CommandLine](INFO): End of History
2019-08-06T20:31:04.386Z,1565123464.386 [CommandLine](INFO): End of History
2019-08-06T20:31:04.387Z,1565123464.387 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:04.466Z,1565123464.466 [CommandLine](INFO): End of History
2019-08-06T20:31:04.513Z,1565123464.513 [CommandLine](INFO): End of History
2019-08-06T20:31:04.566Z,1565123464.566 [CommandLine](INFO): End of History
2019-08-06T20:31:04.610Z,1565123464.610 [CommandLine](INFO): End of History
2019-08-06T20:31:04.633Z,1565123464.633 [CommandLine](INFO): End of History
2019-08-06T20:31:04.673Z,1565123464.673 [CommandLine](INFO): End of History
2019-08-06T20:31:04.695Z,1565123464.695 [CommandLine](INFO): End of History
2019-08-06T20:31:04.717Z,1565123464.717 [CommandLine](INFO): End of History
2019-08-06T20:31:04.740Z,1565123464.740 [CommandLine](INFO): End of History
2019-08-06T20:31:04.822Z,1565123464.822 [CommandLine](INFO): End of History
2019-08-06T20:31:04.890Z,1565123464.890 [CommandLine](INFO): End of History
2019-08-06T20:31:04.914Z,1565123464.914 [CommandLine](INFO): End of History
2019-08-06T20:31:04.936Z,1565123464.936 [CommandLine](INFO): End of History
2019-08-06T20:31:04.996Z,1565123464.996 [CommandLine](INFO): End of History
2019-08-06T20:31:05.018Z,1565123465.018 [CommandLine](INFO): End of History
2019-08-06T20:31:05.040Z,1565123465.040 [CommandLine](INFO): End of History
2019-08-06T20:31:05.089Z,1565123465.089 [CommandLine](INFO): End of History
2019-08-06T20:31:05.112Z,1565123465.112 [CommandLine](INFO): End of History
2019-08-06T20:31:05.134Z,1565123465.134 [CommandLine](INFO): End of History
2019-08-06T20:31:05.156Z,1565123465.156 [CommandLine](INFO): End of History
2019-08-06T20:31:05.260Z,1565123465.260 [CommandLine](INFO): End of History
2019-08-06T20:31:05.311Z,1565123465.311 [CommandLine](INFO): End of History
2019-08-06T20:31:05.334Z,1565123465.334 [CommandLine](INFO): End of History
2019-08-06T20:31:05.357Z,1565123465.357 [CommandLine](INFO): End of History
2019-08-06T20:31:05.401Z,1565123465.401 [CommandLine](INFO): End of History
2019-08-06T20:31:05.429Z,1565123465.429 [CommandLine](INFO): End of History
2019-08-06T20:31:05.451Z,1565123465.451 [CommandLine](INFO): End of History
2019-08-06T20:31:05.474Z,1565123465.474 [CommandLine](INFO): End of History
2019-08-06T20:31:05.528Z,1565123465.528 [CommandLine](INFO): End of History
2019-08-06T20:31:05.550Z,1565123465.550 [CommandLine](INFO): End of History
2019-08-06T20:31:05.573Z,1565123465.573 [CommandLine](INFO): End of History
2019-08-06T20:31:05.675Z,1565123465.675 [CommandLine](INFO): End of History
2019-08-06T20:31:05.717Z,1565123465.717 [CommandLine](INFO): End of History
2019-08-06T20:31:05.740Z,1565123465.740 [CommandLine](INFO): End of History
2019-08-06T20:31:05.764Z,1565123465.764 [CommandLine](INFO): End of History
2019-08-06T20:31:05.786Z,1565123465.786 [CommandLine](INFO): End of History
2019-08-06T20:31:05.839Z,1565123465.839 [CommandLine](INFO): End of History
2019-08-06T20:31:05.863Z,1565123465.863 [CommandLine](INFO): End of History
2019-08-06T20:31:05.885Z,1565123465.885 [CommandLine](INFO): End of History
2019-08-06T20:31:05.933Z,1565123465.933 [CommandLine](INFO): End of History
2019-08-06T20:31:07.615Z,1565123467.615 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:10.451Z,1565123470.451 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:13.275Z,1565123473.275 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:16.510Z,1565123476.510 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:19.339Z,1565123479.339 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:22.571Z,1565123482.571 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:25.395Z,1565123485.395 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:28.627Z,1565123488.627 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:31.455Z,1565123491.455 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:34.687Z,1565123494.687 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:37.519Z,1565123497.519 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:40.343Z,1565123500.343 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:43.578Z,1565123503.578 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:46.403Z,1565123506.403 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:49.639Z,1565123509.639 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:52.467Z,1565123512.467 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:55.695Z,1565123515.695 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:31:56.090Z,1565123516.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201621.00,A,3648.16491,N,12147.27473,W,0.544,145.29,060819,,,A*74
2019-08-06T20:31:56.093Z,1565123516.093 [NAL9602](INFO): GPS fix at 20190806T201621: (36.802748, -121.787912)
2019-08-06T20:31:56.269Z,1565123516.269 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T20:31:56.269Z,1565123516.269 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T20:31:56.538Z,1565123516.538 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-06T20:31:57.239Z,1565123517.239 [CommandLine](IMPORTANT): got command configSet list
2019-08-06T20:31:57.239Z,1565123517.239 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-08-06T20:31:57.301Z,1565123517.301 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-08-06T20:31:57.301Z,1565123517.301 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-08-06T20:31:57.301Z,1565123517.301 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=0 count;

2019-08-06T20:31:57.301Z,1565123517.301 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=6 hour;

2019-08-06T20:31:57.301Z,1565123517.301 [CommandLine](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2019-08-06T20:31:57.301Z,1565123517.301 [CommandLine](IMPORTANT): CBIT.abortDepth=296 meter;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): CBIT.stopDepth=288 meter;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): DAT.ignoreElevationAngle=1 bool;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-08-06T20:31:57.302Z,1565123517.302 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2019-08-06T20:31:57.303Z,1565123517.303 [CommandLine](IMPORTANT): Express linearApproximation DAT.range 5.000000 meter;

2019-08-06T20:31:57.319Z,1565123517.319 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2019-08-06T20:31:57.319Z,1565123517.319 [CommandLine](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2019-08-06T20:31:57.319Z,1565123517.319 [CommandLine](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2019-08-06T20:31:57.319Z,1565123517.319 [CommandLine](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2019-08-06T20:31:57.320Z,1565123517.320 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-08-06T20:31:57.320Z,1565123517.320 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=274 cubic_centimeter;

2019-08-06T20:31:57.320Z,1565123517.320 [CommandLine](IMPORTANT): VerticalControl.massDefault=-3 millimeter;

2019-08-06T20:31:57.320Z,1565123517.320 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-31 millimeter;

2019-08-06T20:31:57.320Z,1565123517.320 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter;

2019-08-06T20:31:57.320Z,1565123517.320 [CommandLine](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2019-08-06T20:32:08.246Z,1565123528.246 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:32:08.246Z,1565123528.246 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T20:32:08.246Z,1565123528.246 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:32:08.268Z,1565123528.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:32:08.659Z,1565123528.659 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:32:08.659Z,1565123528.659 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T20:32:13.770Z,1565123533.770 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190806T173145/Courier0065.lzma
2019-08-06T20:32:14.577Z,1565123534.577 [DataOverHttps](INFO): Moved sent file to Logs/20190806T173145/Courier0065.lzma.bak
2019-08-06T20:32:14.577Z,1565123534.577 [DataOverHttps](INFO): SBD MOMSN=11528929
2019-08-06T20:32:25.851Z,1565123545.851 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20190806T202556/Courier0004.lzma
2019-08-06T20:32:26.656Z,1565123546.656 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0004.lzma.bak
2019-08-06T20:32:26.657Z,1565123546.657 [DataOverHttps](INFO): SBD MOMSN=11528931
2019-08-06T20:32:28.396Z,1565123548.396 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T20:32:38.582Z,1565123558.582 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190806T173145/Express0067.lzma
2019-08-06T20:32:39.389Z,1565123559.389 [DataOverHttps](INFO): Moved sent file to Logs/20190806T173145/Express0067.lzma.bak
2019-08-06T20:32:39.389Z,1565123559.389 [DataOverHttps](INFO): SBD MOMSN=11528935
2019-08-06T20:32:51.484Z,1565123571.484 [DataOverHttps](INFO): Sending 1159 bytes from file Logs/20190806T202556/Express0001.lzma
2019-08-06T20:32:52.288Z,1565123572.288 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0001.lzma.bak
2019-08-06T20:32:52.288Z,1565123572.288 [DataOverHttps](INFO): SBD MOMSN=11528938
2019-08-06T20:33:03.940Z,1565123583.940 [DataOverHttps](INFO): Sending 806 bytes from file Logs/20190806T202556/Express0005.lzma
2019-08-06T20:33:04.744Z,1565123584.744 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0005.lzma.bak
2019-08-06T20:33:04.745Z,1565123584.745 [DataOverHttps](INFO): SBD MOMSN=11528978
2019-08-06T20:33:16.331Z,1565123596.331 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20190806T202556/Express0006.lzma
2019-08-06T20:33:17.136Z,1565123597.136 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0006.lzma.bak
2019-08-06T20:33:17.137Z,1565123597.137 [DataOverHttps](INFO): SBD MOMSN=11529010
2019-08-06T20:33:18.709Z,1565123598.709 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T20:33:18.710Z,1565123598.710 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T20:33:18.710Z,1565123598.710 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T20:33:36.378Z,1565123616.378 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-08-06T20:33:36.381Z,1565123616.381 [BPC1](INFO): Received data from all battery sticks.
2019-08-06T20:34:09.119Z,1565123649.119 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:34:09.119Z,1565123649.119 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T20:34:09.119Z,1565123649.119 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:34:09.130Z,1565123649.130 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:34:09.543Z,1565123649.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:34:09.543Z,1565123649.543 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T20:34:30.737Z,1565123670.737 [DataOverHttps](IMPORTANT): SBD MTMSN=20190806T201855
2019-08-06T20:34:35.499Z,1565123675.499 [DataOverHttps](INFO): Received command:load Maintenance/sample.xml;set sample.NumberOfSamples 15 count;set sample.WaitBeforeSample 10 second;set sample:SampleAtDepth.TargetDepth 7 meter;set sample:SampleAtDepth.MaxWaitNotReachingDepth 10 minute;run 
2019-08-06T20:34:35.512Z,1565123675.512 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml
2019-08-06T20:34:35.513Z,1565123675.513 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
2019-08-06T20:34:35.537Z,1565123675.537 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
2019-08-06T20:34:35.540Z,1565123675.540 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
2019-08-06T20:34:35.543Z,1565123675.543 [MissionManager](INFO): DefineArg sample.CartridgeType = -1.000000 count
2019-08-06T20:34:35.545Z,1565123675.545 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count
2019-08-06T20:34:35.548Z,1565123675.548 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min
2019-08-06T20:34:35.549Z,1565123675.549 [sample:A.AbortSample](DEBUG): Construct.
2019-08-06T20:34:35.554Z,1565123675.554 [sample:B.Pitch](DEBUG): Construct.
2019-08-06T20:34:35.617Z,1565123675.617 [sample:C.SetSpeed](DEBUG): Construct.
2019-08-06T20:34:35.619Z,1565123675.619 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
2019-08-06T20:34:35.807Z,1565123675.807 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
2019-08-06T20:34:35.828Z,1565123675.828 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 10.000000 s
2019-08-06T20:34:35.831Z,1565123675.831 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
2019-08-06T20:34:35.834Z,1565123675.834 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
2019-08-06T20:34:35.838Z,1565123675.838 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 1.000000 bool
2019-08-06T20:34:35.841Z,1565123675.841 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 0.000000 bool
2019-08-06T20:34:35.845Z,1565123675.845 [MissionManager](INFO): DefineArg sample:SampleAtDepth.ESPCartridgeType = nan count
2019-08-06T20:34:35.872Z,1565123675.872 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2019-08-06T20:34:35.875Z,1565123675.875 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2019-08-06T20:34:35.879Z,1565123675.879 [MissionManager](INFO): DefineArg sample:SampleAtDepth.BuoyancyNeutral = 273.999991 cc
2019-08-06T20:34:35.881Z,1565123675.881 [sample:SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Construct.
2019-08-06T20:34:35.904Z,1565123675.904 [sample:SampleAtDepth:ApproachDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-08-06T20:34:35.906Z,1565123675.906 [sample:SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Construct.
2019-08-06T20:34:35.936Z,1565123675.936 [sample:SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Construct Wait.
2019-08-06T20:34:35.972Z,1565123675.972 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-08-06T20:34:35.974Z,1565123675.974 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-08-06T20:34:35.975Z,1565123675.975 [MissionManager](ERROR): Slate does not contain celsius
2019-08-06T20:34:35.975Z,1565123675.975 [MissionManager](ERROR): Slate does not contain WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-08-06T20:34:35.975Z,1565123675.975 [MissionManager](ERROR): Slate does not contain microgram_per_liter
2019-08-06T20:34:35.999Z,1565123675.999 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-08-06T20:34:36.003Z,1565123676.003 [MissionManager](CRITICAL): Slate does not contain ESPComponent.cartridge
2019-08-06T20:34:36.003Z,1565123676.003 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](CRITICAL): Error!!!! no component for child behavior.
2019-08-06T20:34:36.006Z,1565123676.006 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-08-06T20:34:36.006Z,1565123676.006 [MissionManager](ERROR): Slate does not contain celsius
2019-08-06T20:34:36.006Z,1565123676.006 [MissionManager](ERROR): Slate does not contain WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-08-06T20:34:36.007Z,1565123676.007 [MissionManager](ERROR): Slate does not contain microgram_per_liter
2019-08-06T20:34:36.053Z,1565123676.053 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="sample">

    <Description>
        This mission is designed to be run in the test tank only and samples
        whichever sampler is installed once the vehicle reaches the target
        depth.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth to sample at.
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="CartridgeType"><Description>
        Specifies ESP cartridge type code.
    </Description><Units:count/><Value>-1</Value></DefineArg>
    
    <DefineArg Name="NumberOfSamples"><Description>
        Number of samples to take.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="WaitBeforeSample"><Description>
        How long to wait between samples. This starts *before* the first sample
        to exercise the SettleTime setting in the insert aggregate.
    </Description><Units:minute/><Value>3</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

     <Trigger:AbortSample>
     	  <Parallel/>
     </Trigger:AbortSample>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <Insert Filename="Insert/SampleAtDepth.xml"/>

    <Assign><Sequence/><Arg Name="SampleAtDepth:TargetDepth"/><Arg Name="Depth"/></Assign>

    <Assign><Sequence/><Arg Name="SampleAtDepth:SettleTime"/><Arg Name="WaitBeforeSample"/></Assign>

    <Assign><Sequence/><Arg Name="SampleAtDepth:ESPCartridgeType"/><Arg Name="CartridgeType"/></Assign>

    <Aggregate Id="SampleRepeater">

        <Sequence Repeat="NumberOfSamples"/>

        <Call Id="Sample" RefId="SampleAtDepth"/>

    </Aggregate>

</Mission>


2019-08-06T20:34:36.054Z,1565123676.054 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml
2019-08-06T20:34:42.984Z,1565123682.984 [CommandLine](IMPORTANT): got command set sample.NumberOfSamples 15.000000 count
2019-08-06T20:34:42.985Z,1565123682.985 [CommandLine](IMPORTANT): got command set sample.WaitBeforeSample 10.000000 second
2019-08-06T20:34:42.986Z,1565123682.986 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.TargetDepth 7.000000 meter
2019-08-06T20:34:42.987Z,1565123682.987 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.MaxWaitNotReachingDepth 10.000000 minute
2019-08-06T20:34:42.987Z,1565123682.987 [CommandLine](IMPORTANT): got command run 
2019-08-06T20:34:42.989Z,1565123682.989 [CommandLine](IMPORTANT): Running 
2019-08-06T20:34:42.995Z,1565123682.995 [CommandLine](FAULT): Scheduling is paused
2019-08-06T20:34:42.995Z,1565123682.995 [CBIT](INFO): Critical error at 20190806T203436
2019-08-06T20:34:42.996Z,1565123682.996 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-08-06T20:34:43.119Z,1565123683.119 [Default] Stopped
2019-08-06T20:34:43.120Z,1565123683.120 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-06T20:34:43.120Z,1565123683.120 [Default:B.GoToSurface] Stopped
2019-08-06T20:34:43.120Z,1565123683.120 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-06T20:34:43.120Z,1565123683.120 [Default:CheckIn] Stopped
2019-08-06T20:34:43.120Z,1565123683.120 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T20:34:43.120Z,1565123683.120 [Default:CheckIn:C.Wait] Stopped
2019-08-06T20:34:43.120Z,1565123683.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T20:34:43.120Z,1565123683.120 [MissionManager](IMPORTANT): Started mission sample
2019-08-06T20:34:43.121Z,1565123683.121 [sample] Running Loop=1
2019-08-06T20:34:43.121Z,1565123683.121 [sample](DEBUG): Aggregate::initialize sample
2019-08-06T20:34:43.121Z,1565123683.121 [sample:A.AbortSample] Running Loop=1
2019-08-06T20:34:43.121Z,1565123683.121 [sample:A.AbortSample](INFO): Initializing AbortSample.
2019-08-06T20:34:43.121Z,1565123683.121 [sample:B.Pitch] Running Loop=1
2019-08-06T20:34:43.121Z,1565123683.121 [sample:B.Pitch](DEBUG): Initialize.
2019-08-06T20:34:43.121Z,1565123683.121 [sample:C.SetSpeed] Running Loop=1
2019-08-06T20:34:43.121Z,1565123683.121 [sample:C.SetSpeed](DEBUG): Initialize.
2019-08-06T20:34:43.122Z,1565123683.122 [sample:E] Running Loop=1
2019-08-06T20:34:43.122Z,1565123683.122 [sample:E] Stopped
2019-08-06T20:34:43.122Z,1565123683.122 [sample:F] Running Loop=1
2019-08-06T20:34:43.122Z,1565123683.122 [sample:C.SetSpeed] Running Loop=1
2019-08-06T20:34:43.122Z,1565123683.122 [sample:B.Pitch] Running Loop=1
2019-08-06T20:34:43.123Z,1565123683.123 [sample:A.AbortSample] Running Loop=1
2019-08-06T20:34:43.123Z,1565123683.123 [MissionManager](INFO): MissionManager is completed.
2019-08-06T20:34:43.123Z,1565123683.123 [MissionManager](INFO): Uninitializing Mission sample
2019-08-06T20:34:43.123Z,1565123683.123 [sample] Stopped
2019-08-06T20:34:43.123Z,1565123683.123 [sample](DEBUG): Aggregate::uninitialize sample
2019-08-06T20:34:43.123Z,1565123683.123 [sample:A.AbortSample] Stopped
2019-08-06T20:34:43.123Z,1565123683.123 [sample:A.AbortSample](INFO): Uninitializing AbortSample.
2019-08-06T20:34:43.124Z,1565123683.124 [sample:B.Pitch] Stopped
2019-08-06T20:34:43.124Z,1565123683.124 [sample:C.SetSpeed] Stopped
2019-08-06T20:34:43.124Z,1565123683.124 [sample:C.SetSpeed](DEBUG): Uninitialize.
2019-08-06T20:34:43.124Z,1565123683.124 [sample:F] Stopped
2019-08-06T20:34:43.433Z,1565123683.433 [MissionManager](IMPORTANT): Started mission Default
2019-08-06T20:34:43.433Z,1565123683.433 [Default] Running Loop=1
2019-08-06T20:34:43.433Z,1565123683.433 [Default](DEBUG): Aggregate::initialize Default
2019-08-06T20:34:43.433Z,1565123683.433 [Default:B.GoToSurface] Running Loop=1
2019-08-06T20:34:43.433Z,1565123683.433 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-06T20:34:43.433Z,1565123683.433 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-06T20:34:43.434Z,1565123683.434 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-06T20:34:43.434Z,1565123683.434 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-06T20:34:43.434Z,1565123683.434 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-06T20:34:43.435Z,1565123683.435 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-06T20:34:43.435Z,1565123683.435 [Default:A.Wait] Running Loop=1
2019-08-06T20:34:43.435Z,1565123683.435 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-06T20:34:56.761Z,1565123696.761 [Default:A.Wait](INFO): Done Waiting.
2019-08-06T20:34:56.762Z,1565123696.762 [Default:A.Wait] Stopped
2019-08-06T20:34:56.762Z,1565123696.762 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T20:34:57.178Z,1565123697.178 [Default:CheckIn] Running Loop=1
2019-08-06T20:34:57.178Z,1565123697.178 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T20:34:57.178Z,1565123697.178 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T20:34:58.771Z,1565123698.771 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:34:59.169Z,1565123699.169 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201924.00,A,3648.16595,N,12147.28076,W,0.564,145.29,060819,,,A*77
2019-08-06T20:34:59.172Z,1565123699.172 [NAL9602](INFO): GPS fix at 20190806T201924: (36.802766, -121.788013)
2019-08-06T20:34:59.198Z,1565123699.198 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T20:34:59.198Z,1565123699.198 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T20:35:05.112Z,1565123705.112 [DataOverHttps](INFO): Sending 362 bytes from file Logs/20190806T202556/Courier0007.lzma
2019-08-06T20:35:05.916Z,1565123705.916 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0007.lzma.bak
2019-08-06T20:35:05.916Z,1565123705.916 [DataOverHttps](INFO): SBD MOMSN=11529018
2019-08-06T20:35:16.798Z,1565123716.798 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190806T202556/Courier0010.lzma
2019-08-06T20:35:17.604Z,1565123717.604 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0010.lzma.bak
2019-08-06T20:35:17.605Z,1565123717.605 [DataOverHttps](INFO): SBD MOMSN=11529024
2019-08-06T20:35:29.256Z,1565123729.256 [DataOverHttps](INFO): Sending 902 bytes from file Logs/20190806T202556/Express0008.lzma
2019-08-06T20:35:30.060Z,1565123730.060 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0008.lzma.bak
2019-08-06T20:35:30.061Z,1565123730.061 [DataOverHttps](INFO): SBD MOMSN=11529026
2019-08-06T20:35:41.694Z,1565123741.694 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190806T202556/Express0012.lzma
2019-08-06T20:35:42.500Z,1565123742.500 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0012.lzma.bak
2019-08-06T20:35:42.501Z,1565123742.501 [DataOverHttps](INFO): SBD MOMSN=11529066
2019-08-06T20:35:44.074Z,1565123744.074 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T20:35:44.074Z,1565123744.074 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T20:35:44.074Z,1565123744.074 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T20:36:10.308Z,1565123770.308 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:36:10.308Z,1565123770.308 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T20:36:10.308Z,1565123770.308 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:36:10.319Z,1565123770.319 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:36:10.734Z,1565123770.734 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:36:10.734Z,1565123770.734 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T20:38:11.500Z,1565123891.500 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:38:11.500Z,1565123891.500 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T20:38:11.500Z,1565123891.500 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:38:11.527Z,1565123891.527 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:38:11.926Z,1565123891.926 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:38:11.926Z,1565123891.926 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T20:38:14.713Z,1565123894.713 [NAL9602](INFO): SBD MO Status=0, MOMSN=15848, MT Status=0, MTMSN=0
2019-08-06T20:38:14.713Z,1565123894.713 [NAL9602](INFO): No messages in MT queue
2019-08-06T20:38:45.408Z,1565123925.408 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T20:40:12.319Z,1565124012.319 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:40:12.319Z,1565124012.319 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T20:40:12.319Z,1565124012.319 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:40:12.330Z,1565124012.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:40:12.727Z,1565124012.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:40:12.727Z,1565124012.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T20:40:44.633Z,1565124044.633 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T20:40:44.633Z,1565124044.633 [Default:CheckIn:C.Wait] Stopped
2019-08-06T20:40:44.633Z,1565124044.633 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T20:40:44.634Z,1565124044.634 [Default:CheckIn:D] Running Loop=1
2019-08-06T20:40:45.045Z,1565124045.045 [Default:CheckIn:D] Stopped
2019-08-06T20:40:45.046Z,1565124045.046 [Default:CheckIn:E] Running Loop=1
2019-08-06T20:40:45.441Z,1565124045.441 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.026874 min 
2019-08-06T20:40:45.441Z,1565124045.441 [Default:CheckIn:E] Stopped
2019-08-06T20:40:45.442Z,1565124045.442 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T20:40:45.442Z,1565124045.442 [Default:CheckIn] Stopped
2019-08-06T20:40:45.442Z,1565124045.442 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T20:40:45.442Z,1565124045.442 [Default:CheckIn](INFO): Running loop #2
2019-08-06T20:40:45.442Z,1565124045.442 [Default:CheckIn] Running Loop=2
2019-08-06T20:40:45.442Z,1565124045.442 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T20:40:45.442Z,1565124045.442 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T20:40:47.051Z,1565124047.051 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:40:47.446Z,1565124047.446 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202511.00,A,3648.16717,N,12147.28185,W,0.292,167.10,060819,,,A*7F
2019-08-06T20:40:47.448Z,1565124047.448 [NAL9602](INFO): GPS fix at 20190806T202511: (36.802786, -121.788031)
2019-08-06T20:40:47.492Z,1565124047.492 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T20:40:47.493Z,1565124047.493 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T20:40:53.470Z,1565124053.470 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190806T202556/Courier0011.lzma
2019-08-06T20:40:54.276Z,1565124054.276 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0011.lzma.bak
2019-08-06T20:40:54.277Z,1565124054.277 [DataOverHttps](INFO): SBD MOMSN=11529069
2019-08-06T20:41:06.974Z,1565124066.974 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190806T202556/Express0013.lzma
2019-08-06T20:41:07.784Z,1565124067.784 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0013.lzma.bak
2019-08-06T20:41:07.785Z,1565124067.785 [DataOverHttps](INFO): SBD MOMSN=11529071
2019-08-06T20:41:09.308Z,1565124069.308 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T20:41:09.308Z,1565124069.308 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T20:41:09.308Z,1565124069.308 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T20:41:26.637Z,1565124086.637 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T20:41:26.637Z,1565124086.637 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T20:42:13.523Z,1565124133.523 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:42:13.523Z,1565124133.523 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T20:42:13.523Z,1565124133.523 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:42:13.534Z,1565124133.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:42:13.931Z,1565124133.931 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:42:13.931Z,1565124133.931 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T20:42:21.177Z,1565124141.177 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T20:42:21.177Z,1565124141.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T20:42:53.901Z,1565124173.901 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T20:42:53.901Z,1565124173.901 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T20:44:14.723Z,1565124254.723 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:44:14.724Z,1565124254.724 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T20:44:14.724Z,1565124254.724 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:44:14.734Z,1565124254.734 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:44:15.132Z,1565124255.132 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:44:15.132Z,1565124255.132 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T20:45:50.040Z,1565124350.040 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-06T20:46:09.861Z,1565124369.861 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T20:46:09.861Z,1565124369.861 [Default:CheckIn:C.Wait] Stopped
2019-08-06T20:46:09.861Z,1565124369.861 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T20:46:09.862Z,1565124369.862 [Default:CheckIn:D] Running Loop=1
2019-08-06T20:46:10.261Z,1565124370.261 [Default:CheckIn:D] Stopped
2019-08-06T20:46:10.261Z,1565124370.261 [Default:CheckIn:E] Running Loop=1
2019-08-06T20:46:10.664Z,1565124370.664 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.447139 min 
2019-08-06T20:46:10.664Z,1565124370.664 [Default:CheckIn:E] Stopped
2019-08-06T20:46:10.664Z,1565124370.664 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T20:46:10.665Z,1565124370.665 [Default:CheckIn] Stopped
2019-08-06T20:46:10.665Z,1565124370.665 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T20:46:10.665Z,1565124370.665 [Default:CheckIn](INFO): Running loop #3
2019-08-06T20:46:10.665Z,1565124370.665 [Default:CheckIn] Running Loop=3
2019-08-06T20:46:10.665Z,1565124370.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T20:46:10.665Z,1565124370.665 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T20:46:12.271Z,1565124372.271 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:46:12.662Z,1565124372.662 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203036.00,A,3648.16896,N,12147.27928,W,0.136,162.66,060819,,,A*71
2019-08-06T20:46:12.664Z,1565124372.664 [NAL9602](INFO): GPS fix at 20190806T203036: (36.802816, -121.787988)
2019-08-06T20:46:12.687Z,1565124372.687 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T20:46:12.687Z,1565124372.687 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T20:46:15.594Z,1565124375.594 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:46:15.594Z,1565124375.594 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T20:46:15.594Z,1565124375.594 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:46:15.616Z,1565124375.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:46:16.004Z,1565124376.004 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:46:16.004Z,1565124376.004 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T20:46:18.366Z,1565124378.366 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190806T202556/Courier0016.lzma
2019-08-06T20:46:19.172Z,1565124379.172 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0016.lzma.bak
2019-08-06T20:46:19.173Z,1565124379.173 [DataOverHttps](INFO): SBD MOMSN=11529111
2019-08-06T20:46:30.571Z,1565124390.571 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20190806T202556/Express0017.lzma
2019-08-06T20:46:31.376Z,1565124391.376 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0017.lzma.bak
2019-08-06T20:46:31.377Z,1565124391.377 [DataOverHttps](INFO): SBD MOMSN=11529114
2019-08-06T20:46:32.980Z,1565124392.980 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T20:46:32.980Z,1565124392.980 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T20:46:32.980Z,1565124392.980 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T20:46:44.640Z,1565124404.640 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T20:48:16.771Z,1565124496.771 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:48:16.771Z,1565124496.771 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T20:48:16.771Z,1565124496.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:48:16.781Z,1565124496.781 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:48:17.199Z,1565124497.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:48:17.199Z,1565124497.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T20:50:17.974Z,1565124617.974 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:50:17.974Z,1565124617.974 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T20:50:17.975Z,1565124617.975 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:50:17.985Z,1565124617.985 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:50:18.399Z,1565124618.399 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:50:18.399Z,1565124618.399 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T20:51:33.534Z,1565124693.534 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T20:51:33.534Z,1565124693.534 [Default:CheckIn:C.Wait] Stopped
2019-08-06T20:51:33.534Z,1565124693.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T20:51:33.535Z,1565124693.535 [Default:CheckIn:D] Running Loop=1
2019-08-06T20:51:33.925Z,1565124693.925 [Default:CheckIn:D] Stopped
2019-08-06T20:51:33.925Z,1565124693.925 [Default:CheckIn:E] Running Loop=1
2019-08-06T20:51:34.337Z,1565124694.337 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.841536 min 
2019-08-06T20:51:34.338Z,1565124694.338 [Default:CheckIn:E] Stopped
2019-08-06T20:51:34.338Z,1565124694.338 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T20:51:34.338Z,1565124694.338 [Default:CheckIn] Stopped
2019-08-06T20:51:34.338Z,1565124694.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T20:51:34.338Z,1565124694.338 [Default:CheckIn](INFO): Running loop #4
2019-08-06T20:51:34.338Z,1565124694.338 [Default:CheckIn] Running Loop=4
2019-08-06T20:51:34.338Z,1565124694.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T20:51:34.338Z,1565124694.338 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T20:51:35.939Z,1565124695.939 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:51:36.335Z,1565124696.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203559.00,A,3648.16811,N,12147.28231,W,0.214,162.66,060819,,,A*7D
2019-08-06T20:51:36.337Z,1565124696.337 [NAL9602](INFO): GPS fix at 20190806T203559: (36.802802, -121.788038)
2019-08-06T20:51:36.366Z,1565124696.366 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T20:51:36.366Z,1565124696.366 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T20:51:42.202Z,1565124702.202 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0019.lzma
2019-08-06T20:51:43.008Z,1565124703.008 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0019.lzma.bak
2019-08-06T20:51:43.009Z,1565124703.009 [DataOverHttps](INFO): SBD MOMSN=11529119
2019-08-06T20:51:54.627Z,1565124714.627 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20190806T202556/Express0020.lzma
2019-08-06T20:51:55.432Z,1565124715.432 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0020.lzma.bak
2019-08-06T20:51:55.433Z,1565124715.433 [DataOverHttps](INFO): SBD MOMSN=11529122
2019-08-06T20:51:56.977Z,1565124716.977 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T20:51:56.977Z,1565124716.977 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T20:51:56.977Z,1565124716.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T20:52:07.036Z,1565124727.036 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-08-06T20:52:07.120Z,1565124727.120 [NAL9602](FAULT): received: 
+CSQ:0

OK849, 2, 0, 0, 0

OK
2019-08-06T20:52:07.120Z,1565124727.120 [NAL9602] Data Fault, FailCount= 1
2019-08-06T20:52:07.120Z,1565124727.120 [NAL9602](ERROR): Data Fault
2019-08-06T20:52:07.184Z,1565124727.184 [CBIT](ERROR): Data Fault in component: NAL9602
2019-08-06T20:52:07.440Z,1565124727.440 [NAL9602](INFO): Powering down
2019-08-06T20:52:07.844Z,1565124727.844 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2019-08-06T20:52:07.844Z,1565124727.844 [NAL9602] Hardware Fault, FailCount= 1
2019-08-06T20:52:07.844Z,1565124727.844 [NAL9602](ERROR): Hardware Fault
2019-08-06T20:52:08.316Z,1565124728.316 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-06T20:52:08.316Z,1565124728.316 [NAL9602] No Fault, FailCount= 1
2019-08-06T20:52:19.178Z,1565124739.178 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:52:19.178Z,1565124739.178 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T20:52:19.179Z,1565124739.179 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:52:19.233Z,1565124739.233 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:52:19.596Z,1565124739.596 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:52:19.596Z,1565124739.596 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T20:52:37.740Z,1565124757.740 [NAL9602](INFO): Powering up NAL9602
2019-08-06T20:52:48.648Z,1565124768.648 [NAL9602](INFO): NAL9602 initialized
2019-08-06T20:53:19.752Z,1565124799.752 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T20:54:20.382Z,1565124860.382 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:54:20.382Z,1565124860.382 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T20:54:20.382Z,1565124860.382 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:54:20.392Z,1565124860.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:54:20.803Z,1565124860.803 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:54:20.803Z,1565124860.803 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T20:56:21.571Z,1565124981.571 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:56:21.571Z,1565124981.571 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T20:56:21.571Z,1565124981.571 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:56:21.582Z,1565124981.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:56:22.017Z,1565124982.017 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:56:22.017Z,1565124982.017 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T20:56:57.529Z,1565125017.529 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T20:56:57.529Z,1565125017.529 [Default:CheckIn:C.Wait] Stopped
2019-08-06T20:56:57.530Z,1565125017.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T20:56:57.530Z,1565125017.530 [Default:CheckIn:D] Running Loop=1
2019-08-06T20:56:57.937Z,1565125017.937 [Default:CheckIn:D] Stopped
2019-08-06T20:56:57.937Z,1565125017.937 [Default:CheckIn:E] Running Loop=1
2019-08-06T20:56:58.346Z,1565125018.346 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.241728 min 
2019-08-06T20:56:58.346Z,1565125018.346 [Default:CheckIn:E] Stopped
2019-08-06T20:56:58.346Z,1565125018.346 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T20:56:58.346Z,1565125018.346 [Default:CheckIn] Stopped
2019-08-06T20:56:58.346Z,1565125018.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T20:56:58.346Z,1565125018.346 [Default:CheckIn](INFO): Running loop #5
2019-08-06T20:56:58.346Z,1565125018.346 [Default:CheckIn] Running Loop=5
2019-08-06T20:56:58.346Z,1565125018.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T20:56:58.347Z,1565125018.347 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T20:56:59.943Z,1565125019.943 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:02.360Z,1565125022.360 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-06T20:57:03.179Z,1565125023.179 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:06.003Z,1565125026.003 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:08.839Z,1565125028.839 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:11.663Z,1565125031.663 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:14.895Z,1565125034.895 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:17.723Z,1565125037.723 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:20.955Z,1565125040.955 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:23.787Z,1565125043.787 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:27.015Z,1565125047.015 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:29.847Z,1565125049.847 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:33.075Z,1565125053.075 [NAL9602](DEBUG): Fix Requested
2019-08-06T20:57:33.474Z,1565125053.474 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204156.00,A,3648.16755,N,12147.28011,W,0.019,0.00,060819,,,A*74
2019-08-06T20:57:33.476Z,1565125053.476 [NAL9602](INFO): GPS fix at 20190806T204156: (36.802793, -121.788002)
2019-08-06T20:57:33.498Z,1565125053.498 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T20:57:33.498Z,1565125053.498 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T20:57:39.154Z,1565125059.154 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190806T202556/Courier0022.lzma
2019-08-06T20:57:39.960Z,1565125059.960 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0022.lzma.bak
2019-08-06T20:57:39.961Z,1565125059.961 [DataOverHttps](INFO): SBD MOMSN=11529160
2019-08-06T20:57:51.355Z,1565125071.355 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20190806T202556/Express0023.lzma
2019-08-06T20:57:52.178Z,1565125072.178 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0023.lzma.bak
2019-08-06T20:57:52.178Z,1565125072.178 [DataOverHttps](INFO): SBD MOMSN=11529163
2019-08-06T20:57:53.716Z,1565125073.716 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T20:57:53.716Z,1565125073.716 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T20:57:53.716Z,1565125073.716 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T20:58:05.784Z,1565125085.784 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T20:58:22.771Z,1565125102.771 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T20:58:22.771Z,1565125102.771 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T20:58:22.771Z,1565125102.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T20:58:22.806Z,1565125102.806 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T20:58:23.213Z,1565125103.213 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T20:58:23.213Z,1565125103.213 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:00:23.973Z,1565125223.973 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:00:23.973Z,1565125223.973 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:00:23.973Z,1565125223.973 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:00:23.983Z,1565125223.983 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:00:24.404Z,1565125224.404 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:00:24.404Z,1565125224.404 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:02:24.780Z,1565125344.780 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:02:24.780Z,1565125344.780 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T21:02:24.780Z,1565125344.780 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:02:24.790Z,1565125344.790 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:02:25.197Z,1565125345.197 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:02:25.197Z,1565125345.197 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T21:02:54.285Z,1565125374.285 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:02:54.285Z,1565125374.285 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:02:54.285Z,1565125374.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:02:54.286Z,1565125374.286 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:02:54.695Z,1565125374.695 [Default:CheckIn:D] Stopped
2019-08-06T21:02:54.695Z,1565125374.695 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:02:55.081Z,1565125375.081 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.187703 min 
2019-08-06T21:02:55.082Z,1565125375.082 [Default:CheckIn:E] Stopped
2019-08-06T21:02:55.082Z,1565125375.082 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:02:55.082Z,1565125375.082 [Default:CheckIn] Stopped
2019-08-06T21:02:55.082Z,1565125375.082 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:02:55.082Z,1565125375.082 [Default:CheckIn](INFO): Running loop #6
2019-08-06T21:02:55.082Z,1565125375.082 [Default:CheckIn] Running Loop=6
2019-08-06T21:02:55.082Z,1565125375.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:02:55.082Z,1565125375.082 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:02:56.691Z,1565125376.691 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:02:57.090Z,1565125377.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204719.00,A,3648.16777,N,12147.28230,W,0.194,50.33,060819,,,A*49
2019-08-06T21:02:57.092Z,1565125377.092 [NAL9602](INFO): GPS fix at 20190806T204719: (36.802796, -121.788038)
2019-08-06T21:02:57.114Z,1565125377.114 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:02:57.114Z,1565125377.114 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:03:02.698Z,1565125382.698 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0025.lzma
2019-08-06T21:03:03.504Z,1565125383.504 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0025.lzma.bak
2019-08-06T21:03:03.504Z,1565125383.504 [DataOverHttps](INFO): SBD MOMSN=11529170
2019-08-06T21:03:14.870Z,1565125394.870 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20190806T202556/Express0026.lzma
2019-08-06T21:03:15.679Z,1565125395.679 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0026.lzma.bak
2019-08-06T21:03:15.679Z,1565125395.679 [DataOverHttps](INFO): SBD MOMSN=11529180
2019-08-06T21:03:17.344Z,1565125397.344 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:03:17.344Z,1565125397.344 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:03:17.344Z,1565125397.344 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:04:25.635Z,1565125465.635 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:04:25.635Z,1565125465.635 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:04:25.635Z,1565125465.635 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:04:25.646Z,1565125465.646 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:04:26.045Z,1565125466.045 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:04:26.045Z,1565125466.045 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:05:28.637Z,1565125528.637 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T21:05:28.638Z,1565125528.638 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:05:53.285Z,1565125553.285 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T21:05:53.285Z,1565125553.285 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:06:26.839Z,1565125586.839 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:06:26.839Z,1565125586.839 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:06:26.839Z,1565125586.839 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:06:26.850Z,1565125586.850 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:06:27.260Z,1565125587.260 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:06:27.260Z,1565125587.260 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:06:30.457Z,1565125590.457 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T21:06:30.457Z,1565125590.457 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:06:44.197Z,1565125604.197 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T21:06:44.197Z,1565125604.197 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:07:12.881Z,1565125632.881 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T21:07:12.881Z,1565125632.881 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:07:30.249Z,1565125650.249 [NAL9602](INFO): SBD MO Status=2, MOMSN=15849, MT Status=2, MTMSN=0
2019-08-06T21:07:30.249Z,1565125650.249 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:07:39.945Z,1565125659.945 [NAL9602](INFO): SBD MO Status=0, MOMSN=15849, MT Status=0, MTMSN=0
2019-08-06T21:07:39.945Z,1565125659.945 [NAL9602](INFO): No messages in MT queue
2019-08-06T21:08:10.672Z,1565125690.672 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T21:08:17.963Z,1565125697.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:08:17.963Z,1565125697.963 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:08:17.963Z,1565125697.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:08:17.964Z,1565125697.964 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:08:18.377Z,1565125698.377 [Default:CheckIn:D] Stopped
2019-08-06T21:08:18.377Z,1565125698.377 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:08:18.770Z,1565125698.770 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.582406 min 
2019-08-06T21:08:18.770Z,1565125698.770 [Default:CheckIn:E] Stopped
2019-08-06T21:08:18.770Z,1565125698.770 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:08:18.770Z,1565125698.770 [Default:CheckIn] Stopped
2019-08-06T21:08:18.770Z,1565125698.770 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:08:18.771Z,1565125698.771 [Default:CheckIn](INFO): Running loop #7
2019-08-06T21:08:18.783Z,1565125698.783 [Default:CheckIn] Running Loop=7
2019-08-06T21:08:18.783Z,1565125698.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:08:18.783Z,1565125698.783 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:08:20.379Z,1565125700.379 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:08:20.778Z,1565125700.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205242.00,A,3648.16726,N,12147.28230,W,0.136,129.73,060819,,,A*74
2019-08-06T21:08:20.780Z,1565125700.780 [NAL9602](INFO): GPS fix at 20190806T205242: (36.802788, -121.788038)
2019-08-06T21:08:20.802Z,1565125700.802 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:08:20.802Z,1565125700.802 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:08:27.659Z,1565125707.659 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:08:27.659Z,1565125707.659 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:08:27.659Z,1565125707.659 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:08:27.669Z,1565125707.669 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:08:27.910Z,1565125707.910 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190806T202556/Courier0028.lzma
2019-08-06T21:08:28.074Z,1565125708.074 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:08:28.074Z,1565125708.074 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:08:28.716Z,1565125708.716 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0028.lzma.bak
2019-08-06T21:08:28.717Z,1565125708.717 [DataOverHttps](INFO): SBD MOMSN=11529212
2019-08-06T21:08:31.683Z,1565125711.683 [NAL9602](INFO): SBD MO Status=2, MOMSN=15850, MT Status=2, MTMSN=0
2019-08-06T21:08:31.683Z,1565125711.683 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:08:40.379Z,1565125720.379 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20190806T202556/Express0029.lzma
2019-08-06T21:08:41.965Z,1565125721.965 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0029.lzma.bak
2019-08-06T21:08:41.966Z,1565125721.966 [DataOverHttps](INFO): SBD MOMSN=11529215
2019-08-06T21:08:43.448Z,1565125723.448 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:08:43.448Z,1565125723.448 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:08:43.448Z,1565125723.448 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:08:48.653Z,1565125728.653 [NAL9602](INFO): SBD MO Status=2, MOMSN=15850, MT Status=2, MTMSN=0
2019-08-06T21:08:48.653Z,1565125728.653 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:10:28.867Z,1565125828.867 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:10:28.867Z,1565125828.867 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:10:28.867Z,1565125828.867 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:10:28.878Z,1565125828.878 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:10:29.280Z,1565125829.280 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:10:29.280Z,1565125829.280 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:12:29.687Z,1565125949.687 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:12:29.687Z,1565125949.687 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T21:12:29.687Z,1565125949.687 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:12:29.698Z,1565125949.698 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:12:30.092Z,1565125950.092 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:12:30.092Z,1565125950.092 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T21:13:22.580Z,1565126002.580 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-06T21:13:44.005Z,1565126024.005 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:13:44.005Z,1565126024.005 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:13:44.005Z,1565126024.005 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:13:44.005Z,1565126024.005 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:13:44.417Z,1565126024.417 [Default:CheckIn:D] Stopped
2019-08-06T21:13:44.417Z,1565126024.417 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:13:44.813Z,1565126024.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.016402 min 
2019-08-06T21:13:44.814Z,1565126024.814 [Default:CheckIn:E] Stopped
2019-08-06T21:13:44.814Z,1565126024.814 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:13:44.814Z,1565126024.814 [Default:CheckIn] Stopped
2019-08-06T21:13:44.814Z,1565126024.814 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:13:44.814Z,1565126024.814 [Default:CheckIn](INFO): Running loop #8
2019-08-06T21:13:44.814Z,1565126024.814 [Default:CheckIn] Running Loop=8
2019-08-06T21:13:44.814Z,1565126024.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:13:44.814Z,1565126024.814 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:13:46.427Z,1565126026.427 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:13:46.821Z,1565126026.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205808.00,A,3648.16668,N,12147.28147,W,0.117,129.73,060819,,,A*7B
2019-08-06T21:13:46.824Z,1565126026.824 [NAL9602](INFO): GPS fix at 20190806T205808: (36.802778, -121.788025)
2019-08-06T21:13:46.846Z,1565126026.846 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:13:46.846Z,1565126026.846 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:13:58.230Z,1565126038.230 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190806T202556/Courier0031.lzma
2019-08-06T21:13:59.057Z,1565126039.057 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0031.lzma.bak
2019-08-06T21:13:59.057Z,1565126039.057 [DataOverHttps](INFO): SBD MOMSN=11529261
2019-08-06T21:14:13.515Z,1565126053.515 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20190806T202556/Express0032.lzma
2019-08-06T21:14:14.321Z,1565126054.321 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0032.lzma.bak
2019-08-06T21:14:14.321Z,1565126054.321 [DataOverHttps](INFO): SBD MOMSN=11529264
2019-08-06T21:14:15.981Z,1565126055.981 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:14:15.981Z,1565126055.981 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:14:15.981Z,1565126055.981 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:14:19.144Z,1565126059.144 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T21:14:30.875Z,1565126070.875 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:14:30.875Z,1565126070.875 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:14:30.875Z,1565126070.875 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:14:30.886Z,1565126070.886 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:14:31.300Z,1565126071.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:14:31.300Z,1565126071.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:16:32.079Z,1565126192.079 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:16:32.079Z,1565126192.079 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:16:32.079Z,1565126192.079 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:16:32.090Z,1565126192.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:16:32.504Z,1565126192.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:16:32.504Z,1565126192.504 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:18:32.895Z,1565126312.895 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:18:32.895Z,1565126312.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T21:18:32.895Z,1565126312.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:18:32.906Z,1565126312.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:18:33.311Z,1565126313.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:18:33.311Z,1565126313.311 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T21:19:16.525Z,1565126356.525 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:19:16.525Z,1565126356.525 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:19:16.525Z,1565126356.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:19:16.526Z,1565126356.526 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:19:16.924Z,1565126356.924 [Default:CheckIn:D] Stopped
2019-08-06T21:19:16.924Z,1565126356.924 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:19:17.329Z,1565126357.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.558183 min 
2019-08-06T21:19:17.329Z,1565126357.329 [Default:CheckIn:E] Stopped
2019-08-06T21:19:17.330Z,1565126357.330 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:19:17.330Z,1565126357.330 [Default:CheckIn] Stopped
2019-08-06T21:19:17.330Z,1565126357.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:19:17.330Z,1565126357.330 [Default:CheckIn](INFO): Running loop #9
2019-08-06T21:19:17.330Z,1565126357.330 [Default:CheckIn] Running Loop=9
2019-08-06T21:19:17.330Z,1565126357.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:19:17.330Z,1565126357.330 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:19:18.939Z,1565126358.939 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:19:19.330Z,1565126359.330 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210340.00,A,3648.16198,N,12147.28565,W,0.603,129.73,060819,,,A*76
2019-08-06T21:19:19.332Z,1565126359.332 [NAL9602](INFO): GPS fix at 20190806T210340: (36.802700, -121.788094)
2019-08-06T21:19:19.358Z,1565126359.358 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:19:19.358Z,1565126359.358 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:19:25.258Z,1565126365.258 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190806T202556/Courier0034.lzma
2019-08-06T21:19:26.064Z,1565126366.064 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0034.lzma.bak
2019-08-06T21:19:26.065Z,1565126366.065 [DataOverHttps](INFO): SBD MOMSN=11529270
2019-08-06T21:19:37.591Z,1565126377.591 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20190806T202556/Express0035.lzma
2019-08-06T21:19:38.397Z,1565126378.397 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0035.lzma.bak
2019-08-06T21:19:38.397Z,1565126378.397 [DataOverHttps](INFO): SBD MOMSN=11529273
2019-08-06T21:19:39.988Z,1565126379.988 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:19:39.988Z,1565126379.988 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:19:39.988Z,1565126379.988 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:19:50.032Z,1565126390.032 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-08-06T21:19:50.119Z,1565126390.119 [NAL9602](FAULT): received: 
+CSQ:0

OK850, 2, 0, 0, 0

OK
2019-08-06T21:19:50.119Z,1565126390.119 [NAL9602] Data Fault, FailCount= 1
2019-08-06T21:19:50.119Z,1565126390.119 [NAL9602](ERROR): Data Fault
2019-08-06T21:19:50.184Z,1565126390.184 [CBIT](ERROR): Data Fault in component: NAL9602
2019-08-06T21:19:50.436Z,1565126390.436 [NAL9602](INFO): Powering down
2019-08-06T21:19:51.271Z,1565126391.271 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-06T21:19:51.272Z,1565126391.272 [NAL9602] No Fault, FailCount= 1
2019-08-06T21:20:20.736Z,1565126420.736 [NAL9602](INFO): Powering up NAL9602
2019-08-06T21:20:31.644Z,1565126431.644 [NAL9602](INFO): NAL9602 initialized
2019-08-06T21:20:34.087Z,1565126434.087 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:20:34.087Z,1565126434.087 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:20:34.087Z,1565126434.087 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:20:34.098Z,1565126434.098 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:20:34.499Z,1565126434.499 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:20:34.500Z,1565126434.500 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:21:02.752Z,1565126462.752 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T21:22:35.291Z,1565126555.291 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:22:35.291Z,1565126555.291 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:22:35.291Z,1565126555.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:22:35.301Z,1565126555.301 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:22:35.699Z,1565126555.699 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:22:35.699Z,1565126555.699 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:24:36.493Z,1565126676.493 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:24:36.493Z,1565126676.493 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T21:24:36.493Z,1565126676.493 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:24:36.503Z,1565126676.503 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:24:36.904Z,1565126676.904 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:24:36.905Z,1565126676.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T21:24:40.525Z,1565126680.525 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:24:40.525Z,1565126680.525 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:24:40.525Z,1565126680.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:24:40.526Z,1565126680.526 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:24:40.933Z,1565126680.933 [Default:CheckIn:D] Stopped
2019-08-06T21:24:40.933Z,1565126680.933 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:24:41.338Z,1565126681.338 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.958325 min 
2019-08-06T21:24:41.338Z,1565126681.338 [Default:CheckIn:E] Stopped
2019-08-06T21:24:41.338Z,1565126681.338 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:24:41.338Z,1565126681.338 [Default:CheckIn] Stopped
2019-08-06T21:24:41.338Z,1565126681.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:24:41.338Z,1565126681.338 [Default:CheckIn](INFO): Running loop #10
2019-08-06T21:24:41.338Z,1565126681.338 [Default:CheckIn] Running Loop=10
2019-08-06T21:24:41.339Z,1565126681.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:24:41.339Z,1565126681.339 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:24:42.939Z,1565126682.939 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:24:43.339Z,1565126683.339 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210902.00,A,3648.16531,N,12147.28618,W,0.233,0.00,060819,,,A*7D
2019-08-06T21:24:43.341Z,1565126683.341 [NAL9602](INFO): GPS fix at 20190806T210902: (36.802755, -121.788103)
2019-08-06T21:24:43.366Z,1565126683.366 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:24:43.366Z,1565126683.366 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:24:44.952Z,1565126684.952 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-06T21:24:49.174Z,1565126689.174 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0037.lzma
2019-08-06T21:24:49.980Z,1565126689.980 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0037.lzma.bak
2019-08-06T21:24:49.981Z,1565126689.981 [DataOverHttps](INFO): SBD MOMSN=11529317
2019-08-06T21:25:01.495Z,1565126701.495 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20190806T202556/Express0038.lzma
2019-08-06T21:25:02.300Z,1565126702.300 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0038.lzma.bak
2019-08-06T21:25:02.301Z,1565126702.301 [DataOverHttps](INFO): SBD MOMSN=11529320
2019-08-06T21:25:03.999Z,1565126703.999 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:25:03.000Z,1565126704.000 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:25:03.000Z,1565126704.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:25:15.660Z,1565126715.660 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T21:26:37.691Z,1565126797.691 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:26:37.691Z,1565126797.691 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:26:37.691Z,1565126797.691 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:26:37.702Z,1565126797.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:26:38.134Z,1565126798.134 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:26:38.134Z,1565126798.134 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:28:38.892Z,1565126918.892 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:28:38.892Z,1565126918.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:28:38.893Z,1565126918.893 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:28:38.903Z,1565126918.903 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:28:39.316Z,1565126919.316 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:28:39.316Z,1565126919.316 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:30:04.561Z,1565127004.561 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:30:04.561Z,1565127004.561 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:30:04.561Z,1565127004.561 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:30:04.561Z,1565127004.561 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:30:04.953Z,1565127004.953 [Default:CheckIn:D] Stopped
2019-08-06T21:30:04.953Z,1565127004.953 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:30:05.345Z,1565127005.345 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.358671 min 
2019-08-06T21:30:05.345Z,1565127005.345 [Default:CheckIn:E] Stopped
2019-08-06T21:30:05.345Z,1565127005.345 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:30:05.345Z,1565127005.345 [Default:CheckIn] Stopped
2019-08-06T21:30:05.345Z,1565127005.345 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:30:05.345Z,1565127005.345 [Default:CheckIn](INFO): Running loop #11
2019-08-06T21:30:05.345Z,1565127005.345 [Default:CheckIn] Running Loop=11
2019-08-06T21:30:05.346Z,1565127005.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:30:05.346Z,1565127005.346 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:30:06.947Z,1565127006.947 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:30:07.346Z,1565127007.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211426.00,A,3648.16491,N,12147.28320,W,0.058,150.79,060819,,,A*77
2019-08-06T21:30:07.348Z,1565127007.348 [NAL9602](INFO): GPS fix at 20190806T211426: (36.802748, -121.788053)
2019-08-06T21:30:07.374Z,1565127007.374 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:30:07.374Z,1565127007.374 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:30:13.094Z,1565127013.094 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0040.lzma
2019-08-06T21:30:13.900Z,1565127013.900 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0040.lzma.bak
2019-08-06T21:30:13.901Z,1565127013.901 [DataOverHttps](INFO): SBD MOMSN=11529326
2019-08-06T21:30:25.287Z,1565127025.287 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20190806T202556/Express0041.lzma
2019-08-06T21:30:26.978Z,1565127026.978 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0041.lzma.bak
2019-08-06T21:30:26.978Z,1565127026.978 [DataOverHttps](INFO): SBD MOMSN=11529329
2019-08-06T21:30:28.373Z,1565127028.373 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:30:28.373Z,1565127028.373 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:30:28.373Z,1565127028.373 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:30:40.096Z,1565127040.096 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:30:40.096Z,1565127040.096 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:30:40.096Z,1565127040.096 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:30:40.107Z,1565127040.107 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:30:40.500Z,1565127040.500 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:30:40.500Z,1565127040.500 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:32:41.299Z,1565127161.299 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:32:41.299Z,1565127161.299 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:32:41.299Z,1565127161.299 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:32:41.310Z,1565127161.310 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:32:41.712Z,1565127161.712 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:32:41.712Z,1565127161.712 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:33:25.306Z,1565127205.306 [NAL9602](INFO): SBD MO Status=2, MOMSN=15850, MT Status=2, MTMSN=0
2019-08-06T21:33:25.306Z,1565127205.306 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:34:18.233Z,1565127258.233 [NAL9602](INFO): SBD MO Status=2, MOMSN=15850, MT Status=2, MTMSN=0
2019-08-06T21:34:18.233Z,1565127258.233 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-06T21:34:42.501Z,1565127282.501 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:34:42.501Z,1565127282.501 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T21:34:42.501Z,1565127282.501 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:34:42.512Z,1565127282.512 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:34:42.904Z,1565127282.904 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:34:42.904Z,1565127282.904 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T21:35:09.540Z,1565127309.540 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-06T21:35:28.958Z,1565127328.958 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:35:28.958Z,1565127328.958 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:35:28.959Z,1565127328.959 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:35:28.959Z,1565127328.959 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:35:29.362Z,1565127329.362 [Default:CheckIn:D] Stopped
2019-08-06T21:35:29.362Z,1565127329.362 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:35:29.766Z,1565127329.766 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.765479 min 
2019-08-06T21:35:29.766Z,1565127329.766 [Default:CheckIn:E] Stopped
2019-08-06T21:35:29.766Z,1565127329.766 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:35:29.766Z,1565127329.766 [Default:CheckIn] Stopped
2019-08-06T21:35:29.766Z,1565127329.766 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:35:29.766Z,1565127329.766 [Default:CheckIn](INFO): Running loop #12
2019-08-06T21:35:29.766Z,1565127329.766 [Default:CheckIn] Running Loop=12
2019-08-06T21:35:29.766Z,1565127329.766 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:35:29.767Z,1565127329.767 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:35:31.371Z,1565127331.371 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:35:31.762Z,1565127331.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211951.00,A,3648.16907,N,12147.28253,W,0.233,150.79,060819,,,A*72
2019-08-06T21:35:31.764Z,1565127331.764 [NAL9602](INFO): GPS fix at 20190806T211951: (36.802818, -121.788042)
2019-08-06T21:35:31.794Z,1565127331.794 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:35:31.794Z,1565127331.794 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:35:38.886Z,1565127338.886 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0043.lzma
2019-08-06T21:35:39.692Z,1565127339.692 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0043.lzma.bak
2019-08-06T21:35:39.693Z,1565127339.693 [DataOverHttps](INFO): SBD MOMSN=11529366
2019-08-06T21:35:52.843Z,1565127352.843 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20190806T202556/Express0044.lzma
2019-08-06T21:35:53.649Z,1565127353.649 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0044.lzma.bak
2019-08-06T21:35:53.649Z,1565127353.649 [DataOverHttps](INFO): SBD MOMSN=11529369
2019-08-06T21:35:55.307Z,1565127355.307 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:35:55.308Z,1565127355.308 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:35:55.308Z,1565127355.308 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:36:04.556Z,1565127364.556 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T21:36:43.363Z,1565127403.363 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:36:43.363Z,1565127403.363 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:36:43.363Z,1565127403.363 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:36:43.373Z,1565127403.373 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:36:43.791Z,1565127403.791 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:36:43.791Z,1565127403.791 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:38:44.183Z,1565127524.183 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:38:44.183Z,1565127524.183 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:38:44.183Z,1565127524.183 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:38:44.194Z,1565127524.194 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:38:44.592Z,1565127524.592 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:38:44.592Z,1565127524.592 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:40:45.375Z,1565127645.375 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:40:45.375Z,1565127645.375 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T21:40:45.376Z,1565127645.376 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:40:45.386Z,1565127645.386 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:40:45.796Z,1565127645.796 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:40:45.796Z,1565127645.796 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T21:40:55.889Z,1565127655.889 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:40:55.889Z,1565127655.889 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:40:55.889Z,1565127655.889 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:40:55.890Z,1565127655.890 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:40:56.289Z,1565127656.289 [Default:CheckIn:D] Stopped
2019-08-06T21:40:56.289Z,1565127656.289 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:40:56.697Z,1565127656.697 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.214258 min 
2019-08-06T21:40:56.698Z,1565127656.698 [Default:CheckIn:E] Stopped
2019-08-06T21:40:56.698Z,1565127656.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:40:56.698Z,1565127656.698 [Default:CheckIn] Stopped
2019-08-06T21:40:56.698Z,1565127656.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:40:56.698Z,1565127656.698 [Default:CheckIn](INFO): Running loop #13
2019-08-06T21:40:56.698Z,1565127656.698 [Default:CheckIn] Running Loop=13
2019-08-06T21:40:56.698Z,1565127656.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:40:56.698Z,1565127656.698 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:40:58.299Z,1565127658.299 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:40:58.698Z,1565127658.698 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212517.00,A,3648.16421,N,12147.28185,W,0.039,150.79,060819,,,A*76
2019-08-06T21:40:58.700Z,1565127658.700 [NAL9602](INFO): GPS fix at 20190806T212517: (36.802737, -121.788031)
2019-08-06T21:40:58.722Z,1565127658.722 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:40:58.722Z,1565127658.722 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:41:04.550Z,1565127664.550 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0046.lzma
2019-08-06T21:41:05.357Z,1565127665.357 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0046.lzma.bak
2019-08-06T21:41:05.357Z,1565127665.357 [DataOverHttps](INFO): SBD MOMSN=11529375
2019-08-06T21:41:16.987Z,1565127676.987 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20190806T202556/Express0047.lzma
2019-08-06T21:41:17.811Z,1565127677.811 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0047.lzma.bak
2019-08-06T21:41:17.811Z,1565127677.811 [DataOverHttps](INFO): SBD MOMSN=11529378
2019-08-06T21:41:19.340Z,1565127679.340 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:41:19.340Z,1565127679.340 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:41:19.340Z,1565127679.340 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:41:29.396Z,1565127689.396 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-08-06T21:41:29.479Z,1565127689.479 [NAL9602](FAULT): received: 
+CSQ:0

OK850, 2, 0, 0, 0

OK
2019-08-06T21:41:29.479Z,1565127689.479 [NAL9602] Data Fault, FailCount= 1
2019-08-06T21:41:29.479Z,1565127689.479 [NAL9602](ERROR): Data Fault
2019-08-06T21:41:29.529Z,1565127689.529 [CBIT](ERROR): Data Fault in component: NAL9602
2019-08-06T21:41:29.800Z,1565127689.800 [NAL9602](INFO): Powering down
2019-08-06T21:41:30.634Z,1565127690.634 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-06T21:41:30.634Z,1565127690.634 [NAL9602] No Fault, FailCount= 1
2019-08-06T21:42:00.100Z,1565127720.100 [NAL9602](INFO): Powering up NAL9602
2019-08-06T21:42:11.012Z,1565127731.012 [NAL9602](INFO): NAL9602 initialized
2019-08-06T21:42:42.120Z,1565127762.120 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T21:42:46.575Z,1565127766.575 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:42:46.576Z,1565127766.576 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:42:46.576Z,1565127766.576 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:42:46.610Z,1565127766.610 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:42:46.991Z,1565127766.991 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:42:46.991Z,1565127766.991 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:44:47.783Z,1565127887.783 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:44:47.783Z,1565127887.783 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:44:47.783Z,1565127887.783 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:44:47.793Z,1565127887.793 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:44:48.200Z,1565127888.200 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:44:48.200Z,1565127888.200 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:46:19.901Z,1565127979.901 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:46:19.901Z,1565127979.901 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:46:19.901Z,1565127979.901 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:46:19.901Z,1565127979.901 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:46:20.309Z,1565127980.309 [Default:CheckIn:D] Stopped
2019-08-06T21:46:20.309Z,1565127980.309 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:46:20.714Z,1565127980.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.614608 min 
2019-08-06T21:46:20.714Z,1565127980.714 [Default:CheckIn:E] Stopped
2019-08-06T21:46:20.715Z,1565127980.715 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:46:20.715Z,1565127980.715 [Default:CheckIn] Stopped
2019-08-06T21:46:20.715Z,1565127980.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:46:20.715Z,1565127980.715 [Default:CheckIn](INFO): Running loop #14
2019-08-06T21:46:20.715Z,1565127980.715 [Default:CheckIn] Running Loop=14
2019-08-06T21:46:20.715Z,1565127980.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:46:20.715Z,1565127980.715 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:46:22.315Z,1565127982.315 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:24.724Z,1565127984.724 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-06T21:46:25.547Z,1565127985.547 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:28.371Z,1565127988.371 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:31.195Z,1565127991.195 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:34.427Z,1565127994.427 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:37.259Z,1565127997.259 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:40.487Z,1565128000.487 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:43.319Z,1565128003.319 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:46.151Z,1565128006.151 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:48.575Z,1565128008.575 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:49.007Z,1565128009.007 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:46:49.007Z,1565128009.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T21:46:49.007Z,1565128009.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:46:49.054Z,1565128009.054 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:46:49.412Z,1565128009.412 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:46:49.412Z,1565128009.412 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T21:46:51.395Z,1565128011.395 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:54.635Z,1565128014.635 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:46:57.455Z,1565128017.455 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:00.691Z,1565128020.691 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:03.519Z,1565128023.519 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:06.347Z,1565128026.347 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:09.179Z,1565128029.179 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:12.411Z,1565128032.411 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:15.635Z,1565128035.635 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:18.470Z,1565128038.470 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:21.299Z,1565128041.299 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:24.527Z,1565128044.527 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:27.359Z,1565128047.359 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:30.587Z,1565128050.587 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:33.419Z,1565128053.419 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:36.647Z,1565128056.647 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:39.479Z,1565128059.479 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:42.307Z,1565128062.307 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:45.535Z,1565128065.535 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:47:45.933Z,1565128065.933 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213204.00,A,3648.15508,N,12147.28370,W,0.117,0.00,060819,,,A*74
2019-08-06T21:47:45.936Z,1565128065.936 [NAL9602](INFO): GPS fix at 20190806T213204: (36.802585, -121.788062)
2019-08-06T21:47:45.979Z,1565128065.979 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:47:45.980Z,1565128065.980 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:47:53.190Z,1565128073.190 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190806T202556/Courier0049.lzma
2019-08-06T21:47:53.996Z,1565128073.996 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0049.lzma.bak
2019-08-06T21:47:53.997Z,1565128073.997 [DataOverHttps](INFO): SBD MOMSN=11529414
2019-08-06T21:48:08.899Z,1565128088.899 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20190806T202556/Express0050.lzma
2019-08-06T21:48:09.705Z,1565128089.705 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0050.lzma.bak
2019-08-06T21:48:09.705Z,1565128089.705 [DataOverHttps](INFO): SBD MOMSN=11529417
2019-08-06T21:48:11.456Z,1565128091.456 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:48:11.456Z,1565128091.456 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:48:11.456Z,1565128091.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:48:18.272Z,1565128098.272 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T21:48:49.831Z,1565128129.831 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:48:49.831Z,1565128129.831 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:48:49.831Z,1565128129.831 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:48:49.852Z,1565128129.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:48:50.230Z,1565128130.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:48:50.231Z,1565128130.231 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:50:51.038Z,1565128251.038 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:50:51.038Z,1565128251.038 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:50:51.038Z,1565128251.038 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:50:51.085Z,1565128251.085 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:50:51.450Z,1565128251.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:50:51.451Z,1565128251.451 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:52:52.274Z,1565128372.274 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:52:52.274Z,1565128372.274 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T21:52:52.274Z,1565128372.274 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:52:52.285Z,1565128372.285 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:52:52.664Z,1565128372.664 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:52:52.664Z,1565128372.664 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T21:53:12.011Z,1565128392.011 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:53:12.011Z,1565128392.011 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:53:12.011Z,1565128392.011 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:53:12.011Z,1565128392.011 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:53:12.407Z,1565128392.407 [Default:CheckIn:D] Stopped
2019-08-06T21:53:12.407Z,1565128392.407 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:53:12.813Z,1565128392.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.482894 min 
2019-08-06T21:53:12.813Z,1565128392.813 [Default:CheckIn:E] Stopped
2019-08-06T21:53:12.813Z,1565128392.813 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:53:12.813Z,1565128392.813 [Default:CheckIn] Stopped
2019-08-06T21:53:12.814Z,1565128392.814 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:53:12.814Z,1565128392.814 [Default:CheckIn](INFO): Running loop #15
2019-08-06T21:53:12.814Z,1565128392.814 [Default:CheckIn] Running Loop=15
2019-08-06T21:53:12.814Z,1565128392.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:53:12.814Z,1565128392.814 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:53:14.419Z,1565128394.419 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:53:14.813Z,1565128394.813 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213732.00,A,3648.16378,N,12147.28181,W,0.097,167.17,060819,,,A*75
2019-08-06T21:53:14.815Z,1565128394.815 [NAL9602](INFO): GPS fix at 20190806T213732: (36.802730, -121.788030)
2019-08-06T21:53:14.842Z,1565128394.842 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:53:14.842Z,1565128394.842 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:53:20.446Z,1565128400.446 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190806T202556/Courier0052.lzma
2019-08-06T21:53:21.252Z,1565128401.252 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0052.lzma.bak
2019-08-06T21:53:21.253Z,1565128401.253 [DataOverHttps](INFO): SBD MOMSN=11529422
2019-08-06T21:53:32.767Z,1565128412.767 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20190806T202556/Express0053.lzma
2019-08-06T21:53:33.573Z,1565128413.573 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0053.lzma.bak
2019-08-06T21:53:33.573Z,1565128413.573 [DataOverHttps](INFO): SBD MOMSN=11529425
2019-08-06T21:53:35.093Z,1565128415.093 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:53:35.094Z,1565128415.094 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:53:35.094Z,1565128415.094 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:54:53.068Z,1565128493.068 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:54:53.068Z,1565128493.068 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:54:53.068Z,1565128493.068 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:54:53.079Z,1565128493.079 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:54:53.511Z,1565128493.511 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:54:53.511Z,1565128493.511 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:56:54.278Z,1565128614.278 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:56:54.278Z,1565128614.278 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T21:56:54.278Z,1565128614.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:56:54.288Z,1565128614.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:56:54.680Z,1565128614.680 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:56:54.680Z,1565128614.680 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T21:58:16.652Z,1565128696.652 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-06T21:58:35.657Z,1565128715.657 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T21:58:35.657Z,1565128715.657 [Default:CheckIn:C.Wait] Stopped
2019-08-06T21:58:35.657Z,1565128715.657 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T21:58:35.658Z,1565128715.658 [Default:CheckIn:D] Running Loop=1
2019-08-06T21:58:36.067Z,1565128716.067 [Default:CheckIn:D] Stopped
2019-08-06T21:58:36.067Z,1565128716.067 [Default:CheckIn:E] Running Loop=1
2019-08-06T21:58:36.477Z,1565128716.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.877238 min 
2019-08-06T21:58:36.478Z,1565128716.478 [Default:CheckIn:E] Stopped
2019-08-06T21:58:36.478Z,1565128716.478 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T21:58:36.478Z,1565128716.478 [Default:CheckIn] Stopped
2019-08-06T21:58:36.478Z,1565128716.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T21:58:36.478Z,1565128716.478 [Default:CheckIn](INFO): Running loop #16
2019-08-06T21:58:36.478Z,1565128716.478 [Default:CheckIn] Running Loop=16
2019-08-06T21:58:36.478Z,1565128716.478 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T21:58:36.478Z,1565128716.478 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T21:58:38.079Z,1565128718.079 [NAL9602](DEBUG): Fix Requested
2019-08-06T21:58:38.469Z,1565128718.469 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214255.00,A,3648.16470,N,12147.28265,W,0.019,200.03,060819,,,A*71
2019-08-06T21:58:38.472Z,1565128718.472 [NAL9602](INFO): GPS fix at 20190806T214255: (36.802745, -121.788044)
2019-08-06T21:58:38.502Z,1565128718.502 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T21:58:38.502Z,1565128718.502 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T21:58:44.290Z,1565128724.290 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0055.lzma
2019-08-06T21:58:45.096Z,1565128725.096 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0055.lzma.bak
2019-08-06T21:58:45.097Z,1565128725.097 [DataOverHttps](INFO): SBD MOMSN=11529469
2019-08-06T21:58:55.078Z,1565128735.078 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T21:58:55.078Z,1565128735.078 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T21:58:55.078Z,1565128735.078 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T21:58:55.088Z,1565128735.088 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T21:58:55.502Z,1565128735.502 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T21:58:55.503Z,1565128735.503 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T21:58:56.571Z,1565128736.571 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20190806T202556/Express0056.lzma
2019-08-06T21:58:57.377Z,1565128737.377 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0056.lzma.bak
2019-08-06T21:58:57.377Z,1565128737.377 [DataOverHttps](INFO): SBD MOMSN=11529472
2019-08-06T21:58:59.128Z,1565128739.128 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T21:58:59.128Z,1565128739.128 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T21:58:59.128Z,1565128739.128 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T21:59:10.384Z,1565128750.384 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T22:00:44.337Z,1565128844.337 [CommandLine](IMPORTANT): got command failComponent
2019-08-06T22:00:44.337Z,1565128844.337 [CommandLine](IMPORTANT): Failed components:
2019-08-06T22:00:44.338Z,1565128844.338 [CommandLine](IMPORTANT): No failed Components.
2019-08-06T22:00:49.933Z,1565128849.933 [CommandLine](IMPORTANT): got command show variable ESPComponent
2019-08-06T22:00:49.996Z,1565128849.996 [CommandLine](IMPORTANT): ESPComponent.loadControl (none)
2019-08-06T22:00:49.996Z,1565128849.996 [CommandLine](IMPORTANT): ESPComponent.loadControl2 (none)
2019-08-06T22:00:49.996Z,1565128849.996 [CommandLine](IMPORTANT): ESPComponent.uart (none)
2019-08-06T22:00:49.997Z,1565128849.997 [CommandLine](IMPORTANT): ESPComponent.consoleUart (none)
2019-08-06T22:00:49.997Z,1565128849.997 [CommandLine](IMPORTANT): ESPComponent.baud (bit_per_second)
2019-08-06T22:00:50.026Z,1565128850.026 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup (bool)
2019-08-06T22:00:50.027Z,1565128850.027 [CommandLine](IMPORTANT): ESPComponent.simulateHardware (bool)
2019-08-06T22:00:50.027Z,1565128850.027 [CommandLine](IMPORTANT): ESPComponent.connectTimeout (second)
2019-08-06T22:00:50.027Z,1565128850.027 [CommandLine](IMPORTANT): ESPComponent.debug (bool)
2019-08-06T22:00:50.028Z,1565128850.028 [CommandLine](IMPORTANT): ESPComponent.espLogFilterRegex (none)
2019-08-06T22:00:50.028Z,1565128850.028 [CommandLine](IMPORTANT): ESPComponent.espServerHost (none)
2019-08-06T22:00:50.029Z,1565128850.029 [CommandLine](IMPORTANT): ESPComponent.filterCompleteTimeout (minute)
2019-08-06T22:00:50.029Z,1565128850.029 [CommandLine](IMPORTANT): ESPComponent.filterResultTimeout (second)
2019-08-06T22:00:50.029Z,1565128850.029 [CommandLine](IMPORTANT): ESPComponent.initialPromptTimeout (second)
2019-08-06T22:00:50.030Z,1565128850.030 [CommandLine](IMPORTANT): ESPComponent.loadCartridgeTimeout (minute)
2019-08-06T22:00:50.030Z,1565128850.030 [CommandLine](IMPORTANT): ESPComponent.poTimeout (second)
2019-08-06T22:00:50.031Z,1565128850.031 [CommandLine](IMPORTANT): ESPComponent.power (watt)
2019-08-06T22:00:50.031Z,1565128850.031 [CommandLine](IMPORTANT): ESPComponent.pppConnect (none)
2019-08-06T22:00:50.031Z,1565128850.031 [CommandLine](IMPORTANT): ESPComponent.pppFlow (none)
2019-08-06T22:00:50.032Z,1565128850.032 [CommandLine](IMPORTANT): ESPComponent.processCompleteTimeout (minute)
2019-08-06T22:00:50.032Z,1565128850.032 [CommandLine](IMPORTANT): ESPComponent.processResultTimeout (second)
2019-08-06T22:00:50.033Z,1565128850.033 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout (minute)
2019-08-06T22:00:50.033Z,1565128850.033 [CommandLine](IMPORTANT): ESPComponent.socketServerPort (count)
2019-08-06T22:00:50.033Z,1565128850.033 [CommandLine](IMPORTANT): ESPComponent.stopResultTimeout (minute)
2019-08-06T22:00:50.034Z,1565128850.034 [CommandLine](IMPORTANT): ESPComponent.upsync (none)
2019-08-06T22:00:50.034Z,1565128850.034 [CommandLine](IMPORTANT): ESPComponent.upsyncTimeout (minute)
2019-08-06T22:00:56.261Z,1565128856.261 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T22:00:56.261Z,1565128856.261 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T22:00:56.262Z,1565128856.262 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T22:00:56.272Z,1565128856.272 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T22:00:56.696Z,1565128856.696 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T22:00:56.696Z,1565128856.696 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T22:00:57.794Z,1565128857.794 [CommandLine](IMPORTANT): got command get ESPComponent.loadAtStartup
2019-08-06T22:00:57.794Z,1565128857.794 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup 0 bool
2019-08-06T22:02:57.451Z,1565128977.451 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T22:02:57.451Z,1565128977.451 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T22:02:57.451Z,1565128977.451 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T22:02:57.461Z,1565128977.461 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T22:02:57.864Z,1565128977.864 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T22:02:57.864Z,1565128977.864 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T22:03:59.672Z,1565129039.672 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T22:03:59.672Z,1565129039.672 [Default:CheckIn:C.Wait] Stopped
2019-08-06T22:03:59.672Z,1565129039.672 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T22:03:59.673Z,1565129039.673 [Default:CheckIn:D] Running Loop=1
2019-08-06T22:04:00.086Z,1565129040.086 [Default:CheckIn:D] Stopped
2019-08-06T22:04:00.086Z,1565129040.086 [Default:CheckIn:E] Running Loop=1
2019-08-06T22:04:00.477Z,1565129040.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.277555 min 
2019-08-06T22:04:00.477Z,1565129040.477 [Default:CheckIn:E] Stopped
2019-08-06T22:04:00.478Z,1565129040.478 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T22:04:00.478Z,1565129040.478 [Default:CheckIn] Stopped
2019-08-06T22:04:00.478Z,1565129040.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T22:04:00.478Z,1565129040.478 [Default:CheckIn](INFO): Running loop #17
2019-08-06T22:04:00.478Z,1565129040.478 [Default:CheckIn] Running Loop=17
2019-08-06T22:04:00.478Z,1565129040.478 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T22:04:00.478Z,1565129040.478 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T22:04:02.091Z,1565129042.091 [NAL9602](DEBUG): Fix Requested
2019-08-06T22:04:02.482Z,1565129042.482 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214819.00,A,3648.16412,N,12147.28209,W,0.136,200.03,060819,,,A*71
2019-08-06T22:04:02.484Z,1565129042.484 [NAL9602](INFO): GPS fix at 20190806T214819: (36.802735, -121.788035)
2019-08-06T22:04:02.514Z,1565129042.514 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T22:04:02.514Z,1565129042.514 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T22:04:11.222Z,1565129051.222 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0058.lzma
2019-08-06T22:04:12.029Z,1565129052.029 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0058.lzma.bak
2019-08-06T22:04:12.029Z,1565129052.029 [DataOverHttps](INFO): SBD MOMSN=11529477
2019-08-06T22:04:24.704Z,1565129064.704 [DataOverHttps](INFO): Sending 637 bytes from file Logs/20190806T202556/Express0059.lzma
2019-08-06T22:04:25.509Z,1565129065.509 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0059.lzma.bak
2019-08-06T22:04:25.509Z,1565129065.509 [DataOverHttps](INFO): SBD MOMSN=11529480
2019-08-06T22:04:27.210Z,1565129067.210 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T22:04:27.210Z,1565129067.210 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T22:04:27.211Z,1565129067.211 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T22:04:58.283Z,1565129098.283 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T22:04:58.283Z,1565129098.283 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T22:04:58.283Z,1565129098.283 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T22:04:58.294Z,1565129098.294 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T22:04:58.684Z,1565129098.684 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T22:04:58.685Z,1565129098.685 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T22:06:59.103Z,1565129219.103 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T22:06:59.103Z,1565129219.103 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T22:06:59.103Z,1565129219.103 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T22:06:59.145Z,1565129219.145 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T22:06:59.520Z,1565129219.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T22:06:59.520Z,1565129219.520 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T22:08:19.215Z,1565129299.215 [CommandLine](IMPORTANT): got command show variable dvl_micro
2019-08-06T22:08:19.248Z,1565129299.248 [CommandLine](IMPORTANT): DVL_micro.loadControl (none)
2019-08-06T22:08:19.249Z,1565129299.249 [CommandLine](IMPORTANT): DVL_micro.uart (none)
2019-08-06T22:08:19.249Z,1565129299.249 [CommandLine](IMPORTANT): DVL_micro.baud (bit_per_second)
2019-08-06T22:08:19.260Z,1565129299.260 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup (bool)
2019-08-06T22:08:19.261Z,1565129299.261 [CommandLine](IMPORTANT): DVL_micro.simulateHardware (bool)
2019-08-06T22:08:19.261Z,1565129299.261 [CommandLine](IMPORTANT): DVL_micro.magDeviation (degree)
2019-08-06T22:08:19.261Z,1565129299.261 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree)
2019-08-06T22:08:19.262Z,1565129299.262 [CommandLine](IMPORTANT): DVL_micro.power (watt)
2019-08-06T22:08:19.262Z,1565129299.262 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree)
2019-08-06T22:08:24.853Z,1565129304.852 [CommandLine](IMPORTANT): got command get DVL_micro.loadAtStartup
2019-08-06T22:08:24.853Z,1565129304.853 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup 0 bool
2019-08-06T22:09:00.299Z,1565129340.299 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T22:09:00.300Z,1565129340.300 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-06T22:09:00.300Z,1565129340.300 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T22:09:00.310Z,1565129340.310 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T22:09:00.724Z,1565129340.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T22:09:00.724Z,1565129340.724 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-06T22:09:04.721Z,1565129344.721 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-06T22:09:27.769Z,1565129367.769 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-06T22:09:27.769Z,1565129367.769 [Default:CheckIn:C.Wait] Stopped
2019-08-06T22:09:27.769Z,1565129367.769 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T22:09:27.770Z,1565129367.770 [Default:CheckIn:D] Running Loop=1
2019-08-06T22:09:28.185Z,1565129368.185 [Default:CheckIn:D] Stopped
2019-08-06T22:09:28.185Z,1565129368.185 [Default:CheckIn:E] Running Loop=1
2019-08-06T22:09:28.584Z,1565129368.584 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.745874 min 
2019-08-06T22:09:28.584Z,1565129368.584 [Default:CheckIn:E] Stopped
2019-08-06T22:09:28.584Z,1565129368.584 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-06T22:09:28.584Z,1565129368.584 [Default:CheckIn] Stopped
2019-08-06T22:09:28.585Z,1565129368.585 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T22:09:28.585Z,1565129368.585 [Default:CheckIn](INFO): Running loop #18
2019-08-06T22:09:28.585Z,1565129368.585 [Default:CheckIn] Running Loop=18
2019-08-06T22:09:28.585Z,1565129368.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-06T22:09:28.585Z,1565129368.585 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-06T22:09:30.191Z,1565129370.191 [NAL9602](DEBUG): Fix Requested
2019-08-06T22:09:30.581Z,1565129370.581 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215346.00,A,3648.16848,N,12147.28256,W,0.253,200.03,060819,,,A*78
2019-08-06T22:09:30.584Z,1565129370.584 [NAL9602](INFO): GPS fix at 20190806T215346: (36.802808, -121.788043)
2019-08-06T22:09:30.614Z,1565129370.614 [Default:CheckIn:Read_GPS] Stopped
2019-08-06T22:09:30.614Z,1565129370.614 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-06T22:09:36.599Z,1565129376.599 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190806T202556/Courier0061.lzma
2019-08-06T22:09:37.405Z,1565129377.405 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0061.lzma.bak
2019-08-06T22:09:37.405Z,1565129377.405 [DataOverHttps](IMPORTANT): SBD MOMSN=11529552, MTMSN=20190806T215353
2019-08-06T22:09:43.203Z,1565129383.203 [DataOverHttps](INFO): Received command:configSet VerticalControl.buoyancyNeutral 200 cubic_centimeter persist;configSet VerticalControl.massDefault -4 millimeter persist
2019-08-06T22:09:43.705Z,1565129383.705 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 200.000000 cubic_centimeter persist
2019-08-06T22:09:43.709Z,1565129383.709 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault -4.000000 millimeter persist
2019-08-06T22:09:49.133Z,1565129389.133 [DataOverHttps](INFO): Sending 478 bytes from file Logs/20190806T202556/Express0062.lzma
2019-08-06T22:09:49.933Z,1565129389.933 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0062.lzma.bak
2019-08-06T22:09:49.933Z,1565129389.933 [DataOverHttps](INFO): SBD MOMSN=11529556
2019-08-06T22:10:00.870Z,1565129400.870 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190806T202556/Courier0064.lzma
2019-08-06T22:10:01.672Z,1565129401.672 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Courier0064.lzma.bak
2019-08-06T22:10:01.673Z,1565129401.673 [DataOverHttps](INFO): SBD MOMSN=11529574
2019-08-06T22:10:02.536Z,1565129402.536 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-06T22:10:17.066Z,1565129417.066 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20190806T202556/Express0065.lzma
2019-08-06T22:10:17.873Z,1565129417.873 [DataOverHttps](INFO): Moved sent file to Logs/20190806T202556/Express0065.lzma.bak
2019-08-06T22:10:17.873Z,1565129417.873 [DataOverHttps](INFO): SBD MOMSN=11529576
2019-08-06T22:10:19.549Z,1565129419.549 [Default:CheckIn:Read_Iridium] Stopped
2019-08-06T22:10:19.550Z,1565129419.550 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-06T22:10:19.550Z,1565129419.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-06T22:11:01.174Z,1565129461.174 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T22:11:01.174Z,1565129461.174 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-06T22:11:01.174Z,1565129461.174 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T22:11:01.184Z,1565129461.184 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T22:11:01.586Z,1565129461.586 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T22:11:01.586Z,1565129461.586 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-06T22:13:01.986Z,1565129581.986 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-06T22:13:01.986Z,1565129581.986 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-06T22:13:01.986Z,1565129581.986 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-06T22:13:02.008Z,1565129582.008 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-06T22:13:02.419Z,1565129582.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-06T22:13:02.419Z,1565129582.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-06T22:14:05.096Z,1565129645.096 [DataOverHttps](IMPORTANT): SBD MTMSN=20190806T215820
2019-08-06T22:14:11.598Z,1565129651.598 [DataOverHttps](INFO): Received command:restart sys
2019-08-06T22:14:11.688Z,1565129651.688 [CommandLine](IMPORTANT): got command restart system
2019-08-06T22:14:13.770Z,1565129653.770 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T22:14:13.771Z,1565129653.771 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:13.811Z,1565129653.811 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-08-06T22:14:13.811Z,1565129653.811 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:13.812Z,1565129653.812 [CommandLine](INFO): Join timeout helper Thread ID is 4921
2019-08-06T22:14:13.812Z,1565129653.812 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-08-06T22:14:13.812Z,1565129653.812 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:13.813Z,1565129653.813 [NavChartDb](INFO): Join timeout helper Thread ID is 4922
2019-08-06T22:14:14.015Z,1565129654.015 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T22:14:14.015Z,1565129654.015 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.023Z,1565129654.023 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-08-06T22:14:14.023Z,1565129654.023 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.023Z,1565129654.023 [Radio_Surface](INFO): Join timeout helper Thread ID is 4923
2019-08-06T22:14:14.055Z,1565129654.055 [Radio_Surface](INFO): Powering down
2019-08-06T22:14:14.056Z,1565129654.056 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T22:14:14.056Z,1565129654.056 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.063Z,1565129654.063 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-08-06T22:14:14.063Z,1565129654.063 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.063Z,1565129654.063 [DataOverHttps](INFO): Join timeout helper Thread ID is 4924
2019-08-06T22:14:14.423Z,1565129654.423 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T22:14:14.423Z,1565129654.423 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.427Z,1565129654.427 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-08-06T22:14:14.427Z,1565129654.427 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.427Z,1565129654.427 [logger](INFO): Join timeout helper Thread ID is 4925
2019-08-06T22:14:14.471Z,1565129654.471 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T22:14:14.471Z,1565129654.471 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.488Z,1565129654.488 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-08-06T22:14:14.488Z,1565129654.488 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.488Z,1565129654.488 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-08-06T22:14:14.488Z,1565129654.488 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.489Z,1565129654.489 [controlThread](INFO): Join timeout helper Thread ID is 4926
2019-08-06T22:14:14.659Z,1565129654.659 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-06T22:14:14.659Z,1565129654.659 [controlThread](DEBUG): Uninitializing ControlThread
2019-08-06T22:14:14.660Z,1565129654.660 [NAL9602](INFO): Powering down
2019-08-06T22:14:14.662Z,1565129654.662 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-08-06T22:14:14.663Z,1565129654.663 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-08-06T22:14:14.663Z,1565129654.663 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-08-06T22:14:14.664Z,1565129654.664 [MissionManager](INFO): Uninitializing Mission Default
2019-08-06T22:14:14.664Z,1565129654.664 [Default] Stopped
2019-08-06T22:14:14.664Z,1565129654.664 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-06T22:14:14.664Z,1565129654.664 [Default:B.GoToSurface] Stopped
2019-08-06T22:14:14.664Z,1565129654.664 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-06T22:14:14.664Z,1565129654.664 [Default:CheckIn] Stopped
2019-08-06T22:14:14.664Z,1565129654.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-06T22:14:14.664Z,1565129654.664 [Default:CheckIn:C.Wait] Stopped
2019-08-06T22:14:14.664Z,1565129654.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-06T22:14:14.667Z,1565129654.667 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-08-06T22:14:14.667Z,1565129654.667 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-08-06T22:14:14.667Z,1565129654.667 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-08-06T22:14:14.668Z,1565129654.668 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-08-06T22:14:14.668Z,1565129654.668 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-08-06T22:14:14.668Z,1565129654.668 [BuoyancyServo](INFO): Powering down
2019-08-06T22:14:14.683Z,1565129654.683 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-08-06T22:14:14.683Z,1565129654.683 [ElevatorServo](INFO): Powering down
2019-08-06T22:14:14.684Z,1565129654.684 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-08-06T22:14:14.684Z,1565129654.684 [MassServo](INFO): Powering down
2019-08-06T22:14:14.685Z,1565129654.685 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-06T22:14:14.685Z,1565129654.685 [RudderServo](INFO): Powering down
2019-08-06T22:14:14.686Z,1565129654.686 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-08-06T22:14:14.686Z,1565129654.686 [ThrusterServo](INFO): Powering down
2019-08-06T22:14:14.686Z,1565129654.686 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-08-06T22:14:14.687Z,1565129654.687 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-08-06T22:14:14.687Z,1565129654.687 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-08-06T22:14:14.687Z,1565129654.687 [CBIT](DEBUG): Powering off loads.
2019-08-06T22:14:14.699Z,1565129654.699 [CBIT](DEBUG): Disabling WDT.
2019-08-06T22:14:14.711Z,1565129654.711 [CBIT](DEBUG): Opening all GF detection circuits.
2019-08-06T22:14:14.711Z,1565129654.711 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.807Z,1565129654.807 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.814Z,1565129654.814 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.923Z,1565129654.923 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-06T22:14:14.994Z,1565129654.994 [logger ThreadHandler](INFO): Thread cancelled.