2018-02-20T18:38:59.468Z,1519151939.468 [Supervisor](DEBUG): Initializing supervisor.
2018-02-20T18:38:59.471Z,1519151939.471 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-02-20T18:38:59.471Z,1519151939.471 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-02-20T18:38:59.472Z,1519151939.472 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-02-20T18:38:59.473Z,1519151939.473 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-02-20T18:38:59.473Z,1519151939.473 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-02-20T18:38:59.475Z,1519151939.475 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-02-20T18:38:59.486Z,1519151939.486 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-02-20T18:38:59.487Z,1519151939.487 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-02-20T18:38:59.488Z,1519151939.488 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-02-20T18:38:59.488Z,1519151939.488 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-02-20T18:38:59.489Z,1519151939.489 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-02-20T18:38:59.490Z,1519151939.490 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-02-20T18:38:59.492Z,1519151939.492 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-02-20T18:38:59.492Z,1519151939.492 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-02-20T18:38:59.496Z,1519151939.496 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-02-20T18:38:59.795Z,1519151939.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-02-20T18:38:59.797Z,1519151939.797 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-02-20T18:38:59.983Z,1519151939.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-02-20T18:38:59.985Z,1519151939.985 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-02-20T18:39:00.246Z,1519151940.246 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-02-20T18:39:00.247Z,1519151940.247 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-02-20T18:39:00.384Z,1519151940.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-02-20T18:39:00.384Z,1519151940.384 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-02-20T18:39:00.480Z,1519151940.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-02-20T18:39:00.482Z,1519151940.482 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-02-20T18:39:00.909Z,1519151940.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-02-20T18:39:00.910Z,1519151940.910 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-02-20T18:39:01.231Z,1519151941.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-02-20T18:39:01.232Z,1519151941.232 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-02-20T18:39:01.640Z,1519151941.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-02-20T18:39:01.642Z,1519151941.642 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-02-20T18:39:01.743Z,1519151941.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-02-20T18:39:01.745Z,1519151941.745 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-02-20T18:39:01.840Z,1519151941.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-02-20T18:39:01.842Z,1519151941.842 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-02-20T18:39:02.036Z,1519151942.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-02-20T18:39:02.037Z,1519151942.037 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-02-20T18:39:02.235Z,1519151942.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-02-20T18:39:02.236Z,1519151942.236 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-02-20T18:39:02.555Z,1519151942.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-02-20T18:39:02.555Z,1519151942.555 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-02-20T18:39:02.691Z,1519151942.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-02-20T18:39:02.692Z,1519151942.692 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-02-20T18:39:02.773Z,1519151942.773 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-02-20T18:39:02.777Z,1519151942.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-02-20T18:39:03.472Z,1519151943.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-02-20T18:39:03.659Z,1519151943.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-02-20T18:39:03.751Z,1519151943.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-02-20T18:39:03.834Z,1519151943.834 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-02-20T18:39:03.978Z,1519151943.978 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-02-20T18:39:04.142Z,1519151944.142 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-02-20T18:39:04.217Z,1519151944.217 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-02-20T18:39:04.438Z,1519151944.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-02-20T18:39:04.439Z,1519151944.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-02-20T18:39:04.521Z,1519151944.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-02-20T18:39:04.610Z,1519151944.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-02-20T18:39:04.708Z,1519151944.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-02-20T18:39:04.829Z,1519151944.829 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-02-20T18:39:04.840Z,1519151944.840 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-02-20T18:39:05.117Z,1519151945.117 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-02-20T18:39:05.118Z,1519151945.118 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-02-20T18:39:05.173Z,1519151945.173 [DepthRateCalculator] Loaded
2018-02-20T18:39:05.173Z,1519151945.173 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-02-20T18:39:05.179Z,1519151945.179 [PitchRateCalculator] Loaded
2018-02-20T18:39:05.179Z,1519151945.179 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-02-20T18:39:05.195Z,1519151945.195 [SpeedCalculator] Loaded
2018-02-20T18:39:05.195Z,1519151945.195 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-02-20T18:39:05.215Z,1519151945.215 [TempGradientCalculator] Loaded
2018-02-20T18:39:05.215Z,1519151945.215 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-02-20T18:39:05.230Z,1519151945.230 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-02-20T18:39:05.231Z,1519151945.231 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-02-20T18:39:05.236Z,1519151945.236 [YawRateCalculator] Loaded
2018-02-20T18:39:05.236Z,1519151945.236 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-02-20T18:39:05.237Z,1519151945.237 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-02-20T18:39:05.237Z,1519151945.237 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-02-20T18:39:05.355Z,1519151945.355 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-02-20T18:39:05.355Z,1519151945.355 [StratificationFrontDetector](DEBUG): (re)initializing
2018-02-20T18:39:05.356Z,1519151945.356 [StratificationFrontDetector] Loaded
2018-02-20T18:39:05.356Z,1519151945.356 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-02-20T18:39:05.356Z,1519151945.356 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-02-20T18:39:05.357Z,1519151945.357 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-02-20T18:39:05.490Z,1519151945.490 [BuoyancyServo] Loaded
2018-02-20T18:39:05.490Z,1519151945.490 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-02-20T18:39:05.504Z,1519151945.504 [ElevatorServo] Loaded
2018-02-20T18:39:05.505Z,1519151945.505 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-02-20T18:39:05.519Z,1519151945.519 [MassServo] Loaded
2018-02-20T18:39:05.519Z,1519151945.519 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-02-20T18:39:05.534Z,1519151945.534 [RudderServo] Loaded
2018-02-20T18:39:05.534Z,1519151945.534 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-02-20T18:39:05.548Z,1519151945.548 [ThrusterServo] Loaded
2018-02-20T18:39:05.548Z,1519151945.548 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-02-20T18:39:05.548Z,1519151945.548 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-02-20T18:39:05.549Z,1519151945.549 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-02-20T18:39:05.573Z,1519151945.573 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-02-20T18:39:05.573Z,1519151945.573 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-02-20T18:39:05.694Z,1519151945.694 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-02-20T18:39:05.695Z,1519151945.695 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-02-20T18:39:06.228Z,1519151946.228 [DataOverHttps] Loaded
2018-02-20T18:39:06.228Z,1519151946.228 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-02-20T18:39:06.241Z,1519151946.241 [Depth_Keller] Loaded
2018-02-20T18:39:06.242Z,1519151946.242 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-02-20T18:39:06.246Z,1519151946.246 [DropWeight] Loaded
2018-02-20T18:39:06.246Z,1519151946.246 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-02-20T18:39:06.341Z,1519151946.341 [NAL9602] Loaded
2018-02-20T18:39:06.341Z,1519151946.341 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-02-20T18:39:06.357Z,1519151946.357 [Onboard] Loaded
2018-02-20T18:39:06.357Z,1519151946.357 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-02-20T18:39:06.367Z,1519151946.367 [Radio_Surface] Loaded
2018-02-20T18:39:06.367Z,1519151946.367 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-02-20T18:39:06.368Z,1519151946.368 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0
2018-02-20T18:39:06.368Z,1519151946.368 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884
2018-02-20T18:39:06.520Z,1519151946.520 [PNI_TCM] Loaded
2018-02-20T18:39:06.520Z,1519151946.520 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-02-20T18:39:08.532Z,1519151948.532 [BPC1] Loaded
2018-02-20T18:39:08.533Z,1519151948.533 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-02-20T18:39:08.533Z,1519151948.533 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-02-20T18:39:08.620Z,1519151948.620 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-02-20T18:39:08.771Z,1519151948.771 [SBIT](DEBUG): Construct Startup Built In Test.
2018-02-20T18:39:08.796Z,1519151948.796 [SBIT] Loaded
2018-02-20T18:39:08.796Z,1519151948.796 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-02-20T18:39:08.796Z,1519151948.796 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-02-20T18:39:08.808Z,1519151948.808 [IBIT] Loaded
2018-02-20T18:39:08.808Z,1519151948.808 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-02-20T18:39:08.811Z,1519151948.811 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-02-20T18:39:08.959Z,1519151948.959 [CBIT] Loaded
2018-02-20T18:39:08.959Z,1519151948.959 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-02-20T18:39:08.959Z,1519151948.959 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-02-20T18:39:08.960Z,1519151948.960 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-02-20T18:39:09.143Z,1519151949.143 [Aanderaa_O2] Loaded
2018-02-20T18:39:09.143Z,1519151949.143 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-02-20T18:39:09.155Z,1519151949.155 [CANONSampler] Loaded
2018-02-20T18:39:09.155Z,1519151949.155 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-02-20T18:39:09.231Z,1519151949.231 [CTD_NeilBrown] Loaded
2018-02-20T18:39:09.231Z,1519151949.231 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-02-20T18:39:09.232Z,1519151949.232 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4092F4E0
2018-02-20T18:39:09.232Z,1519151949.232 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 885
2018-02-20T18:39:09.277Z,1519151949.277 [CTD_Seabird] Loaded
2018-02-20T18:39:09.277Z,1519151949.277 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-02-20T18:39:09.278Z,1519151949.278 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4095F4E0
2018-02-20T18:39:09.279Z,1519151949.279 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 886
2018-02-20T18:39:09.293Z,1519151949.293 [PAR_Licor] Loaded
2018-02-20T18:39:09.293Z,1519151949.293 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-02-20T18:39:09.340Z,1519151949.340 [WetLabsBB2FL] Loaded
2018-02-20T18:39:09.340Z,1519151949.340 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-02-20T18:39:09.341Z,1519151949.341 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4098F4E0
2018-02-20T18:39:09.342Z,1519151949.342 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 887
2018-02-20T18:39:09.342Z,1519151949.342 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-02-20T18:39:09.343Z,1519151949.343 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-02-20T18:39:09.429Z,1519151949.429 [VerticalControl](DEBUG): Construct VerticalControl.
2018-02-20T18:39:09.514Z,1519151949.514 [VerticalControl] Loaded
2018-02-20T18:39:09.514Z,1519151949.514 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-02-20T18:39:09.515Z,1519151949.515 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-02-20T18:39:09.571Z,1519151949.571 [HorizontalControl] Loaded
2018-02-20T18:39:09.571Z,1519151949.571 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-02-20T18:39:09.572Z,1519151949.572 [SpeedControl](DEBUG): Construct SpeedControl.
2018-02-20T18:39:09.573Z,1519151949.573 [SpeedControl] Loaded
2018-02-20T18:39:09.574Z,1519151949.574 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-02-20T18:39:09.574Z,1519151949.574 [LoopControl](DEBUG): Construct LoopControl.
2018-02-20T18:39:09.575Z,1519151949.575 [LoopControl] Loaded
2018-02-20T18:39:09.575Z,1519151949.575 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-02-20T18:39:09.576Z,1519151949.576 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-02-20T18:39:09.576Z,1519151949.576 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-02-20T18:39:09.612Z,1519151949.612 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-02-20T18:39:09.613Z,1519151949.613 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-02-20T18:39:09.718Z,1519151949.718 [DeadReckonUsingSpeedCalculator] Loaded
2018-02-20T18:39:09.718Z,1519151949.718 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-02-20T18:39:09.771Z,1519151949.771 [DeadReckonWithRespectToSeafloor] Loaded
2018-02-20T18:39:09.772Z,1519151949.772 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-02-20T18:39:09.788Z,1519151949.788 [NavChart] Loaded
2018-02-20T18:39:09.788Z,1519151949.788 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-02-20T18:39:09.792Z,1519151949.792 [UniversalFixResidualReporter] Loaded
2018-02-20T18:39:09.792Z,1519151949.792 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-02-20T18:39:09.793Z,1519151949.793 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-02-20T18:39:09.796Z,1519151949.796 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-02-20T18:39:09.797Z,1519151949.797 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-02-20T18:39:09.803Z,1519151949.803 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-02-20T18:39:09.804Z,1519151949.804 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0
2018-02-20T18:39:09.805Z,1519151949.805 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 888
2018-02-20T18:39:09.809Z,1519151949.809 [Supervisor](INFO): Main Thread ID is 795
2018-02-20T18:39:09.809Z,1519151949.809 [Supervisor](DEBUG): Running supervisor.
2018-02-20T18:39:09.810Z,1519151949.810 [CommandLine ThreadHandler](INFO): Handler Thread ID is 889
2018-02-20T18:39:09.813Z,1519151949.813 [controlThread ThreadHandler](INFO): Handler Thread ID is 890
2018-02-20T18:39:09.813Z,1519151949.813 [controlThread](DEBUG): Initializing ControlThread
2018-02-20T18:39:09.814Z,1519151949.814 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-20T18:39:09.814Z,1519151949.814 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-02-20T18:39:09.815Z,1519151949.815 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-02-20T18:39:09.815Z,1519151949.815 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-02-20T18:39:09.815Z,1519151949.815 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-02-20T18:39:09.816Z,1519151949.816 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-02-20T18:39:09.823Z,1519151949.823 [SBIT](INFO): Initialize SBIT Component.
2018-02-20T18:39:09.824Z,1519151949.824 [SBIT](IMPORTANT): git: 2018-02-19
2018-02-20T18:39:09.824Z,1519151949.824 [SBIT](INFO): git hash: 86d1787471b878e1ef111bfaa0f2159f48b0acda
2018-02-20T18:39:09.824Z,1519151949.824 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-02-20T18:39:09.825Z,1519151949.825 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-02-20T18:39:09.826Z,1519151949.826 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-02-20T18:39:09.827Z,1519151949.827 [IBIT](INFO): Initialize IBIT Component.
2018-02-20T18:39:09.828Z,1519151949.828 [CBIT](DEBUG): Initialize CBIT Component.
2018-02-20T18:39:09.828Z,1519151949.828 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-02-20T18:39:09.829Z,1519151949.829 [logger ThreadHandler](INFO): Handler Thread ID is 891
2018-02-20T18:39:09.845Z,1519151949.845 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 892
2018-02-20T18:39:09.851Z,1519151949.851 [Radio_Surface](INFO): Powering up
2018-02-20T18:39:09.857Z,1519151949.857 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 893
2018-02-20T18:39:09.858Z,1519151949.858 [CTD_NeilBrown](INFO): Powering down
2018-02-20T18:39:09.889Z,1519151949.889 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 894
2018-02-20T18:39:09.890Z,1519151949.890 [CTD_Seabird](INFO): Initializing
2018-02-20T18:39:09.890Z,1519151949.890 [CTD_Seabird](INFO): Checking LCM
2018-02-20T18:39:09.937Z,1519151949.937 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-02-20T18:39:09.939Z,1519151949.939 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-02-20T18:39:09.940Z,1519151949.940 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-02-20T18:39:09.940Z,1519151949.940 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-02-20T18:39:09.940Z,1519151949.940 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-20T18:39:09.941Z,1519151949.941 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:39:09.942Z,1519151949.942 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:39:09.942Z,1519151949.942 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T18:39:09.942Z,1519151949.942 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:39:09.943Z,1519151949.943 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:39:09.943Z,1519151949.943 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-02-20T18:39:09.944Z,1519151949.944 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-02-20T18:39:10.027Z,1519151950.027 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-02-20T18:39:10.038Z,1519151950.038 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 895
2018-02-20T18:39:10.039Z,1519151950.039 [WetLabsBB2FL](INFO): Powering down
2018-02-20T18:39:10.057Z,1519151950.057 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896
2018-02-20T18:39:10.170Z,1519151950.170 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-02-20T18:39:10.170Z,1519151950.170 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-02-20T18:39:10.171Z,1519151950.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-02-20T18:39:10.171Z,1519151950.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-02-20T18:39:10.171Z,1519151950.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-02-20T18:39:10.171Z,1519151950.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-02-20T18:39:10.171Z,1519151950.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-02-20T18:39:10.171Z,1519151950.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-02-20T18:39:10.172Z,1519151950.172 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-02-20T18:39:10.172Z,1519151950.172 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-02-20T18:39:10.172Z,1519151950.172 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-02-20T18:39:10.172Z,1519151950.172 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-02-20T18:39:10.172Z,1519151950.172 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-02-20T18:39:10.173Z,1519151950.173 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-02-20T18:39:10.173Z,1519151950.173 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-02-20T18:39:10.173Z,1519151950.173 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-02-20T18:39:10.188Z,1519151950.188 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-20T18:39:10.269Z,1519151950.269 [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>


2018-02-20T18:39:10.270Z,1519151950.270 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-02-20T18:39:10.353Z,1519151950.353 [CTD_Seabird](INFO): LCM OK
2018-02-20T18:39:10.353Z,1519151950.353 [CTD_Seabird](INFO): Powering up
2018-02-20T18:39:10.378Z,1519151950.378 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-02-20T18:39:10.379Z,1519151950.379 [Default:A.Wait](DEBUG): Construct Wait.
2018-02-20T18:39:10.380Z,1519151950.380 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-20T18:39:10.422Z,1519151950.422 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-02-20T18:39:10.425Z,1519151950.425 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-02-20T18:39:10.446Z,1519151950.446 [Default:E.Execute](DEBUG): Construct Execute.
2018-02-20T18:39:10.450Z,1519151950.450 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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>


2018-02-20T18:39:10.460Z,1519151950.460 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-02-20T18:39:10.483Z,1519151950.483 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-02-20T18:39:10.751Z,1519151950.751 [CANONSampler](INFO): Powering down
2018-02-20T18:39:11.034Z,1519151951.034 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:11.127Z,1519151951.127 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:11.229Z,1519151951.229 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-20T18:39:11.237Z,1519151951.237 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-20T18:39:11.262Z,1519151951.262 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-02-20T18:39:11.269Z,1519151951.269 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-02-20T18:39:11.275Z,1519151951.275 [MassServo](DEBUG): Initializing EZServoServo.
2018-02-20T18:39:11.281Z,1519151951.281 [MassServo](DEBUG): Initializing MassServo.
2018-02-20T18:39:11.287Z,1519151951.287 [RudderServo](DEBUG): Initializing EZServoServo.
2018-02-20T18:39:11.293Z,1519151951.293 [RudderServo](DEBUG): Initializing RudderServo.
2018-02-20T18:39:11.306Z,1519151951.306 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-02-20T18:39:11.313Z,1519151951.313 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-02-20T18:39:11.630Z,1519151951.630 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:11.631Z,1519151951.631 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:11.856Z,1519151951.856 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:11.857Z,1519151951.857 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:12.185Z,1519151952.185 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-02-20T18:39:12.185Z,1519151952.185 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-02-20T18:39:12.185Z,1519151952.185 [BuoyancyServo] Communications Fault, FailCount= 1
2018-02-20T18:39:12.185Z,1519151952.185 [BuoyancyServo](ERROR): Communications Fault
2018-02-20T18:39:12.392Z,1519151952.392 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-02-20T18:39:12.484Z,1519151952.484 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:12.485Z,1519151952.485 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:12.490Z,1519151952.490 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-20T18:39:12.491Z,1519151952.491 [BuoyancyServo](INFO): Powering down
2018-02-20T18:39:12.848Z,1519151952.848 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:12.849Z,1519151952.849 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:13.272Z,1519151953.272 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:13.273Z,1519151953.273 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:13.668Z,1519151953.668 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:13.669Z,1519151953.669 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:14.157Z,1519151954.157 [Aanderaa_O2](INFO): Powering down
2018-02-20T18:39:14.212Z,1519151954.212 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:14.229Z,1519151954.229 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-02-20T18:39:14.633Z,1519151954.633 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-02-20T18:39:14.633Z,1519151954.633 [BuoyancyServo] No Fault, FailCount= 1
2018-02-20T18:39:14.873Z,1519151954.873 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-20T18:39:14.994Z,1519151954.994 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-20T18:39:15.474Z,1519151955.474 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-20T18:39:15.632Z,1519151955.632 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-02-20T18:39:15.775Z,1519151955.775 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-02-20T18:39:16.209Z,1519151956.209 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-20T18:39:16.209Z,1519151956.209 [CTD_Seabird](INFO): Powering down
2018-02-20T18:39:33.321Z,1519151973.321 [SBIT](IMPORTANT): Beginning Startup BIT
2018-02-20T18:39:33.325Z,1519151973.325 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T18:39:36.591Z,1519151976.591 [NAL9602](INFO): Powering up NAL9602
2018-02-20T18:39:44.267Z,1519151984.267 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.013294
CHAN A1 (24V): -0.016920
CHAN A2 (12V): 4.697929
CHAN A3 (5V): -0.001138
CHAN B0 (3.3V): -0.000316
CHAN B1 (3.15aV): 0.000022
CHAN B2 (3.15bV): -0.000469
CHAN B3 (GND): -0.000236
OPEN: -0.000374
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T18:39:47.790Z,1519151987.790 [NAL9602](INFO): NAL9602 initialized
2018-02-20T18:39:52.459Z,1519151992.459 [BPC1](ERROR): Failed to receive battery data
2018-02-20T18:39:52.459Z,1519151992.459 [BPC1] Communications Fault, FailCount= 1
2018-02-20T18:39:52.459Z,1519151992.459 [BPC1](ERROR): Communications Fault
2018-02-20T18:39:52.594Z,1519151992.594 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-20T18:39:53.766Z,1519151993.766 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-20T18:39:53.766Z,1519151993.766 [BPC1] No Fault, FailCount= 1
2018-02-20T18:40:27.450Z,1519152027.450 [SBIT](IMPORTANT): SBIT PASSED
2018-02-20T18:40:27.780Z,1519152027.780 [MissionManager](IMPORTANT): Started mission Startup
2018-02-20T18:40:27.781Z,1519152027.781 [Startup] Running Loop=1
2018-02-20T18:40:27.781Z,1519152027.781 [Startup](DEBUG): Aggregate::initialize Startup
2018-02-20T18:40:27.781Z,1519152027.781 [Startup:A.GoToSurface] Running Loop=1
2018-02-20T18:40:27.781Z,1519152027.781 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-20T18:40:27.782Z,1519152027.782 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-20T18:40:27.782Z,1519152027.782 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-20T18:40:27.782Z,1519152027.782 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-20T18:40:27.783Z,1519152027.783 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-20T18:40:27.783Z,1519152027.783 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-20T18:40:27.788Z,1519152027.788 [Startup:StartupSatComms] Running Loop=1
2018-02-20T18:40:27.789Z,1519152027.789 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-02-20T18:40:27.789Z,1519152027.789 [Startup:StartupSatComms:A] Running Loop=1
2018-02-20T18:40:28.180Z,1519152028.180 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-02-20T18:41:28.180Z,1519152088.180 [Startup:StartupSatComms:A](INFO): Timed out from 2018-02-20T18:40:27.8Z
2018-02-20T18:41:28.180Z,1519152088.180 [Startup:StartupSatComms:A] Stopped
2018-02-20T18:41:28.185Z,1519152088.185 [Startup:StartupSatComms:B] Running Loop=1
2018-02-20T18:41:28.581Z,1519152088.581 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-02-20T18:41:42.592Z,1519152102.592 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003560
2018-02-20T18:41:46.658Z,1519152106.658 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180220T183722/Courier0004.lzma
2018-02-20T18:41:47.457Z,1519152107.457 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183722/Courier0004.lzma.bak
2018-02-20T18:41:47.458Z,1519152107.458 [DataOverHttps](INFO): SBD MOMSN=7866543
2018-02-20T18:41:56.390Z,1519152116.390 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180220T183859/Courier0000.lzma
2018-02-20T18:41:57.189Z,1519152117.189 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Courier0000.lzma.bak
2018-02-20T18:41:57.189Z,1519152117.189 [DataOverHttps](INFO): SBD MOMSN=7866545
2018-02-20T18:42:06.109Z,1519152126.109 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180220T183722/Express0005.lzma
2018-02-20T18:42:06.897Z,1519152126.897 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183722/Express0005.lzma.bak
2018-02-20T18:42:06.898Z,1519152126.898 [DataOverHttps](INFO): SBD MOMSN=7866548
2018-02-20T18:42:10.863Z,1519152130.863 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:42:10.864Z,1519152130.864 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-02-20T18:42:10.864Z,1519152130.864 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-20T18:42:10.881Z,1519152130.881 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:42:10.882Z,1519152130.882 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-02-20T18:42:10.882Z,1519152130.882 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T18:42:10.975Z,1519152130.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-20T18:42:10.976Z,1519152130.976 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T18:42:11.154Z,1519152131.154 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-20T18:42:11.154Z,1519152131.154 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-02-20T18:42:11.154Z,1519152131.154 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T18:42:11.154Z,1519152131.154 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-02-20T18:42:11.451Z,1519152131.451 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-20T18:42:11.452Z,1519152131.452 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:42:11.452Z,1519152131.452 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:42:11.452Z,1519152131.452 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T18:42:11.453Z,1519152131.453 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:42:11.453Z,1519152131.453 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:42:15.840Z,1519152135.840 [DataOverHttps](INFO): Sending 566 bytes from file Logs/20180220T183859/Express0001.lzma
2018-02-20T18:42:16.637Z,1519152136.637 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Express0001.lzma.bak
2018-02-20T18:42:16.637Z,1519152136.637 [DataOverHttps](INFO): SBD MOMSN=7866550
2018-02-20T18:42:17.098Z,1519152137.098 [Startup:StartupSatComms:B] Stopped
2018-02-20T18:42:17.098Z,1519152137.098 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-02-20T18:42:17.098Z,1519152137.098 [Startup:StartupSatComms] Stopped
2018-02-20T18:42:17.098Z,1519152137.098 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-02-20T18:42:17.099Z,1519152137.099 [Startup](INFO): Completed Startup
2018-02-20T18:42:17.099Z,1519152137.099 [MissionManager](INFO): Startup is completed.
2018-02-20T18:42:17.100Z,1519152137.100 [MissionManager](INFO): Uninitializing Mission Startup
2018-02-20T18:42:17.100Z,1519152137.100 [Startup] Stopped
2018-02-20T18:42:17.100Z,1519152137.100 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-02-20T18:42:17.100Z,1519152137.100 [Startup:A.GoToSurface] Stopped
2018-02-20T18:42:17.100Z,1519152137.100 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-20T18:42:17.495Z,1519152137.495 [MissionManager](IMPORTANT): Started mission Default
2018-02-20T18:42:17.495Z,1519152137.495 [Default] Running Loop=1
2018-02-20T18:42:17.495Z,1519152137.496 [Default](DEBUG): Aggregate::initialize Default
2018-02-20T18:42:17.496Z,1519152137.496 [Default:B.GoToSurface] Running Loop=1
2018-02-20T18:42:17.496Z,1519152137.496 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-20T18:42:17.496Z,1519152137.496 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-20T18:42:17.496Z,1519152137.496 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-20T18:42:17.501Z,1519152137.501 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-20T18:42:17.501Z,1519152137.501 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-20T18:42:17.502Z,1519152137.502 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-20T18:42:17.502Z,1519152137.502 [Default:A.Wait] Running Loop=1
2018-02-20T18:42:17.502Z,1519152137.502 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-02-20T18:42:30.738Z,1519152150.738 [Default:A.Wait](INFO): Done Waiting.
2018-02-20T18:42:30.739Z,1519152150.739 [Default:A.Wait] Stopped
2018-02-20T18:42:30.739Z,1519152150.739 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-02-20T18:42:31.134Z,1519152151.134 [Default:CheckIn] Running Loop=1
2018-02-20T18:42:31.135Z,1519152151.135 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-20T18:42:31.135Z,1519152151.135 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-20T18:42:31.544Z,1519152151.544 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-02-20T18:44:51.202Z,1519152291.202 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-02-20T18:45:11.659Z,1519152311.659 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:45:11.659Z,1519152311.659 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-02-20T18:45:11.659Z,1519152311.659 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-20T18:45:11.661Z,1519152311.661 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:45:11.661Z,1519152311.661 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-02-20T18:45:11.661Z,1519152311.661 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T18:45:11.741Z,1519152311.741 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-20T18:45:11.741Z,1519152311.741 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T18:45:12.142Z,1519152312.142 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-20T18:45:12.142Z,1519152312.142 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-02-20T18:45:12.143Z,1519152312.143 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T18:45:12.143Z,1519152312.143 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-02-20T18:45:12.434Z,1519152312.434 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-20T18:45:12.435Z,1519152312.435 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:45:12.435Z,1519152312.435 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:45:12.436Z,1519152312.436 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T18:45:12.436Z,1519152312.436 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:45:12.436Z,1519152312.436 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:47:31.245Z,1519152451.245 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-02-20T18:42:31.1Z
2018-02-20T18:47:31.245Z,1519152451.245 [Default:CheckIn:Read_GPS] Stopped
2018-02-20T18:47:31.245Z,1519152451.245 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-20T18:47:31.647Z,1519152451.647 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-02-20T18:47:35.565Z,1519152455.565 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180220T183859/Courier0004.lzma
2018-02-20T18:47:36.365Z,1519152456.365 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Courier0004.lzma.bak
2018-02-20T18:47:36.365Z,1519152456.365 [DataOverHttps](INFO): SBD MOMSN=7866561
2018-02-20T18:47:45.888Z,1519152465.888 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180220T183859/Express0005.lzma
2018-02-20T18:47:46.685Z,1519152466.685 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Express0005.lzma.bak
2018-02-20T18:47:46.686Z,1519152466.685 [DataOverHttps](INFO): SBD MOMSN=7866563
2018-02-20T18:47:47.133Z,1519152467.133 [Default:CheckIn:Read_Iridium] Stopped
2018-02-20T18:47:47.133Z,1519152467.133 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-20T18:47:47.133Z,1519152467.133 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-20T18:48:12.715Z,1519152492.715 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:48:12.715Z,1519152492.715 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-02-20T18:48:12.715Z,1519152492.715 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-20T18:48:12.717Z,1519152492.717 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:48:12.717Z,1519152492.717 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-02-20T18:48:12.717Z,1519152492.717 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T18:48:12.754Z,1519152492.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-20T18:48:12.754Z,1519152492.754 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T18:48:13.126Z,1519152493.126 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-20T18:48:13.126Z,1519152493.126 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-02-20T18:48:13.127Z,1519152493.127 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T18:48:13.127Z,1519152493.127 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-02-20T18:48:13.516Z,1519152493.516 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-20T18:48:13.521Z,1519152493.521 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:48:13.521Z,1519152493.521 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:48:13.522Z,1519152493.522 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T18:48:13.522Z,1519152493.522 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:48:13.523Z,1519152493.523 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:49:09.916Z,1519152549.916 [CBIT](DEBUG): EFC running - data check-sum false
2018-02-20T18:49:53.014Z,1519152593.014 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-02-20T18:49:53.014Z,1519152593.014 [NAL9602] Data Fault, FailCount= 1
2018-02-20T18:49:53.014Z,1519152593.014 [NAL9602](ERROR): Data Fault
2018-02-20T18:49:53.099Z,1519152593.099 [CBIT](ERROR): Data Fault in component: NAL9602
2018-02-20T18:49:53.414Z,1519152593.414 [NAL9602](INFO): Powering down
2018-02-20T18:49:54.276Z,1519152594.276 [CBIT](INFO): Clearing failed state for component NAL9602
2018-02-20T18:49:54.276Z,1519152594.276 [NAL9602] No Fault, FailCount= 1
2018-02-20T18:50:23.418Z,1519152623.418 [NAL9602](INFO): Powering up NAL9602
2018-02-20T18:50:34.614Z,1519152634.614 [NAL9602](INFO): NAL9602 initialized
2018-02-20T18:51:13.854Z,1519152673.854 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:51:13.854Z,1519152673.854 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-02-20T18:51:13.854Z,1519152673.854 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-20T18:51:13.856Z,1519152673.856 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:51:13.856Z,1519152673.856 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-02-20T18:51:13.856Z,1519152673.856 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T18:51:13.868Z,1519152673.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-20T18:51:13.868Z,1519152673.868 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T18:51:14.294Z,1519152674.294 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-20T18:51:14.294Z,1519152674.294 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-02-20T18:51:14.294Z,1519152674.294 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T18:51:14.294Z,1519152674.294 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-02-20T18:51:14.658Z,1519152674.658 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-20T18:51:14.659Z,1519152674.659 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:51:14.659Z,1519152674.659 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:51:14.659Z,1519152674.659 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T18:51:14.660Z,1519152674.660 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:51:14.660Z,1519152674.660 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:51:53.017Z,1519152713.017 [DataOverHttps](IMPORTANT): SBD MTMSN=20180220T185152
2018-02-20T18:51:57.384Z,1519152717.384 [DataOverHttps](INFO): Received command:ibit
2018-02-20T18:51:57.404Z,1519152717.404 [CommandLine](IMPORTANT): got command ibit
2018-02-20T18:51:57.497Z,1519152717.497 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-02-20T18:51:57.497Z,1519152717.497 [IBIT](IMPORTANT): Beginning control surface checks.
2018-02-20T18:51:57.500Z,1519152717.500 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T18:52:08.260Z,1519152728.260 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121308
CHAN A1 (24V): -0.016804
CHAN A2 (12V): -0.002476
CHAN A3 (5V): -0.001450
CHAN B0 (3.3V): 0.000391
CHAN B1 (3.15aV): -0.000182
CHAN B2 (3.15bV): -0.000240
CHAN B3 (GND): 0.000524
OPEN: -0.000589
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T18:52:27.387Z,1519152747.387 [NAL9602](INFO): GPS fix at 20180220T185226: (36.802345, -121.787318)
2018-02-20T18:52:30.590Z,1519152750.590 [NAL9602](INFO): GPS fix at 20180220T185229: (36.802344, -121.787323)
2018-02-20T18:52:33.395Z,1519152753.395 [NAL9602](INFO): GPS fix at 20180220T185232: (36.802336, -121.787331)
2018-02-20T18:52:36.586Z,1519152756.586 [NAL9602](INFO): GPS fix at 20180220T185235: (36.802338, -121.787335)
2018-02-20T18:52:39.385Z,1519152759.385 [NAL9602](INFO): GPS fix at 20180220T185238: (36.802337, -121.787340)
2018-02-20T18:52:42.586Z,1519152762.586 [NAL9602](INFO): GPS fix at 20180220T185241: (36.802327, -121.787343)
2018-02-20T18:52:42.800Z,1519152762.800 [CommandLine](INFO): End of History
2018-02-20T18:52:42.804Z,1519152762.804 [CommandLine](INFO): End of History
2018-02-20T18:52:42.907Z,1519152762.907 [CommandLine](INFO): End of History
2018-02-20T18:52:42.912Z,1519152762.912 [CommandLine](INFO): End of History
2018-02-20T18:52:42.915Z,1519152762.915 [CommandLine](INFO): End of History
2018-02-20T18:52:43.463Z,1519152763.463 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802326 Longitude: -121.787346
2018-02-20T18:52:43.853Z,1519152763.853 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2018-02-20T18:52:43.853Z,1519152763.853 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2018-02-20T18:52:43.853Z,1519152763.853 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2018-02-20T18:52:44.244Z,1519152764.244 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-02-20T18:52:44.245Z,1519152764.245 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-02-20T18:52:44.245Z,1519152764.245 [IBIT](IMPORTANT): Pressure:9.472821 PSI
2018-02-20T18:52:44.246Z,1519152764.246 [IBIT](IMPORTANT): Humidity:14.552557 %
2018-02-20T18:52:44.662Z,1519152764.662 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-02-20T18:52:44.662Z,1519152764.662 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2018-02-20T18:52:44.662Z,1519152764.662 [IBIT](IMPORTANT): massDefault: -0.075000 cm
2018-02-20T18:52:44.662Z,1519152764.662 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2018-02-20T18:52:44.663Z,1519152764.663 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-02-20T18:52:44.663Z,1519152764.663 [IBIT](IMPORTANT): IBIT PASSED
2018-02-20T18:52:47.831Z,1519152767.831 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-02-20T18:52:47.831Z,1519152767.831 [Default:CheckIn:C.Wait] Stopped
2018-02-20T18:52:47.831Z,1519152767.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-20T18:52:47.831Z,1519152767.831 [Default:CheckIn:D] Running Loop=1
2018-02-20T18:52:48.237Z,1519152768.237 [Default:CheckIn:D] Stopped
2018-02-20T18:52:48.237Z,1519152768.237 [Default:CheckIn:E] Running Loop=1
2018-02-20T18:52:48.645Z,1519152768.645 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.512327 min 
2018-02-20T18:52:48.647Z,1519152768.647 [Default:CheckIn:E] Stopped
2018-02-20T18:52:48.647Z,1519152768.647 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-02-20T18:52:48.647Z,1519152768.647 [Default:CheckIn] Stopped
2018-02-20T18:52:48.647Z,1519152768.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-20T18:52:48.647Z,1519152768.647 [Default:CheckIn](INFO): Running loop #2
2018-02-20T18:52:48.648Z,1519152768.648 [Default:CheckIn] Running Loop=2
2018-02-20T18:52:48.648Z,1519152768.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-20T18:52:48.648Z,1519152768.648 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-20T18:52:50.589Z,1519152770.589 [NAL9602](INFO): GPS fix at 20180220T185249: (36.802323, -121.787246)
2018-02-20T18:52:50.650Z,1519152770.650 [Default:CheckIn:Read_GPS] Stopped
2018-02-20T18:52:50.650Z,1519152770.650 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-20T18:52:55.027Z,1519152775.027 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20180220T183859/Courier0008.lzma
2018-02-20T18:52:55.793Z,1519152775.793 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Courier0008.lzma.bak
2018-02-20T18:52:55.794Z,1519152775.794 [DataOverHttps](INFO): SBD MOMSN=7866569
2018-02-20T18:52:55.831Z,1519152775.831 [CommandLine](IMPORTANT): got command ibit
2018-02-20T18:52:55.917Z,1519152775.917 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-02-20T18:52:55.918Z,1519152775.918 [IBIT](IMPORTANT): Beginning control surface checks.
2018-02-20T18:52:55.935Z,1519152775.935 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T18:53:00.766Z,1519152780.766 [NAL9602](INFO): GPS fix at 20180220T185259: (36.802339, -121.787152)
2018-02-20T18:53:05.809Z,1519152785.809 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180220T183859/Courier0012.lzma
2018-02-20T18:53:06.210Z,1519152786.210 [NAL9602](INFO): GPS fix at 20180220T185304: (36.802340, -121.787151)
2018-02-20T18:53:06.601Z,1519152786.601 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Courier0012.lzma.bak
2018-02-20T18:53:06.602Z,1519152786.602 [DataOverHttps](INFO): SBD MOMSN=7866572
2018-02-20T18:53:11.094Z,1519152791.094 [NAL9602](INFO): GPS fix at 20180220T185309: (36.802343, -121.787150)
2018-02-20T18:53:15.624Z,1519152795.624 [DataOverHttps](INFO): Sending 916 bytes from file Logs/20180220T183859/Express0009.lzma
2018-02-20T18:53:16.035Z,1519152796.035 [NAL9602](INFO): GPS fix at 20180220T185314: (36.802343, -121.787148)
2018-02-20T18:53:16.421Z,1519152796.421 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Express0009.lzma.bak
2018-02-20T18:53:16.422Z,1519152796.422 [DataOverHttps](INFO): SBD MOMSN=7866574
2018-02-20T18:53:17.413Z,1519152797.413 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121447
CHAN A1 (24V): -0.016880
CHAN A2 (12V): -0.002091
CHAN A3 (5V): -0.001481
CHAN B0 (3.3V): -0.000116
CHAN B1 (3.15aV): -0.000328
CHAN B2 (3.15bV): 0.000001
CHAN B3 (GND): -0.000198
OPEN: -0.000276
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T18:53:20.930Z,1519152800.930 [NAL9602](INFO): GPS fix at 20180220T185319: (36.802345, -121.787147)
2018-02-20T18:53:25.336Z,1519152805.336 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180220T183859/Express0013.lzma
2018-02-20T18:53:25.741Z,1519152805.741 [NAL9602](INFO): GPS fix at 20180220T185324: (36.802347, -121.787149)
2018-02-20T18:53:26.133Z,1519152806.133 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Express0013.lzma.bak
2018-02-20T18:53:26.133Z,1519152806.133 [DataOverHttps](INFO): SBD MOMSN=7866598
2018-02-20T18:53:30.970Z,1519152810.970 [NAL9602](INFO): GPS fix at 20180220T185329: (36.802347, -121.787154)
2018-02-20T18:53:35.986Z,1519152815.986 [NAL9602](INFO): GPS fix at 20180220T185334: (36.802344, -121.787160)
2018-02-20T18:53:40.966Z,1519152820.966 [NAL9602](INFO): GPS fix at 20180220T185339: (36.802344, -121.787163)
2018-02-20T18:53:41.835Z,1519152821.835 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802345 Longitude: -121.787163
2018-02-20T18:53:45.115Z,1519152825.115 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2018-02-20T18:53:45.115Z,1519152825.115 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2018-02-20T18:53:45.116Z,1519152825.116 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2018-02-20T18:53:45.275Z,1519152825.275 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-02-20T18:53:45.275Z,1519152825.275 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-02-20T18:53:45.275Z,1519152825.275 [IBIT](IMPORTANT): Pressure:9.454391 PSI
2018-02-20T18:53:45.276Z,1519152825.276 [IBIT](IMPORTANT): Humidity:14.601392 %
2018-02-20T18:53:45.597Z,1519152825.597 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-02-20T18:53:45.597Z,1519152825.597 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2018-02-20T18:53:45.598Z,1519152825.598 [IBIT](IMPORTANT): massDefault: -0.075000 cm
2018-02-20T18:53:45.598Z,1519152825.598 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2018-02-20T18:53:45.598Z,1519152825.598 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-02-20T18:53:45.598Z,1519152825.598 [IBIT](IMPORTANT): IBIT PASSED
2018-02-20T18:53:45.977Z,1519152825.977 [Default:CheckIn:Read_Iridium] Stopped
2018-02-20T18:53:45.977Z,1519152825.977 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-20T18:53:45.977Z,1519152825.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-20T18:54:14.841Z,1519152854.841 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:54:14.841Z,1519152854.841 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-02-20T18:54:14.841Z,1519152854.841 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T18:54:14.853Z,1519152854.853 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T18:54:15.202Z,1519152855.202 [NAL9602](INFO): Powering down
2018-02-20T18:54:15.301Z,1519152855.301 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T18:54:15.301Z,1519152855.301 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-02-20T18:54:15.658Z,1519152855.658 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T18:54:15.658Z,1519152855.658 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:54:15.659Z,1519152855.659 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:56:09.784Z,1519152969.784 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T18:56:09.861Z,1519152969.861 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T18:56:20.623Z,1519152980.623 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121406
CHAN A1 (24V): -0.016651
CHAN A2 (12V): -0.002120
CHAN A3 (5V): -0.000941
CHAN B0 (3.3V): 0.000064
CHAN B1 (3.15aV): 0.000223
CHAN B2 (3.15bV): 0.000133
CHAN B3 (GND): 0.000741
OPEN: -0.000320
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T18:57:15.781Z,1519153035.781 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T18:57:15.781Z,1519153035.781 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-02-20T18:57:15.781Z,1519153035.781 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T18:57:15.794Z,1519153035.794 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T18:57:16.196Z,1519153036.196 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T18:57:16.196Z,1519153036.196 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-02-20T18:57:16.574Z,1519153036.574 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T18:57:16.574Z,1519153036.574 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T18:57:16.575Z,1519153036.575 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T18:58:43.224Z,1519153123.224 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T18:58:43.517Z,1519153123.517 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T18:58:46.676Z,1519153126.676 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-02-20T18:58:46.676Z,1519153126.676 [Default:CheckIn:C.Wait] Stopped
2018-02-20T18:58:46.676Z,1519153126.676 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-20T18:58:46.676Z,1519153126.676 [Default:CheckIn:D] Running Loop=1
2018-02-20T18:58:47.083Z,1519153127.083 [Default:CheckIn:D] Stopped
2018-02-20T18:58:47.083Z,1519153127.083 [Default:CheckIn:E] Running Loop=1
2018-02-20T18:58:47.487Z,1519153127.487 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.493126 min 
2018-02-20T18:58:47.487Z,1519153127.487 [Default:CheckIn:E] Stopped
2018-02-20T18:58:47.488Z,1519153127.488 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-02-20T18:58:47.488Z,1519153127.488 [Default:CheckIn] Stopped
2018-02-20T18:58:47.488Z,1519153127.488 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-20T18:58:47.488Z,1519153127.488 [Default:CheckIn](INFO): Running loop #3
2018-02-20T18:58:47.488Z,1519153127.488 [Default:CheckIn] Running Loop=3
2018-02-20T18:58:47.488Z,1519153127.488 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-20T18:58:47.488Z,1519153127.488 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-20T18:58:51.127Z,1519153131.127 [NAL9602](INFO): Powering up
2018-02-20T18:58:56.556Z,1519153136.556 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.120911
CHAN A1 (24V): -0.016157
CHAN A2 (12V): -0.001574
CHAN A3 (5V): -0.001571
CHAN B0 (3.3V): 0.000481
CHAN B1 (3.15aV): -0.000993
CHAN B2 (3.15bV): -0.000323
CHAN B3 (GND): 0.000221
OPEN: -0.000165
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T18:59:02.103Z,1519153142.103 [NAL9602](INFO): NAL9602 initialized
2018-02-20T18:59:10.201Z,1519153150.201 [CBIT](DEBUG): EFC running - data check-sum false
2018-02-20T18:59:18.903Z,1519153158.903 [NAL9602](INFO): SBD MO Status=0, MOMSN=4486, MT Status=0, MTMSN=0
2018-02-20T18:59:18.904Z,1519153158.904 [NAL9602](INFO): No messages in MT queue
2018-02-20T18:59:31.702Z,1519153171.702 [NAL9602](INFO): GPS fix at 20180220T185930: (36.802352, -121.787131)
2018-02-20T18:59:31.776Z,1519153171.776 [Default:CheckIn:Read_GPS] Stopped
2018-02-20T18:59:31.776Z,1519153171.776 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-20T18:59:36.116Z,1519153176.116 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180220T183859/Courier0016.lzma
2018-02-20T18:59:36.918Z,1519153176.918 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Courier0016.lzma.bak
2018-02-20T18:59:36.918Z,1519153176.918 [DataOverHttps](INFO): SBD MOMSN=7866600
2018-02-20T18:59:46.097Z,1519153186.097 [DataOverHttps](INFO): Sending 795 bytes from file Logs/20180220T183859/Express0017.lzma
2018-02-20T18:59:46.894Z,1519153186.894 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Express0017.lzma.bak
2018-02-20T18:59:46.894Z,1519153186.894 [DataOverHttps](INFO): SBD MOMSN=7866603
2018-02-20T18:59:47.358Z,1519153187.358 [Default:CheckIn:Read_Iridium] Stopped
2018-02-20T18:59:47.358Z,1519153187.358 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-20T18:59:47.358Z,1519153187.358 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-20T19:00:06.254Z,1519153206.254 [NAL9602](INFO): Powering down
2018-02-20T19:00:16.701Z,1519153216.701 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T19:00:16.701Z,1519153216.701 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-02-20T19:00:16.701Z,1519153216.701 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T19:00:16.714Z,1519153216.714 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T19:00:17.116Z,1519153217.116 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T19:00:17.116Z,1519153217.116 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-02-20T19:00:17.503Z,1519153217.503 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T19:00:17.504Z,1519153217.504 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T19:00:17.504Z,1519153217.504 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T19:02:38.269Z,1519153358.269 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:02:38.680Z,1519153358.680 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:02:51.991Z,1519153371.991 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121284
CHAN A1 (24V): -0.016434
CHAN A2 (12V): -0.002154
CHAN A3 (5V): -0.001384
CHAN B0 (3.3V): -0.000440
CHAN B1 (3.15aV): -0.000594
CHAN B2 (3.15bV): 0.000060
CHAN B3 (GND): 0.000449
OPEN: -0.000303
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:03:17.580Z,1519153397.580 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T19:03:17.581Z,1519153397.581 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-02-20T19:03:17.581Z,1519153397.581 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T19:03:17.594Z,1519153397.594 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T19:03:17.978Z,1519153397.978 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T19:03:17.978Z,1519153397.978 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-02-20T19:03:18.360Z,1519153398.360 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T19:03:18.377Z,1519153398.377 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T19:03:18.377Z,1519153398.377 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T19:04:47.967Z,1519153487.967 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-02-20T19:04:47.968Z,1519153487.968 [Default:CheckIn:C.Wait] Stopped
2018-02-20T19:04:47.968Z,1519153487.968 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-20T19:04:47.968Z,1519153487.968 [Default:CheckIn:D] Running Loop=1
2018-02-20T19:04:48.372Z,1519153488.372 [Default:CheckIn:D] Stopped
2018-02-20T19:04:48.372Z,1519153488.372 [Default:CheckIn:E] Running Loop=1
2018-02-20T19:04:48.780Z,1519153488.780 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.514604 min 
2018-02-20T19:04:48.780Z,1519153488.780 [Default:CheckIn:E] Stopped
2018-02-20T19:04:48.781Z,1519153488.781 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-02-20T19:04:48.781Z,1519153488.781 [Default:CheckIn] Stopped
2018-02-20T19:04:48.781Z,1519153488.781 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-20T19:04:48.781Z,1519153488.781 [Default:CheckIn](INFO): Running loop #4
2018-02-20T19:04:48.781Z,1519153488.781 [Default:CheckIn] Running Loop=4
2018-02-20T19:04:48.781Z,1519153488.781 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-20T19:04:48.781Z,1519153488.781 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-20T19:04:51.946Z,1519153491.946 [NAL9602](INFO): Powering up
2018-02-20T19:05:03.099Z,1519153503.099 [NAL9602](INFO): NAL9602 initialized
2018-02-20T19:05:20.303Z,1519153520.303 [NAL9602](INFO): SBD MO Status=0, MOMSN=4487, MT Status=0, MTMSN=0
2018-02-20T19:05:20.303Z,1519153520.303 [NAL9602](INFO): No messages in MT queue
2018-02-20T19:05:33.497Z,1519153533.497 [NAL9602](INFO): GPS fix at 20180220T190532: (36.802346, -121.787155)
2018-02-20T19:05:33.575Z,1519153533.575 [Default:CheckIn:Read_GPS] Stopped
2018-02-20T19:05:33.575Z,1519153533.575 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-20T19:05:37.925Z,1519153537.925 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180220T183859/Courier0020.lzma
2018-02-20T19:05:38.717Z,1519153538.717 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Courier0020.lzma.bak
2018-02-20T19:05:38.717Z,1519153538.717 [DataOverHttps](INFO): SBD MOMSN=7866632
2018-02-20T19:05:47.531Z,1519153547.531 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20180220T183859/Express0021.lzma
2018-02-20T19:05:48.297Z,1519153548.297 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Express0021.lzma.bak
2018-02-20T19:05:48.298Z,1519153548.298 [DataOverHttps](INFO): SBD MOMSN=7866635
2018-02-20T19:05:48.748Z,1519153548.748 [Default:CheckIn:Read_Iridium] Stopped
2018-02-20T19:05:48.748Z,1519153548.748 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-20T19:05:48.748Z,1519153548.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-20T19:06:08.062Z,1519153568.062 [NAL9602](INFO): Powering down
2018-02-20T19:06:18.510Z,1519153578.510 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T19:06:18.510Z,1519153578.510 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-02-20T19:06:18.510Z,1519153578.510 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T19:06:18.523Z,1519153578.523 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T19:06:18.931Z,1519153578.931 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T19:06:18.931Z,1519153578.931 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-02-20T19:06:19.315Z,1519153579.315 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T19:06:19.316Z,1519153579.316 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T19:06:19.316Z,1519153579.316 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T19:09:10.349Z,1519153750.349 [CBIT](DEBUG): EFC running - data check-sum false
2018-02-20T19:09:19.533Z,1519153759.533 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T19:09:19.533Z,1519153759.533 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-02-20T19:09:19.534Z,1519153759.534 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T19:09:19.546Z,1519153759.546 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T19:09:19.944Z,1519153759.944 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T19:09:19.944Z,1519153759.944 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-02-20T19:09:20.336Z,1519153760.336 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T19:09:20.337Z,1519153760.337 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T19:09:20.337Z,1519153760.337 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T19:10:22.266Z,1519153822.266 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:10:22.460Z,1519153822.460 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:10:33.129Z,1519153833.129 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121420
CHAN A1 (24V): -0.016518
CHAN A2 (12V): -0.002167
CHAN A3 (5V): -0.001312
CHAN B0 (3.3V): -0.000560
CHAN B1 (3.15aV): 0.000236
CHAN B2 (3.15bV): -0.000860
CHAN B3 (GND): -0.000282
OPEN: -0.000489
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:10:49.505Z,1519153849.505 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-02-20T19:10:49.506Z,1519153849.506 [Default:CheckIn:C.Wait] Stopped
2018-02-20T19:10:49.506Z,1519153849.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-20T19:10:49.506Z,1519153849.506 [Default:CheckIn:D] Running Loop=1
2018-02-20T19:10:49.911Z,1519153849.911 [Default:CheckIn:D] Stopped
2018-02-20T19:10:49.912Z,1519153849.912 [Default:CheckIn:E] Running Loop=1
2018-02-20T19:10:50.315Z,1519153850.315 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.540265 min 
2018-02-20T19:10:50.315Z,1519153850.315 [Default:CheckIn:E] Stopped
2018-02-20T19:10:50.315Z,1519153850.315 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-02-20T19:10:50.316Z,1519153850.316 [Default:CheckIn] Stopped
2018-02-20T19:10:50.316Z,1519153850.316 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-20T19:10:50.316Z,1519153850.316 [Default:CheckIn](INFO): Running loop #5
2018-02-20T19:10:50.316Z,1519153850.316 [Default:CheckIn] Running Loop=5
2018-02-20T19:10:50.316Z,1519153850.316 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-20T19:10:50.316Z,1519153850.316 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-20T19:10:53.504Z,1519153853.504 [NAL9602](INFO): Powering up
2018-02-20T19:11:04.634Z,1519153864.634 [NAL9602](INFO): NAL9602 initialized
2018-02-20T19:11:21.835Z,1519153881.835 [NAL9602](INFO): SBD MO Status=0, MOMSN=4488, MT Status=0, MTMSN=0
2018-02-20T19:11:21.835Z,1519153881.835 [NAL9602](INFO): No messages in MT queue
2018-02-20T19:11:31.437Z,1519153891.437 [NAL9602](INFO): GPS fix at 20180220T191130: (36.802353, -121.787160)
2018-02-20T19:11:31.490Z,1519153891.490 [Default:CheckIn:Read_GPS] Stopped
2018-02-20T19:11:31.490Z,1519153891.490 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-20T19:11:36.255Z,1519153896.255 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180220T183859/Courier0024.lzma
2018-02-20T19:11:37.053Z,1519153897.053 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Courier0024.lzma.bak
2018-02-20T19:11:37.054Z,1519153897.054 [DataOverHttps](INFO): SBD MOMSN=7866642
2018-02-20T19:11:46.244Z,1519153906.244 [DataOverHttps](INFO): Sending 326 bytes from file Logs/20180220T183859/Express0025.lzma
2018-02-20T19:11:47.041Z,1519153907.041 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Express0025.lzma.bak
2018-02-20T19:11:47.042Z,1519153907.042 [DataOverHttps](INFO): SBD MOMSN=7866645
2018-02-20T19:11:47.495Z,1519153907.495 [Default:CheckIn:Read_Iridium] Stopped
2018-02-20T19:11:47.495Z,1519153907.495 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-20T19:11:47.495Z,1519153907.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-20T19:12:05.998Z,1519153925.998 [NAL9602](INFO): Powering down
2018-02-20T19:12:20.458Z,1519153940.458 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T19:12:20.458Z,1519153940.458 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-02-20T19:12:20.458Z,1519153940.458 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T19:12:20.470Z,1519153940.470 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T19:12:20.878Z,1519153940.878 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T19:12:20.878Z,1519153940.878 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-02-20T19:12:21.298Z,1519153941.298 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T19:12:21.298Z,1519153941.298 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T19:12:21.299Z,1519153941.299 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T19:12:45.798Z,1519153965.798 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:12:46.066Z,1519153966.066 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:12:59.339Z,1519153979.339 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121503
CHAN A1 (24V): -0.016605
CHAN A2 (12V): -0.002142
CHAN A3 (5V): -0.000991
CHAN B0 (3.3V): -0.000387
CHAN B1 (3.15aV): -0.000108
CHAN B2 (3.15bV): 0.000133
CHAN B3 (GND): 0.000233
OPEN: -0.000492
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:14:31.872Z,1519154071.872 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:14:32.120Z,1519154072.120 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:14:42.923Z,1519154082.923 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121508
CHAN A1 (24V): -0.016134
CHAN A2 (12V): -0.002565
CHAN A3 (5V): -0.001687
CHAN B0 (3.3V): 0.000240
CHAN B1 (3.15aV): -0.000134
CHAN B2 (3.15bV): -0.000190
CHAN B3 (GND): 0.000197
OPEN: -0.000633
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:15:04.840Z,1519154104.840 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:15:05.030Z,1519154105.030 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:15:15.834Z,1519154115.834 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121050
CHAN A1 (24V): -0.016645
CHAN A2 (12V): -0.002207
CHAN A3 (5V): -0.001480
CHAN B0 (3.3V): 0.000335
CHAN B1 (3.15aV): -0.000326
CHAN B2 (3.15bV): -0.000195
CHAN B3 (GND): 0.000044
OPEN: -0.000248
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:15:21.422Z,1519154121.422 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T19:15:21.422Z,1519154121.422 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-02-20T19:15:21.422Z,1519154121.422 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T19:15:21.448Z,1519154121.448 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T19:15:21.831Z,1519154121.831 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T19:15:21.831Z,1519154121.831 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-02-20T19:15:22.232Z,1519154122.232 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T19:15:22.232Z,1519154122.232 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T19:15:22.232Z,1519154122.232 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T19:15:45.695Z,1519154145.695 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:15:45.846Z,1519154145.846 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:15:59.045Z,1519154159.045 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121591
CHAN A1 (24V): -0.015974
CHAN A2 (12V): -0.002627
CHAN A3 (5V): -0.001169
CHAN B0 (3.3V): 0.000135
CHAN B1 (3.15aV): -0.000463
CHAN B2 (3.15bV): -0.000457
CHAN B3 (GND): -0.000077
OPEN: -0.000264
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:16:48.227Z,1519154208.227 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-02-20T19:16:48.227Z,1519154208.227 [Default:CheckIn:C.Wait] Stopped
2018-02-20T19:16:48.227Z,1519154208.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-20T19:16:48.227Z,1519154208.227 [Default:CheckIn:D] Running Loop=1
2018-02-20T19:16:48.623Z,1519154208.623 [Default:CheckIn:D] Stopped
2018-02-20T19:16:48.623Z,1519154208.623 [Default:CheckIn:E] Running Loop=1
2018-02-20T19:16:49.023Z,1519154209.023 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.518799 min 
2018-02-20T19:16:49.023Z,1519154209.023 [Default:CheckIn:E] Stopped
2018-02-20T19:16:49.023Z,1519154209.023 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-02-20T19:16:49.023Z,1519154209.023 [Default:CheckIn] Stopped
2018-02-20T19:16:49.024Z,1519154209.024 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-20T19:16:49.024Z,1519154209.024 [Default:CheckIn](INFO): Running loop #6
2018-02-20T19:16:49.024Z,1519154209.024 [Default:CheckIn] Running Loop=6
2018-02-20T19:16:49.024Z,1519154209.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-02-20T19:16:49.024Z,1519154209.024 [Default:CheckIn:Read_GPS] Running Loop=1
2018-02-20T19:16:52.202Z,1519154212.202 [NAL9602](INFO): Powering up
2018-02-20T19:17:03.346Z,1519154223.346 [NAL9602](INFO): NAL9602 initialized
2018-02-20T19:17:08.754Z,1519154228.754 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:17:09.017Z,1519154229.017 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:17:18.147Z,1519154238.147 [NAL9602](INFO): SBD MO Status=1, MOMSN=4490, MT Status=0, MTMSN=0
2018-02-20T19:17:18.147Z,1519154238.147 [NAL9602](INFO): No messages in MT queue
2018-02-20T19:17:19.346Z,1519154239.346 [NAL9602](INFO): GPS fix at 20180220T191718: (36.802302, -121.787218)
2018-02-20T19:17:19.420Z,1519154239.420 [Default:CheckIn:Read_GPS] Stopped
2018-02-20T19:17:19.420Z,1519154239.420 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-02-20T19:17:19.829Z,1519154239.829 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121441
CHAN A1 (24V): -0.017025
CHAN A2 (12V): -0.002077
CHAN A3 (5V): -0.002116
CHAN B0 (3.3V): -0.000234
CHAN B1 (3.15aV): -0.000142
CHAN B2 (3.15bV): -0.000357
CHAN B3 (GND): 0.000076
OPEN: -0.000533
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:17:23.813Z,1519154243.813 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180220T183859/Courier0028.lzma
2018-02-20T19:17:24.605Z,1519154244.605 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Courier0028.lzma.bak
2018-02-20T19:17:24.605Z,1519154244.605 [DataOverHttps](INFO): SBD MOMSN=7866652
2018-02-20T19:17:34.705Z,1519154254.705 [DataOverHttps](INFO): Sending 512 bytes from file Logs/20180220T183859/Express0029.lzma
2018-02-20T19:17:35.501Z,1519154255.501 [DataOverHttps](INFO): Moved sent file to Logs/20180220T183859/Express0029.lzma.bak
2018-02-20T19:17:35.502Z,1519154255.502 [DataOverHttps](INFO): SBD MOMSN=7866655
2018-02-20T19:17:35.967Z,1519154255.967 [Default:CheckIn:Read_Iridium] Stopped
2018-02-20T19:17:35.967Z,1519154255.967 [Default:CheckIn:C.Wait] Running Loop=1
2018-02-20T19:17:35.967Z,1519154255.967 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-02-20T19:17:38.068Z,1519154258.068 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:17:39.621Z,1519154259.621 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:17:50.110Z,1519154270.110 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.120947
CHAN A1 (24V): -0.016212
CHAN A2 (12V): -0.002227
CHAN A3 (5V): -0.001288
CHAN B0 (3.3V): 0.000005
CHAN B1 (3.15aV): -0.000552
CHAN B2 (3.15bV): -0.000370
CHAN B3 (GND): 0.000238
OPEN: -0.000535
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:17:54.030Z,1519154274.030 [NAL9602](INFO): Powering down
2018-02-20T19:18:19.339Z,1519154299.339 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:18:19.689Z,1519154299.689 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:18:22.515Z,1519154302.515 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-20T19:18:22.515Z,1519154302.515 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-02-20T19:18:22.515Z,1519154302.515 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-02-20T19:18:22.540Z,1519154302.540 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-02-20T19:18:22.969Z,1519154302.969 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-02-20T19:18:22.969Z,1519154302.969 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-02-20T19:18:23.274Z,1519154303.274 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-02-20T19:18:23.274Z,1519154303.274 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-02-20T19:18:23.275Z,1519154303.275 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-02-20T19:18:30.498Z,1519154310.498 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121585
CHAN A1 (24V): -0.016496
CHAN A2 (12V): -0.002563
CHAN A3 (5V): -0.001457
CHAN B0 (3.3V): -0.000133
CHAN B1 (3.15aV): -0.000122
CHAN B2 (3.15bV): -0.000451
CHAN B3 (GND): -0.000421
OPEN: -0.000265
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:18:44.612Z,1519154324.612 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:18:44.881Z,1519154324.881 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:18:55.694Z,1519154335.694 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121727
CHAN A1 (24V): -0.016231
CHAN A2 (12V): -0.001926
CHAN A3 (5V): -0.001962
CHAN B0 (3.3V): 0.000493
CHAN B1 (3.15aV): 0.000214
CHAN B2 (3.15bV): -0.000555
CHAN B3 (GND): 0.000426
OPEN: -0.000495
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:19:10.483Z,1519154350.483 [CBIT](DEBUG): EFC running - data check-sum false
2018-02-20T19:19:16.232Z,1519154356.232 [CommandLine](IMPORTANT): got command gfscan
2018-02-20T19:19:16.504Z,1519154356.504 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-20T19:19:27.276Z,1519154367.276 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.121933
CHAN A1 (24V): -0.016764
CHAN A2 (12V): -0.002162
CHAN A3 (5V): -0.002110
CHAN B0 (3.3V): -0.000102
CHAN B1 (3.15aV): 0.000411
CHAN B2 (3.15bV): -0.000060
CHAN B3 (GND): 0.000167
OPEN: -0.000245
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-20T19:20:14.270Z,1519154414.270 [CommandLine](IMPORTANT): got command restart system
2018-02-20T19:20:16.509Z,1519154416.509 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-02-20T19:20:16.510Z,1519154416.510 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-20T19:20:16.510Z,1519154416.510 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:16.653Z,1519154416.653 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-02-20T19:20:16.653Z,1519154416.653 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:16.654Z,1519154416.654 [CommandLine](INFO): Join timeout helper Thread ID is 1064
2018-02-20T19:20:16.667Z,1519154416.667 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-02-20T19:20:16.667Z,1519154416.667 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:16.667Z,1519154416.667 [NavChartDb](INFO): Join timeout helper Thread ID is 1065
2018-02-20T19:20:16.920Z,1519154416.920 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-20T19:20:16.920Z,1519154416.920 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:16.921Z,1519154416.921 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-02-20T19:20:16.921Z,1519154416.921 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:16.921Z,1519154416.921 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1066
2018-02-20T19:20:16.929Z,1519154416.929 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-20T19:20:16.929Z,1519154416.929 [WetLabsBB2FL](INFO): Powering down
2018-02-20T19:20:16.930Z,1519154416.930 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:16.941Z,1519154416.941 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-02-20T19:20:16.941Z,1519154416.941 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:16.941Z,1519154416.941 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1067
2018-02-20T19:20:17.105Z,1519154417.105 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-20T19:20:17.259Z,1519154417.259 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-20T19:20:17.259Z,1519154417.259 [CTD_Seabird](INFO): Powering down
2018-02-20T19:20:17.260Z,1519154417.260 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.269Z,1519154417.269 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-02-20T19:20:17.269Z,1519154417.269 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.270Z,1519154417.270 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1069
2018-02-20T19:20:17.429Z,1519154417.429 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-20T19:20:17.429Z,1519154417.429 [CTD_NeilBrown](INFO): Powering down
2018-02-20T19:20:17.430Z,1519154417.430 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.438Z,1519154417.438 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-02-20T19:20:17.438Z,1519154417.438 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.438Z,1519154417.438 [Radio_Surface](INFO): Join timeout helper Thread ID is 1070
2018-02-20T19:20:17.765Z,1519154417.765 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-20T19:20:17.765Z,1519154417.765 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.774Z,1519154417.774 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-02-20T19:20:17.774Z,1519154417.774 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.775Z,1519154417.775 [logger](INFO): Join timeout helper Thread ID is 1071
2018-02-20T19:20:17.775Z,1519154417.775 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-20T19:20:17.775Z,1519154417.775 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.776Z,1519154417.776 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-02-20T19:20:17.777Z,1519154417.777 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.777Z,1519154417.777 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-02-20T19:20:17.777Z,1519154417.777 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:17.777Z,1519154417.777 [controlThread](INFO): Join timeout helper Thread ID is 1072
2018-02-20T19:20:17.981Z,1519154417.981 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-20T19:20:17.981Z,1519154417.981 [controlThread](DEBUG): Uninitializing ControlThread
2018-02-20T19:20:17.982Z,1519154417.982 [NAL9602](INFO): Powering down
2018-02-20T19:20:17.983Z,1519154417.983 [PNI_TCM](INFO): Powering down
2018-02-20T19:20:18.069Z,1519154418.069 [Aanderaa_O2](INFO): Powering down
2018-02-20T19:20:18.071Z,1519154418.071 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-20T19:20:18.073Z,1519154418.073 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-02-20T19:20:18.074Z,1519154418.074 [MissionManager](INFO): Uninitializing Mission Default
2018-02-20T19:20:18.074Z,1519154418.074 [Default] Stopped
2018-02-20T19:20:18.074Z,1519154418.074 [Default](DEBUG): Aggregate::uninitialize Default
2018-02-20T19:20:18.074Z,1519154418.074 [Default:B.GoToSurface] Stopped
2018-02-20T19:20:18.074Z,1519154418.074 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-20T19:20:18.074Z,1519154418.074 [Default:CheckIn] Stopped
2018-02-20T19:20:18.074Z,1519154418.074 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-02-20T19:20:18.074Z,1519154418.074 [Default:CheckIn:C.Wait] Stopped
2018-02-20T19:20:18.074Z,1519154418.074 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-02-20T19:20:18.078Z,1519154418.078 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-02-20T19:20:18.078Z,1519154418.078 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-02-20T19:20:18.078Z,1519154418.078 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-02-20T19:20:18.079Z,1519154418.079 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-02-20T19:20:18.079Z,1519154418.079 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-20T19:20:18.079Z,1519154418.079 [BuoyancyServo](INFO): Powering down
2018-02-20T19:20:18.093Z,1519154418.093 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-02-20T19:20:18.093Z,1519154418.093 [ElevatorServo](INFO): Powering down
2018-02-20T19:20:18.094Z,1519154418.094 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-02-20T19:20:18.094Z,1519154418.094 [MassServo](INFO): Powering down
2018-02-20T19:20:18.095Z,1519154418.095 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-02-20T19:20:18.095Z,1519154418.095 [RudderServo](INFO): Powering down
2018-02-20T19:20:18.096Z,1519154418.096 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-02-20T19:20:18.096Z,1519154418.096 [ThrusterServo](INFO): Powering down
2018-02-20T19:20:18.097Z,1519154418.097 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-02-20T19:20:18.097Z,1519154418.097 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-02-20T19:20:18.098Z,1519154418.098 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-02-20T19:20:18.098Z,1519154418.098 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:18.214Z,1519154418.214 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:18.250Z,1519154418.250 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:18.254Z,1519154418.254 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:18.256Z,1519154418.256 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:18.284Z,1519154418.284 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-20T19:20:18.340Z,1519154418.340 [logger ThreadHandler](INFO): Thread cancelled.