2019-06-10T00:33:46.544Z,1560126826.544 [Supervisor](DEBUG): Initializing supervisor.
2019-06-10T00:33:46.547Z,1560126826.547 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-06-10T00:33:46.547Z,1560126826.547 [SyncHandler](INFO): Protected caller Thread ID is 2495
2019-06-10T00:33:46.548Z,1560126826.548 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-06-10T00:33:46.549Z,1560126826.549 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-06-10T00:33:46.549Z,1560126826.549 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2496
2019-06-10T00:33:46.552Z,1560126826.552 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-06-10T00:33:46.564Z,1560126826.564 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-06-10T00:33:46.565Z,1560126826.565 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-06-10T00:33:46.566Z,1560126826.566 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2497
2019-06-10T00:33:46.566Z,1560126826.566 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-06-10T00:33:46.567Z,1560126826.567 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-06-10T00:33:46.568Z,1560126826.568 [logger ThreadHandler](INFO): Protected caller Thread ID is 2498
2019-06-10T00:33:46.570Z,1560126826.570 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-06-10T00:33:46.570Z,1560126826.570 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-06-10T00:33:46.572Z,1560126826.572 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-06-10T00:33:47.007Z,1560126827.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-06-10T00:33:47.008Z,1560126827.008 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-06-10T00:33:47.106Z,1560126827.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-06-10T00:33:47.107Z,1560126827.107 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-06-10T00:33:47.455Z,1560126827.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-06-10T00:33:47.456Z,1560126827.456 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-06-10T00:33:47.765Z,1560126827.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-06-10T00:33:47.765Z,1560126827.765 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-06-10T00:33:47.961Z,1560126827.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-06-10T00:33:47.962Z,1560126827.962 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-06-10T00:33:48.429Z,1560126828.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-06-10T00:33:48.430Z,1560126828.430 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-06-10T00:33:48.644Z,1560126828.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-06-10T00:33:48.645Z,1560126828.645 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-06-10T00:33:48.793Z,1560126828.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-06-10T00:33:48.794Z,1560126828.794 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-06-10T00:33:48.996Z,1560126828.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-06-10T00:33:48.996Z,1560126828.996 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-06-10T00:33:49.093Z,1560126829.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-06-10T00:33:49.094Z,1560126829.094 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-06-10T00:33:49.425Z,1560126829.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-06-10T00:33:49.425Z,1560126829.425 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-06-10T00:33:49.505Z,1560126829.505 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-06-10T00:33:49.610Z,1560126829.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-06-10T00:33:49.610Z,1560126829.610 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-06-10T00:33:50.232Z,1560126830.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-06-10T00:33:50.233Z,1560126830.233 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-06-10T00:33:50.632Z,1560126830.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-06-10T00:33:50.634Z,1560126830.634 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-06-10T00:33:50.635Z,1560126830.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-06-10T00:33:50.854Z,1560126830.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-06-10T00:33:50.957Z,1560126830.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-06-10T00:33:51.057Z,1560126831.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-06-10T00:33:51.288Z,1560126831.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-06-10T00:33:51.288Z,1560126831.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-06-10T00:33:51.373Z,1560126831.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-06-10T00:33:51.468Z,1560126831.468 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-06-10T00:33:51.565Z,1560126831.565 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-06-10T00:33:51.648Z,1560126831.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-06-10T00:33:51.758Z,1560126831.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-06-10T00:33:51.946Z,1560126831.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-06-10T00:33:52.080Z,1560126832.080 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-06-10T00:33:52.099Z,1560126832.099 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-06-10T00:33:52.505Z,1560126832.505 [AHRS_M2] Loaded
2019-06-10T00:33:52.505Z,1560126832.505 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-06-10T00:33:52.582Z,1560126832.582 [DataOverHttps] Loaded
2019-06-10T00:33:52.582Z,1560126832.582 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-06-10T00:33:52.583Z,1560126832.583 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407644E0
2019-06-10T00:33:52.584Z,1560126832.584 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2577
2019-06-10T00:33:52.597Z,1560126832.597 [Depth_Keller] Loaded
2019-06-10T00:33:52.597Z,1560126832.597 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-06-10T00:33:52.602Z,1560126832.602 [DropWeight] Loaded
2019-06-10T00:33:52.602Z,1560126832.602 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-06-10T00:33:52.659Z,1560126832.659 [DUSBL_Hydroid] Loaded
2019-06-10T00:33:52.659Z,1560126832.659 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-06-10T00:33:52.708Z,1560126832.708 [Micromodem] Loaded
2019-06-10T00:33:52.708Z,1560126832.708 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-06-10T00:33:52.805Z,1560126832.805 [NAL9602] Loaded
2019-06-10T00:33:52.806Z,1560126832.806 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-06-10T00:33:52.822Z,1560126832.822 [Onboard] Loaded
2019-06-10T00:33:52.822Z,1560126832.822 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-06-10T00:33:52.828Z,1560126832.828 [PowerOnly] Loaded
2019-06-10T00:33:52.828Z,1560126832.828 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-06-10T00:33:52.834Z,1560126832.834 [Radio_Surface] Loaded
2019-06-10T00:33:52.835Z,1560126832.835 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-06-10T00:33:52.836Z,1560126832.836 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407944E0
2019-06-10T00:33:52.836Z,1560126832.836 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2578
2019-06-10T00:33:52.892Z,1560126832.892 [RDI_Pathfinder] Loaded
2019-06-10T00:33:52.895Z,1560126832.895 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-06-10T00:33:54.883Z,1560126834.883 [BPC1] Loaded
2019-06-10T00:33:54.884Z,1560126834.884 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-06-10T00:33:54.884Z,1560126834.884 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-06-10T00:33:54.885Z,1560126834.885 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-06-10T00:33:54.898Z,1560126834.898 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-06-10T00:33:54.899Z,1560126834.899 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-06-10T00:33:55.006Z,1560126835.006 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-06-10T00:33:55.007Z,1560126835.007 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-06-10T00:33:55.027Z,1560126835.027 [NavChart] Loaded
2019-06-10T00:33:55.028Z,1560126835.028 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-06-10T00:33:55.032Z,1560126835.032 [UniversalFixResidualReporter] Loaded
2019-06-10T00:33:55.032Z,1560126835.032 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-06-10T00:33:55.033Z,1560126835.033 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-06-10T00:33:55.033Z,1560126835.033 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-06-10T00:33:55.143Z,1560126835.143 [BuoyancyServo] Loaded
2019-06-10T00:33:55.143Z,1560126835.143 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-06-10T00:33:55.159Z,1560126835.159 [ElevatorServo] Loaded
2019-06-10T00:33:55.159Z,1560126835.159 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-06-10T00:33:55.174Z,1560126835.174 [MassServo] Loaded
2019-06-10T00:33:55.174Z,1560126835.174 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-06-10T00:33:55.189Z,1560126835.189 [RudderServo] Loaded
2019-06-10T00:33:55.190Z,1560126835.190 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-06-10T00:33:55.204Z,1560126835.204 [ThrusterServo] Loaded
2019-06-10T00:33:55.205Z,1560126835.205 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-06-10T00:33:55.205Z,1560126835.205 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-06-10T00:33:55.206Z,1560126835.206 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-06-10T00:33:55.424Z,1560126835.424 [WetLabsSeaOWL_UV_A] Loaded
2019-06-10T00:33:55.424Z,1560126835.424 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-06-10T00:33:55.425Z,1560126835.425 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408D64E0
2019-06-10T00:33:55.425Z,1560126835.425 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 2579
2019-06-10T00:33:55.426Z,1560126835.426 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-06-10T00:33:55.426Z,1560126835.426 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-06-10T00:33:55.727Z,1560126835.727 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-06-10T00:33:55.727Z,1560126835.727 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-06-10T00:33:55.769Z,1560126835.769 [DepthRateCalculator] Loaded
2019-06-10T00:33:55.769Z,1560126835.769 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-06-10T00:33:55.774Z,1560126835.774 [PitchRateCalculator] Loaded
2019-06-10T00:33:55.775Z,1560126835.775 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-06-10T00:33:55.787Z,1560126835.787 [SpeedCalculator] Loaded
2019-06-10T00:33:55.787Z,1560126835.787 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-06-10T00:33:55.809Z,1560126835.809 [TempGradientCalculator] Loaded
2019-06-10T00:33:55.809Z,1560126835.809 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-06-10T00:33:55.814Z,1560126835.814 [YawRateCalculator] Loaded
2019-06-10T00:33:55.815Z,1560126835.815 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-06-10T00:33:55.855Z,1560126835.855 [ElevatorOffsetCalculator] Loaded
2019-06-10T00:33:55.855Z,1560126835.855 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-06-10T00:33:55.856Z,1560126835.856 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-06-10T00:33:55.856Z,1560126835.856 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-06-10T00:33:55.994Z,1560126835.994 [SBIT](DEBUG): Construct Startup Built In Test.
2019-06-10T00:33:56.016Z,1560126836.016 [SBIT] Loaded
2019-06-10T00:33:56.016Z,1560126836.016 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-06-10T00:33:56.017Z,1560126836.017 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-06-10T00:33:56.029Z,1560126836.029 [IBIT] Loaded
2019-06-10T00:33:56.029Z,1560126836.029 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-06-10T00:33:56.032Z,1560126836.032 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-06-10T00:33:56.170Z,1560126836.170 [CBIT] Loaded
2019-06-10T00:33:56.171Z,1560126836.171 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-06-10T00:33:56.171Z,1560126836.171 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-06-10T00:33:56.172Z,1560126836.172 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-06-10T00:33:56.254Z,1560126836.254 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-06-10T00:33:56.255Z,1560126836.255 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-06-10T00:33:56.356Z,1560126836.356 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-06-10T00:33:56.357Z,1560126836.357 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-06-10T00:33:56.424Z,1560126836.424 [VerticalControl](DEBUG): Construct VerticalControl.
2019-06-10T00:33:56.508Z,1560126836.508 [VerticalControl] Loaded
2019-06-10T00:33:56.508Z,1560126836.508 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-06-10T00:33:56.509Z,1560126836.509 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-06-10T00:33:56.567Z,1560126836.567 [HorizontalControl] Loaded
2019-06-10T00:33:56.567Z,1560126836.567 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-06-10T00:33:56.568Z,1560126836.568 [SpeedControl](DEBUG): Construct SpeedControl.
2019-06-10T00:33:56.570Z,1560126836.570 [SpeedControl] Loaded
2019-06-10T00:33:56.570Z,1560126836.570 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-06-10T00:33:56.571Z,1560126836.571 [LoopControl](DEBUG): Construct LoopControl.
2019-06-10T00:33:56.571Z,1560126836.571 [LoopControl] Loaded
2019-06-10T00:33:56.572Z,1560126836.572 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-06-10T00:33:56.572Z,1560126836.572 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-06-10T00:33:56.573Z,1560126836.573 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-06-10T00:33:56.600Z,1560126836.600 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-06-10T00:33:56.604Z,1560126836.604 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-06-10T00:33:56.605Z,1560126836.605 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-06-10T00:33:56.612Z,1560126836.612 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-06-10T00:33:56.613Z,1560126836.613 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2019-06-10T00:33:56.613Z,1560126836.613 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2580
2019-06-10T00:33:56.618Z,1560126836.618 [Supervisor](INFO): Main Thread ID is 802
2019-06-10T00:33:56.618Z,1560126836.618 [Supervisor](DEBUG): Running supervisor.
2019-06-10T00:33:56.619Z,1560126836.619 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2581
2019-06-10T00:33:56.622Z,1560126836.622 [controlThread ThreadHandler](INFO): Handler Thread ID is 2582
2019-06-10T00:33:56.622Z,1560126836.622 [controlThread](DEBUG): Initializing ControlThread
2019-06-10T00:33:56.630Z,1560126836.630 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-06-10T00:33:56.631Z,1560126836.631 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-06-10T00:33:56.632Z,1560126836.632 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-06-10T00:33:56.632Z,1560126836.632 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-06-10T00:33:56.633Z,1560126836.633 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-06-10T00:33:56.633Z,1560126836.633 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-06-10T00:33:56.633Z,1560126836.633 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-06-10T00:33:56.634Z,1560126836.634 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-06-10T00:33:56.634Z,1560126836.634 [SBIT](INFO): Initialize SBIT Component.
2019-06-10T00:33:56.635Z,1560126836.635 [SBIT](IMPORTANT): git: 2019-06-10
2019-06-10T00:33:56.635Z,1560126836.635 [SBIT](INFO): git hash: 5e86765136096a3ca061b696ac0d2f40864d4b36
2019-06-10T00:33:56.635Z,1560126836.635 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-06-10T00:33:56.636Z,1560126836.636 [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-06-10T00:33:56.638Z,1560126836.638 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2019-06-10T00:33:56.638Z,1560126836.638 [IBIT](INFO): Initialize IBIT Component.
2019-06-10T00:33:56.639Z,1560126836.639 [CBIT](DEBUG): Initialize CBIT Component.
2019-06-10T00:33:56.640Z,1560126836.640 [logger ThreadHandler](INFO): Handler Thread ID is 2583
2019-06-10T00:33:56.651Z,1560126836.651 [CBIT](DEBUG): Initialized mux pins.
2019-06-10T00:33:56.651Z,1560126836.651 [CBIT](DEBUG): Initializing the watchdog timer.
2019-06-10T00:33:56.660Z,1560126836.660 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2584
2019-06-10T00:33:56.661Z,1560126836.661 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-06-10T00:33:56.672Z,1560126836.672 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2585
2019-06-10T00:33:56.675Z,1560126836.675 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-06-10T00:33:56.675Z,1560126836.675 [CBIT](DEBUG): Initializing heartbeat.
2019-06-10T00:33:56.692Z,1560126836.692 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 2586
2019-06-10T00:33:56.692Z,1560126836.692 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-06-10T00:33:56.732Z,1560126836.732 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2587
2019-06-10T00:33:56.735Z,1560126836.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-06-10T00:33:56.735Z,1560126836.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-06-10T00:33:56.735Z,1560126836.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-06-10T00:33:56.736Z,1560126836.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-06-10T00:33:56.736Z,1560126836.736 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-06-10T00:33:56.736Z,1560126836.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-06-10T00:33:56.736Z,1560126836.736 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-06-10T00:33:56.736Z,1560126836.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-06-10T00:33:56.737Z,1560126836.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-06-10T00:33:56.737Z,1560126836.737 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-06-10T00:33:56.737Z,1560126836.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-06-10T00:33:56.737Z,1560126836.737 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-06-10T00:33:56.737Z,1560126836.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-06-10T00:33:56.737Z,1560126836.737 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-06-10T00:33:56.738Z,1560126836.738 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-06-10T00:33:56.738Z,1560126836.738 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-06-10T00:33:56.747Z,1560126836.747 [CBIT](DEBUG): Deactivating GF circuits.
2019-06-10T00:33:56.747Z,1560126836.747 [CBIT](DEBUG): Deactivating emergency mode.
2019-06-10T00:33:56.783Z,1560126836.783 [CBIT](DEBUG): Backplane powered.
2019-06-10T00:33:56.784Z,1560126836.784 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-06-10T00:33:56.785Z,1560126836.785 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-06-10T00:33:56.786Z,1560126836.786 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-06-10T00:33:56.786Z,1560126836.786 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-06-10T00:33:56.787Z,1560126836.787 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-06-10T00:33:56.798Z,1560126836.798 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-06-10T00:33:56.821Z,1560126836.821 [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-06-10T00:33:56.822Z,1560126836.822 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-06-10T00:33:56.898Z,1560126836.898 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-06-10T00:33:56.899Z,1560126836.899 [Default:A.Wait](DEBUG): Construct Wait.
2019-06-10T00:33:56.901Z,1560126836.901 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-06-10T00:33:56.944Z,1560126836.944 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-06-10T00:33:56.947Z,1560126836.947 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-06-10T00:33:56.960Z,1560126836.960 [Default:E.Execute](DEBUG): Construct Execute.
2019-06-10T00:33:56.964Z,1560126836.964 [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-06-10T00:33:56.984Z,1560126836.984 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,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-06-10T00:33:56.989Z,1560126836.989 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-06-10T00:33:57.026Z,1560126837.026 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-06-10T00:33:57.027Z,1560126837.027 [DUSBL_Hydroid](INFO): Powering up
2019-06-10T00:33:57.027Z,1560126837.027 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-06-10T00:33:57.075Z,1560126837.075 [Radio_Surface](INFO): Powering up
2019-06-10T00:33:57.099Z,1560126837.099 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-06-10T00:33:57.114Z,1560126837.114 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-06-10T00:33:57.123Z,1560126837.123 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-06-10T00:33:57.124Z,1560126837.124 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-06-10T00:33:57.131Z,1560126837.131 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-06-10T00:33:57.132Z,1560126837.132 [MassServo](DEBUG): Initializing EZServoServo.
2019-06-10T00:33:57.140Z,1560126837.140 [MassServo](DEBUG): Initializing MassServo.
2019-06-10T00:33:57.140Z,1560126837.140 [RudderServo](DEBUG): Initializing EZServoServo.
2019-06-10T00:33:57.148Z,1560126837.148 [RudderServo](DEBUG): Initializing RudderServo.
2019-06-10T00:33:57.148Z,1560126837.148 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-06-10T00:33:57.155Z,1560126837.155 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-06-10T00:33:57.411Z,1560126837.411 [Micromodem](INFO): Powering up
2019-06-10T00:33:57.411Z,1560126837.411 [Micromodem](DEBUG): Initializing Micromodem.
2019-06-10T00:34:02.283Z,1560126842.283 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-06-10T00:34:02.686Z,1560126842.686 [Micromodem](INFO): Nmea in: $CATMG,2019-06-10T00:34:01Z,RTC,RTC*75

2019-06-10T00:34:02.686Z,1560126842.686 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2019-06-10T00:34:01Z,RTC,RTC*75

2019-06-10T00:34:03.077Z,1560126843.077 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-06-10T00:34:03.078Z,1560126843.078 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-06-10T00:34:03.481Z,1560126843.481 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-06-10T00:34:03.482Z,1560126843.482 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-06-10T00:34:03.886Z,1560126843.886 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-06-10T00:34:03.886Z,1560126843.886 [Micromodem](INFO): Nmea out: $CCCFG,nav.nst,1*4D

2019-06-10T00:34:04.301Z,1560126844.301 [Micromodem](INFO): Nmea in: $CACFG,nav.nst,1*4F

2019-06-10T00:34:04.302Z,1560126844.302 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-06-10T00:34:04.706Z,1560126844.706 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-06-10T00:34:04.706Z,1560126844.706 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-06-10T00:34:05.097Z,1560126845.097 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-06-10T00:34:05.098Z,1560126845.098 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2019-06-10T00:34:05.506Z,1560126845.506 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2019-06-10T00:34:05.506Z,1560126845.506 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2019-06-10T00:34:05.893Z,1560126845.893 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2019-06-10T00:34:05.894Z,1560126845.894 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2019-06-10T00:34:06.305Z,1560126846.305 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2019-06-10T00:34:06.306Z,1560126846.306 [Micromodem](INFO): Nmea out: $CCCLK,2019,06,10,00,34,07*49

2019-06-10T00:34:06.698Z,1560126846.698 [Micromodem](INFO): Nmea in: $CACLK,2019,6,10,0,34,7*7B

2019-06-10T00:34:06.699Z,1560126846.699 [Micromodem](INFO): Nmea in: $CATMS,0,2019-06-10T00:34:08Z*74

2019-06-10T00:34:06.700Z,1560126846.700 [Micromodem](INFO): Nmea in: $CATMG,2019-06-10T00:34:08Z,USER_CMD,RTC*3D

2019-06-10T00:34:10.354Z,1560126850.354 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-06-10T00:34:15.185Z,1560126855.185 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-06-10T00:34:17.221Z,1560126857.221 [SBIT](IMPORTANT): Beginning Startup BIT
2019-06-10T00:34:17.225Z,1560126857.225 [CBIT](IMPORTANT): Beginning ground fault scan
2019-06-10T00:34:22.949Z,1560126862.949 [NAL9602](INFO): Powering up NAL9602
2019-06-10T00:34:28.279Z,1560126868.279 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011137
CHAN A1 (24V): -0.028900
CHAN A2 (12V): -0.007264
CHAN A3 (5V): -0.002224
CHAN B0 (3.3V): 0.000449
CHAN B1 (3.15aV): 0.000025
CHAN B2 (3.15bV): 0.000075
CHAN B3 (GND): 0.001994
OPEN: 0.005571
Full Scale Calc: 4.765 mA, -1.589 mA
2019-06-10T00:34:34.018Z,1560126874.018 [NAL9602](INFO): NAL9602 initialized
2019-06-10T00:34:34.839Z,1560126874.839 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:35:10.524Z,1560126910.524 [SBIT](IMPORTANT): SBIT PASSED
2019-06-10T00:35:10.580Z,1560126910.580 [CommandLine](IMPORTANT): got command configSet list
2019-06-10T00:35:10.581Z,1560126910.581 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-06-10T00:35:10.581Z,1560126910.581 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=3 hour;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): BPC1.loadAtStartup=1 bool;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): Courier linearApproximation WetLabsSeaOWL_UV_A.OutputFDOM 100.000000 count;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): Courier none latitude;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.range 2.000000 meter;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 angular_degree;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.BottomVelocityFlag 0.000000 count;

