2019-06-09T18:18:00.770Z,1560104280.770 [Supervisor](DEBUG): Initializing supervisor.
2019-06-09T18:18:00.773Z,1560104280.773 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-06-09T18:18:00.773Z,1560104280.773 [SyncHandler](INFO): Protected caller Thread ID is 976
2019-06-09T18:18:00.774Z,1560104280.774 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-06-09T18:18:00.775Z,1560104280.775 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-06-09T18:18:00.775Z,1560104280.775 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 977
2019-06-09T18:18:00.778Z,1560104280.778 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-06-09T18:18:00.790Z,1560104280.790 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-06-09T18:18:00.791Z,1560104280.791 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-06-09T18:18:00.791Z,1560104280.791 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 978
2019-06-09T18:18:00.792Z,1560104280.792 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-06-09T18:18:00.793Z,1560104280.793 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-06-09T18:18:00.794Z,1560104280.794 [logger ThreadHandler](INFO): Protected caller Thread ID is 979
2019-06-09T18:18:00.796Z,1560104280.796 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-06-09T18:18:00.796Z,1560104280.796 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-06-09T18:18:00.798Z,1560104280.798 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-06-09T18:18:01.219Z,1560104281.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-06-09T18:18:01.220Z,1560104281.220 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-06-09T18:18:01.317Z,1560104281.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-06-09T18:18:01.318Z,1560104281.318 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-06-09T18:18:01.642Z,1560104281.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-06-09T18:18:01.643Z,1560104281.643 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-06-09T18:18:01.783Z,1560104281.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-06-09T18:18:01.784Z,1560104281.784 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-06-09T18:18:01.975Z,1560104281.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-06-09T18:18:01.976Z,1560104281.976 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-06-09T18:18:02.430Z,1560104282.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-06-09T18:18:02.431Z,1560104282.431 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-06-09T18:18:02.640Z,1560104282.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-06-09T18:18:02.640Z,1560104282.640 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-06-09T18:18:02.785Z,1560104282.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-06-09T18:18:02.786Z,1560104282.786 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-06-09T18:18:03.024Z,1560104283.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-06-09T18:18:03.024Z,1560104283.024 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-06-09T18:18:03.221Z,1560104283.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-06-09T18:18:03.221Z,1560104283.221 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-06-09T18:18:03.687Z,1560104283.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-06-09T18:18:03.688Z,1560104283.688 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-06-09T18:18:03.768Z,1560104283.768 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-06-09T18:18:03.870Z,1560104283.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-06-09T18:18:03.871Z,1560104283.871 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-06-09T18:18:04.448Z,1560104284.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-06-09T18:18:04.448Z,1560104284.448 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-06-09T18:18:04.836Z,1560104284.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-06-09T18:18:04.838Z,1560104284.838 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-06-09T18:18:04.839Z,1560104284.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-06-09T18:18:05.047Z,1560104285.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-06-09T18:18:05.146Z,1560104285.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-06-09T18:18:05.244Z,1560104285.244 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-06-09T18:18:05.469Z,1560104285.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-06-09T18:18:05.470Z,1560104285.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-06-09T18:18:05.554Z,1560104285.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-06-09T18:18:05.646Z,1560104285.646 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-06-09T18:18:05.742Z,1560104285.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-06-09T18:18:05.824Z,1560104285.824 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-06-09T18:18:05.935Z,1560104285.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-06-09T18:18:06.112Z,1560104286.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-06-09T18:18:06.246Z,1560104286.246 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-06-09T18:18:06.264Z,1560104286.264 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-06-09T18:18:06.639Z,1560104286.639 [AHRS_M2] Loaded
2019-06-09T18:18:06.639Z,1560104286.639 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-06-09T18:18:06.714Z,1560104286.714 [DataOverHttps] Loaded
2019-06-09T18:18:06.714Z,1560104286.714 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-06-09T18:18:06.715Z,1560104286.715 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4075A4E0
2019-06-09T18:18:06.716Z,1560104286.716 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1058
2019-06-09T18:18:06.729Z,1560104286.729 [Depth_Keller] Loaded
2019-06-09T18:18:06.729Z,1560104286.729 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-06-09T18:18:06.734Z,1560104286.734 [DropWeight] Loaded
2019-06-09T18:18:06.734Z,1560104286.734 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-06-09T18:18:06.791Z,1560104286.791 [DUSBL_Hydroid] Loaded
2019-06-09T18:18:06.791Z,1560104286.791 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-06-09T18:18:06.832Z,1560104286.832 [Micromodem] Loaded
2019-06-09T18:18:06.832Z,1560104286.832 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-06-09T18:18:06.931Z,1560104286.931 [NAL9602] Loaded
2019-06-09T18:18:06.931Z,1560104286.931 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-06-09T18:18:06.946Z,1560104286.946 [Onboard] Loaded
2019-06-09T18:18:06.947Z,1560104286.947 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-06-09T18:18:06.953Z,1560104286.953 [PowerOnly] Loaded
2019-06-09T18:18:06.953Z,1560104286.953 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-06-09T18:18:06.959Z,1560104286.959 [Radio_Surface] Loaded
2019-06-09T18:18:06.960Z,1560104286.960 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-06-09T18:18:06.961Z,1560104286.961 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4078A4E0
2019-06-09T18:18:06.961Z,1560104286.961 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1059
2019-06-09T18:18:07.006Z,1560104287.006 [RDI_Pathfinder] Loaded
2019-06-09T18:18:07.006Z,1560104287.006 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-06-09T18:18:08.484Z,1560104288.484 [BPC1] Loaded
2019-06-09T18:18:08.484Z,1560104288.484 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-06-09T18:18:08.485Z,1560104288.485 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-06-09T18:18:08.485Z,1560104288.485 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-06-09T18:18:08.544Z,1560104288.544 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-06-09T18:18:08.544Z,1560104288.544 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-06-09T18:18:08.741Z,1560104288.741 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-06-09T18:18:08.742Z,1560104288.742 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-06-09T18:18:08.762Z,1560104288.762 [NavChart] Loaded
2019-06-09T18:18:08.763Z,1560104288.763 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-06-09T18:18:08.768Z,1560104288.768 [UniversalFixResidualReporter] Loaded
2019-06-09T18:18:08.790Z,1560104288.790 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-06-09T18:18:08.791Z,1560104288.791 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-06-09T18:18:08.856Z,1560104288.856 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-06-09T18:18:09.153Z,1560104289.153 [BuoyancyServo] Loaded
2019-06-09T18:18:09.154Z,1560104289.154 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-06-09T18:18:09.169Z,1560104289.169 [ElevatorServo] Loaded
2019-06-09T18:18:09.169Z,1560104289.169 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-06-09T18:18:09.228Z,1560104289.228 [MassServo] Loaded
2019-06-09T18:18:09.229Z,1560104289.229 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-06-09T18:18:09.272Z,1560104289.272 [RudderServo] Loaded
2019-06-09T18:18:09.272Z,1560104289.272 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-06-09T18:18:09.359Z,1560104289.359 [ThrusterServo] Loaded
2019-06-09T18:18:09.359Z,1560104289.359 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-06-09T18:18:09.360Z,1560104289.360 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-06-09T18:18:09.360Z,1560104289.360 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-06-09T18:18:09.573Z,1560104289.573 [WetLabsSeaOWL_UV_A] Loaded
2019-06-09T18:18:09.574Z,1560104289.574 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-06-09T18:18:09.575Z,1560104289.575 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408CC4E0
2019-06-09T18:18:09.575Z,1560104289.575 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1060
2019-06-09T18:18:09.576Z,1560104289.576 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-06-09T18:18:09.576Z,1560104289.576 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-06-09T18:18:09.867Z,1560104289.867 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-06-09T18:18:09.867Z,1560104289.867 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-06-09T18:18:09.909Z,1560104289.909 [DepthRateCalculator] Loaded
2019-06-09T18:18:09.909Z,1560104289.909 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-06-09T18:18:09.915Z,1560104289.915 [PitchRateCalculator] Loaded
2019-06-09T18:18:09.915Z,1560104289.915 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-06-09T18:18:09.928Z,1560104289.928 [SpeedCalculator] Loaded
2019-06-09T18:18:09.928Z,1560104289.928 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-06-09T18:18:09.950Z,1560104289.950 [TempGradientCalculator] Loaded
2019-06-09T18:18:09.950Z,1560104289.950 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-06-09T18:18:09.955Z,1560104289.955 [YawRateCalculator] Loaded
2019-06-09T18:18:09.956Z,1560104289.956 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-06-09T18:18:09.996Z,1560104289.996 [ElevatorOffsetCalculator] Loaded
2019-06-09T18:18:09.997Z,1560104289.997 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-06-09T18:18:09.997Z,1560104289.997 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-06-09T18:18:09.998Z,1560104289.998 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-06-09T18:18:10.130Z,1560104290.130 [SBIT](DEBUG): Construct Startup Built In Test.
2019-06-09T18:18:10.152Z,1560104290.152 [SBIT] Loaded
2019-06-09T18:18:10.153Z,1560104290.153 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-06-09T18:18:10.153Z,1560104290.153 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-06-09T18:18:10.165Z,1560104290.165 [IBIT] Loaded
2019-06-09T18:18:10.166Z,1560104290.166 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-06-09T18:18:10.169Z,1560104290.169 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-06-09T18:18:10.309Z,1560104290.309 [CBIT] Loaded
2019-06-09T18:18:10.309Z,1560104290.309 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-06-09T18:18:10.310Z,1560104290.310 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-06-09T18:18:10.310Z,1560104290.310 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-06-09T18:18:10.388Z,1560104290.388 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-06-09T18:18:10.389Z,1560104290.389 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-06-09T18:18:10.487Z,1560104290.487 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-06-09T18:18:10.488Z,1560104290.488 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-06-09T18:18:10.556Z,1560104290.556 [VerticalControl](DEBUG): Construct VerticalControl.
2019-06-09T18:18:10.640Z,1560104290.640 [VerticalControl] Loaded
2019-06-09T18:18:10.640Z,1560104290.640 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-06-09T18:18:10.641Z,1560104290.641 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-06-09T18:18:10.699Z,1560104290.699 [HorizontalControl] Loaded
2019-06-09T18:18:10.699Z,1560104290.699 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-06-09T18:18:10.700Z,1560104290.700 [SpeedControl](DEBUG): Construct SpeedControl.
2019-06-09T18:18:10.702Z,1560104290.702 [SpeedControl] Loaded
2019-06-09T18:18:10.702Z,1560104290.702 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-06-09T18:18:10.703Z,1560104290.703 [LoopControl](DEBUG): Construct LoopControl.
2019-06-09T18:18:10.704Z,1560104290.704 [LoopControl] Loaded
2019-06-09T18:18:10.704Z,1560104290.704 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-06-09T18:18:10.704Z,1560104290.704 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-06-09T18:18:10.705Z,1560104290.705 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-06-09T18:18:10.731Z,1560104290.731 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-06-09T18:18:10.735Z,1560104290.735 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-06-09T18:18:10.736Z,1560104290.736 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-06-09T18:18:10.743Z,1560104290.743 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-06-09T18:18:10.744Z,1560104290.744 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA54E0
2019-06-09T18:18:10.744Z,1560104290.744 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1061
2019-06-09T18:18:10.749Z,1560104290.749 [Supervisor](INFO): Main Thread ID is 803
2019-06-09T18:18:10.749Z,1560104290.749 [Supervisor](DEBUG): Running supervisor.
2019-06-09T18:18:10.750Z,1560104290.750 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1062
2019-06-09T18:18:10.752Z,1560104290.752 [controlThread ThreadHandler](INFO): Handler Thread ID is 1063
2019-06-09T18:18:10.752Z,1560104290.752 [controlThread](DEBUG): Initializing ControlThread
2019-06-09T18:18:10.761Z,1560104290.761 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-06-09T18:18:10.761Z,1560104290.761 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-06-09T18:18:10.762Z,1560104290.762 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-06-09T18:18:10.762Z,1560104290.762 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-06-09T18:18:10.763Z,1560104290.763 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-06-09T18:18:10.763Z,1560104290.763 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-06-09T18:18:10.763Z,1560104290.763 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-06-09T18:18:10.764Z,1560104290.764 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-06-09T18:18:10.764Z,1560104290.764 [SBIT](INFO): Initialize SBIT Component.
2019-06-09T18:18:10.765Z,1560104290.765 [SBIT](IMPORTANT): git: 2019-05-29
2019-06-09T18:18:10.765Z,1560104290.765 [SBIT](INFO): git hash: 3a408d161d85e02f941062c72493668314c507d3
2019-06-09T18:18:10.765Z,1560104290.765 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-06-09T18:18:10.767Z,1560104290.767 [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-09T18:18:10.768Z,1560104290.768 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2019-06-09T18:18:10.768Z,1560104290.768 [IBIT](INFO): Initialize IBIT Component.
2019-06-09T18:18:10.769Z,1560104290.769 [CBIT](DEBUG): Initialize CBIT Component.
2019-06-09T18:18:10.770Z,1560104290.770 [logger ThreadHandler](INFO): Handler Thread ID is 1064
2019-06-09T18:18:10.781Z,1560104290.781 [CBIT](DEBUG): Initialized mux pins.
2019-06-09T18:18:10.781Z,1560104290.781 [CBIT](DEBUG): Initializing the watchdog timer.
2019-06-09T18:18:10.789Z,1560104290.789 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1065
2019-06-09T18:18:10.790Z,1560104290.790 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-06-09T18:18:10.801Z,1560104290.801 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1066
2019-06-09T18:18:10.805Z,1560104290.805 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-06-09T18:18:10.805Z,1560104290.805 [CBIT](DEBUG): Initializing heartbeat.
2019-06-09T18:18:10.821Z,1560104290.821 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1067
2019-06-09T18:18:10.822Z,1560104290.822 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-06-09T18:18:10.849Z,1560104290.849 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1068
2019-06-09T18:18:10.853Z,1560104290.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-06-09T18:18:10.853Z,1560104290.853 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-06-09T18:18:10.853Z,1560104290.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-06-09T18:18:10.853Z,1560104290.853 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-06-09T18:18:10.853Z,1560104290.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-06-09T18:18:10.854Z,1560104290.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-06-09T18:18:10.854Z,1560104290.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-06-09T18:18:10.854Z,1560104290.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-06-09T18:18:10.854Z,1560104290.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-06-09T18:18:10.854Z,1560104290.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-06-09T18:18:10.855Z,1560104290.855 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-06-09T18:18:10.855Z,1560104290.855 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-06-09T18:18:10.855Z,1560104290.855 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-06-09T18:18:10.855Z,1560104290.855 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-06-09T18:18:10.855Z,1560104290.855 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-06-09T18:18:10.856Z,1560104290.856 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-06-09T18:18:10.877Z,1560104290.877 [CBIT](DEBUG): Deactivating GF circuits.
2019-06-09T18:18:10.877Z,1560104290.877 [CBIT](DEBUG): Deactivating emergency mode.
2019-06-09T18:18:10.913Z,1560104290.913 [CBIT](DEBUG): Backplane powered.
2019-06-09T18:18:10.913Z,1560104290.913 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-06-09T18:18:10.915Z,1560104290.915 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-06-09T18:18:10.915Z,1560104290.915 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-06-09T18:18:10.916Z,1560104290.916 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-06-09T18:18:10.917Z,1560104290.917 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-06-09T18:18:10.926Z,1560104290.926 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-06-09T18:18:10.949Z,1560104290.949 [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-09T18:18:10.950Z,1560104290.950 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-06-09T18:18:11.026Z,1560104291.026 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-06-09T18:18:11.027Z,1560104291.027 [Default:A.Wait](DEBUG): Construct Wait.
2019-06-09T18:18:11.029Z,1560104291.029 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-06-09T18:18:11.063Z,1560104291.063 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-06-09T18:18:11.074Z,1560104291.074 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-06-09T18:18:11.079Z,1560104291.079 [Default:E.Execute](DEBUG): Construct Execute.
2019-06-09T18:18:11.103Z,1560104291.103 [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-09T18:18:11.113Z,1560104291.113 [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-09T18:18:11.118Z,1560104291.118 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-06-09T18:18:11.151Z,1560104291.151 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-06-09T18:18:11.152Z,1560104291.152 [DUSBL_Hydroid](INFO): Powering up
2019-06-09T18:18:11.152Z,1560104291.152 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-06-09T18:18:11.205Z,1560104291.205 [Radio_Surface](INFO): Powering up
2019-06-09T18:18:11.241Z,1560104291.241 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-06-09T18:18:11.260Z,1560104291.260 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-06-09T18:18:11.269Z,1560104291.269 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-06-09T18:18:11.270Z,1560104291.270 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-06-09T18:18:11.277Z,1560104291.277 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-06-09T18:18:11.278Z,1560104291.278 [MassServo](DEBUG): Initializing EZServoServo.
2019-06-09T18:18:11.285Z,1560104291.285 [MassServo](DEBUG): Initializing MassServo.
2019-06-09T18:18:11.286Z,1560104291.286 [RudderServo](DEBUG): Initializing EZServoServo.
2019-06-09T18:18:11.293Z,1560104291.293 [RudderServo](DEBUG): Initializing RudderServo.
2019-06-09T18:18:11.294Z,1560104291.294 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-06-09T18:18:11.309Z,1560104291.309 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-06-09T18:18:11.543Z,1560104291.543 [Micromodem](INFO): Powering up
2019-06-09T18:18:11.543Z,1560104291.543 [Micromodem](DEBUG): Initializing Micromodem.
2019-06-09T18:18:11.577Z,1560104291.577 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-06-09T18:18:24.481Z,1560104304.481 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-06-09T18:18:27.690Z,1560104307.690 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-06-09T18:18:29.346Z,1560104309.346 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-06-09T18:18:31.350Z,1560104311.350 [SBIT](IMPORTANT): Beginning Startup BIT
2019-06-09T18:18:31.354Z,1560104311.354 [CBIT](IMPORTANT): Beginning ground fault scan
2019-06-09T18:18:37.063Z,1560104317.063 [NAL9602](INFO): Powering up NAL9602
2019-06-09T18:18:39.095Z,1560104319.095 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-06-09T18:18:42.398Z,1560104322.398 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009092
CHAN A1 (24V): -0.028093
CHAN A2 (12V): -0.007630
CHAN A3 (5V): -0.002209
CHAN B0 (3.3V): 0.000070
CHAN B1 (3.15aV): 0.000164
CHAN B2 (3.15bV): 0.000110
CHAN B3 (GND): 0.001933
OPEN: 0.004470
Full Scale Calc: 4.765 mA, -1.589 mA
2019-06-09T18:18:48.163Z,1560104328.163 [NAL9602](INFO): NAL9602 initialized
2019-06-09T18:18:48.989Z,1560104328.989 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:19:24.663Z,1560104364.663 [SBIT](IMPORTANT): SBIT PASSED
2019-06-09T18:19:24.726Z,1560104364.726 [CommandLine](IMPORTANT): got command configSet list
2019-06-09T18:19:24.726Z,1560104364.726 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-06-09T18:19:24.727Z,1560104364.727 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=3 hour;

2019-06-09T18:19:24.727Z,1560104364.727 [CommandLine](IMPORTANT): BPC1.loadAtStartup=1 bool;

2019-06-09T18:19:24.727Z,1560104364.727 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-06-09T18:19:24.727Z,1560104364.727 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2019-06-09T18:19:24.727Z,1560104364.727 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.range 2.000000 meter;

2019-06-09T18:19:24.727Z,1560104364.727 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 angular_degree;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.BottomVelocityFlag 0.000000 count;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express none TrackAcousticContact.contact_latitude;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express none TrackAcousticContact.contact_longitude;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.OutputFDOM 100.000000 count;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.darkCountsOil 250.000000 count;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 0.000020 kilogram_per_cubic_meter;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 1.000000 meter;

2019-06-09T18:19:24.728Z,1560104364.728 [CommandLine](IMPORTANT): Express linearApproximation mass_concentration_of_chlorophyll_in_sea_water 5.000000 microgram_per_liter;

2019-06-09T18:19:24.729Z,1560104364.729 [CommandLine](IMPORTANT): Express none mass_concentration_of_oxygen_in_sea_water;

2019-06-09T18:19:24.730Z,1560104364.730 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-06-09T18:19:24.730Z,1560104364.730 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=190 cubic_centimeter;

2019-06-09T18:19:24.731Z,1560104364.731 [CommandLine](IMPORTANT): VerticalControl.massDefault=0 centimeter;

2019-06-09T18:19:25.058Z,1560104365.058 [MissionManager](IMPORTANT): Started mission Startup
2019-06-09T18:19:25.058Z,1560104365.058 [Startup] Running Loop=1
2019-06-09T18:19:25.058Z,1560104365.058 [Startup](DEBUG): Aggregate::initialize Startup
2019-06-09T18:19:25.058Z,1560104365.058 [Startup:A.GoToSurface] Running Loop=1
2019-06-09T18:19:25.058Z,1560104365.058 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-06-09T18:19:25.059Z,1560104365.059 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-06-09T18:19:25.059Z,1560104365.059 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-06-09T18:19:25.060Z,1560104365.060 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-06-09T18:19:25.060Z,1560104365.060 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-06-09T18:19:25.060Z,1560104365.060 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-06-09T18:19:25.062Z,1560104365.062 [Startup:StartupSatComms] Running Loop=1
2019-06-09T18:19:25.062Z,1560104365.062 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-06-09T18:19:25.062Z,1560104365.062 [Startup:StartupSatComms:A] Running Loop=1
2019-06-09T18:19:25.475Z,1560104365.475 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-06-09T18:19:26.673Z,1560104366.673 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-06-09T18:20:08.697Z,1560104408.697 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-06-09T18:20:08.697Z,1560104408.697 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19060911285351,35.0, -0.1,  8.9,  0
2019-06-09T18:20:25.361Z,1560104425.361 [Startup:StartupSatComms:A](INFO): Timed out from 2019-06-09T18:19:25.1Z
2019-06-09T18:20:25.362Z,1560104425.362 [Startup:StartupSatComms:A] Stopped
2019-06-09T18:20:25.362Z,1560104425.362 [Startup:StartupSatComms:B] Running Loop=1
2019-06-09T18:20:25.670Z,1560104425.670 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-06-09T18:20:51.497Z,1560104451.497 [NAL9602](INFO): SBD MO Status=2, MOMSN=7417, MT Status=2, MTMSN=0
2019-06-09T18:20:51.498Z,1560104451.498 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-06-09T18:21:10.921Z,1560104470.921 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-06-09T18:21:10.921Z,1560104470.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-09T18:21:10.931Z,1560104470.931 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-09T18:21:11.332Z,1560104471.332 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-09T18:21:11.333Z,1560104471.333 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-06-09T18:21:25.494Z,1560104485.494 [Startup:StartupSatComms:B](INFO): Timed out from 2019-06-09T18:20:25.4Z
2019-06-09T18:21:25.494Z,1560104485.494 [Startup:StartupSatComms:B] Stopped
2019-06-09T18:21:25.494Z,1560104485.494 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-06-09T18:21:25.494Z,1560104485.494 [Startup:StartupSatComms] Stopped
2019-06-09T18:21:25.495Z,1560104485.495 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-06-09T18:21:25.495Z,1560104485.495 [Startup](INFO): Completed Startup
2019-06-09T18:21:25.495Z,1560104485.495 [MissionManager](INFO): Startup is completed.
2019-06-09T18:21:25.496Z,1560104485.496 [MissionManager](INFO): Uninitializing Mission Startup
2019-06-09T18:21:25.496Z,1560104485.496 [Startup] Stopped
2019-06-09T18:21:25.496Z,1560104485.496 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-06-09T18:21:25.496Z,1560104485.496 [Startup:A.GoToSurface] Stopped
2019-06-09T18:21:25.496Z,1560104485.496 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-06-09T18:21:25.857Z,1560104485.857 [MissionManager](IMPORTANT): Started mission Default
2019-06-09T18:21:25.857Z,1560104485.857 [Default] Running Loop=1
2019-06-09T18:21:25.858Z,1560104485.858 [Default](DEBUG): Aggregate::initialize Default
2019-06-09T18:21:25.858Z,1560104485.858 [Default:B.GoToSurface] Running Loop=1
2019-06-09T18:21:25.858Z,1560104485.858 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-06-09T18:21:25.858Z,1560104485.858 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-06-09T18:21:25.858Z,1560104485.858 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-06-09T18:21:25.859Z,1560104485.859 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-06-09T18:21:25.859Z,1560104485.859 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-06-09T18:21:25.859Z,1560104485.859 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-06-09T18:21:25.860Z,1560104485.860 [Default:A.Wait] Running Loop=1
2019-06-09T18:21:25.860Z,1560104485.860 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-06-09T18:21:33.203Z,1560104493.203 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005046
2019-06-09T18:21:39.186Z,1560104499.186 [Default:A.Wait](INFO): Done Waiting.
2019-06-09T18:21:39.186Z,1560104499.186 [Default:A.Wait] Stopped
2019-06-09T18:21:39.186Z,1560104499.186 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-06-09T18:21:39.595Z,1560104499.595 [Default:CheckIn] Running Loop=1
2019-06-09T18:21:39.595Z,1560104499.595 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-06-09T18:21:39.595Z,1560104499.595 [Default:CheckIn:Read_GPS] Running Loop=1
2019-06-09T18:21:39.998Z,1560104499.998 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-06-09T18:21:55.728Z,1560104515.728 [NAL9602](INFO): SBD MO Status=0, MOMSN=7417, MT Status=0, MTMSN=0
2019-06-09T18:21:55.728Z,1560104515.728 [NAL9602](INFO): No messages in MT queue
2019-06-09T18:21:56.553Z,1560104516.553 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:21:59.777Z,1560104519.777 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:02.605Z,1560104522.605 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:05.841Z,1560104525.841 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:08.665Z,1560104528.665 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:11.897Z,1560104531.897 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:14.725Z,1560104534.725 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:17.557Z,1560104537.557 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:20.785Z,1560104540.785 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:23.617Z,1560104543.617 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:26.845Z,1560104546.845 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:29.673Z,1560104549.673 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:32.909Z,1560104552.909 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:35.737Z,1560104555.737 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:38.561Z,1560104558.561 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:41.797Z,1560104561.797 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:44.625Z,1560104564.625 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:47.857Z,1560104567.857 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:50.681Z,1560104570.681 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:53.513Z,1560104573.513 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:56.741Z,1560104576.741 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:22:59.569Z,1560104579.569 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:02.805Z,1560104582.805 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:05.633Z,1560104585.633 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:08.877Z,1560104588.877 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:11.693Z,1560104591.693 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:14.521Z,1560104594.521 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:16.945Z,1560104596.945 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:19.773Z,1560104599.773 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:22.601Z,1560104602.601 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:25.833Z,1560104605.833 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:28.661Z,1560104608.661 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:31.893Z,1560104611.893 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:34.725Z,1560104614.725 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:37.545Z,1560104617.545 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:40.777Z,1560104620.777 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:43.605Z,1560104623.605 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:46.841Z,1560104626.841 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:49.665Z,1560104629.665 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:52.901Z,1560104632.901 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:55.729Z,1560104635.729 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:23:58.553Z,1560104638.553 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:01.785Z,1560104641.785 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:04.629Z,1560104644.629 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:07.853Z,1560104647.853 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:10.681Z,1560104650.681 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:11.914Z,1560104651.914 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-06-09T18:24:11.914Z,1560104651.914 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-09T18:24:11.924Z,1560104651.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-09T18:24:12.310Z,1560104652.310 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-09T18:24:12.310Z,1560104652.310 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-06-09T18:24:13.905Z,1560104653.905 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:16.737Z,1560104656.737 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:19.565Z,1560104659.565 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:22.797Z,1560104662.797 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:25.621Z,1560104665.621 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:28.857Z,1560104668.857 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:31.685Z,1560104671.685 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:34.517Z,1560104674.517 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:36.529Z,1560104676.529 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:38.553Z,1560104678.553 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:41.789Z,1560104681.789 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:44.617Z,1560104684.617 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:47.849Z,1560104687.849 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:50.677Z,1560104690.677 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:53.901Z,1560104693.901 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:56.733Z,1560104696.733 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:24:59.965Z,1560104699.965 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:02.797Z,1560104702.797 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:06.029Z,1560104706.029 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:08.853Z,1560104708.853 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:11.681Z,1560104711.681 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:14.509Z,1560104714.509 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:17.737Z,1560104717.737 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:20.565Z,1560104720.565 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:23.801Z,1560104723.801 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:26.633Z,1560104726.633 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:29.857Z,1560104729.857 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:32.689Z,1560104732.689 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:33.920Z,1560104733.920 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-06-09T18:25:33.923Z,1560104733.923 [BPC1](INFO): Received data from all battery sticks.
2019-06-09T18:25:35.917Z,1560104735.917 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:38.749Z,1560104738.749 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:41.977Z,1560104741.977 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:44.809Z,1560104744.809 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:48.037Z,1560104748.037 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:50.873Z,1560104750.873 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:53.693Z,1560104753.693 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:56.525Z,1560104756.525 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:25:58.545Z,1560104758.545 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:01.777Z,1560104761.777 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:04.601Z,1560104764.601 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:07.837Z,1560104767.837 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:10.661Z,1560104770.661 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:13.893Z,1560104773.893 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:16.725Z,1560104776.725 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:19.957Z,1560104779.957 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:22.789Z,1560104782.789 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:25.609Z,1560104785.609 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:28.849Z,1560104788.849 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:31.669Z,1560104791.669 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:34.905Z,1560104794.905 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:37.729Z,1560104797.729 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:39.762Z,1560104799.762 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-09T18:21:39.6Z
2019-06-09T18:26:39.762Z,1560104799.762 [Default:CheckIn:Read_GPS] Stopped
2019-06-09T18:26:39.762Z,1560104799.762 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-06-09T18:26:40.175Z,1560104800.175 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-06-09T18:26:41.065Z,1560104801.065 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:43.893Z,1560104803.893 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:45.384Z,1560104805.384 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20190609T171951/Courier0019.lzma
2019-06-09T18:26:46.190Z,1560104806.190 [DataOverHttps](INFO): Moved sent file to Logs/20190609T171951/Courier0019.lzma.bak
2019-06-09T18:26:46.191Z,1560104806.191 [DataOverHttps](INFO): SBD MOMSN=11358128
2019-06-09T18:26:46.725Z,1560104806.725 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:49.953Z,1560104809.953 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:52.789Z,1560104812.789 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:55.625Z,1560104815.625 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:58.849Z,1560104818.849 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:26:59.337Z,1560104819.337 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190609T181800/Courier0004.lzma
2019-06-09T18:27:00.146Z,1560104820.146 [DataOverHttps](INFO): Moved sent file to Logs/20190609T181800/Courier0004.lzma.bak
2019-06-09T18:27:00.146Z,1560104820.146 [DataOverHttps](INFO): SBD MOMSN=11358131
2019-06-09T18:27:01.677Z,1560104821.677 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:04.909Z,1560104824.909 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:07.733Z,1560104827.733 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:10.977Z,1560104830.977 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:11.526Z,1560104831.526 [DataOverHttps](INFO): Sending 885 bytes from file Logs/20190609T171951/Express0020.lzma
2019-06-09T18:27:12.330Z,1560104832.330 [DataOverHttps](INFO): Moved sent file to Logs/20190609T171951/Express0020.lzma.bak
2019-06-09T18:27:12.331Z,1560104832.331 [DataOverHttps](INFO): SBD MOMSN=11358134
2019-06-09T18:27:13.048Z,1560104833.048 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-06-09T18:27:13.057Z,1560104833.057 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-09T18:27:13.083Z,1560104833.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-09T18:27:13.409Z,1560104833.409 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-09T18:27:13.410Z,1560104833.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-06-09T18:27:13.793Z,1560104833.793 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:17.029Z,1560104837.029 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:19.853Z,1560104839.853 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:22.693Z,1560104842.693 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:23.835Z,1560104843.835 [DataOverHttps](INFO): Sending 1433 bytes from file Logs/20190609T181800/Express0001.lzma
2019-06-09T18:27:24.638Z,1560104844.638 [DataOverHttps](INFO): Moved sent file to Logs/20190609T181800/Express0001.lzma.bak
2019-06-09T18:27:24.638Z,1560104844.638 [DataOverHttps](INFO): SBD MOMSN=11358158
2019-06-09T18:27:25.913Z,1560104845.913 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:28.741Z,1560104848.741 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:30.392Z,1560104850.392 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-06-09T18:27:30.392Z,1560104850.392 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.0  +0.00,545.99
2019-06-09T18:27:31.973Z,1560104851.973 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:34.801Z,1560104854.801 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:36.237Z,1560104856.237 [DataOverHttps](INFO): Sending 563 bytes from file Logs/20190609T181800/Express0005.lzma
2019-06-09T18:27:37.042Z,1560104857.042 [DataOverHttps](INFO): Moved sent file to Logs/20190609T181800/Express0005.lzma.bak
2019-06-09T18:27:37.042Z,1560104857.042 [DataOverHttps](INFO): SBD MOMSN=11358189
2019-06-09T18:27:38.037Z,1560104858.037 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:38.058Z,1560104858.058 [Default:CheckIn:Read_Iridium] Stopped
2019-06-09T18:27:38.058Z,1560104858.058 [Default:CheckIn:C.Wait] Running Loop=1
2019-06-09T18:27:38.058Z,1560104858.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-06-09T18:27:40.873Z,1560104860.873 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:43.709Z,1560104863.709 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:46.921Z,1560104866.921 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:49.753Z,1560104869.753 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:52.989Z,1560104872.989 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:55.809Z,1560104875.809 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:27:59.045Z,1560104879.045 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:01.877Z,1560104881.877 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:04.700Z,1560104884.700 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:07.937Z,1560104887.937 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:10.757Z,1560104890.757 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:13.993Z,1560104893.993 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:16.817Z,1560104896.817 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:19.665Z,1560104899.665 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:22.877Z,1560104902.877 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:25.709Z,1560104905.709 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:28.937Z,1560104908.937 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:31.772Z,1560104911.772 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:34.997Z,1560104914.997 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:37.833Z,1560104917.833 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:40.657Z,1560104920.657 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:43.889Z,1560104923.889 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:46.717Z,1560104926.717 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:28:49.931Z,1560104929.931 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-06-09T18:28:49.931Z,1560104929.931 [NAL9602] Data Fault, FailCount= 1
2019-06-09T18:28:49.931Z,1560104929.931 [NAL9602](ERROR): Data Fault
2019-06-09T18:28:49.966Z,1560104929.966 [CBIT](ERROR): Data Fault in component: NAL9602
2019-06-09T18:28:50.345Z,1560104930.345 [NAL9602](INFO): Powering down
2019-06-09T18:28:51.189Z,1560104931.189 [CBIT](INFO): Clearing failed state for component NAL9602
2019-06-09T18:28:51.189Z,1560104931.189 [NAL9602] No Fault, FailCount= 1
2019-06-09T18:29:20.639Z,1560104960.639 [NAL9602](INFO): Powering up NAL9602
2019-06-09T18:29:31.551Z,1560104971.551 [NAL9602](INFO): NAL9602 initialized
2019-06-09T18:29:32.377Z,1560104972.377 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:35.193Z,1560104975.193 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:38.425Z,1560104978.425 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:41.253Z,1560104981.253 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:44.081Z,1560104984.081 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:46.101Z,1560104986.101 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:48.125Z,1560104988.125 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:51.357Z,1560104991.357 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:54.189Z,1560104994.189 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:29:57.417Z,1560104997.417 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:00.241Z,1560105000.241 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:03.473Z,1560105003.473 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:06.325Z,1560105006.325 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:09.145Z,1560105009.145 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:09.611Z,1560105009.611 [CommandLine](IMPORTANT): got command get latitude
2019-06-09T18:30:09.611Z,1560105009.611 [CommandLine](FAULT): Element has no value
2019-06-09T18:30:12.365Z,1560105012.365 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:14.015Z,1560105014.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-06-09T18:30:14.015Z,1560105014.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-09T18:30:14.025Z,1560105014.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-09T18:30:14.424Z,1560105014.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-09T18:30:14.424Z,1560105014.424 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-06-09T18:30:15.193Z,1560105015.193 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:18.421Z,1560105018.421 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:21.249Z,1560105021.249 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:24.485Z,1560105024.485 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:27.332Z,1560105027.332 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:30.141Z,1560105030.141 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:33.377Z,1560105033.377 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:36.205Z,1560105036.205 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:39.441Z,1560105039.441 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:42.258Z,1560105042.258 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:45.093Z,1560105045.093 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:47.105Z,1560105047.105 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:49.129Z,1560105049.129 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:52.357Z,1560105052.357 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:55.189Z,1560105055.189 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:30:58.421Z,1560105058.421 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:01.245Z,1560105061.245 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:04.073Z,1560105064.073 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:06.109Z,1560105066.109 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:08.113Z,1560105068.113 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:10.133Z,1560105070.133 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:13.373Z,1560105073.373 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:16.197Z,1560105076.197 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:19.441Z,1560105079.441 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:22.253Z,1560105082.253 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:25.081Z,1560105085.081 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:27.101Z,1560105087.101 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:29.125Z,1560105089.125 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:32.353Z,1560105092.353 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:35.185Z,1560105095.185 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:38.417Z,1560105098.417 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:41.241Z,1560105101.241 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:44.481Z,1560105104.481 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:47.325Z,1560105107.325 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:49.729Z,1560105109.729 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:52.961Z,1560105112.961 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:55.789Z,1560105115.789 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:31:58.616Z,1560105118.616 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:01.849Z,1560105121.849 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:04.677Z,1560105124.677 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:07.921Z,1560105127.921 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:10.733Z,1560105130.733 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:13.565Z,1560105133.565 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:16.793Z,1560105136.793 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:19.621Z,1560105139.621 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:22.857Z,1560105142.857 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:25.689Z,1560105145.689 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:28.913Z,1560105148.913 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:31.745Z,1560105151.745 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:34.973Z,1560105154.973 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:37.792Z,1560105157.792 [Micromodem](ERROR): Response from modem failed NMEA checksum: 0609,183043.311179,3,0,40,4000,9760,0,1,9,0,1,1,2,0*40

2019-06-09T18:32:37.805Z,1560105157.805 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:38.643Z,1560105158.643 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-06-09T18:32:38.643Z,1560105158.643 [Default:CheckIn:C.Wait] Stopped
2019-06-09T18:32:38.643Z,1560105158.643 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-06-09T18:32:38.643Z,1560105158.643 [Default:CheckIn:D] Running Loop=1
2019-06-09T18:32:39.030Z,1560105159.030 [Default:CheckIn:D] Stopped
2019-06-09T18:32:39.030Z,1560105159.030 [Default:CheckIn:E] Running Loop=1
2019-06-09T18:32:39.452Z,1560105159.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.219538 min 
2019-06-09T18:32:39.452Z,1560105159.452 [Default:CheckIn:E] Stopped
2019-06-09T18:32:39.452Z,1560105159.452 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-06-09T18:32:39.452Z,1560105159.452 [Default:CheckIn] Stopped
2019-06-09T18:32:39.452Z,1560105159.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-06-09T18:32:39.453Z,1560105159.453 [Default:CheckIn](INFO): Running loop #2
2019-06-09T18:32:39.453Z,1560105159.453 [Default:CheckIn] Running Loop=2
2019-06-09T18:32:39.453Z,1560105159.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-06-09T18:32:39.453Z,1560105159.453 [Default:CheckIn:Read_GPS] Running Loop=1
2019-06-09T18:32:40.633Z,1560105160.633 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:41.484Z,1560105161.484 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-06-09T18:32:41.484Z,1560105161.484 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19060911412651,35.0, -0.1, 48.9,  0
2019-06-09T18:32:43.861Z,1560105163.861 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:46.693Z,1560105166.693 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:49.925Z,1560105169.925 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:52.761Z,1560105172.761 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:55.993Z,1560105175.993 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:32:58.817Z,1560105178.817 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:01.637Z,1560105181.637 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:04.874Z,1560105184.874 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:07.697Z,1560105187.697 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:10.937Z,1560105190.937 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:13.757Z,1560105193.757 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:14.981Z,1560105194.981 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-06-09T18:33:14.981Z,1560105194.981 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-09T18:33:14.991Z,1560105194.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-09T18:33:15.402Z,1560105195.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-09T18:33:15.402Z,1560105195.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-06-09T18:33:16.993Z,1560105196.993 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:19.817Z,1560105199.817 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:22.681Z,1560105202.681 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:25.877Z,1560105205.877 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:28.705Z,1560105208.705 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:31.937Z,1560105211.937 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:34.765Z,1560105214.765 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:37.997Z,1560105217.997 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:40.826Z,1560105220.826 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:43.681Z,1560105223.681 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:46.893Z,1560105226.893 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:49.717Z,1560105229.717 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:52.953Z,1560105232.953 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:55.777Z,1560105235.777 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:33:59.009Z,1560105239.009 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:01.833Z,1560105241.833 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:03.851Z,1560105243.851 [Micromodem](ERROR): Response from modem failed NMEA checksum: 0.46*4C

2019-06-09T18:34:03.852Z,1560105243.852 [Micromodem](ERROR): Response from modem failed NMEA checksum: CATXF,0*54

2019-06-09T18:34:04.661Z,1560105244.661 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:07.897Z,1560105247.897 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:10.729Z,1560105250.729 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:13.957Z,1560105253.957 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:16.797Z,1560105256.797 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:20.017Z,1560105260.017 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:22.841Z,1560105262.841 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:25.673Z,1560105265.673 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:28.901Z,1560105268.901 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:31.729Z,1560105271.729 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:34.961Z,1560105274.961 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:37.817Z,1560105277.817 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:41.021Z,1560105281.021 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:43.853Z,1560105283.853 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:46.677Z,1560105286.677 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:49.909Z,1560105289.909 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:52.757Z,1560105292.757 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:55.973Z,1560105295.973 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:34:58.805Z,1560105298.805 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:02.033Z,1560105302.033 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:04.861Z,1560105304.861 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:07.688Z,1560105307.688 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:10.925Z,1560105310.925 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:13.749Z,1560105313.749 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:16.981Z,1560105316.981 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:19.805Z,1560105319.805 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:22.662Z,1560105322.662 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:25.865Z,1560105325.865 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:28.713Z,1560105328.713 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:31.925Z,1560105331.925 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:34.757Z,1560105334.757 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:37.985Z,1560105337.985 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:40.821Z,1560105340.821 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:44.045Z,1560105344.045 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:46.877Z,1560105346.877 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:49.701Z,1560105349.701 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:52.933Z,1560105352.933 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:55.765Z,1560105355.765 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:35:58.993Z,1560105358.993 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:01.821Z,1560105361.821 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:04.649Z,1560105364.649 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:07.889Z,1560105367.889 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:10.717Z,1560105370.717 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:13.945Z,1560105373.945 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:15.985Z,1560105375.985 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-06-09T18:36:15.985Z,1560105375.985 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-09T18:36:15.995Z,1560105375.995 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-09T18:36:16.398Z,1560105376.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-09T18:36:16.399Z,1560105376.399 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-06-09T18:36:16.777Z,1560105376.777 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:20.005Z,1560105380.005 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:22.829Z,1560105382.829 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:25.657Z,1560105385.657 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:28.889Z,1560105388.889 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:31.717Z,1560105391.717 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:34.949Z,1560105394.949 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:37.790Z,1560105397.790 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:41.021Z,1560105401.021 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:43.845Z,1560105403.845 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:47.069Z,1560105407.069 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:49.901Z,1560105409.901 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:52.729Z,1560105412.729 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:55.969Z,1560105415.969 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:36:58.789Z,1560105418.789 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:02.021Z,1560105422.021 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:04.845Z,1560105424.845 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:08.081Z,1560105428.081 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:10.909Z,1560105430.909 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:13.737Z,1560105433.737 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:16.973Z,1560105436.973 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:19.796Z,1560105439.796 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:23.041Z,1560105443.041 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:25.853Z,1560105445.853 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:28.681Z,1560105448.681 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:31.913Z,1560105451.913 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:34.745Z,1560105454.745 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:37.973Z,1560105457.973 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:39.618Z,1560105459.618 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-09T18:32:39.5Z
2019-06-09T18:37:39.618Z,1560105459.618 [Default:CheckIn:Read_GPS] Stopped
2019-06-09T18:37:39.618Z,1560105459.618 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-06-09T18:37:40.873Z,1560105460.873 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:43.705Z,1560105463.705 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:45.488Z,1560105465.488 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190609T181800/Courier0007.lzma
2019-06-09T18:37:46.298Z,1560105466.298 [DataOverHttps](INFO): Moved sent file to Logs/20190609T181800/Courier0007.lzma.bak
2019-06-09T18:37:46.299Z,1560105466.299 [DataOverHttps](INFO): SBD MOMSN=11358201
2019-06-09T18:37:46.937Z,1560105466.937 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:49.765Z,1560105469.765 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:52.997Z,1560105472.997 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:55.825Z,1560105475.825 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:57.849Z,1560105477.849 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAX

2019-06-09T18:37:58.014Z,1560105478.014 [DataOverHttps](INFO): Sending 1075 bytes from file Logs/20190609T181800/Express0008.lzma
2019-06-09T18:37:58.818Z,1560105478.818 [DataOverHttps](INFO): Moved sent file to Logs/20190609T181800/Express0008.lzma.bak
2019-06-09T18:37:58.818Z,1560105478.818 [DataOverHttps](INFO): SBD MOMSN=11358204
2019-06-09T18:37:59.065Z,1560105479.065 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:37:59.886Z,1560105479.886 [Default:CheckIn:Read_Iridium] Stopped
2019-06-09T18:37:59.886Z,1560105479.886 [Default:CheckIn:C.Wait] Running Loop=1
2019-06-09T18:37:59.886Z,1560105479.886 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-06-09T18:38:01.889Z,1560105481.889 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:04.709Z,1560105484.709 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:07.945Z,1560105487.945 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:10.769Z,1560105490.769 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:14.005Z,1560105494.005 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:16.829Z,1560105496.829 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:20.061Z,1560105500.061 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:22.889Z,1560105502.889 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:25.721Z,1560105505.721 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:28.957Z,1560105508.957 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:31.785Z,1560105511.785 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:35.009Z,1560105515.009 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:37.861Z,1560105517.861 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:41.069Z,1560105521.069 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:43.897Z,1560105523.897 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:46.725Z,1560105526.725 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:49.961Z,1560105529.961 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:52.785Z,1560105532.785 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:56.018Z,1560105536.018 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:38:58.849Z,1560105538.849 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:02.077Z,1560105542.077 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:04.917Z,1560105544.917 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:07.737Z,1560105547.737 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:10.969Z,1560105550.969 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:13.793Z,1560105553.793 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:17.029Z,1560105557.029 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:17.053Z,1560105557.053 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-06-09T18:39:17.053Z,1560105557.053 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-09T18:39:17.071Z,1560105557.071 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-09T18:39:17.479Z,1560105557.479 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-09T18:39:17.479Z,1560105557.479 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-06-09T18:39:19.853Z,1560105559.853 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:22.689Z,1560105562.689 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:25.913Z,1560105565.913 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:28.745Z,1560105568.745 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:31.989Z,1560105571.989 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:39:32.363Z,1560105572.363 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-06-09T18:39:32.363Z,1560105572.363 [NAL9602] Data Fault, FailCount= 2
2019-06-09T18:39:32.363Z,1560105572.363 [NAL9602](ERROR): Data Fault
2019-06-09T18:39:32.411Z,1560105572.411 [CBIT](ERROR): Data Fault in component: NAL9602
2019-06-09T18:39:32.787Z,1560105572.787 [NAL9602](INFO): Powering down
2019-06-09T18:39:33.669Z,1560105573.669 [CBIT](INFO): Clearing failed state for component NAL9602
2019-06-09T18:39:33.670Z,1560105573.670 [NAL9602] No Fault, FailCount= 2
2019-06-09T18:40:03.071Z,1560105603.071 [NAL9602](INFO): Powering up NAL9602
2019-06-09T18:40:13.979Z,1560105613.979 [NAL9602](INFO): NAL9602 initialized
2019-06-09T18:40:16.817Z,1560105616.817 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:19.645Z,1560105619.645 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:22.881Z,1560105622.881 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:25.709Z,1560105625.709 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:28.937Z,1560105628.937 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:31.765Z,1560105631.765 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:34.597Z,1560105634.597 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:37.829Z,1560105637.829 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:40.657Z,1560105640.657 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:43.885Z,1560105643.885 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:46.717Z,1560105646.717 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:49.545Z,1560105649.545 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:52.785Z,1560105652.785 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:55.601Z,1560105655.601 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:40:58.837Z,1560105658.837 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:01.661Z,1560105661.661 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:04.897Z,1560105664.897 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:07.721Z,1560105667.721 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:10.549Z,1560105670.549 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:13.781Z,1560105673.781 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:16.617Z,1560105676.617 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:19.857Z,1560105679.857 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:22.685Z,1560105682.685 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:25.909Z,1560105685.909 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:28.733Z,1560105688.733 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:31.557Z,1560105691.557 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:34.793Z,1560105694.793 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:37.621Z,1560105697.621 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:40.849Z,1560105700.849 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:43.705Z,1560105703.705 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:43.856Z,1560105703.856 [CommandLine](IMPORTANT): got command get latitude
2019-06-09T18:41:43.856Z,1560105703.856 [CommandLine](FAULT): Element has no value
2019-06-09T18:41:45.362Z,1560105705.362 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-06-09T18:41:45.362Z,1560105705.362 [RDI_Pathfinder](ERROR): Failed to parse:
:BD91
2019-06-09T18:41:46.913Z,1560105706.913 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:49.745Z,1560105709.745 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:52.565Z,1560105712.565 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:54.593Z,1560105714.593 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:41:57.821Z,1560105717.821 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:00.649Z,1560105720.649 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:03.893Z,1560105723.893 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:06.713Z,1560105726.713 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:09.937Z,1560105729.937 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:12.773Z,1560105732.773 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:15.597Z,1560105735.597 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:18.025Z,1560105738.025 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:18.068Z,1560105738.068 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-06-09T18:42:18.068Z,1560105738.068 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-09T18:42:18.088Z,1560105738.088 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-09T18:42:18.531Z,1560105738.531 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-09T18:42:18.531Z,1560105738.531 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-06-09T18:42:20.849Z,1560105740.849 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:24.077Z,1560105744.077 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:26.925Z,1560105746.925 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:29.761Z,1560105749.761 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:32.973Z,1560105752.973 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:35.809Z,1560105755.809 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:38.629Z,1560105758.629 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:41.857Z,1560105761.857 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:44.701Z,1560105764.701 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:47.925Z,1560105767.925 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:50.741Z,1560105770.741 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:53.981Z,1560105773.981 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:42:56.801Z,1560105776.801 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:00.037Z,1560105780.037 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:00.467Z,1560105780.467 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-06-09T18:43:00.468Z,1560105780.468 [Default:CheckIn:C.Wait] Stopped
2019-06-09T18:43:00.468Z,1560105780.468 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-06-09T18:43:00.468Z,1560105780.468 [Default:CheckIn:D] Running Loop=1
2019-06-09T18:43:00.865Z,1560105780.865 [Default:CheckIn:D] Stopped
2019-06-09T18:43:00.866Z,1560105780.866 [Default:CheckIn:E] Running Loop=1
2019-06-09T18:43:01.262Z,1560105781.262 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.583466 min 
2019-06-09T18:43:01.262Z,1560105781.262 [Default:CheckIn:E] Stopped
2019-06-09T18:43:01.262Z,1560105781.262 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-06-09T18:43:01.262Z,1560105781.262 [Default:CheckIn] Stopped
2019-06-09T18:43:01.262Z,1560105781.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-06-09T18:43:01.263Z,1560105781.263 [Default:CheckIn](INFO): Running loop #3
2019-06-09T18:43:01.263Z,1560105781.263 [Default:CheckIn] Running Loop=3
2019-06-09T18:43:01.263Z,1560105781.263 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-06-09T18:43:01.263Z,1560105781.263 [Default:CheckIn:Read_GPS] Running Loop=1
2019-06-09T18:43:02.865Z,1560105782.865 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:06.113Z,1560105786.113 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:08.921Z,1560105788.921 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:11.753Z,1560105791.753 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:14.577Z,1560105794.577 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:17.813Z,1560105797.813 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:20.637Z,1560105800.637 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:21.482Z,1560105801.482 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-06-09T18:43:21.482Z,1560105801.482 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-2768,-32768,V
2019-06-09T18:43:23.869Z,1560105803.869 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:26.701Z,1560105806.701 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:29.933Z,1560105809.933 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:32.757Z,1560105812.757 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:35.993Z,1560105815.993 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:38.825Z,1560105818.825 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:42.057Z,1560105822.057 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:44.881Z,1560105824.881 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:48.109Z,1560105828.109 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:50.941Z,1560105830.941 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:53.765Z,1560105833.765 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:56.597Z,1560105836.597 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:43:59.825Z,1560105839.825 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:44:02.673Z,1560105842.673 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:44:03.178Z,1560105843.178 [DataOverHttps](IMPORTANT): SBD MTMSN=20190609T184310
2019-06-09T18:44:05.889Z,1560105845.889 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:44:08.269Z,1560105848.269 [DataOverHttps](INFO): Received command:restart sys
2019-06-09T18:44:08.282Z,1560105848.282 [CommandLine](IMPORTANT): got command restart system
2019-06-09T18:44:08.717Z,1560105848.717 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:44:10.573Z,1560105850.573 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-06-09T18:44:10.573Z,1560105850.573 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-09T18:44:10.573Z,1560105850.573 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:10.621Z,1560105850.621 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-06-09T18:44:10.621Z,1560105850.621 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:10.622Z,1560105850.622 [CommandLine](INFO): Join timeout helper Thread ID is 1163
2019-06-09T18:44:10.622Z,1560105850.622 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-06-09T18:44:10.622Z,1560105850.622 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:10.623Z,1560105850.623 [NavChartDb](INFO): Join timeout helper Thread ID is 1164
2019-06-09T18:44:10.913Z,1560105850.913 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-09T18:44:10.913Z,1560105850.913 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:10.917Z,1560105850.917 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-06-09T18:44:10.917Z,1560105850.917 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:10.917Z,1560105850.917 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1165
2019-06-09T18:44:11.293Z,1560105851.293 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-09T18:44:11.293Z,1560105851.293 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-06-09T18:44:11.294Z,1560105851.294 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.309Z,1560105851.309 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-06-09T18:44:11.309Z,1560105851.309 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.309Z,1560105851.309 [Radio_Surface](INFO): Join timeout helper Thread ID is 1166
2019-06-09T18:44:11.697Z,1560105851.697 [Radio_Surface](INFO): Powering down
2019-06-09T18:44:11.698Z,1560105851.698 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-09T18:44:11.698Z,1560105851.698 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.701Z,1560105851.701 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-06-09T18:44:11.701Z,1560105851.701 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.701Z,1560105851.701 [DataOverHttps](INFO): Join timeout helper Thread ID is 1167
2019-06-09T18:44:11.893Z,1560105851.893 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-09T18:44:11.893Z,1560105851.893 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.906Z,1560105851.906 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-06-09T18:44:11.906Z,1560105851.906 [logger ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.906Z,1560105851.906 [logger](INFO): Join timeout helper Thread ID is 1168
2019-06-09T18:44:11.931Z,1560105851.931 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-09T18:44:11.932Z,1560105851.932 [logger ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.945Z,1560105851.945 [NAL9602](DEBUG): Fix Requested
2019-06-09T18:44:11.946Z,1560105851.946 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-06-09T18:44:11.946Z,1560105851.946 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.946Z,1560105851.946 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-06-09T18:44:11.946Z,1560105851.946 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:11.947Z,1560105851.947 [controlThread](INFO): Join timeout helper Thread ID is 1169
2019-06-09T18:44:11.978Z,1560105851.978 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-09T18:44:11.978Z,1560105851.978 [controlThread](DEBUG): Uninitializing ControlThread
2019-06-09T18:44:11.978Z,1560105851.978 [AHRS_M2](INFO): Powering down
2019-06-09T18:44:12.050Z,1560105852.050 [DUSBL_Hydroid](INFO): Powering down
2019-06-09T18:44:12.141Z,1560105852.141 [Micromodem](INFO): Powering down
2019-06-09T18:44:12.237Z,1560105852.237 [NAL9602](INFO): Powering down
2019-06-09T18:44:12.309Z,1560105852.309 [RDI_Pathfinder](INFO): Powering down
2019-06-09T18:44:12.310Z,1560105852.310 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-06-09T18:44:12.311Z,1560105852.311 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-06-09T18:44:12.312Z,1560105852.312 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-06-09T18:44:12.312Z,1560105852.312 [MissionManager](INFO): Uninitializing Mission Default
2019-06-09T18:44:12.312Z,1560105852.312 [Default] Stopped
2019-06-09T18:44:12.312Z,1560105852.312 [Default](DEBUG): Aggregate::uninitialize Default
2019-06-09T18:44:12.312Z,1560105852.312 [Default:B.GoToSurface] Stopped
2019-06-09T18:44:12.313Z,1560105852.313 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-06-09T18:44:12.313Z,1560105852.313 [Default:CheckIn] Stopped
2019-06-09T18:44:12.313Z,1560105852.313 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-06-09T18:44:12.313Z,1560105852.313 [Default:CheckIn:Read_GPS] Stopped
2019-06-09T18:44:12.315Z,1560105852.315 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-06-09T18:44:12.316Z,1560105852.316 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-06-09T18:44:12.316Z,1560105852.316 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-06-09T18:44:12.316Z,1560105852.316 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-06-09T18:44:12.316Z,1560105852.316 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-06-09T18:44:12.317Z,1560105852.317 [BuoyancyServo](INFO): Powering down
2019-06-09T18:44:12.329Z,1560105852.329 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-06-09T18:44:12.329Z,1560105852.329 [ElevatorServo](INFO): Powering down
2019-06-09T18:44:12.330Z,1560105852.330 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-06-09T18:44:12.330Z,1560105852.330 [MassServo](INFO): Powering down
2019-06-09T18:44:12.331Z,1560105852.331 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-06-09T18:44:12.331Z,1560105852.331 [RudderServo](INFO): Powering down
2019-06-09T18:44:12.332Z,1560105852.332 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-06-09T18:44:12.332Z,1560105852.332 [ThrusterServo](INFO): Powering down
2019-06-09T18:44:12.333Z,1560105852.333 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-06-09T18:44:12.333Z,1560105852.333 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-06-09T18:44:12.333Z,1560105852.333 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-06-09T18:44:12.333Z,1560105852.333 [CBIT](DEBUG): Powering off loads.
2019-06-09T18:44:12.345Z,1560105852.345 [CBIT](DEBUG): Disabling WDT.
2019-06-09T18:44:12.357Z,1560105852.357 [CBIT](DEBUG): Opening all GF detection circuits.
2019-06-09T18:44:12.358Z,1560105852.358 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:12.403Z,1560105852.403 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:12.412Z,1560105852.412 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:12.459Z,1560105852.459 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:12.531Z,1560105852.531 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-09T18:44:12.590Z,1560105852.590 [logger ThreadHandler](INFO): Thread cancelled.