2019-05-09T00:22:18.000Z,1557361338.000 [Supervisor](DEBUG): Initializing supervisor.
2019-05-09T00:22:18.004Z,1557361338.004 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-09T00:22:18.005Z,1557361338.005 [SyncHandler](INFO): Protected caller Thread ID is 1413
2019-05-09T00:22:18.005Z,1557361338.005 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-09T00:22:18.006Z,1557361338.006 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-09T00:22:18.006Z,1557361338.006 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1414
2019-05-09T00:22:18.010Z,1557361338.010 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-09T00:22:18.022Z,1557361338.022 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-09T00:22:18.023Z,1557361338.023 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-09T00:22:18.024Z,1557361338.024 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1415
2019-05-09T00:22:18.025Z,1557361338.025 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-09T00:22:18.026Z,1557361338.026 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-09T00:22:18.026Z,1557361338.026 [logger ThreadHandler](INFO): Protected caller Thread ID is 1416
2019-05-09T00:22:18.028Z,1557361338.028 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-09T00:22:18.028Z,1557361338.028 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-09T00:22:18.030Z,1557361338.030 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-09T00:22:18.125Z,1557361338.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-09T00:22:18.125Z,1557361338.125 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-09T00:22:18.325Z,1557361338.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-09T00:22:18.326Z,1557361338.326 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-09T00:22:18.462Z,1557361338.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-09T00:22:18.462Z,1557361338.462 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-09T00:22:18.996Z,1557361338.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-09T00:22:18.997Z,1557361338.997 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-09T00:22:19.448Z,1557361339.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-09T00:22:19.449Z,1557361339.449 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-09T00:22:19.913Z,1557361339.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-09T00:22:19.914Z,1557361339.914 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-09T00:22:20.264Z,1557361340.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-09T00:22:20.264Z,1557361340.264 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-09T00:22:20.742Z,1557361340.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-09T00:22:20.743Z,1557361340.743 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-09T00:22:21.140Z,1557361341.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-09T00:22:21.141Z,1557361341.141 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-09T00:22:21.291Z,1557361341.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-09T00:22:21.292Z,1557361341.292 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-09T00:22:21.397Z,1557361341.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-09T00:22:21.397Z,1557361341.397 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-09T00:22:21.478Z,1557361341.478 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-09T00:22:21.579Z,1557361341.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-09T00:22:21.580Z,1557361341.580 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-09T00:22:21.776Z,1557361341.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-09T00:22:21.776Z,1557361341.776 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-09T00:22:21.983Z,1557361341.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-09T00:22:21.985Z,1557361341.985 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-05-09T00:22:21.986Z,1557361341.986 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-05-09T00:22:22.068Z,1557361342.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-05-09T00:22:22.293Z,1557361342.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-09T00:22:22.294Z,1557361342.294 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-05-09T00:22:22.389Z,1557361342.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-05-09T00:22:22.548Z,1557361342.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-05-09T00:22:22.750Z,1557361342.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-05-09T00:22:22.835Z,1557361342.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-05-09T00:22:22.933Z,1557361342.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-05-09T00:22:23.026Z,1557361343.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-05-09T00:22:23.179Z,1557361343.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-05-09T00:22:23.283Z,1557361343.283 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-05-09T00:22:23.381Z,1557361343.381 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-05-09T00:22:23.382Z,1557361343.382 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-09T00:22:23.391Z,1557361343.391 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-09T00:22:23.489Z,1557361343.489 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-09T00:22:23.490Z,1557361343.490 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-09T00:22:23.623Z,1557361343.623 [BuoyancyServo] Loaded
2019-05-09T00:22:23.623Z,1557361343.623 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-09T00:22:23.638Z,1557361343.638 [ElevatorServo] Loaded
2019-05-09T00:22:23.639Z,1557361343.639 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-09T00:22:23.653Z,1557361343.653 [MassServo] Loaded
2019-05-09T00:22:23.653Z,1557361343.653 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-09T00:22:23.668Z,1557361343.668 [RudderServo] Loaded
2019-05-09T00:22:23.668Z,1557361343.668 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-09T00:22:23.682Z,1557361343.682 [ThrusterServo] Loaded
2019-05-09T00:22:23.682Z,1557361343.682 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-09T00:22:23.683Z,1557361343.683 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-09T00:22:23.683Z,1557361343.683 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-09T00:22:23.817Z,1557361343.817 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-09T00:22:23.846Z,1557361343.846 [SBIT] Loaded
2019-05-09T00:22:23.846Z,1557361343.846 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-09T00:22:23.847Z,1557361343.847 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-09T00:22:23.874Z,1557361343.874 [IBIT] Loaded
2019-05-09T00:22:23.874Z,1557361343.874 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-09T00:22:23.877Z,1557361343.877 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-09T00:22:24.257Z,1557361344.257 [CBIT] Loaded
2019-05-09T00:22:24.258Z,1557361344.258 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-09T00:22:24.258Z,1557361344.258 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-09T00:22:24.259Z,1557361344.259 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-09T00:22:24.451Z,1557361344.451 [ESPComponent] Loaded
2019-05-09T00:22:24.452Z,1557361344.452 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-05-09T00:22:24.465Z,1557361344.465 [PAR_Licor] Loaded
2019-05-09T00:22:24.466Z,1557361344.466 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-09T00:22:24.513Z,1557361344.513 [WetLabsBB2FL] Loaded
2019-05-09T00:22:24.513Z,1557361344.513 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-09T00:22:24.514Z,1557361344.514 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406624E0
2019-05-09T00:22:24.514Z,1557361344.514 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1495
2019-05-09T00:22:24.515Z,1557361344.515 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-09T00:22:24.516Z,1557361344.516 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-09T00:22:24.556Z,1557361344.556 [DepthRateCalculator] Loaded
2019-05-09T00:22:24.557Z,1557361344.557 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-09T00:22:24.562Z,1557361344.562 [PitchRateCalculator] Loaded
2019-05-09T00:22:24.562Z,1557361344.562 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-09T00:22:24.578Z,1557361344.578 [SpeedCalculator] Loaded
2019-05-09T00:22:24.578Z,1557361344.578 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-09T00:22:24.599Z,1557361344.599 [TempGradientCalculator] Loaded
2019-05-09T00:22:24.599Z,1557361344.599 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-09T00:22:24.605Z,1557361344.605 [YawRateCalculator] Loaded
2019-05-09T00:22:24.605Z,1557361344.605 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-09T00:22:24.637Z,1557361344.637 [ElevatorOffsetCalculator] Loaded
2019-05-09T00:22:24.637Z,1557361344.637 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-09T00:22:24.638Z,1557361344.638 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-09T00:22:24.638Z,1557361344.638 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-09T00:22:24.666Z,1557361344.666 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-09T00:22:24.667Z,1557361344.667 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-09T00:22:24.769Z,1557361344.769 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-09T00:22:24.770Z,1557361344.770 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-09T00:22:24.790Z,1557361344.790 [NavChart] Loaded
2019-05-09T00:22:24.791Z,1557361344.791 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-09T00:22:24.795Z,1557361344.795 [UniversalFixResidualReporter] Loaded
2019-05-09T00:22:24.795Z,1557361344.795 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-09T00:22:24.795Z,1557361344.795 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-09T00:22:24.796Z,1557361344.796 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-09T00:22:25.131Z,1557361345.131 [DataOverHttps] Loaded
2019-05-09T00:22:25.132Z,1557361345.132 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-09T00:22:25.133Z,1557361345.133 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409394E0
2019-05-09T00:22:25.133Z,1557361345.133 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1496
2019-05-09T00:22:25.146Z,1557361345.146 [Depth_Keller] Loaded
2019-05-09T00:22:25.147Z,1557361345.147 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-09T00:22:25.235Z,1557361345.235 [NAL9602] Loaded
2019-05-09T00:22:25.235Z,1557361345.235 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-09T00:22:25.240Z,1557361345.240 [Onboard] Loaded
2019-05-09T00:22:25.241Z,1557361345.241 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-09T00:22:25.244Z,1557361345.244 [Radio_Surface] Loaded
2019-05-09T00:22:25.244Z,1557361345.244 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-09T00:22:25.245Z,1557361345.245 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409694E0
2019-05-09T00:22:25.246Z,1557361345.246 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1497
2019-05-09T00:22:26.773Z,1557361346.773 [BPC1] Loaded
2019-05-09T00:22:26.773Z,1557361346.773 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-09T00:22:26.774Z,1557361346.774 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-09T00:22:26.774Z,1557361346.774 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-09T00:22:26.847Z,1557361346.847 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-09T00:22:26.848Z,1557361346.848 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-09T00:22:26.913Z,1557361346.913 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-09T00:22:26.996Z,1557361346.996 [VerticalControl] Loaded
2019-05-09T00:22:26.996Z,1557361346.996 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-09T00:22:26.997Z,1557361346.997 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-09T00:22:27.053Z,1557361347.053 [HorizontalControl] Loaded
2019-05-09T00:22:27.053Z,1557361347.053 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-09T00:22:27.054Z,1557361347.054 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-09T00:22:27.056Z,1557361347.056 [SpeedControl] Loaded
2019-05-09T00:22:27.056Z,1557361347.056 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-09T00:22:27.057Z,1557361347.057 [LoopControl](DEBUG): Construct LoopControl.
2019-05-09T00:22:27.057Z,1557361347.057 [LoopControl] Loaded
2019-05-09T00:22:27.057Z,1557361347.057 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-09T00:22:27.058Z,1557361347.058 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-09T00:22:27.058Z,1557361347.058 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-09T00:22:27.072Z,1557361347.072 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-09T00:22:27.072Z,1557361347.072 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-09T00:22:27.362Z,1557361347.362 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-09T00:22:27.366Z,1557361347.366 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-09T00:22:27.367Z,1557361347.367 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-09T00:22:27.374Z,1557361347.374 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-09T00:22:27.375Z,1557361347.375 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA34E0
2019-05-09T00:22:27.375Z,1557361347.375 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1498
2019-05-09T00:22:27.380Z,1557361347.380 [Supervisor](INFO): Main Thread ID is 802
2019-05-09T00:22:27.380Z,1557361347.380 [Supervisor](DEBUG): Running supervisor.
2019-05-09T00:22:27.381Z,1557361347.381 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1499
2019-05-09T00:22:27.384Z,1557361347.384 [controlThread ThreadHandler](INFO): Handler Thread ID is 1500
2019-05-09T00:22:27.384Z,1557361347.384 [controlThread](DEBUG): Initializing ControlThread
2019-05-09T00:22:27.386Z,1557361347.386 [SBIT](INFO): Initialize SBIT Component.
2019-05-09T00:22:27.386Z,1557361347.386 [SBIT](IMPORTANT): git: 2019-05-01-7-g40891d5
2019-05-09T00:22:27.386Z,1557361347.386 [SBIT](INFO): git hash: 40891d5da2a12f3e7b025957fbb9a071b5aeb942
2019-05-09T00:22:27.387Z,1557361347.387 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-09T00:22:27.388Z,1557361347.388 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-09T00:22:27.389Z,1557361347.389 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-05-09T00:22:27.390Z,1557361347.390 [IBIT](INFO): Initialize IBIT Component.
2019-05-09T00:22:27.391Z,1557361347.391 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-09T00:22:27.392Z,1557361347.392 [logger ThreadHandler](INFO): Handler Thread ID is 1501
2019-05-09T00:22:27.405Z,1557361347.405 [CBIT](DEBUG): Initialized mux pins.
2019-05-09T00:22:27.405Z,1557361347.405 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-09T00:22:27.417Z,1557361347.417 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1502
2019-05-09T00:22:27.418Z,1557361347.418 [WetLabsBB2FL](INFO): Powering down
2019-05-09T00:22:27.429Z,1557361347.429 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-09T00:22:27.429Z,1557361347.429 [CBIT](DEBUG): Initializing heartbeat.
2019-05-09T00:22:27.446Z,1557361347.446 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1503
2019-05-09T00:22:27.447Z,1557361347.447 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-09T00:22:27.457Z,1557361347.457 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1504
2019-05-09T00:22:27.477Z,1557361347.477 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1505
2019-05-09T00:22:27.480Z,1557361347.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-09T00:22:27.481Z,1557361347.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-09T00:22:27.481Z,1557361347.481 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-09T00:22:27.481Z,1557361347.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-09T00:22:27.481Z,1557361347.481 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-09T00:22:27.482Z,1557361347.482 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-09T00:22:27.482Z,1557361347.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-09T00:22:27.482Z,1557361347.482 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-09T00:22:27.482Z,1557361347.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-09T00:22:27.482Z,1557361347.482 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-09T00:22:27.483Z,1557361347.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-09T00:22:27.483Z,1557361347.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-09T00:22:27.483Z,1557361347.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-09T00:22:27.483Z,1557361347.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-09T00:22:27.483Z,1557361347.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-09T00:22:27.483Z,1557361347.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-09T00:22:27.501Z,1557361347.501 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-09T00:22:27.501Z,1557361347.501 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-09T00:22:27.537Z,1557361347.537 [CBIT](DEBUG): Backplane powered.
2019-05-09T00:22:27.541Z,1557361347.541 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-09T00:22:27.541Z,1557361347.541 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-09T00:22:27.542Z,1557361347.542 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-09T00:22:27.542Z,1557361347.542 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-09T00:22:27.543Z,1557361347.543 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-09T00:22:27.543Z,1557361347.543 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-09T00:22:27.544Z,1557361347.544 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-09T00:22:27.544Z,1557361347.544 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-09T00:22:27.554Z,1557361347.554 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-09T00:22:27.556Z,1557361347.556 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-09T00:22:27.556Z,1557361347.556 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-09T00:22:27.565Z,1557361347.565 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-09T00:22:27.566Z,1557361347.566 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-09T00:22:27.601Z,1557361347.601 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-09T00:22:27.628Z,1557361347.628 [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-05-09T00:22:27.641Z,1557361347.641 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-09T00:22:27.704Z,1557361347.704 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-09T00:22:27.733Z,1557361347.733 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-09T00:22:27.735Z,1557361347.735 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-09T00:22:27.785Z,1557361347.785 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-09T00:22:27.787Z,1557361347.787 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-09T00:22:27.825Z,1557361347.825 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-09T00:22:27.828Z,1557361347.828 [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-05-09T00:22:27.833Z,1557361347.833 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,Depth_Keller,NAL9602,Onboard,BPC1,PAR_Licor,Depth_Keller,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-05-09T00:22:27.858Z,1557361347.858 [ESPComponent](INFO): powering down ESP
2019-05-09T00:22:27.861Z,1557361347.861 [Radio_Surface](INFO): Powering up
2019-05-09T00:22:28.146Z,1557361348.146 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-05-09T00:22:28.209Z,1557361348.209 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-09T00:22:28.235Z,1557361348.235 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-09T00:22:28.241Z,1557361348.241 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-09T00:22:28.242Z,1557361348.242 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-09T00:22:28.249Z,1557361348.249 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-09T00:22:28.250Z,1557361348.250 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-09T00:22:28.257Z,1557361348.257 [MassServo](DEBUG): Initializing MassServo.
2019-05-09T00:22:28.258Z,1557361348.258 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-09T00:22:28.266Z,1557361348.266 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-09T00:22:28.267Z,1557361348.267 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-09T00:22:28.273Z,1557361348.273 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-09T00:22:37.633Z,1557361357.633 [CBIT](CRITICAL): Environmental Failure. Press:14.648429 PSI. Humidity:36%. Temp:27 C. ABORTING MISSION
2019-05-09T00:22:37.633Z,1557361357.633 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-05-09T00:22:38.038Z,1557361358.038 [CommandLine](FAULT): Scheduling is paused
2019-05-09T00:22:38.039Z,1557361358.039 [CBIT](INFO): Critical error at 20190509T002237
2019-05-09T00:22:52.166Z,1557361372.166 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-09T00:22:52.170Z,1557361372.170 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-09T00:22:55.454Z,1557361375.454 [NAL9602](INFO): Powering up NAL9602
2019-05-09T00:23:03.209Z,1557361383.209 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006763
CHAN A1 (24V): -0.133334
CHAN A2 (12V): -0.005169
CHAN A3 (5V): -0.002433
CHAN B0 (3.3V): -0.001175
CHAN B1 (3.15aV): -0.001002
CHAN B2 (3.15bV): -0.000960
CHAN B3 (GND): -0.000092
OPEN: 0.006575
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-09T00:23:06.362Z,1557361386.362 [NAL9602](INFO): NAL9602 initialized
2019-05-09T00:23:06.533Z,1557361386.533 [CommandLine](IMPORTANT): got command get ESPComponent.loadControl
2019-05-09T00:23:06.534Z,1557361386.534 [CommandLine](IMPORTANT): ESPComponent.loadControl /dev/loadA6
2019-05-09T00:23:07.173Z,1557361387.173 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:23:10.909Z,1557361390.909 [CommandLine](IMPORTANT): got command get ESPComponent.loadControl2
2019-05-09T00:23:10.910Z,1557361390.910 [CommandLine](IMPORTANT): ESPComponent.loadControl2 /dev/loadA7
2019-05-09T00:23:45.422Z,1557361425.422 [SBIT](IMPORTANT): SBIT PASSED
2019-05-09T00:23:45.463Z,1557361425.463 [CommandLine](IMPORTANT): got command configSet list
2019-05-09T00:23:45.464Z,1557361425.464 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-09T00:23:45.465Z,1557361425.465 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-05-09T00:23:45.465Z,1557361425.465 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.loadAtStartup=0 bool;

2019-05-09T00:23:45.465Z,1557361425.465 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-05-09T00:23:45.465Z,1557361425.465 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-09T00:23:45.465Z,1557361425.465 [CommandLine](IMPORTANT): PAR_Licor.adcCal=8.765e-06 microampere_per_count;

2019-05-09T00:23:45.465Z,1557361425.465 [CommandLine](IMPORTANT): PAR_Licor.darkCount=0 count;

2019-05-09T00:23:45.466Z,1557361425.466 [CommandLine](IMPORTANT): PAR_Licor.multiplier=190.3 micromole_per_second_per_square_meter_per_microampere;

2019-05-09T00:23:45.466Z,1557361425.466 [CommandLine](IMPORTANT): PNI_TCM.loadAtStartup=0 bool;

2019-05-09T00:23:45.466Z,1557361425.466 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-05-09T00:23:45.818Z,1557361425.818 [MissionManager](IMPORTANT): Started mission Startup
2019-05-09T00:23:45.818Z,1557361425.818 [Startup] Running Loop=1
2019-05-09T00:23:45.818Z,1557361425.818 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-09T00:23:45.818Z,1557361425.818 [Startup:A.GoToSurface] Running Loop=1
2019-05-09T00:23:45.818Z,1557361425.818 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-09T00:23:45.819Z,1557361425.819 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-09T00:23:45.819Z,1557361425.819 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-09T00:23:45.820Z,1557361425.820 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-09T00:23:45.820Z,1557361425.820 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-09T00:23:45.820Z,1557361425.820 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-09T00:23:45.822Z,1557361425.822 [Startup:StartupSatComms] Running Loop=1
2019-05-09T00:23:45.822Z,1557361425.822 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-09T00:23:45.822Z,1557361425.822 [Startup:StartupSatComms:A] Running Loop=1
2019-05-09T00:23:46.222Z,1557361426.222 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-09T00:24:27.895Z,1557361467.895 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:24:27.895Z,1557361467.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-09T00:24:27.895Z,1557361467.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:24:27.906Z,1557361467.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:24:28.254Z,1557361468.254 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:24:28.254Z,1557361468.254 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-09T00:24:46.004Z,1557361486.004 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-09T00:23:45.8Z
2019-05-09T00:24:46.004Z,1557361486.004 [Startup:StartupSatComms:A] Stopped
2019-05-09T00:24:46.004Z,1557361486.004 [Startup:StartupSatComms:B] Running Loop=1
2019-05-09T00:24:46.403Z,1557361486.403 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-09T00:25:07.323Z,1557361507.323 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005085
2019-05-09T00:25:15.954Z,1557361515.954 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190509T000206/Courier0007.lzma
2019-05-09T00:25:16.562Z,1557361516.562 [DataOverHttps](INFO): Moved sent file to Logs/20190509T000206/Courier0007.lzma.bak
2019-05-09T00:25:16.563Z,1557361516.563 [DataOverHttps](INFO): SBD MOMSN=10981778
2019-05-09T00:25:30.732Z,1557361530.732 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20190509T002217/Courier0000.lzma
2019-05-09T00:25:31.538Z,1557361531.538 [DataOverHttps](INFO): Moved sent file to Logs/20190509T002217/Courier0000.lzma.bak
2019-05-09T00:25:31.538Z,1557361531.538 [DataOverHttps](INFO): SBD MOMSN=10981808
2019-05-09T00:25:45.453Z,1557361545.453 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20190509T000206/Express0008.lzma
2019-05-09T00:25:46.218Z,1557361546.218 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-09T00:24:46.0Z
2019-05-09T00:25:46.218Z,1557361546.218 [Startup:StartupSatComms:B] Stopped
2019-05-09T00:25:46.218Z,1557361546.218 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-09T00:25:46.218Z,1557361546.218 [Startup:StartupSatComms] Stopped
2019-05-09T00:25:46.218Z,1557361546.218 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-09T00:25:46.219Z,1557361546.219 [Startup](INFO): Completed Startup
2019-05-09T00:25:46.219Z,1557361546.219 [MissionManager](INFO): Startup is completed.
2019-05-09T00:25:46.219Z,1557361546.219 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-09T00:25:46.220Z,1557361546.220 [Startup] Stopped
2019-05-09T00:25:46.220Z,1557361546.220 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-09T00:25:46.220Z,1557361546.220 [Startup:A.GoToSurface] Stopped
2019-05-09T00:25:46.220Z,1557361546.220 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-09T00:25:46.258Z,1557361546.258 [DataOverHttps](INFO): Moved sent file to Logs/20190509T000206/Express0008.lzma.bak
2019-05-09T00:25:46.258Z,1557361546.258 [DataOverHttps](INFO): SBD MOMSN=10981812
2019-05-09T00:25:46.618Z,1557361546.618 [MissionManager](IMPORTANT): Started mission Default
2019-05-09T00:25:46.618Z,1557361546.618 [Default] Running Loop=1
2019-05-09T00:25:46.618Z,1557361546.618 [Default](DEBUG): Aggregate::initialize Default
2019-05-09T00:25:46.618Z,1557361546.618 [Default:B.GoToSurface] Running Loop=1
2019-05-09T00:25:46.618Z,1557361546.618 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-09T00:25:46.618Z,1557361546.618 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-09T00:25:46.619Z,1557361546.619 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-09T00:25:46.619Z,1557361546.619 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-09T00:25:46.619Z,1557361546.619 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-09T00:25:46.620Z,1557361546.620 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-09T00:25:46.620Z,1557361546.620 [Default:A.Wait] Running Loop=1
2019-05-09T00:25:46.620Z,1557361546.620 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-09T00:25:46.718Z,1557361546.718 [CommandLine](IMPORTANT): got command show stack
2019-05-09T00:25:46.718Z,1557361546.718 [CommandLine](IMPORTANT): Behavior Stack:

2019-05-09T00:25:46.718Z,1557361546.718 [Default](IMPORTANT): Priority 0: Default:A.Wait
2019-05-09T00:25:46.718Z,1557361546.718 [Default](IMPORTANT): Priority 1: Default:B.GoToSurface
2019-05-09T00:25:53.642Z,1557361553.642 [CommandLine](IMPORTANT): got command run ./Missions/test.xml
2019-05-09T00:25:53.642Z,1557361553.642 [MissionManager](INFO): Loading Mission: ./Missions/test.xml
2019-05-09T00:25:53.972Z,1557361553.972 [MissionManager](INFO): DefineArg SampleAtDepth.TargetDepth = 5.000000 m
2019-05-09T00:25:54.024Z,1557361554.024 [MissionManager](INFO): DefineArg SampleAtDepth.SettleTime = 10.000000 s
2019-05-09T00:25:54.039Z,1557361554.039 [MissionManager](INFO): DefineArg SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
2019-05-09T00:25:54.094Z,1557361554.094 [MissionManager](INFO): DefineArg SampleAtDepth.RotateOnly = 0.000000 bool
2019-05-09T00:25:54.109Z,1557361554.109 [MissionManager](INFO): DefineArg SampleAtDepth.UseCANONSampler = 0.000000 bool
2019-05-09T00:25:54.149Z,1557361554.149 [MissionManager](INFO): DefineArg SampleAtDepth.UseESP = 1.000000 bool
2019-05-09T00:25:54.152Z,1557361554.152 [MissionManager](INFO): DefineArg SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2019-05-09T00:25:54.192Z,1557361554.192 [MissionManager](INFO): DefineArg SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2019-05-09T00:25:54.200Z,1557361554.200 [MissionManager](INFO): DefineArg SampleAtDepth.BuoyancyNeutral = 134.999995 cc
2019-05-09T00:25:54.227Z,1557361554.227 [SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Construct.
2019-05-09T00:25:54.311Z,1557361554.311 [SampleAtDepth:ApproachDepth:B.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-09T00:25:54.326Z,1557361554.326 [SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Construct.
2019-05-09T00:25:54.351Z,1557361554.351 [SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Construct Wait.
2019-05-09T00:25:54.393Z,1557361554.393 [SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-09T00:25:54.396Z,1557361554.396 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-05-09T00:25:54.396Z,1557361554.396 [MissionManager](ERROR): Slate does not contain celsius
2019-05-09T00:25:54.432Z,1557361554.432 [SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-09T00:25:54.462Z,1557361554.462 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
2019-05-09T00:25:54.462Z,1557361554.462 [MissionManager](ERROR): Slate does not contain celsius
2019-05-09T00:25:54.509Z,1557361554.509 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Universal="Tethys/Universal"
           xmlns:Control="Tethys/Control"
           xmlns:Guidance="Tethys/Guidance"
           xmlns:Science="Tethys/Science"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                               Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd"
       Id="SampleAtDepth">

    <Description>
        Take a water sample at a specified depth.
    </Description>

    <DefineArg Name="TargetDepth"><Description>
        Depth to sample at. Initialized to 5 m.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        Time to wait after reaching target depth before triggering sample.
        Initialized to 10 minutes.
    </Description><Units:second/><Value>10</Value></DefineArg>

    <DefineArg Name="MaxWaitNotReachingDepth"><Description>
        Maximum wait time the vehicle cannot reach the targeted depth.
        Initialized to 3 hours.
    </Description><Units:hour/><Value>3</Value></DefineArg>

    <DefineArg Name="RotateOnly"><Units:bool/><Value>0</Value></DefineArg>

    <DefineArg Name="UseCANONSampler"><Description>
        Whether to use CANON Sampler to sample.
    </Description><Science:CANONSampler.loadAtStartup/></DefineArg>

    <DefineArg Name="UseESP"><Description>
        Whether to use ESP to sample.
    </Description><Science:ESPComponent.loadAtStartup/></DefineArg>

    <DefineArg Name="CANONSamplerTriggerTimeout"><Description>
        How long to wait for a CANON Sampler sample to start.
    </Description><Units:minute/><Value>1</Value></DefineArg>

    <DefineArg Name="CANONSamplerTimeout"><Description>
        How long to wait for a CANON Sampler sample to complete.
    </Description><Science:CANONSampler.sampleTimeout/></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position during spiral or donut. Defaults to
        buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc
        for active buoyancy
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <When>
        <Called/>
    </When>

    <Aggregate Id="ApproachDepth">
        <Sequence/>

        <Syslog>Moving to <Arg Name="TargetDepth"/><Units:meter/></Syslog>

        <Guidance:Pitch>
            <Sequence/>
            <Timeout Duration="MaxWaitNotReachingDepth">
                <Syslog Severity="Important">Timed out trying to reach the targeted depth. Stopping mission.</Syslog>

                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                </Guidance:Execute>
            </Timeout>
            <Setting><Guidance:Pitch.depth/><Arg Name="TargetDepth"/></Setting>
        </Guidance:Pitch>
    </Aggregate>

    <Aggregate Id="SampleWrapper">
        <Sequence/>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.depth/><Arg Name="TargetDepth"/></Setting>
        </Guidance:Pitch>

        <Syslog Severity="Important">At <Universal:depth/><Units:meter/>, settling for <Arg Name="SettleTime"/><Units:minute/></Syslog>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="SettleTime"/></Setting>
        </Guidance:Wait>

        <Aggregate Id="SampleCANONSampler">
            <Sequence/>

            <Break>
                <Not><Arg Name="UseCANONSampler"/></Not>
            </Break>

            <Assign><Parallel/><Science:CANONSampler.sampleTimeout/><Arg Name="CANONSamplerTimeout"/></Assign>

            <Assign><Parallel/><Science:CANONSampler.rotateOnly/><Arg Name="RotateOnly"/></Assign>

            <ReadDatum Id="TriggerCANONSampler">
                <Timeout Duration="CANONSamplerTriggerTimeout">
                    <Syslog Severity="Important">Timed out triggering CANONSampler. Stopping mission.</Syslog>

                    <Guidance:Execute>
                        <Sequence/>
                        <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                    </Guidance:Execute>
                </Timeout>
                <Science:CANONSampler.sampling/>
            </ReadDatum>

            <Syslog Severity="Important">CANONSampler sampling at <Universal:depth/><Units:meter/> in <Science:CTD_NeilBrown.bin_mean_sea_water_temperature/><Units:celsius/> water with <Science:WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water/><Units:microgram_per_liter/> chlorophyll fluorescence.</Syslog>

            <ReadDatum>
                <Timeout Duration="CANONSamplerTimeout">
                    <Syslog Severity="Important">Timed out sampling with CANONSampler. Stopping mission.</Syslog>

                    <Guidance:Execute>
                        <Sequence/>
                        <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                    </Guidance:Execute>
                </Timeout>
                <Science:CANONSampler.sample_number/>
            </ReadDatum>
        </Aggregate>

        <Aggregate Id="SampleESP">
            <Sequence/>

            <Break>
                <Not><Arg Name="UseESP"/></Not>
            </Break>

            <ReadDatum Id="TriggerESP">
                <Science:ESPComponent.sampling/>
            </ReadDatum>

            <Syslog Severity="Important">ESP sampling at <Universal:depth/><Units:meter/> in <Science:CTD_NeilBrown.bin_mean_sea_water_temperature/><Units:celsius/> water with <Science:WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water/><Units:microgram_per_liter/>chlorophyll fluorescence.</Syslog>

            <ReadDatum Id="WaitForESP">
                <Science:ESPComponent.sample_number/>
            </ReadDatum>
        </Aggregate>

        <Aggregate Id="TestEndFiltering">
            <Parallel/>
            <Timeout Duration="P1M">
                <Syslog Severity="Important">Timed out to test filtering-to-processing transition</Syslog>
                <Assign><Parallel/><Science:ESPComponent.endFiltering/><True/></Assign>
            </Timeout>
        </Aggregate>

    </Aggregate>
</Aggregate>


2019-05-09T00:25:54.679Z,1557361554.679 [Default] Stopped
2019-05-09T00:25:54.680Z,1557361554.680 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-09T00:25:54.680Z,1557361554.680 [Default:A.Wait] Stopped
2019-05-09T00:25:54.680Z,1557361554.680 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-09T00:25:54.680Z,1557361554.680 [Default:B.GoToSurface] Stopped
2019-05-09T00:25:54.680Z,1557361554.680 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-09T00:25:54.680Z,1557361554.680 [MissionManager](IMPORTANT): Started mission SampleAtDepth
2019-05-09T00:25:54.680Z,1557361554.680 [SampleAtDepth] Running Loop=1
2019-05-09T00:25:54.680Z,1557361554.680 [SampleAtDepth](DEBUG): Aggregate::initialize SampleAtDepth
2019-05-09T00:25:54.685Z,1557361554.685 [SampleAtDepth:ApproachDepth] Running Loop=1
2019-05-09T00:25:54.685Z,1557361554.685 [SampleAtDepth:ApproachDepth](DEBUG): Aggregate::initialize SampleAtDepth:ApproachDepth
2019-05-09T00:25:54.685Z,1557361554.685 [SampleAtDepth:ApproachDepth:A] Running Loop=1
2019-05-09T00:25:54.685Z,1557361554.685 [SampleAtDepth:ApproachDepth:A](INFO): Moving to 5.000000 m 
2019-05-09T00:25:54.686Z,1557361554.686 [SampleAtDepth:ApproachDepth:A] Stopped
2019-05-09T00:25:54.686Z,1557361554.686 [SampleAtDepth:ApproachDepth:B.Pitch] Running Loop=1
2019-05-09T00:25:54.686Z,1557361554.686 [SampleAtDepth:ApproachDepth:B.Pitch](DEBUG): Initialize.
2019-05-09T00:25:55.634Z,1557361555.634 [CommandLine](IMPORTANT): Running ./Missions/test.xml
2019-05-09T00:26:13.341Z,1557361573.341 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 5.000000 meter
2019-05-09T00:26:13.343Z,1557361573.343 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2019-05-09T00:26:13.372Z,1557361573.372 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,Depth_Keller,NAL9602,Onboard,BPC1,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,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-05-09T00:26:13.668Z,1557361573.668 [SampleAtDepth:ApproachDepth:B.Pitch] Stopped
2019-05-09T00:26:13.668Z,1557361573.668 [SampleAtDepth:ApproachDepth](INFO): Completed SampleAtDepth:ApproachDepth
2019-05-09T00:26:13.668Z,1557361573.668 [SampleAtDepth:ApproachDepth] Stopped
2019-05-09T00:26:13.668Z,1557361573.668 [SampleAtDepth:ApproachDepth](DEBUG): Aggregate::uninitialize SampleAtDepth:ApproachDepth
2019-05-09T00:26:13.668Z,1557361573.668 [SampleAtDepth:SampleWrapper] Running Loop=1
2019-05-09T00:26:13.668Z,1557361573.668 [SampleAtDepth:SampleWrapper](DEBUG): Aggregate::initialize SampleAtDepth:SampleWrapper
2019-05-09T00:26:13.668Z,1557361573.668 [SampleAtDepth:SampleWrapper:A.Pitch] Running Loop=1
2019-05-09T00:26:13.669Z,1557361573.669 [SampleAtDepth:SampleWrapper:A.Pitch](DEBUG): Initialize.
2019-05-09T00:26:13.669Z,1557361573.669 [SampleAtDepth:SampleWrapper:TestEndFiltering] Running Loop=1
2019-05-09T00:26:13.669Z,1557361573.669 [SampleAtDepth:SampleWrapper:TestEndFiltering](DEBUG): Aggregate::initialize SampleAtDepth:SampleWrapper:TestEndFiltering
2019-05-09T00:26:13.669Z,1557361573.669 [SampleAtDepth:SampleWrapper:B] Running Loop=1
2019-05-09T00:26:14.078Z,1557361574.078 [SampleAtDepth:SampleWrapper:TestEndFiltering] Running Loop=1
2019-05-09T00:26:14.078Z,1557361574.078 [SampleAtDepth:SampleWrapper:B](IMPORTANT): At 5.000000 m , settling for 0.166667 min 
2019-05-09T00:26:14.079Z,1557361574.079 [SampleAtDepth:SampleWrapper:B] Stopped
2019-05-09T00:26:14.079Z,1557361574.079 [SampleAtDepth:SampleWrapper:C.Wait] Running Loop=1
2019-05-09T00:26:14.079Z,1557361574.079 [SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Initialize Wait Component.
2019-05-09T00:26:14.079Z,1557361574.079 [SampleAtDepth:SampleWrapper:A.Pitch] Running Loop=1
2019-05-09T00:26:24.578Z,1557361584.578 [SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
2019-05-09T00:26:24.579Z,1557361584.579 [SampleAtDepth:SampleWrapper:C.Wait] Stopped
2019-05-09T00:26:24.579Z,1557361584.579 [SampleAtDepth:SampleWrapper:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-09T00:26:24.579Z,1557361584.579 [SampleAtDepth:SampleWrapper:SampleCANONSampler] Running Loop=1
2019-05-09T00:26:24.579Z,1557361584.579 [SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::initialize SampleAtDepth:SampleWrapper:SampleCANONSampler
2019-05-09T00:26:24.579Z,1557361584.579 [SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1
2019-05-09T00:26:24.579Z,1557361584.579 [SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Running Loop=1
2019-05-09T00:26:24.579Z,1557361584.579 [SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Running Loop=1
2019-05-09T00:26:24.981Z,1557361584.981 [SampleAtDepth:SampleWrapper:SampleCANONSampler] Stopped
2019-05-09T00:26:24.982Z,1557361584.982 [SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::uninitialize SampleAtDepth:SampleWrapper:SampleCANONSampler
2019-05-09T00:26:24.982Z,1557361584.982 [SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Stopped
2019-05-09T00:26:24.982Z,1557361584.982 [SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Stopped
2019-05-09T00:26:24.982Z,1557361584.982 [SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Stopped
2019-05-09T00:26:24.982Z,1557361584.982 [SampleAtDepth:SampleWrapper:SampleESP] Running Loop=1
2019-05-09T00:26:24.982Z,1557361584.982 [SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::initialize SampleAtDepth:SampleWrapper:SampleESP
2019-05-09T00:26:24.982Z,1557361584.982 [SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Running Loop=1
2019-05-09T00:26:25.394Z,1557361585.394 [SampleAtDepth:SampleWrapper:SampleESP:TriggerESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sampling
2019-05-09T00:26:26.162Z,1557361586.162 [ESPComponent](IMPORTANT): Setting regex for ESP Log summary: 'Selecting Cartridge|Sampled|Error|Fail|Retry|Cmd::|Sampler::'
2019-05-09T00:26:26.165Z,1557361586.165 [ESPComponent](INFO): compiled regex: "Selecting Cartridge|Sampled|Error|Fail|Retry|Cmd::|Sampler::"
2019-05-09T00:26:26.567Z,1557361586.567 [ESPComponent](INFO): powering up ESP
2019-05-09T00:26:26.568Z,1557361586.568 [ESPComponent](IMPORTANT): Starting PPPD with command:/sbin/pppd xonxoff asyncmap A0000 /dev/ttyTX1 linkname esp noauth local lock 115200 10.89.5.2:10.89.5.3 persist maxfail 0 holdoff 10 lcp-echo-interval 60 lcp-echo-failure 3 proxyarp ktune deflate 12 ms-dns 8.8.8.8
2019-05-09T00:26:26.694Z,1557361586.694 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=150.0000)
2019-05-09T00:26:29.103Z,1557361589.103 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:26:29.103Z,1557361589.103 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-09T00:26:29.104Z,1557361589.104 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:26:29.153Z,1557361589.153 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:26:29.452Z,1557361589.452 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:26:29.452Z,1557361589.452 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-09T00:27:10.267Z,1557361630.267 [CommandLine](IMPORTANT): got command stop
2019-05-09T00:27:10.267Z,1557361630.267 [CommandLine](IMPORTANT): Scheduling is paused
2019-05-09T00:27:10.267Z,1557361630.267 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-05-09T00:27:10.272Z,1557361630.272 [MissionManager](INFO): MissionManager is completed.
2019-05-09T00:27:10.272Z,1557361630.272 [MissionManager](INFO): Uninitializing Mission SampleAtDepth
2019-05-09T00:27:10.272Z,1557361630.272 [SampleAtDepth] Stopped
2019-05-09T00:27:10.272Z,1557361630.272 [SampleAtDepth](DEBUG): Aggregate::uninitialize SampleAtDepth
2019-05-09T00:27:10.272Z,1557361630.272 [SampleAtDepth:SampleWrapper] Stopped
2019-05-09T00:27:10.272Z,1557361630.272 [SampleAtDepth:SampleWrapper](DEBUG): Aggregate::uninitialize SampleAtDepth:SampleWrapper
2019-05-09T00:27:10.273Z,1557361630.273 [SampleAtDepth:SampleWrapper:A.Pitch] Stopped
2019-05-09T00:27:10.273Z,1557361630.273 [SampleAtDepth:SampleWrapper:SampleESP] Stopped
2019-05-09T00:27:10.273Z,1557361630.273 [SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::uninitialize SampleAtDepth:SampleWrapper:SampleESP
2019-05-09T00:27:10.273Z,1557361630.273 [SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Stopped
2019-05-09T00:27:10.273Z,1557361630.273 [SampleAtDepth:SampleWrapper:TestEndFiltering] Stopped
2019-05-09T00:27:10.273Z,1557361630.273 [SampleAtDepth:SampleWrapper:TestEndFiltering](DEBUG): Aggregate::uninitialize SampleAtDepth:SampleWrapper:TestEndFiltering
2019-05-09T00:27:10.661Z,1557361630.661 [MissionManager](IMPORTANT): Started mission Default
2019-05-09T00:27:10.662Z,1557361630.662 [Default] Running Loop=1
2019-05-09T00:27:10.662Z,1557361630.662 [Default](DEBUG): Aggregate::initialize Default
2019-05-09T00:27:10.662Z,1557361630.662 [Default:B.GoToSurface] Running Loop=1
2019-05-09T00:27:10.662Z,1557361630.662 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-09T00:27:10.662Z,1557361630.662 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-09T00:27:10.662Z,1557361630.662 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-09T00:27:10.663Z,1557361630.663 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-09T00:27:10.663Z,1557361630.663 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-09T00:27:10.663Z,1557361630.663 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-09T00:27:10.664Z,1557361630.664 [Default:A.Wait] Running Loop=1
2019-05-09T00:27:10.664Z,1557361630.664 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-09T00:27:15.986Z,1557361635.986 [CommandLine](IMPORTANT): got command maintain clear
2019-05-09T00:27:16.026Z,1557361636.026 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,Depth_Keller,NAL9602,Onboard,BPC1,PAR_Licor,Depth_Keller,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-05-09T00:27:24.026Z,1557361644.026 [Default:A.Wait](INFO): Done Waiting.
2019-05-09T00:27:24.026Z,1557361644.026 [Default:A.Wait] Stopped
2019-05-09T00:27:24.026Z,1557361644.026 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-09T00:27:24.414Z,1557361644.414 [Default:CheckIn] Running Loop=1
2019-05-09T00:27:24.414Z,1557361644.414 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-09T00:27:24.414Z,1557361644.414 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-09T00:27:24.817Z,1557361644.817 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-09T00:28:09.235Z,1557361689.235 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-05-09T00:28:10.057Z,1557361690.057 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:14.905Z,1557361694.905 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:18.137Z,1557361698.137 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:20.961Z,1557361700.961 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:24.197Z,1557361704.197 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:27.025Z,1557361707.025 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:29.857Z,1557361709.857 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:29.896Z,1557361709.896 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:28:29.896Z,1557361709.896 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-09T00:28:29.896Z,1557361709.896 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:28:29.907Z,1557361709.907 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:28:30.364Z,1557361710.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:28:30.364Z,1557361710.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-09T00:28:31.873Z,1557361711.873 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:33.893Z,1557361713.893 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:37.125Z,1557361717.125 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:39.953Z,1557361719.953 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:43.189Z,1557361723.189 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:46.013Z,1557361726.013 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:49.245Z,1557361729.245 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:52.077Z,1557361732.077 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:54.901Z,1557361734.901 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:56.897Z,1557361736.897 [ESPComponent](CRITICAL): Timeout while waiting for ESP to connect (elapsed=150.2032)
2019-05-09T00:28:56.898Z,1557361736.898 [ESPComponent] Communications Fault, FailCount= 1
2019-05-09T00:28:56.898Z,1557361736.898 [ESPComponent](ERROR): Communications Fault
2019-05-09T00:28:56.921Z,1557361736.921 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:28:56.968Z,1557361736.968 [CommandLine](FAULT): Scheduling is paused
2019-05-09T00:28:56.968Z,1557361736.968 [CBIT](INFO): Critical error at 20190509T002856
2019-05-09T00:28:56.971Z,1557361736.971 [CBIT](ERROR): Communications Fault in component: ESPComponent
2019-05-09T00:28:57.301Z,1557361737.301 [ESPComponent](IMPORTANT): [sample #0] No ESP summary messages.
2019-05-09T00:28:57.301Z,1557361737.301 [ESPComponent](INFO): powering down ESP
2019-05-09T00:28:58.178Z,1557361738.178 [CBIT](INFO): Clearing failed state for component ESPComponent
2019-05-09T00:28:58.179Z,1557361738.179 [ESPComponent] No Fault, FailCount= 1
2019-05-09T00:28:58.917Z,1557361738.917 [ESPComponent](IMPORTANT): [sample #0] No ESP summary messages.
2019-05-09T00:28:58.917Z,1557361738.917 [ESPComponent](INFO): powering down ESP
2019-05-09T00:28:59.033Z,1557361739.033 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:02.173Z,1557361742.173 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:05.001Z,1557361745.001 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:08.233Z,1557361748.233 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:11.061Z,1557361751.061 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:14.293Z,1557361754.293 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:17.121Z,1557361757.121 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:19.945Z,1557361759.945 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:21.965Z,1557361761.965 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:25.201Z,1557361765.201 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:28.029Z,1557361768.029 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:31.261Z,1557361771.261 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:34.089Z,1557361774.089 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:37.321Z,1557361777.321 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:40.149Z,1557361780.149 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:42.977Z,1557361782.977 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:44.997Z,1557361784.997 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:47.017Z,1557361787.017 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:50.245Z,1557361790.245 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:53.077Z,1557361793.077 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:56.309Z,1557361796.309 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:29:59.137Z,1557361799.137 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:02.369Z,1557361802.369 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:05.197Z,1557361805.197 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:08.025Z,1557361808.025 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:09.315Z,1557361809.315 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-05-09T00:30:09.318Z,1557361809.318 [BPC1](INFO): Received data from all battery sticks.
2019-05-09T00:30:10.045Z,1557361810.045 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:13.277Z,1557361813.277 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:16.105Z,1557361816.105 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:19.337Z,1557361819.337 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:22.161Z,1557361822.161 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:25.397Z,1557361825.397 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:28.225Z,1557361828.225 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:31.053Z,1557361831.053 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:31.071Z,1557361831.071 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:30:31.071Z,1557361831.071 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-05-09T00:30:31.071Z,1557361831.071 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:30:31.082Z,1557361831.082 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:30:31.488Z,1557361831.488 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:30:31.488Z,1557361831.488 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-05-09T00:30:33.065Z,1557361833.065 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:35.093Z,1557361835.093 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:38.325Z,1557361838.325 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:41.153Z,1557361841.153 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:44.385Z,1557361844.385 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:47.213Z,1557361847.213 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:50.441Z,1557361850.441 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:53.269Z,1557361853.269 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:56.093Z,1557361856.093 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:30:58.121Z,1557361858.121 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:01.353Z,1557361861.353 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:04.177Z,1557361864.177 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:07.410Z,1557361867.410 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:10.241Z,1557361870.241 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:13.469Z,1557361873.469 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:16.301Z,1557361876.301 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:19.125Z,1557361879.125 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:21.145Z,1557361881.145 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:23.165Z,1557361883.165 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:26.401Z,1557361886.401 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:29.229Z,1557361889.229 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:32.453Z,1557361892.453 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:35.289Z,1557361895.289 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:38.513Z,1557361898.513 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:41.349Z,1557361901.349 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:44.177Z,1557361904.177 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:46.197Z,1557361906.197 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:49.429Z,1557361909.429 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:52.253Z,1557361912.253 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:55.489Z,1557361915.489 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:31:58.317Z,1557361918.317 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:01.541Z,1557361921.541 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:04.377Z,1557361924.377 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:07.205Z,1557361927.205 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:09.225Z,1557361929.225 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:12.457Z,1557361932.457 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:15.285Z,1557361935.285 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:18.509Z,1557361938.509 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:21.341Z,1557361941.341 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:24.577Z,1557361944.577 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:24.609Z,1557361944.609 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-09T00:27:24.4Z
2019-05-09T00:32:24.609Z,1557361944.609 [Default:CheckIn:Read_GPS] Stopped
2019-05-09T00:32:24.610Z,1557361944.610 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-09T00:32:25.005Z,1557361945.005 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-09T00:32:27.405Z,1557361947.405 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:30.229Z,1557361950.229 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:32.045Z,1557361952.045 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20190509T002217/Courier0004.lzma
2019-05-09T00:32:32.253Z,1557361952.253 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:32.271Z,1557361952.271 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:32:32.271Z,1557361952.271 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-05-09T00:32:32.271Z,1557361952.271 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:32:32.307Z,1557361952.307 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:32:32.683Z,1557361952.683 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:32:32.683Z,1557361952.683 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-05-09T00:32:32.850Z,1557361952.850 [DataOverHttps](INFO): Moved sent file to Logs/20190509T002217/Courier0004.lzma.bak
2019-05-09T00:32:32.850Z,1557361952.850 [DataOverHttps](INFO): SBD MOMSN=10981876
2019-05-09T00:32:34.269Z,1557361954.269 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:37.505Z,1557361957.505 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:40.329Z,1557361960.329 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:43.561Z,1557361963.561 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:46.389Z,1557361966.389 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:46.702Z,1557361966.702 [DataOverHttps](INFO): Sending 955 bytes from file Logs/20190509T002217/Express0001.lzma
2019-05-09T00:32:47.506Z,1557361967.506 [DataOverHttps](INFO): Moved sent file to Logs/20190509T002217/Express0001.lzma.bak
2019-05-09T00:32:47.507Z,1557361967.507 [DataOverHttps](INFO): SBD MOMSN=10981879
2019-05-09T00:32:49.621Z,1557361969.621 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:52.473Z,1557361972.473 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:55.281Z,1557361975.281 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:32:57.301Z,1557361977.301 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:33:00.529Z,1557361980.529 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:33:02.630Z,1557361982.630 [DataOverHttps](INFO): Sending 864 bytes from file Logs/20190509T002217/Express0005.lzma
2019-05-09T00:33:03.361Z,1557361983.361 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:33:03.434Z,1557361983.434 [DataOverHttps](INFO): Moved sent file to Logs/20190509T002217/Express0005.lzma.bak
2019-05-09T00:33:03.434Z,1557361983.434 [DataOverHttps](INFO): SBD MOMSN=10981906
2019-05-09T00:33:06.225Z,1557361986.225 [Default:CheckIn:Read_Iridium] Stopped
2019-05-09T00:33:06.226Z,1557361986.226 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-09T00:33:06.226Z,1557361986.226 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-09T00:33:06.589Z,1557361986.589 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:33:09.407Z,1557361989.407 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-05-09T00:33:09.407Z,1557361989.407 [NAL9602] Data Fault, FailCount= 1
2019-05-09T00:33:09.407Z,1557361989.407 [NAL9602](ERROR): Data Fault
2019-05-09T00:33:09.454Z,1557361989.454 [CBIT](ERROR): Data Fault in component: NAL9602
2019-05-09T00:33:09.814Z,1557361989.814 [NAL9602](INFO): Powering down
2019-05-09T00:33:10.638Z,1557361990.638 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-09T00:33:10.639Z,1557361990.639 [NAL9602] No Fault, FailCount= 1
2019-05-09T00:33:40.103Z,1557362020.103 [NAL9602](INFO): Powering up NAL9602
2019-05-09T00:33:51.019Z,1557362031.019 [NAL9602](INFO): NAL9602 initialized
2019-05-09T00:33:51.833Z,1557362031.833 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:33:54.265Z,1557362034.265 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:33:57.089Z,1557362037.089 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:00.325Z,1557362040.325 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:03.145Z,1557362043.145 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:06.385Z,1557362046.385 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:09.213Z,1557362049.213 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:12.041Z,1557362052.041 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:14.061Z,1557362054.061 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:16.081Z,1557362056.081 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:19.313Z,1557362059.313 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:22.141Z,1557362062.141 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:25.369Z,1557362065.369 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:28.201Z,1557362068.201 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:31.433Z,1557362071.433 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:33.464Z,1557362073.464 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:34:33.464Z,1557362073.464 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-05-09T00:34:33.464Z,1557362073.464 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:34:33.475Z,1557362073.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:34:33.877Z,1557362073.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:34:33.877Z,1557362073.877 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-05-09T00:34:34.261Z,1557362074.261 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:37.081Z,1557362077.081 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:39.101Z,1557362079.101 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:41.129Z,1557362081.129 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:44.361Z,1557362084.361 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:47.189Z,1557362087.189 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:50.421Z,1557362090.421 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:53.249Z,1557362093.249 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:56.477Z,1557362096.477 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:34:59.309Z,1557362099.309 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:02.137Z,1557362102.137 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:04.157Z,1557362104.157 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:06.177Z,1557362106.177 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:09.401Z,1557362109.401 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:12.237Z,1557362112.237 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:15.469Z,1557362115.469 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:18.297Z,1557362118.297 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:21.529Z,1557362121.529 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:24.357Z,1557362124.357 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:27.181Z,1557362127.181 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:29.205Z,1557362129.205 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:32.437Z,1557362132.437 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:35.265Z,1557362135.265 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:38.497Z,1557362138.497 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:41.325Z,1557362141.325 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:44.553Z,1557362144.553 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:47.381Z,1557362147.381 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:50.209Z,1557362150.209 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:52.229Z,1557362152.229 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:55.493Z,1557362155.493 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:35:58.289Z,1557362158.289 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:01.517Z,1557362161.517 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:04.353Z,1557362164.353 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:07.577Z,1557362167.577 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:10.413Z,1557362170.413 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:13.241Z,1557362173.241 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:15.261Z,1557362175.261 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:17.273Z,1557362177.273 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:20.513Z,1557362180.513 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:23.341Z,1557362183.341 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:26.569Z,1557362186.569 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:29.401Z,1557362189.401 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:32.633Z,1557362192.633 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:34.660Z,1557362194.660 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:36:34.660Z,1557362194.660 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-05-09T00:36:34.660Z,1557362194.660 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:36:34.671Z,1557362194.671 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:36:35.077Z,1557362195.077 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:36:35.077Z,1557362195.077 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-05-09T00:36:35.453Z,1557362195.453 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:38.289Z,1557362198.289 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:40.309Z,1557362200.309 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:43.541Z,1557362203.541 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:46.369Z,1557362206.369 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:49.601Z,1557362209.601 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:52.429Z,1557362212.429 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:55.657Z,1557362215.657 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:36:58.485Z,1557362218.485 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:01.317Z,1557362221.317 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:03.337Z,1557362223.337 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:06.569Z,1557362226.569 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:09.397Z,1557362229.397 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:12.629Z,1557362232.629 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:15.457Z,1557362235.457 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:18.689Z,1557362238.689 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:21.509Z,1557362241.509 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:24.345Z,1557362244.345 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:26.357Z,1557362246.357 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:28.381Z,1557362248.381 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:31.609Z,1557362251.609 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:34.445Z,1557362254.445 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:37.669Z,1557362257.669 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:40.505Z,1557362260.505 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:43.729Z,1557362263.729 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:46.565Z,1557362266.565 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:49.393Z,1557362269.393 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:51.413Z,1557362271.413 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:53.433Z,1557362273.433 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:56.657Z,1557362276.657 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:37:59.493Z,1557362279.493 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:02.717Z,1557362282.717 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:05.553Z,1557362285.553 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:06.765Z,1557362286.765 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-05-09T00:38:06.765Z,1557362286.765 [Default:CheckIn:C.Wait] Stopped
2019-05-09T00:38:06.765Z,1557362286.765 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-09T00:38:06.765Z,1557362286.765 [Default:CheckIn:D] Running Loop=1
2019-05-09T00:38:07.177Z,1557362287.177 [Default:CheckIn:D] Stopped
2019-05-09T00:38:07.177Z,1557362287.177 [Default:CheckIn:E] Running Loop=1
2019-05-09T00:38:07.572Z,1557362287.572 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.941923 min 
2019-05-09T00:38:07.572Z,1557362287.572 [Default:CheckIn:E] Stopped
2019-05-09T00:38:07.572Z,1557362287.572 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-05-09T00:38:07.572Z,1557362287.572 [Default:CheckIn] Stopped
2019-05-09T00:38:07.572Z,1557362287.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-09T00:38:07.572Z,1557362287.572 [Default:CheckIn](INFO): Running loop #2
2019-05-09T00:38:07.572Z,1557362287.572 [Default:CheckIn] Running Loop=2
2019-05-09T00:38:07.572Z,1557362287.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-09T00:38:07.590Z,1557362287.590 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-09T00:38:08.777Z,1557362288.777 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:11.605Z,1557362291.605 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:14.441Z,1557362294.441 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:16.461Z,1557362296.461 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:19.685Z,1557362299.685 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:22.517Z,1557362302.517 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:25.745Z,1557362305.745 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:28.577Z,1557362308.577 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:31.805Z,1557362311.805 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:34.633Z,1557362314.633 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:35.856Z,1557362315.856 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:38:35.856Z,1557362315.856 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-05-09T00:38:35.856Z,1557362315.856 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:38:35.867Z,1557362315.867 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:38:36.273Z,1557362316.273 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:38:36.273Z,1557362316.273 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-05-09T00:38:37.469Z,1557362317.469 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:39.489Z,1557362319.489 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:42.713Z,1557362322.713 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:45.549Z,1557362325.549 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:48.773Z,1557362328.773 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:51.601Z,1557362331.601 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:54.833Z,1557362334.833 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:38:57.665Z,1557362337.665 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:00.497Z,1557362340.497 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:02.517Z,1557362342.517 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:05.741Z,1557362345.741 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:08.569Z,1557362348.569 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:11.801Z,1557362351.801 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:14.629Z,1557362354.629 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:17.869Z,1557362357.869 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:20.689Z,1557362360.689 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:23.525Z,1557362363.525 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:25.545Z,1557362365.545 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:28.773Z,1557362368.773 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:31.597Z,1557362371.597 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:34.829Z,1557362374.829 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:37.657Z,1557362377.657 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:40.889Z,1557362380.889 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:43.717Z,1557362383.717 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:46.553Z,1557362386.553 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:48.565Z,1557362388.565 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:50.585Z,1557362390.585 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:53.825Z,1557362393.825 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:56.645Z,1557362396.645 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:39:59.885Z,1557362399.885 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:02.705Z,1557362402.705 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:05.945Z,1557362405.945 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:08.765Z,1557362408.765 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:11.593Z,1557362411.593 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:13.613Z,1557362413.613 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:15.641Z,1557362415.641 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:18.865Z,1557362418.865 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:21.701Z,1557362421.701 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:24.925Z,1557362424.925 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:27.757Z,1557362427.757 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:30.985Z,1557362430.985 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:33.821Z,1557362433.821 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:36.641Z,1557362436.641 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:37.068Z,1557362437.068 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:40:37.068Z,1557362437.068 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-05-09T00:40:37.068Z,1557362437.068 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:40:37.079Z,1557362437.079 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:40:37.483Z,1557362437.483 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:40:37.484Z,1557362437.484 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-05-09T00:40:38.669Z,1557362438.669 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:41.901Z,1557362441.901 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:44.729Z,1557362444.729 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:47.953Z,1557362447.953 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:50.789Z,1557362450.789 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:54.021Z,1557362454.021 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:56.849Z,1557362456.849 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:40:59.673Z,1557362459.673 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:01.693Z,1557362461.693 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:03.717Z,1557362463.717 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:06.949Z,1557362466.949 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:09.777Z,1557362469.777 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:13.009Z,1557362473.009 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:15.837Z,1557362475.837 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:19.065Z,1557362479.065 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:21.901Z,1557362481.901 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:24.729Z,1557362484.729 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:26.741Z,1557362486.741 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:29.981Z,1557362489.981 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:32.801Z,1557362492.801 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:36.037Z,1557362496.037 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:38.861Z,1557362498.861 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:42.101Z,1557362502.101 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:44.921Z,1557362504.921 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:47.757Z,1557362507.757 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:49.769Z,1557362509.769 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:51.789Z,1557362511.789 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:55.029Z,1557362515.029 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:41:57.853Z,1557362517.853 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:01.089Z,1557362521.089 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:03.917Z,1557362523.917 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:07.149Z,1557362527.149 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:09.977Z,1557362529.977 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:12.797Z,1557362532.797 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:14.817Z,1557362534.817 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:18.057Z,1557362538.057 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:20.877Z,1557362540.877 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:24.117Z,1557362544.117 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:26.941Z,1557362546.941 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:30.173Z,1557362550.173 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:32.997Z,1557362552.997 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:35.825Z,1557362555.825 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:37.845Z,1557362557.845 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:37.868Z,1557362557.868 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:42:37.868Z,1557362557.868 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-05-09T00:42:37.869Z,1557362557.869 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:42:37.879Z,1557362557.879 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:42:38.293Z,1557362558.293 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:42:38.294Z,1557362558.294 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-05-09T00:42:41.085Z,1557362561.085 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:43.909Z,1557362563.909 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:47.145Z,1557362567.145 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:49.965Z,1557362569.965 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:53.205Z,1557362573.205 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:56.033Z,1557362576.033 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:42:58.857Z,1557362578.857 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:00.873Z,1557362580.873 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:02.901Z,1557362582.901 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:06.133Z,1557362586.133 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:07.754Z,1557362587.754 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-09T00:38:07.6Z
2019-05-09T00:43:07.754Z,1557362587.754 [Default:CheckIn:Read_GPS] Stopped
2019-05-09T00:43:07.754Z,1557362587.754 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-09T00:43:08.957Z,1557362588.957 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:12.189Z,1557362592.189 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:14.956Z,1557362594.956 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190509T002217/Courier0007.lzma
2019-05-09T00:43:15.021Z,1557362595.021 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:15.762Z,1557362595.762 [DataOverHttps](INFO): Moved sent file to Logs/20190509T002217/Courier0007.lzma.bak
2019-05-09T00:43:15.762Z,1557362595.762 [DataOverHttps](INFO): SBD MOMSN=10982029
2019-05-09T00:43:18.249Z,1557362598.249 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:21.077Z,1557362601.077 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:23.913Z,1557362603.913 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:25.929Z,1557362605.929 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:27.945Z,1557362607.945 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:30.881Z,1557362610.881 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20190509T002217/Express0008.lzma
2019-05-09T00:43:31.173Z,1557362611.173 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:31.686Z,1557362611.686 [DataOverHttps](INFO): Moved sent file to Logs/20190509T002217/Express0008.lzma.bak
2019-05-09T00:43:31.686Z,1557362611.686 [DataOverHttps](INFO): SBD MOMSN=10982031
2019-05-09T00:43:34.005Z,1557362614.005 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:34.845Z,1557362614.845 [Default:CheckIn:Read_Iridium] Stopped
2019-05-09T00:43:34.845Z,1557362614.845 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-09T00:43:34.845Z,1557362614.845 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-09T00:43:37.237Z,1557362617.237 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:40.061Z,1557362620.061 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:43.301Z,1557362623.301 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:46.125Z,1557362626.125 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:48.957Z,1557362628.957 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:50.969Z,1557362630.969 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:43:54.195Z,1557362634.195 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-05-09T00:43:54.195Z,1557362634.195 [NAL9602] Data Fault, FailCount= 2
2019-05-09T00:43:54.195Z,1557362634.195 [NAL9602](ERROR): Data Fault
2019-05-09T00:43:54.251Z,1557362634.251 [CBIT](ERROR): Data Fault in component: NAL9602
2019-05-09T00:43:54.591Z,1557362634.591 [NAL9602](INFO): Powering down
2019-05-09T00:43:55.434Z,1557362635.434 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-09T00:43:55.434Z,1557362635.434 [NAL9602] No Fault, FailCount= 2
2019-05-09T00:44:24.891Z,1557362664.891 [NAL9602](INFO): Powering up NAL9602
2019-05-09T00:44:35.807Z,1557362675.807 [NAL9602](INFO): NAL9602 initialized
2019-05-09T00:44:36.621Z,1557362676.621 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:44:39.045Z,1557362679.045 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:44:39.072Z,1557362679.072 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:44:39.072Z,1557362679.072 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-05-09T00:44:39.072Z,1557362679.072 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:44:39.083Z,1557362679.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:44:39.488Z,1557362679.488 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:44:39.489Z,1557362679.489 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-05-09T00:44:41.877Z,1557362681.877 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:44:45.105Z,1557362685.105 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:44:47.937Z,1557362687.937 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:44:51.165Z,1557362691.165 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:44:54.001Z,1557362694.001 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:44:56.829Z,1557362696.829 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:44:58.853Z,1557362698.853 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:00.861Z,1557362700.861 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:04.101Z,1557362704.101 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:06.921Z,1557362706.921 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:10.161Z,1557362710.161 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:12.981Z,1557362712.981 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:16.221Z,1557362716.221 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:19.041Z,1557362719.041 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:21.877Z,1557362721.877 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:23.897Z,1557362723.897 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:25.917Z,1557362725.917 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:29.149Z,1557362729.149 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:31.977Z,1557362731.977 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:35.209Z,1557362735.209 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:38.037Z,1557362738.037 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:41.269Z,1557362741.269 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:44.097Z,1557362744.097 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:46.929Z,1557362746.929 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:48.949Z,1557362748.949 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:52.181Z,1557362752.181 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:55.017Z,1557362755.017 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:45:58.241Z,1557362758.241 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:01.081Z,1557362761.081 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:04.305Z,1557362764.305 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:07.133Z,1557362767.133 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:09.961Z,1557362769.961 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:11.981Z,1557362771.981 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:15.209Z,1557362775.209 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:18.049Z,1557362778.049 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:21.273Z,1557362781.273 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:24.121Z,1557362784.121 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:27.333Z,1557362787.333 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:30.161Z,1557362790.161 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:32.981Z,1557362792.981 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:36.221Z,1557362796.221 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:39.041Z,1557362799.041 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:39.880Z,1557362799.880 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:46:39.880Z,1557362799.880 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-05-09T00:46:39.880Z,1557362799.880 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:46:39.890Z,1557362799.890 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:46:40.282Z,1557362800.282 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:46:40.282Z,1557362800.282 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-05-09T00:46:42.281Z,1557362802.281 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:45.101Z,1557362805.101 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:48.341Z,1557362808.341 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:51.161Z,1557362811.161 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:53.993Z,1557362813.993 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:56.017Z,1557362816.017 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:46:58.037Z,1557362818.037 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:00.057Z,1557362820.057 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:03.289Z,1557362823.289 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:06.117Z,1557362826.117 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:09.349Z,1557362829.349 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:12.177Z,1557362832.177 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:15.417Z,1557362835.417 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:18.233Z,1557362838.233 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:21.065Z,1557362841.065 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:23.085Z,1557362843.085 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:26.317Z,1557362846.317 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:29.145Z,1557362849.145 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:32.377Z,1557362852.377 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:35.205Z,1557362855.205 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:38.437Z,1557362858.437 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:41.265Z,1557362861.265 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:44.093Z,1557362864.093 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:46.105Z,1557362866.105 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:48.129Z,1557362868.129 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:51.365Z,1557362871.365 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:54.185Z,1557362874.185 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:47:57.425Z,1557362877.425 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:00.245Z,1557362880.245 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:03.485Z,1557362883.485 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:06.313Z,1557362886.313 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:09.141Z,1557362889.141 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:11.153Z,1557362891.153 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:14.393Z,1557362894.393 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:17.217Z,1557362897.217 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:20.453Z,1557362900.453 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:23.273Z,1557362903.273 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:26.513Z,1557362906.513 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:29.341Z,1557362909.341 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:32.169Z,1557362912.169 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:34.189Z,1557362914.189 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:35.413Z,1557362915.413 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-05-09T00:48:35.413Z,1557362915.413 [Default:CheckIn:C.Wait] Stopped
2019-05-09T00:48:35.414Z,1557362915.414 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-09T00:48:35.414Z,1557362915.414 [Default:CheckIn:D] Running Loop=1
2019-05-09T00:48:35.817Z,1557362915.817 [Default:CheckIn:D] Stopped
2019-05-09T00:48:35.817Z,1557362915.817 [Default:CheckIn:E] Running Loop=1
2019-05-09T00:48:36.209Z,1557362916.209 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:36.234Z,1557362916.234 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.419250 min 
2019-05-09T00:48:36.234Z,1557362916.234 [Default:CheckIn:E] Stopped
2019-05-09T00:48:36.234Z,1557362916.234 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-05-09T00:48:36.234Z,1557362916.234 [Default:CheckIn] Stopped
2019-05-09T00:48:36.234Z,1557362916.234 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-09T00:48:36.234Z,1557362916.234 [Default:CheckIn](INFO): Running loop #3
2019-05-09T00:48:36.234Z,1557362916.234 [Default:CheckIn] Running Loop=3
2019-05-09T00:48:36.234Z,1557362916.234 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-09T00:48:36.235Z,1557362916.235 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-09T00:48:39.441Z,1557362919.441 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:41.062Z,1557362921.062 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:48:41.062Z,1557362921.062 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-05-09T00:48:41.062Z,1557362921.062 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:48:41.104Z,1557362921.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:48:41.475Z,1557362921.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:48:41.475Z,1557362921.475 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-05-09T00:48:42.269Z,1557362922.269 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:45.501Z,1557362925.501 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:48.325Z,1557362928.325 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:51.561Z,1557362931.561 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:54.389Z,1557362934.389 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:57.209Z,1557362937.209 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:48:59.237Z,1557362939.237 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:02.469Z,1557362942.469 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:05.297Z,1557362945.297 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:08.529Z,1557362948.529 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:11.357Z,1557362951.357 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:14.589Z,1557362954.589 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:17.413Z,1557362957.413 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:20.237Z,1557362960.237 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:22.265Z,1557362962.265 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:24.285Z,1557362964.285 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:27.517Z,1557362967.517 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:30.345Z,1557362970.345 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:33.577Z,1557362973.577 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:36.405Z,1557362976.405 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:39.637Z,1557362979.637 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:42.461Z,1557362982.461 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:45.293Z,1557362985.293 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:47.313Z,1557362987.313 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:50.541Z,1557362990.541 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:53.373Z,1557362993.373 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:56.605Z,1557362996.605 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:49:59.433Z,1557362999.433 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:02.665Z,1557363002.665 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:05.493Z,1557363005.493 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:08.321Z,1557363008.321 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:10.341Z,1557363010.341 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:12.353Z,1557363012.353 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:15.593Z,1557363015.593 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:18.417Z,1557363018.417 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:21.653Z,1557363021.653 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:24.481Z,1557363024.481 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:27.713Z,1557363027.713 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:30.541Z,1557363030.541 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:33.369Z,1557363033.369 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:35.385Z,1557363035.385 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:38.621Z,1557363038.621 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:41.449Z,1557363041.449 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:41.871Z,1557363041.871 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:50:41.871Z,1557363041.871 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-05-09T00:50:41.871Z,1557363041.871 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:50:41.881Z,1557363041.881 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:50:42.289Z,1557363042.289 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:50:42.289Z,1557363042.289 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-05-09T00:50:44.681Z,1557363044.681 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:47.505Z,1557363047.505 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:50.741Z,1557363050.741 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:53.561Z,1557363053.561 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:56.397Z,1557363056.397 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:50:58.417Z,1557363058.417 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:01.649Z,1557363061.649 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:04.477Z,1557363064.477 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:07.709Z,1557363067.709 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:10.537Z,1557363070.537 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:13.769Z,1557363073.769 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:16.593Z,1557363076.593 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:19.421Z,1557363079.421 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:21.441Z,1557363081.441 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:23.461Z,1557363083.461 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:26.693Z,1557363086.693 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:29.533Z,1557363089.533 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:32.753Z,1557363092.753 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:35.585Z,1557363095.585 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:38.817Z,1557363098.817 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:41.645Z,1557363101.645 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:44.473Z,1557363104.473 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:46.493Z,1557363106.493 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:49.721Z,1557363109.721 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:52.553Z,1557363112.553 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:55.785Z,1557363115.785 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:51:58.613Z,1557363118.613 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:01.845Z,1557363121.845 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:04.673Z,1557363124.673 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:07.501Z,1557363127.501 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:09.521Z,1557363129.521 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:11.541Z,1557363131.541 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:14.765Z,1557363134.765 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:17.597Z,1557363137.597 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:20.825Z,1557363140.825 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:23.661Z,1557363143.661 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:26.885Z,1557363146.885 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:29.721Z,1557363149.721 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:32.549Z,1557363152.549 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:34.569Z,1557363154.569 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:36.589Z,1557363156.589 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:39.825Z,1557363159.825 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:42.649Z,1557363162.649 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:42.672Z,1557363162.672 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:52:42.672Z,1557363162.672 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-05-09T00:52:42.672Z,1557363162.672 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:52:42.683Z,1557363162.683 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:52:43.085Z,1557363163.085 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:52:43.085Z,1557363163.085 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-05-09T00:52:45.881Z,1557363165.881 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:48.701Z,1557363168.701 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:51.941Z,1557363171.941 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:54.761Z,1557363174.761 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:57.597Z,1557363177.597 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:52:59.617Z,1557363179.617 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:02.849Z,1557363182.849 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:05.669Z,1557363185.669 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:08.909Z,1557363188.909 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:11.729Z,1557363191.729 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:14.969Z,1557363194.969 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:17.793Z,1557363197.793 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:20.621Z,1557363200.621 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:22.645Z,1557363202.645 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:25.877Z,1557363205.877 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:28.697Z,1557363208.697 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:31.937Z,1557363211.937 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:34.757Z,1557363214.757 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:36.386Z,1557363216.386 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-09T00:48:36.2Z
2019-05-09T00:53:36.386Z,1557363216.386 [Default:CheckIn:Read_GPS] Stopped
2019-05-09T00:53:36.386Z,1557363216.386 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-09T00:53:38.001Z,1557363218.001 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:40.821Z,1557363220.821 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:43.649Z,1557363223.649 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:45.673Z,1557363225.673 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:48.901Z,1557363228.901 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:51.725Z,1557363231.725 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:54.969Z,1557363234.969 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:53:56.840Z,1557363236.840 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190509T002217/Courier0010.lzma
2019-05-09T00:53:57.646Z,1557363237.646 [DataOverHttps](INFO): Moved sent file to Logs/20190509T002217/Courier0010.lzma.bak
2019-05-09T00:53:57.646Z,1557363237.646 [DataOverHttps](INFO): SBD MOMSN=10982133
2019-05-09T00:53:57.785Z,1557363237.785 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:01.025Z,1557363241.025 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:03.849Z,1557363243.849 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:06.677Z,1557363246.677 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:08.693Z,1557363248.693 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:10.717Z,1557363250.717 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:12.649Z,1557363252.649 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20190509T002217/Express0011.lzma
2019-05-09T00:54:13.454Z,1557363253.454 [DataOverHttps](INFO): Moved sent file to Logs/20190509T002217/Express0011.lzma.bak
2019-05-09T00:54:13.454Z,1557363253.454 [DataOverHttps](INFO): SBD MOMSN=10982136
2019-05-09T00:54:13.949Z,1557363253.949 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:16.397Z,1557363256.397 [Default:CheckIn:Read_Iridium] Stopped
2019-05-09T00:54:16.398Z,1557363256.398 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-09T00:54:16.398Z,1557363256.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-09T00:54:16.777Z,1557363256.777 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:20.009Z,1557363260.009 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:22.841Z,1557363262.841 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:26.069Z,1557363266.069 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:28.897Z,1557363268.897 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:31.721Z,1557363271.721 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:33.749Z,1557363273.749 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:54:36.967Z,1557363276.967 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-05-09T00:54:36.967Z,1557363276.967 [NAL9602] Data Fault, FailCount= 3
2019-05-09T00:54:36.967Z,1557363276.967 [NAL9602](ERROR): Data Fault
2019-05-09T00:54:36.994Z,1557363276.994 [CBIT](ERROR): Data Fault in component: NAL9602
2019-05-09T00:54:37.374Z,1557363277.374 [NAL9602](INFO): Powering down
2019-05-09T00:54:38.210Z,1557363278.210 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-09T00:54:38.210Z,1557363278.210 [NAL9602] No Fault, FailCount= 3
2019-05-09T00:54:43.846Z,1557363283.846 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:54:43.846Z,1557363283.846 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2019-05-09T00:54:43.846Z,1557363283.846 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:54:43.857Z,1557363283.857 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:54:44.261Z,1557363284.261 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:54:44.261Z,1557363284.261 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2019-05-09T00:55:07.671Z,1557363307.671 [NAL9602](INFO): Powering up NAL9602
2019-05-09T00:55:18.579Z,1557363318.579 [NAL9602](INFO): NAL9602 initialized
2019-05-09T00:55:19.401Z,1557363319.401 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:21.817Z,1557363321.817 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:24.653Z,1557363324.653 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:27.881Z,1557363327.881 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:30.713Z,1557363330.713 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:33.945Z,1557363333.945 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:36.773Z,1557363336.773 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:40.005Z,1557363340.005 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:42.833Z,1557363342.833 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:45.661Z,1557363345.661 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:48.897Z,1557363348.897 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:51.717Z,1557363351.717 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:54.961Z,1557363354.961 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:55:57.789Z,1557363357.789 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:01.029Z,1557363361.029 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:03.841Z,1557363363.841 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:06.661Z,1557363366.661 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:08.689Z,1557363368.689 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:11.921Z,1557363371.921 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:14.749Z,1557363374.749 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:17.981Z,1557363377.981 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:20.809Z,1557363380.809 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:24.041Z,1557363384.041 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:26.861Z,1557363386.861 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:29.693Z,1557363389.693 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:31.717Z,1557363391.717 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:33.737Z,1557363393.737 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:36.989Z,1557363396.989 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:39.813Z,1557363399.813 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:43.029Z,1557363403.029 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:44.547Z,1557363404.547 [CommandLine](IMPORTANT): got command restart application
2019-05-09T00:56:45.054Z,1557363405.054 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-09T00:56:45.054Z,1557363405.054 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2019-05-09T00:56:45.054Z,1557363405.054 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-09T00:56:45.064Z,1557363405.064 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-09T00:56:45.467Z,1557363405.467 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-09T00:56:45.467Z,1557363405.467 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2019-05-09T00:56:45.553Z,1557363405.553 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-09T00:56:45.553Z,1557363405.553 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:45.709Z,1557363405.709 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-09T00:56:45.709Z,1557363405.709 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:45.710Z,1557363405.710 [CommandLine](INFO): Join timeout helper Thread ID is 1601
2019-05-09T00:56:45.710Z,1557363405.710 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-09T00:56:45.710Z,1557363405.710 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:45.711Z,1557363405.711 [NavChartDb](INFO): Join timeout helper Thread ID is 1602
2019-05-09T00:56:45.861Z,1557363405.861 [NAL9602](DEBUG): Fix Requested
2019-05-09T00:56:46.101Z,1557363406.101 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-09T00:56:46.101Z,1557363406.101 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:46.109Z,1557363406.109 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-09T00:56:46.109Z,1557363406.109 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:46.109Z,1557363406.109 [Radio_Surface](INFO): Join timeout helper Thread ID is 1603
2019-05-09T00:56:46.481Z,1557363406.481 [Radio_Surface](INFO): Powering down
2019-05-09T00:56:46.482Z,1557363406.482 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-09T00:56:46.482Z,1557363406.482 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:46.493Z,1557363406.493 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-09T00:56:46.493Z,1557363406.493 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:46.493Z,1557363406.493 [DataOverHttps](INFO): Join timeout helper Thread ID is 1604
2019-05-09T00:56:46.541Z,1557363406.541 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-09T00:56:46.541Z,1557363406.541 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:46.553Z,1557363406.553 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-09T00:56:46.553Z,1557363406.553 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:46.553Z,1557363406.553 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1605
2019-05-09T00:56:46.941Z,1557363406.941 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-09T00:56:46.941Z,1557363406.941 [WetLabsBB2FL](INFO): Powering down
2019-05-09T00:56:46.942Z,1557363406.942 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:46.958Z,1557363406.958 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-09T00:56:46.958Z,1557363406.958 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:46.958Z,1557363406.958 [logger](INFO): Join timeout helper Thread ID is 1606
2019-05-09T00:56:46.997Z,1557363406.997 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-09T00:56:46.997Z,1557363406.997 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:47.017Z,1557363407.017 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-09T00:56:47.018Z,1557363407.018 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:47.018Z,1557363407.018 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-09T00:56:47.018Z,1557363407.018 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:47.018Z,1557363407.018 [controlThread](INFO): Join timeout helper Thread ID is 1607
2019-05-09T00:56:47.041Z,1557363407.041 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-09T00:56:47.041Z,1557363407.041 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-09T00:56:47.043Z,1557363407.043 [NAL9602](INFO): Powering down
2019-05-09T00:56:47.045Z,1557363407.045 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-09T00:56:47.045Z,1557363407.045 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-09T00:56:47.046Z,1557363407.046 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-09T00:56:47.046Z,1557363407.046 [MissionManager](INFO): Uninitializing Mission Default
2019-05-09T00:56:47.046Z,1557363407.046 [Default] Stopped
2019-05-09T00:56:47.046Z,1557363407.046 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-09T00:56:47.046Z,1557363407.046 [Default:B.GoToSurface] Stopped
2019-05-09T00:56:47.046Z,1557363407.046 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-09T00:56:47.046Z,1557363407.046 [Default:CheckIn] Stopped
2019-05-09T00:56:47.047Z,1557363407.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-09T00:56:47.047Z,1557363407.047 [Default:CheckIn:C.Wait] Stopped
2019-05-09T00:56:47.047Z,1557363407.047 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-09T00:56:47.049Z,1557363407.049 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-09T00:56:47.049Z,1557363407.049 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-09T00:56:47.050Z,1557363407.050 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-09T00:56:47.050Z,1557363407.050 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-09T00:56:47.050Z,1557363407.050 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-09T00:56:47.050Z,1557363407.050 [BuoyancyServo](INFO): Powering down
2019-05-09T00:56:47.065Z,1557363407.065 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-09T00:56:47.065Z,1557363407.065 [ElevatorServo](INFO): Powering down
2019-05-09T00:56:47.066Z,1557363407.066 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-09T00:56:47.066Z,1557363407.066 [MassServo](INFO): Powering down
2019-05-09T00:56:47.067Z,1557363407.067 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-09T00:56:47.067Z,1557363407.067 [RudderServo](INFO): Powering down
2019-05-09T00:56:47.068Z,1557363407.068 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-09T00:56:47.068Z,1557363407.068 [ThrusterServo](INFO): Powering down
2019-05-09T00:56:47.068Z,1557363407.068 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-09T00:56:47.069Z,1557363407.069 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-09T00:56:47.069Z,1557363407.069 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-09T00:56:47.069Z,1557363407.069 [CBIT](DEBUG): Powering off loads.
2019-05-09T00:56:47.081Z,1557363407.081 [CBIT](DEBUG): Disabling WDT.
2019-05-09T00:56:47.093Z,1557363407.093 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-09T00:56:47.093Z,1557363407.093 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:47.124Z,1557363407.124 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:47.264Z,1557363407.264 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:47.269Z,1557363407.269 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:47.316Z,1557363407.316 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-09T00:56:47.372Z,1557363407.372 [logger ThreadHandler](INFO): Thread cancelled.