2019-06-10T00:35:10.582Z,1560126910.582 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2019-06-10T00:35:10.583Z,1560126910.583 [CommandLine](IMPORTANT): Express none TrackAcousticContact.contact_latitude;

2019-06-10T00:35:10.583Z,1560126910.583 [CommandLine](IMPORTANT): Express none TrackAcousticContact.contact_longitude;

2019-06-10T00:35:10.583Z,1560126910.583 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.OutputFDOM 100.000000 count;

2019-06-10T00:35:10.583Z,1560126910.583 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.darkCountsOil 250.000000 count;

2019-06-10T00:35:10.583Z,1560126910.583 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 0.000020 kilogram_per_cubic_meter;

2019-06-10T00:35:10.583Z,1560126910.583 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 1.000000 meter;

2019-06-10T00:35:10.583Z,1560126910.583 [CommandLine](IMPORTANT): Express linearApproximation mass_concentration_of_chlorophyll_in_sea_water 5.000000 microgram_per_liter;

2019-06-10T00:35:10.583Z,1560126910.583 [CommandLine](IMPORTANT): Express none mass_concentration_of_oxygen_in_sea_water;

2019-06-10T00:35:10.584Z,1560126910.584 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-06-10T00:35:10.584Z,1560126910.584 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=190 cubic_centimeter;

2019-06-10T00:35:10.584Z,1560126910.584 [CommandLine](IMPORTANT): VerticalControl.massDefault=0 centimeter;

2019-06-10T00:35:10.912Z,1560126910.912 [MissionManager](IMPORTANT): Started mission Startup
2019-06-10T00:35:10.912Z,1560126910.912 [Startup] Running Loop=1
2019-06-10T00:35:10.913Z,1560126910.913 [Startup](DEBUG): Aggregate::initialize Startup
2019-06-10T00:35:10.913Z,1560126910.913 [Startup:A.GoToSurface] Running Loop=1
2019-06-10T00:35:10.913Z,1560126910.913 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-06-10T00:35:10.913Z,1560126910.913 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-06-10T00:35:10.914Z,1560126910.914 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-06-10T00:35:10.914Z,1560126910.914 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-06-10T00:35:10.914Z,1560126910.914 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-06-10T00:35:10.915Z,1560126910.915 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-06-10T00:35:10.916Z,1560126910.916 [Startup:StartupSatComms] Running Loop=1
2019-06-10T00:35:10.917Z,1560126910.917 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-06-10T00:35:10.917Z,1560126910.917 [Startup:StartupSatComms:A] Running Loop=1
2019-06-10T00:35:11.341Z,1560126911.341 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-06-10T00:36:11.126Z,1560126971.126 [Startup:StartupSatComms:A](INFO): Timed out from 2019-06-10T00:35:10.9Z
2019-06-10T00:36:11.126Z,1560126971.126 [Startup:StartupSatComms:A] Stopped
2019-06-10T00:36:11.126Z,1560126971.126 [Startup:StartupSatComms:B] Running Loop=1
2019-06-10T00:36:11.527Z,1560126971.527 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-06-10T00:36:56.763Z,1560127016.763 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-06-10T00:36:56.763Z,1560127016.763 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T00:36:56.773Z,1560127016.773 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T00:36:57.172Z,1560127017.172 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T00:36:57.172Z,1560127017.172 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-06-10T00:37:11.320Z,1560127031.320 [Startup:StartupSatComms:B](INFO): Timed out from 2019-06-10T00:36:11.1Z
2019-06-10T00:37:11.320Z,1560127031.320 [Startup:StartupSatComms:B] Stopped
2019-06-10T00:37:11.320Z,1560127031.320 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-06-10T00:37:11.320Z,1560127031.320 [Startup:StartupSatComms] Stopped
2019-06-10T00:37:11.320Z,1560127031.320 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-06-10T00:37:11.321Z,1560127031.321 [Startup](INFO): Completed Startup
2019-06-10T00:37:11.322Z,1560127031.322 [MissionManager](INFO): Startup is completed.
2019-06-10T00:37:11.322Z,1560127031.322 [MissionManager](INFO): Uninitializing Mission Startup
2019-06-10T00:37:11.322Z,1560127031.322 [Startup] Stopped
2019-06-10T00:37:11.322Z,1560127031.322 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-06-10T00:37:11.322Z,1560127031.322 [Startup:A.GoToSurface] Stopped
2019-06-10T00:37:11.322Z,1560127031.322 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-06-10T00:37:11.732Z,1560127031.732 [MissionManager](IMPORTANT): Started mission Default
2019-06-10T00:37:11.732Z,1560127031.732 [Default] Running Loop=1
2019-06-10T00:37:11.732Z,1560127031.732 [Default](DEBUG): Aggregate::initialize Default
2019-06-10T00:37:11.732Z,1560127031.732 [Default:B.GoToSurface] Running Loop=1
2019-06-10T00:37:11.732Z,1560127031.732 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-06-10T00:37:11.733Z,1560127031.733 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-06-10T00:37:11.733Z,1560127031.733 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-06-10T00:37:11.733Z,1560127031.733 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-06-10T00:37:11.734Z,1560127031.734 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-06-10T00:37:11.734Z,1560127031.734 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-06-10T00:37:11.734Z,1560127031.734 [Default:A.Wait] Running Loop=1
2019-06-10T00:37:11.734Z,1560127031.734 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-06-10T00:37:18.922Z,1560127038.922 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005023
2019-06-10T00:37:25.054Z,1560127045.054 [Default:A.Wait](INFO): Done Waiting.
2019-06-10T00:37:25.054Z,1560127045.054 [Default:A.Wait] Stopped
2019-06-10T00:37:25.054Z,1560127045.054 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-06-10T00:37:25.488Z,1560127045.488 [Default:CheckIn] Running Loop=1
2019-06-10T00:37:25.488Z,1560127045.488 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-06-10T00:37:25.489Z,1560127045.489 [Default:CheckIn:Read_GPS] Running Loop=1
2019-06-10T00:37:25.861Z,1560127045.861 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-06-10T00:38:13.521Z,1560127093.521 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-06-10T00:38:57.531Z,1560127137.531 [NAL9602](INFO): SBD MO Status=2, MOMSN=7465, MT Status=2, MTMSN=0
2019-06-10T00:38:57.531Z,1560127137.531 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-06-10T00:39:14.499Z,1560127154.499 [NAL9602](INFO): SBD MO Status=2, MOMSN=7465, MT Status=2, MTMSN=0
2019-06-10T00:39:14.499Z,1560127154.499 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-06-10T00:39:31.079Z,1560127171.079 [NAL9602](INFO): SBD MO Status=2, MOMSN=7465, MT Status=2, MTMSN=0
2019-06-10T00:39:31.079Z,1560127171.079 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-06-10T00:39:37.134Z,1560127177.134 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-06-10T00:39:37.951Z,1560127177.951 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:39:40.367Z,1560127180.367 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:39:43.195Z,1560127183.195 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:39:46.023Z,1560127186.023 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:39:48.447Z,1560127188.447 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:39:51.279Z,1560127191.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:39:54.507Z,1560127194.507 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:39:57.347Z,1560127197.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:39:57.767Z,1560127197.767 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-06-10T00:39:57.767Z,1560127197.767 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T00:39:57.777Z,1560127197.777 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T00:39:58.180Z,1560127198.180 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T00:39:58.180Z,1560127198.180 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-06-10T00:40:00.187Z,1560127200.187 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:03.395Z,1560127203.395 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:06.239Z,1560127206.239 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:09.051Z,1560127209.051 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:12.303Z,1560127212.303 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:15.111Z,1560127215.111 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:18.359Z,1560127218.359 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:21.187Z,1560127221.187 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:24.407Z,1560127224.407 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:27.231Z,1560127227.231 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:30.463Z,1560127230.463 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:33.291Z,1560127233.291 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:36.523Z,1560127236.523 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:39.351Z,1560127239.351 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:42.187Z,1560127242.187 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:45.015Z,1560127245.015 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:47.027Z,1560127247.027 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:50.263Z,1560127250.263 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:53.087Z,1560127253.087 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:56.319Z,1560127256.319 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:40:59.151Z,1560127259.151 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:01.979Z,1560127261.979 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:03.995Z,1560127263.995 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:06.419Z,1560127266.419 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:09.254Z,1560127269.254 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:12.479Z,1560127272.479 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:15.307Z,1560127275.307 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:18.135Z,1560127278.135 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:20.963Z,1560127280.963 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:21.432Z,1560127281.432 [BPC1](ERROR): Battery stick #36 (s/n: 016E) reported OVER_TEMP_ALARM. Status code: 0x1717.
2019-06-10T00:41:21.433Z,1560127281.433 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2019-06-10T00:41:21.448Z,1560127281.448 [BPC1](INFO): Received data from all battery sticks.
2019-06-10T00:41:22.987Z,1560127282.987 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:25.004Z,1560127285.004 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:27.031Z,1560127287.031 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:30.255Z,1560127290.255 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:33.087Z,1560127293.087 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:36.319Z,1560127296.319 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:39.147Z,1560127299.147 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:42.379Z,1560127302.379 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:45.203Z,1560127305.203 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:48.435Z,1560127308.435 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:51.263Z,1560127311.263 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:54.499Z,1560127314.499 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:41:57.351Z,1560127317.351 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:00.560Z,1560127320.560 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:03.391Z,1560127323.391 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:06.231Z,1560127326.231 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:09.039Z,1560127329.039 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:12.275Z,1560127332.275 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:15.099Z,1560127335.099 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:18.331Z,1560127338.331 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:21.175Z,1560127341.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:24.395Z,1560127344.395 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:25.616Z,1560127345.616 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T00:37:25.5Z
2019-06-10T00:42:25.616Z,1560127345.616 [Default:CheckIn:Read_GPS] Stopped
2019-06-10T00:42:25.616Z,1560127345.616 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-06-10T00:42:26.021Z,1560127346.021 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-06-10T00:42:27.227Z,1560127347.227 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:30.455Z,1560127350.455 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:31.418Z,1560127351.418 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20190610T003346/Courier0000.lzma
2019-06-10T00:42:32.221Z,1560127352.221 [DataOverHttps](INFO): Moved sent file to Logs/20190610T003346/Courier0000.lzma.bak
2019-06-10T00:42:32.221Z,1560127352.221 [DataOverHttps](INFO): SBD MOMSN=11359768
2019-06-10T00:42:33.283Z,1560127353.283 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:34.518Z,1560127354.518 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-06-10T00:42:36.515Z,1560127356.515 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:39.343Z,1560127359.343 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:42.175Z,1560127362.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:43.235Z,1560127363.235 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190610T003346/Courier0004.lzma
2019-06-10T00:42:44.041Z,1560127364.041 [DataOverHttps](INFO): Moved sent file to Logs/20190610T003346/Courier0004.lzma.bak
2019-06-10T00:42:44.041Z,1560127364.041 [DataOverHttps](INFO): SBD MOMSN=11359771
2019-06-10T00:42:45.399Z,1560127365.399 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:48.227Z,1560127368.227 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:51.059Z,1560127371.059 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:54.287Z,1560127374.287 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:55.741Z,1560127375.741 [DataOverHttps](INFO): Sending 824 bytes from file Logs/20190609T202208/Express0242.lzma
2019-06-10T00:42:56.545Z,1560127376.545 [DataOverHttps](INFO): Moved sent file to Logs/20190609T202208/Express0242.lzma.bak
2019-06-10T00:42:56.546Z,1560127376.546 [DataOverHttps](INFO): SBD MOMSN=11359773
2019-06-10T00:42:57.127Z,1560127377.127 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:42:58.759Z,1560127378.759 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-06-10T00:42:58.759Z,1560127378.759 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T00:42:58.806Z,1560127378.806 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T00:42:59.152Z,1560127379.152 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T00:42:59.152Z,1560127379.152 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-06-10T00:43:00.359Z,1560127380.359 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:03.179Z,1560127383.179 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:06.407Z,1560127386.407 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:09.239Z,1560127389.239 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:12.467Z,1560127392.467 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:15.307Z,1560127395.307 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:18.535Z,1560127398.535 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:20.481Z,1560127400.481 [DataOverHttps](INFO): Sending 1450 bytes from file Logs/20190610T003346/Express0001.lzma
2019-06-10T00:43:21.281Z,1560127401.281 [DataOverHttps](INFO): Moved sent file to Logs/20190610T003346/Express0001.lzma.bak
2019-06-10T00:43:21.281Z,1560127401.281 [DataOverHttps](INFO): SBD MOMSN=11359780
2019-06-10T00:43:21.364Z,1560127401.364 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:24.183Z,1560127404.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:27.011Z,1560127407.011 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:29.031Z,1560127409.031 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:32.267Z,1560127412.267 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:32.952Z,1560127412.952 [DataOverHttps](INFO): Sending 678 bytes from file Logs/20190610T003346/Express0005.lzma
2019-06-10T00:43:33.757Z,1560127413.757 [DataOverHttps](INFO): Moved sent file to Logs/20190610T003346/Express0005.lzma.bak
2019-06-10T00:43:33.757Z,1560127413.757 [DataOverHttps](INFO): SBD MOMSN=11359813
2019-06-10T00:43:34.700Z,1560127414.700 [Default:CheckIn:Read_Iridium] Stopped
2019-06-10T00:43:34.700Z,1560127414.700 [Default:CheckIn:C.Wait] Running Loop=1
2019-06-10T00:43:34.700Z,1560127414.700 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-06-10T00:43:35.091Z,1560127415.091 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:38.331Z,1560127418.331 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:41.171Z,1560127421.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:44.395Z,1560127424.395 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:47.211Z,1560127427.211 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:50.055Z,1560127430.055 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:53.271Z,1560127433.271 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:56.099Z,1560127436.099 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:43:59.335Z,1560127439.335 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:02.171Z,1560127442.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:05.395Z,1560127445.395 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:08.223Z,1560127448.223 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:11.047Z,1560127451.047 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:14.279Z,1560127454.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:17.111Z,1560127457.111 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:20.339Z,1560127460.339 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:23.179Z,1560127463.179 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:26.011Z,1560127466.011 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:28.027Z,1560127468.027 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:30.439Z,1560127470.439 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:33.271Z,1560127473.271 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:44:36.486Z,1560127476.486 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-06-10T00:44:36.486Z,1560127476.486 [NAL9602] Data Fault, FailCount= 1
2019-06-10T00:44:36.486Z,1560127476.486 [NAL9602](ERROR): Data Fault
2019-06-10T00:44:36.532Z,1560127476.532 [CBIT](ERROR): Data Fault in component: NAL9602
2019-06-10T00:44:36.890Z,1560127476.890 [NAL9602](INFO): Powering down
2019-06-10T00:44:37.311Z,1560127477.311 [NAL9602](FAULT): LCB fault: LCB Watchdog Reset. Hardware Overcurrent Shutdown. Software Overcurrent.
2019-06-10T00:44:37.312Z,1560127477.312 [NAL9602] Hardware Fault, FailCount= 1
2019-06-10T00:44:37.312Z,1560127477.312 [NAL9602](ERROR): Hardware Fault
2019-06-10T00:44:37.732Z,1560127477.732 [CBIT](INFO): Clearing failed state for component NAL9602
2019-06-10T00:44:37.732Z,1560127477.732 [NAL9602] No Fault, FailCount= 1
2019-06-10T00:45:07.198Z,1560127507.198 [NAL9602](INFO): Powering up NAL9602
2019-06-10T00:45:18.106Z,1560127518.106 [NAL9602](INFO): NAL9602 initialized
2019-06-10T00:45:18.927Z,1560127518.927 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:21.755Z,1560127521.755 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:24.987Z,1560127524.987 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:27.815Z,1560127527.815 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:31.051Z,1560127531.051 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:34.283Z,1560127534.283 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:37.111Z,1560127537.111 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:40.343Z,1560127540.343 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:43.175Z,1560127543.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:45.995Z,1560127545.995 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:48.419Z,1560127548.419 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:51.255Z,1560127551.255 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:54.083Z,1560127554.083 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:57.343Z,1560127557.343 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:45:59.747Z,1560127559.747 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-06-10T00:45:59.747Z,1560127559.747 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T00:45:59.757Z,1560127559.757 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T00:46:00.140Z,1560127560.140 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:00.193Z,1560127560.193 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T00:46:00.193Z,1560127560.193 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-06-10T00:46:02.963Z,1560127562.963 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:04.983Z,1560127564.983 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:08.219Z,1560127568.219 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:11.051Z,1560127571.051 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:14.275Z,1560127574.275 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:17.107Z,1560127577.107 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:20.335Z,1560127580.335 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:23.175Z,1560127583.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:25.991Z,1560127585.991 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:29.227Z,1560127589.227 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:32.051Z,1560127592.051 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:35.287Z,1560127595.287 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:38.115Z,1560127598.115 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:41.347Z,1560127601.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:44.175Z,1560127604.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:46.999Z,1560127606.999 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:50.231Z,1560127610.231 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:53.067Z,1560127613.067 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:56.295Z,1560127616.295 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:46:59.123Z,1560127619.123 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:01.947Z,1560127621.947 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:03.967Z,1560127623.967 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:06.391Z,1560127626.391 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:09.219Z,1560127629.219 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:12.451Z,1560127632.451 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:15.279Z,1560127635.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:18.107Z,1560127638.107 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:21.343Z,1560127641.343 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:24.183Z,1560127644.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:27.404Z,1560127647.404 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:30.227Z,1560127650.227 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:33.167Z,1560127653.167 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:36.287Z,1560127656.287 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:39.119Z,1560127659.119 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:42.347Z,1560127662.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:45.196Z,1560127665.196 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:48.407Z,1560127668.407 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:51.235Z,1560127671.235 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:54.475Z,1560127674.475 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:47:57.308Z,1560127677.308 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:00.527Z,1560127680.527 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:03.355Z,1560127683.355 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:06.200Z,1560127686.200 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:09.011Z,1560127689.011 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:11.039Z,1560127691.039 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:14.263Z,1560127694.263 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:17.091Z,1560127697.091 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:20.323Z,1560127700.323 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:23.179Z,1560127703.179 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:26.383Z,1560127706.383 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:29.211Z,1560127709.211 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:32.043Z,1560127712.043 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:35.279Z,1560127715.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:35.319Z,1560127715.319 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-06-10T00:48:35.319Z,1560127715.319 [Default:CheckIn:C.Wait] Stopped
2019-06-10T00:48:35.319Z,1560127715.319 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-06-10T00:48:35.319Z,1560127715.319 [Default:CheckIn:D] Running Loop=1
2019-06-10T00:48:35.708Z,1560127715.708 [Default:CheckIn:D] Stopped
2019-06-10T00:48:35.708Z,1560127715.708 [Default:CheckIn:E] Running Loop=1
2019-06-10T00:48:36.116Z,1560127716.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.399601 min 
2019-06-10T00:48:36.117Z,1560127716.117 [Default:CheckIn:E] Stopped
2019-06-10T00:48:36.117Z,1560127716.117 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-06-10T00:48:36.117Z,1560127716.117 [Default:CheckIn] Stopped
2019-06-10T00:48:36.117Z,1560127716.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-06-10T00:48:36.117Z,1560127716.117 [Default:CheckIn](INFO): Running loop #2
2019-06-10T00:48:36.117Z,1560127716.117 [Default:CheckIn] Running Loop=2
2019-06-10T00:48:36.117Z,1560127716.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-06-10T00:48:36.117Z,1560127716.117 [Default:CheckIn:Read_GPS] Running Loop=1
2019-06-10T00:48:38.103Z,1560127718.103 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:41.335Z,1560127721.335 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:44.175Z,1560127724.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:47.391Z,1560127727.391 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:50.223Z,1560127730.223 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:53.051Z,1560127733.051 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:56.283Z,1560127736.283 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:48:59.107Z,1560127739.107 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:00.743Z,1560127740.743 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-06-10T00:49:00.743Z,1560127740.743 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T00:49:00.757Z,1560127740.757 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T00:49:01.194Z,1560127741.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T00:49:01.194Z,1560127741.194 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-06-10T00:49:02.339Z,1560127742.339 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:05.187Z,1560127745.187 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:08.399Z,1560127748.399 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:11.231Z,1560127751.231 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:14.067Z,1560127754.067 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:17.295Z,1560127757.295 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:20.115Z,1560127760.115 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:23.347Z,1560127763.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:26.180Z,1560127766.180 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:29.007Z,1560127769.007 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:31.027Z,1560127771.027 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:34.255Z,1560127774.255 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:37.083Z,1560127777.083 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:40.315Z,1560127780.315 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:43.163Z,1560127783.163 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:46.375Z,1560127786.375 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:49.203Z,1560127789.203 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:52.031Z,1560127792.031 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:54.467Z,1560127794.467 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:49:57.291Z,1560127797.291 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:00.519Z,1560127800.519 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:03.343Z,1560127803.343 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:06.171Z,1560127806.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:09.003Z,1560127809.003 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:11.019Z,1560127811.019 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:14.251Z,1560127814.251 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:17.079Z,1560127817.079 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:20.311Z,1560127820.311 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:23.163Z,1560127823.163 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:26.371Z,1560127826.371 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:29.200Z,1560127829.200 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:32.027Z,1560127832.027 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:35.263Z,1560127835.263 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:38.095Z,1560127838.095 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:41.323Z,1560127841.323 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:44.163Z,1560127844.163 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:47.379Z,1560127847.379 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:50.211Z,1560127850.211 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:53.035Z,1560127853.035 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:56.279Z,1560127856.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:50:59.099Z,1560127859.099 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:02.331Z,1560127862.331 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:05.183Z,1560127865.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:08.391Z,1560127868.391 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:11.219Z,1560127871.219 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:14.043Z,1560127874.043 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:17.279Z,1560127877.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:20.103Z,1560127880.103 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:23.335Z,1560127883.335 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:26.163Z,1560127886.163 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:29.399Z,1560127889.399 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:32.227Z,1560127892.227 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:35.051Z,1560127895.051 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:38.291Z,1560127898.291 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:41.111Z,1560127901.111 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:44.347Z,1560127904.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:47.171Z,1560127907.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:50.003Z,1560127910.003 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:52.023Z,1560127912.023 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:54.447Z,1560127914.447 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:51:57.271Z,1560127917.271 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:00.507Z,1560127920.507 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:01.726Z,1560127921.726 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-06-10T00:52:01.726Z,1560127921.726 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T00:52:01.737Z,1560127921.737 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T00:52:02.174Z,1560127922.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T00:52:02.174Z,1560127922.174 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-06-10T00:52:03.331Z,1560127923.331 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:06.563Z,1560127926.563 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:09.395Z,1560127929.395 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:12.223Z,1560127932.223 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:15.047Z,1560127935.047 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:18.295Z,1560127938.295 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:21.107Z,1560127941.107 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:24.339Z,1560127944.339 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:27.167Z,1560127947.167 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:30.399Z,1560127950.399 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:33.227Z,1560127953.227 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:36.459Z,1560127956.459 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:39.291Z,1560127959.291 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:42.523Z,1560127962.523 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:45.347Z,1560127965.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:48.175Z,1560127968.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:51.003Z,1560127971.003 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:53.023Z,1560127973.023 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:56.267Z,1560127976.267 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:52:59.091Z,1560127979.091 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:02.319Z,1560127982.319 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:05.152Z,1560127985.152 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:08.375Z,1560127988.375 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:11.203Z,1560127991.203 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:14.031Z,1560127994.031 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:17.263Z,1560127997.263 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:20.091Z,1560128000.091 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:23.327Z,1560128003.327 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:26.151Z,1560128006.151 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:29.383Z,1560128009.383 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:32.215Z,1560128012.215 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:35.039Z,1560128015.039 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:36.280Z,1560128016.280 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T00:48:36.1Z
2019-06-10T00:53:36.280Z,1560128016.280 [Default:CheckIn:Read_GPS] Stopped
2019-06-10T00:53:36.281Z,1560128016.281 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-06-10T00:53:38.271Z,1560128018.271 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:41.100Z,1560128021.100 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:42.260Z,1560128022.260 [DataOverHttps](INFO): Sending 1056 bytes from file Logs/20190610T003346/Express0008.lzma
2019-06-10T00:53:43.065Z,1560128023.065 [DataOverHttps](INFO): Moved sent file to Logs/20190610T003346/Express0008.lzma.bak
2019-06-10T00:53:43.065Z,1560128023.065 [DataOverHttps](INFO): SBD MOMSN=11359830
2019-06-10T00:53:44.331Z,1560128024.331 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:44.366Z,1560128024.366 [Default:CheckIn:Read_Iridium] Stopped
2019-06-10T00:53:44.366Z,1560128024.366 [Default:CheckIn:C.Wait] Running Loop=1
2019-06-10T00:53:44.367Z,1560128024.367 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-06-10T00:53:47.159Z,1560128027.159 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:50.391Z,1560128030.391 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:53.219Z,1560128033.219 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:56.047Z,1560128036.047 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:53:59.279Z,1560128039.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:02.116Z,1560128042.116 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:05.343Z,1560128045.343 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:08.179Z,1560128048.179 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:10.995Z,1560128050.995 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:13.015Z,1560128053.015 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:16.247Z,1560128056.247 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:19.083Z,1560128059.083 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:22.319Z,1560128062.319 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:25.151Z,1560128065.151 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:28.371Z,1560128068.371 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:31.195Z,1560128071.195 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:34.023Z,1560128074.023 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:36.447Z,1560128076.447 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:39.279Z,1560128079.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:42.511Z,1560128082.511 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:45.347Z,1560128085.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:48.567Z,1560128088.567 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:50.991Z,1560128090.991 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:53.012Z,1560128093.012 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:55.035Z,1560128095.035 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:54:58.263Z,1560128098.263 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:55:01.091Z,1560128101.091 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:55:02.727Z,1560128102.727 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-06-10T00:55:02.727Z,1560128102.727 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T00:55:02.746Z,1560128102.746 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T00:55:03.208Z,1560128103.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T00:55:03.208Z,1560128103.208 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-06-10T00:55:04.323Z,1560128104.323 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:55:07.155Z,1560128107.155 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:55:10.383Z,1560128110.383 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:55:13.211Z,1560128113.211 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:55:16.039Z,1560128116.039 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:55:18.472Z,1560128118.472 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:55:21.286Z,1560128121.286 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-06-10T00:55:21.286Z,1560128121.286 [NAL9602] Data Fault, FailCount= 2
2019-06-10T00:55:21.286Z,1560128121.286 [NAL9602](ERROR): Data Fault
2019-06-10T00:55:21.325Z,1560128121.325 [CBIT](ERROR): Data Fault in component: NAL9602
2019-06-10T00:55:21.687Z,1560128121.687 [NAL9602](INFO): Powering down
2019-06-10T00:55:22.529Z,1560128122.529 [CBIT](INFO): Clearing failed state for component NAL9602
2019-06-10T00:55:22.529Z,1560128122.529 [NAL9602] No Fault, FailCount= 2
2019-06-10T00:55:51.982Z,1560128151.982 [NAL9602](INFO): Powering up NAL9602
2019-06-10T00:56:02.898Z,1560128162.898 [NAL9602](INFO): NAL9602 initialized
2019-06-10T00:56:03.719Z,1560128163.719 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:06.547Z,1560128166.547 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:09.771Z,1560128169.771 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:12.599Z,1560128172.599 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:15.427Z,1560128175.427 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:17.451Z,1560128177.451 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:19.472Z,1560128179.472 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:22.703Z,1560128182.703 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:25.527Z,1560128185.527 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:28.759Z,1560128188.759 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:31.591Z,1560128191.591 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:34.419Z,1560128194.419 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:36.435Z,1560128196.435 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:38.455Z,1560128198.455 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:40.479Z,1560128200.479 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:43.303Z,1560128203.303 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:46.131Z,1560128206.131 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:48.151Z,1560128208.151 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:50.991Z,1560128210.991 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:52.999Z,1560128212.999 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:56.239Z,1560128216.239 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:56:59.059Z,1560128219.059 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:02.291Z,1560128222.291 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:05.119Z,1560128225.119 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:07.947Z,1560128227.947 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:09.971Z,1560128229.971 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:12.395Z,1560128232.395 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:15.219Z,1560128235.219 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:18.455Z,1560128238.455 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:21.279Z,1560128241.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:24.511Z,1560128244.511 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:27.347Z,1560128247.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:30.183Z,1560128250.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:32.995Z,1560128252.995 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:36.244Z,1560128256.244 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:39.067Z,1560128259.067 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:42.291Z,1560128262.291 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:45.119Z,1560128265.119 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:48.347Z,1560128268.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:51.183Z,1560128271.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:54.407Z,1560128274.407 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:57:57.239Z,1560128277.239 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:00.471Z,1560128280.471 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:03.299Z,1560128283.299 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:03.739Z,1560128283.739 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-06-10T00:58:03.739Z,1560128283.739 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T00:58:03.762Z,1560128283.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T00:58:04.176Z,1560128284.176 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T00:58:04.176Z,1560128284.176 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-06-10T00:58:06.535Z,1560128286.535 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:09.359Z,1560128289.359 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:12.183Z,1560128292.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:15.011Z,1560128295.011 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:17.031Z,1560128297.031 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:20.271Z,1560128300.271 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:23.107Z,1560128303.107 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:26.327Z,1560128306.327 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:29.151Z,1560128309.151 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:32.391Z,1560128312.391 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:35.211Z,1560128315.211 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:38.039Z,1560128318.039 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:41.271Z,1560128321.271 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:44.099Z,1560128324.099 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:44.932Z,1560128324.932 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-06-10T00:58:44.932Z,1560128324.932 [Default:CheckIn:C.Wait] Stopped
2019-06-10T00:58:44.932Z,1560128324.932 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-06-10T00:58:44.932Z,1560128324.932 [Default:CheckIn:D] Running Loop=1
2019-06-10T00:58:45.329Z,1560128325.329 [Default:CheckIn:D] Stopped
2019-06-10T00:58:45.329Z,1560128325.329 [Default:CheckIn:E] Running Loop=1
2019-06-10T00:58:45.732Z,1560128325.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.559941 min 
2019-06-10T00:58:45.733Z,1560128325.733 [Default:CheckIn:E] Stopped
2019-06-10T00:58:45.733Z,1560128325.733 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-06-10T00:58:45.733Z,1560128325.733 [Default:CheckIn] Stopped
2019-06-10T00:58:45.733Z,1560128325.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-06-10T00:58:45.733Z,1560128325.733 [Default:CheckIn](INFO): Running loop #3
2019-06-10T00:58:45.733Z,1560128325.733 [Default:CheckIn] Running Loop=3
2019-06-10T00:58:45.733Z,1560128325.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-06-10T00:58:45.733Z,1560128325.733 [Default:CheckIn:Read_GPS] Running Loop=1
2019-06-10T00:58:47.347Z,1560128327.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:50.183Z,1560128330.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:53.395Z,1560128333.395 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:56.236Z,1560128336.236 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:58:59.051Z,1560128339.051 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:02.283Z,1560128342.283 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:05.107Z,1560128345.107 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:08.347Z,1560128348.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:11.171Z,1560128351.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:14.007Z,1560128354.007 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:16.015Z,1560128356.015 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:18.443Z,1560128358.443 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:21.267Z,1560128361.267 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:24.499Z,1560128364.499 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:27.351Z,1560128367.351 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:30.179Z,1560128370.179 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:33.396Z,1560128373.396 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:36.231Z,1560128376.231 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:39.047Z,1560128379.047 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:42.275Z,1560128382.275 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:45.111Z,1560128385.111 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:48.335Z,1560128388.335 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:51.171Z,1560128391.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:54.399Z,1560128394.399 [NAL9602](DEBUG): Fix Requested
2019-06-10T00:59:57.227Z,1560128397.227 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:00.467Z,1560128400.467 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:03.287Z,1560128403.287 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:06.515Z,1560128406.515 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:09.343Z,1560128409.343 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:12.183Z,1560128412.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:15.403Z,1560128415.403 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:18.235Z,1560128418.235 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:21.059Z,1560128421.059 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:24.291Z,1560128424.291 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:27.123Z,1560128427.123 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:30.355Z,1560128430.355 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:33.183Z,1560128433.183 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:36.411Z,1560128436.411 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:39.243Z,1560128439.243 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:42.475Z,1560128442.475 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:45.299Z,1560128445.299 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:48.539Z,1560128448.539 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:51.359Z,1560128451.359 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:54.187Z,1560128454.187 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:00:57.021Z,1560128457.021 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:00.255Z,1560128460.255 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:03.075Z,1560128463.075 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:04.703Z,1560128464.703 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-06-10T01:01:04.703Z,1560128464.703 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T01:01:04.729Z,1560128464.729 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T01:01:05.120Z,1560128465.120 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T01:01:05.120Z,1560128465.120 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-06-10T01:01:06.307Z,1560128466.307 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:09.135Z,1560128469.135 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:12.367Z,1560128472.367 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:15.195Z,1560128475.195 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:18.427Z,1560128478.427 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:21.259Z,1560128481.259 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:24.495Z,1560128484.495 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:27.351Z,1560128487.351 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:30.559Z,1560128490.559 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:33.383Z,1560128493.383 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:36.223Z,1560128496.223 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:39.035Z,1560128499.035 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:42.267Z,1560128502.267 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:45.091Z,1560128505.091 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:48.323Z,1560128508.323 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:51.171Z,1560128511.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:54.383Z,1560128514.383 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:01:57.215Z,1560128517.215 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:00.447Z,1560128520.447 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:03.275Z,1560128523.275 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:06.503Z,1560128526.503 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:09.331Z,1560128529.331 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:12.171Z,1560128532.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:15.391Z,1560128535.391 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:18.219Z,1560128538.219 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:21.047Z,1560128541.047 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:24.279Z,1560128544.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:27.111Z,1560128547.111 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:30.350Z,1560128550.350 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:33.175Z,1560128553.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:36.419Z,1560128556.419 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:39.235Z,1560128559.235 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:42.459Z,1560128562.459 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:45.287Z,1560128565.287 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:48.527Z,1560128568.527 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:51.347Z,1560128571.347 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:54.175Z,1560128574.175 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:57.003Z,1560128577.003 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:02:59.027Z,1560128579.027 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:02.259Z,1560128582.259 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:05.083Z,1560128585.083 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:08.315Z,1560128588.315 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:11.163Z,1560128591.163 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:14.379Z,1560128594.379 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:17.207Z,1560128597.207 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:20.031Z,1560128600.031 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:23.263Z,1560128603.263 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:26.091Z,1560128606.091 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:29.323Z,1560128609.323 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:32.171Z,1560128612.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:35.383Z,1560128615.383 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:38.211Z,1560128618.211 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:41.039Z,1560128621.039 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:44.275Z,1560128624.275 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:45.901Z,1560128625.901 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T00:58:45.7Z
2019-06-10T01:03:45.901Z,1560128625.901 [Default:CheckIn:Read_GPS] Stopped
2019-06-10T01:03:45.901Z,1560128625.901 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-06-10T01:03:47.116Z,1560128627.116 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:50.339Z,1560128630.339 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:51.429Z,1560128631.429 [DataOverHttps](INFO): Sending 899 bytes from file Logs/20190610T003346/Express0011.lzma
2019-06-10T01:03:52.233Z,1560128632.233 [DataOverHttps](INFO): Moved sent file to Logs/20190610T003346/Express0011.lzma.bak
2019-06-10T01:03:52.233Z,1560128632.233 [DataOverHttps](INFO): SBD MOMSN=11359843
2019-06-10T01:03:53.171Z,1560128633.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:53.203Z,1560128633.203 [Default:CheckIn:Read_Iridium] Stopped
2019-06-10T01:03:53.203Z,1560128633.203 [Default:CheckIn:C.Wait] Running Loop=1
2019-06-10T01:03:53.203Z,1560128633.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-06-10T01:03:55.987Z,1560128635.987 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:03:58.007Z,1560128638.007 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:00.431Z,1560128640.431 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:03.263Z,1560128643.263 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:05.699Z,1560128645.699 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-06-10T01:04:05.699Z,1560128645.699 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-10T01:04:05.709Z,1560128645.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-10T01:04:06.108Z,1560128646.108 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-10T01:04:06.108Z,1560128646.108 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-06-10T01:04:06.491Z,1560128646.491 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:09.319Z,1560128649.319 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:12.555Z,1560128652.555 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:15.379Z,1560128655.379 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:18.207Z,1560128658.207 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:21.039Z,1560128661.039 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:24.267Z,1560128664.267 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:27.099Z,1560128667.099 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:30.327Z,1560128670.327 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:33.171Z,1560128673.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:36.391Z,1560128676.391 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:39.215Z,1560128679.215 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:42.447Z,1560128682.447 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:45.279Z,1560128685.279 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:48.523Z,1560128688.523 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:51.335Z,1560128691.335 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:54.171Z,1560128694.171 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:56.991Z,1560128696.991 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:04:59.015Z,1560128699.015 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:02.259Z,1560128702.259 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:05.071Z,1560128705.071 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:08.303Z,1560128708.303 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:11.163Z,1560128711.163 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:14.363Z,1560128714.363 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:17.195Z,1560128717.195 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:20.019Z,1560128720.019 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:23.251Z,1560128723.251 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:26.079Z,1560128726.079 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:29.315Z,1560128729.315 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:32.159Z,1560128732.159 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:35.371Z,1560128735.371 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:38.207Z,1560128738.207 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:41.027Z,1560128741.027 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:44.259Z,1560128744.259 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:47.091Z,1560128747.091 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:50.323Z,1560128750.323 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:53.167Z,1560128753.167 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:56.379Z,1560128756.379 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:05:59.211Z,1560128759.211 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:06:02.035Z,1560128762.035 [NAL9602](DEBUG): Fix Requested
2019-06-10T01:06:05.254Z,1560128765.254 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-06-10T01:06:05.254Z,1560128765.254 [NAL9602] Data Fault, FailCount= 3
2019-06-10T01:06:05.254Z,1560128765.254 [NAL9602](ERROR): Data Fault
2019-06-10T01:06:05.293Z,1560128765.293 [CBIT](ERROR): Data Fault in component: NAL9602
2019-06-10T01:06:05.657Z,1560128765.657 [NAL9602](INFO): Powering down
2019-06-10T01:06:06.509Z,1560128766.509 [CBIT](INFO): Clearing failed state for component NAL9602
2019-06-10T01:06:06.510Z,1560128766.510 [NAL9602] No Fault, FailCount= 3
2019-06-10T01:06:25.300Z,1560128785.300 [DataOverHttps](IMPORTANT): SBD MTMSN=20190610T010531
2019-06-10T01:06:30.392Z,1560128790.392 [DataOverHttps](INFO): Received command:restart sys
2019-06-10T01:06:30.436Z,1560128790.436 [CommandLine](IMPORTANT): got command restart system
2019-06-10T01:06:32.719Z,1560128792.719 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-06-10T01:06:32.719Z,1560128792.719 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-10T01:06:32.720Z,1560128792.720 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:32.779Z,1560128792.779 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-06-10T01:06:32.779Z,1560128792.779 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:32.780Z,1560128792.780 [CommandLine](INFO): Join timeout helper Thread ID is 2696
2019-06-10T01:06:32.795Z,1560128792.795 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-06-10T01:06:32.795Z,1560128792.795 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:32.796Z,1560128792.796 [NavChartDb](INFO): Join timeout helper Thread ID is 2697
2019-06-10T01:06:32.932Z,1560128792.932 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-10T01:06:32.932Z,1560128792.932 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:32.943Z,1560128792.943 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-06-10T01:06:32.943Z,1560128792.943 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:32.944Z,1560128792.944 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 2698
2019-06-10T01:06:33.339Z,1560128793.339 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-10T01:06:33.340Z,1560128793.340 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-06-10T01:06:33.340Z,1560128793.340 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:33.343Z,1560128793.343 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-06-10T01:06:33.343Z,1560128793.343 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:33.344Z,1560128793.344 [Radio_Surface](INFO): Join timeout helper Thread ID is 2699
2019-06-10T01:06:33.739Z,1560128793.739 [Radio_Surface](INFO): Powering down
2019-06-10T01:06:33.740Z,1560128793.740 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-10T01:06:33.741Z,1560128793.741 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:33.747Z,1560128793.747 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-06-10T01:06:33.747Z,1560128793.747 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:33.748Z,1560128793.748 [DataOverHttps](INFO): Join timeout helper Thread ID is 2700
2019-06-10T01:06:34.023Z,1560128794.023 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-10T01:06:34.024Z,1560128794.024 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.036Z,1560128794.036 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-06-10T01:06:34.036Z,1560128794.036 [logger ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.037Z,1560128794.037 [logger](INFO): Join timeout helper Thread ID is 2701
2019-06-10T01:06:34.064Z,1560128794.064 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-10T01:06:34.065Z,1560128794.065 [logger ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.085Z,1560128794.085 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-06-10T01:06:34.086Z,1560128794.086 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.086Z,1560128794.086 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-06-10T01:06:34.086Z,1560128794.086 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.087Z,1560128794.087 [controlThread](INFO): Join timeout helper Thread ID is 2702
2019-06-10T01:06:34.335Z,1560128794.335 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-10T01:06:34.336Z,1560128794.336 [controlThread](DEBUG): Uninitializing ControlThread
2019-06-10T01:06:34.336Z,1560128794.336 [AHRS_M2](INFO): Powering down
2019-06-10T01:06:34.408Z,1560128794.408 [DUSBL_Hydroid](INFO): Powering down
2019-06-10T01:06:34.500Z,1560128794.500 [Micromodem](INFO): Powering down
2019-06-10T01:06:34.596Z,1560128794.596 [NAL9602](INFO): Powering down
2019-06-10T01:06:34.667Z,1560128794.667 [RDI_Pathfinder](INFO): Powering down
2019-06-10T01:06:34.669Z,1560128794.669 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-06-10T01:06:34.670Z,1560128794.670 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-06-10T01:06:34.670Z,1560128794.670 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-06-10T01:06:34.671Z,1560128794.671 [MissionManager](INFO): Uninitializing Mission Default
2019-06-10T01:06:34.671Z,1560128794.671 [Default] Stopped
2019-06-10T01:06:34.671Z,1560128794.671 [Default](DEBUG): Aggregate::uninitialize Default
2019-06-10T01:06:34.671Z,1560128794.671 [Default:B.GoToSurface] Stopped
2019-06-10T01:06:34.671Z,1560128794.671 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-06-10T01:06:34.671Z,1560128794.671 [Default:CheckIn] Stopped
2019-06-10T01:06:34.671Z,1560128794.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-06-10T01:06:34.671Z,1560128794.671 [Default:CheckIn:C.Wait] Stopped
2019-06-10T01:06:34.671Z,1560128794.671 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-06-10T01:06:34.674Z,1560128794.674 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-06-10T01:06:34.674Z,1560128794.674 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-06-10T01:06:34.674Z,1560128794.674 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-06-10T01:06:34.675Z,1560128794.675 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-06-10T01:06:34.675Z,1560128794.675 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-06-10T01:06:34.675Z,1560128794.675 [BuoyancyServo](INFO): Powering down
2019-06-10T01:06:34.687Z,1560128794.687 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-06-10T01:06:34.688Z,1560128794.688 [ElevatorServo](INFO): Powering down
2019-06-10T01:06:34.688Z,1560128794.688 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-06-10T01:06:34.689Z,1560128794.689 [MassServo](INFO): Powering down
2019-06-10T01:06:34.689Z,1560128794.689 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-06-10T01:06:34.689Z,1560128794.689 [RudderServo](INFO): Powering down
2019-06-10T01:06:34.690Z,1560128794.690 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-06-10T01:06:34.690Z,1560128794.690 [ThrusterServo](INFO): Powering down
2019-06-10T01:06:34.691Z,1560128794.691 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-06-10T01:06:34.692Z,1560128794.692 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-06-10T01:06:34.692Z,1560128794.692 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-06-10T01:06:34.692Z,1560128794.692 [CBIT](DEBUG): Powering off loads.
2019-06-10T01:06:34.703Z,1560128794.703 [CBIT](DEBUG): Disabling WDT.
2019-06-10T01:06:34.715Z,1560128794.715 [CBIT](DEBUG): Opening all GF detection circuits.
2019-06-10T01:06:34.716Z,1560128794.716 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.766Z,1560128794.766 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.776Z,1560128794.776 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.818Z,1560128794.818 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.878Z,1560128794.878 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-10T01:06:34.939Z,1560128794.939 [logger ThreadHandler](INFO): Thread cancelled.