2019-04-03T16:41:35.770Z,1554309695.770 [Supervisor](DEBUG): Initializing supervisor.
2019-04-03T16:41:35.773Z,1554309695.773 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-03T16:41:35.773Z,1554309695.773 [SyncHandler](INFO): Protected caller Thread ID is 1096
2019-04-03T16:41:35.774Z,1554309695.774 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-03T16:41:35.775Z,1554309695.775 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-03T16:41:35.775Z,1554309695.775 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1097
2019-04-03T16:41:35.779Z,1554309695.779 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-03T16:41:35.791Z,1554309695.791 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-03T16:41:35.793Z,1554309695.793 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-03T16:41:35.793Z,1554309695.793 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1098
2019-04-03T16:41:35.794Z,1554309695.794 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-03T16:41:35.795Z,1554309695.795 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-03T16:41:35.795Z,1554309695.795 [logger ThreadHandler](INFO): Protected caller Thread ID is 1099
2019-04-03T16:41:35.797Z,1554309695.797 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-03T16:41:35.798Z,1554309695.798 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-03T16:41:35.799Z,1554309695.799 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-03T16:41:36.224Z,1554309696.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-03T16:41:36.224Z,1554309696.224 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-03T16:41:36.324Z,1554309696.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-03T16:41:36.324Z,1554309696.324 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-03T16:41:36.651Z,1554309696.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-03T16:41:36.652Z,1554309696.652 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-03T16:41:36.795Z,1554309696.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-03T16:41:36.796Z,1554309696.796 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-03T16:41:36.990Z,1554309696.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-03T16:41:36.990Z,1554309696.990 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-03T16:41:37.530Z,1554309697.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-03T16:41:37.531Z,1554309697.531 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-03T16:41:37.835Z,1554309697.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-03T16:41:37.836Z,1554309697.836 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-03T16:41:37.983Z,1554309697.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-03T16:41:37.983Z,1554309697.983 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-03T16:41:38.178Z,1554309698.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-03T16:41:38.179Z,1554309698.179 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-03T16:41:38.276Z,1554309698.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-03T16:41:38.277Z,1554309698.277 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-03T16:41:38.613Z,1554309698.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-03T16:41:38.613Z,1554309698.613 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-03T16:41:38.695Z,1554309698.695 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-03T16:41:38.800Z,1554309698.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-03T16:41:38.800Z,1554309698.800 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-03T16:41:39.304Z,1554309699.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-03T16:41:39.304Z,1554309699.304 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-03T16:41:39.700Z,1554309699.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-03T16:41:39.703Z,1554309699.703 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-04-03T16:41:39.704Z,1554309699.704 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-04-03T16:41:39.922Z,1554309699.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-04-03T16:41:40.025Z,1554309700.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-04-03T16:41:40.125Z,1554309700.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-04-03T16:41:40.358Z,1554309700.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-03T16:41:40.358Z,1554309700.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-04-03T16:41:40.444Z,1554309700.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-04-03T16:41:40.540Z,1554309700.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-04-03T16:41:40.638Z,1554309700.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-04-03T16:41:40.722Z,1554309700.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-04-03T16:41:40.835Z,1554309700.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-04-03T16:41:41.018Z,1554309701.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-04-03T16:41:41.151Z,1554309701.151 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-04-03T16:41:41.151Z,1554309701.151 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-03T16:41:41.160Z,1554309701.160 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-03T16:41:41.519Z,1554309701.519 [AHRS_M2] Loaded
2019-04-03T16:41:41.520Z,1554309701.520 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-04-03T16:41:41.603Z,1554309701.603 [DataOverHttps] Loaded
2019-04-03T16:41:41.603Z,1554309701.603 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-03T16:41:41.604Z,1554309701.604 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4075B4E0
2019-04-03T16:41:41.604Z,1554309701.604 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1179
2019-04-03T16:41:41.618Z,1554309701.618 [Depth_Keller] Loaded
2019-04-03T16:41:41.618Z,1554309701.618 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-03T16:41:41.623Z,1554309701.623 [DropWeight] Loaded
2019-04-03T16:41:41.623Z,1554309701.623 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-04-03T16:41:41.676Z,1554309701.676 [DUSBL_Hydroid] Loaded
2019-04-03T16:41:41.677Z,1554309701.677 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-04-03T16:41:41.717Z,1554309701.717 [Micromodem] Loaded
2019-04-03T16:41:41.717Z,1554309701.717 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-04-03T16:41:41.816Z,1554309701.816 [NAL9602] Loaded
2019-04-03T16:41:41.816Z,1554309701.816 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-03T16:41:41.832Z,1554309701.832 [Onboard] Loaded
2019-04-03T16:41:41.832Z,1554309701.832 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-03T16:41:41.838Z,1554309701.838 [PowerOnly] Loaded
2019-04-03T16:41:41.838Z,1554309701.838 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-04-03T16:41:41.845Z,1554309701.845 [Radio_Surface] Loaded
2019-04-03T16:41:41.845Z,1554309701.845 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-03T16:41:41.846Z,1554309701.846 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4078B4E0
2019-04-03T16:41:41.847Z,1554309701.847 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1180
2019-04-03T16:41:41.892Z,1554309701.892 [RDI_Pathfinder] Loaded
2019-04-03T16:41:41.892Z,1554309701.892 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-04-03T16:41:43.375Z,1554309703.375 [BPC1] Loaded
2019-04-03T16:41:43.375Z,1554309703.375 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-03T16:41:43.375Z,1554309703.375 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-03T16:41:43.376Z,1554309703.376 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-03T16:41:43.415Z,1554309703.415 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-03T16:41:43.416Z,1554309703.416 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-03T16:41:43.683Z,1554309703.683 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-03T16:41:43.683Z,1554309703.683 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-03T16:41:43.768Z,1554309703.768 [NavChart] Loaded
2019-04-03T16:41:43.768Z,1554309703.768 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-03T16:41:43.772Z,1554309703.772 [UniversalFixResidualReporter] Loaded
2019-04-03T16:41:43.773Z,1554309703.773 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-03T16:41:43.773Z,1554309703.773 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-03T16:41:43.774Z,1554309703.774 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-03T16:41:44.190Z,1554309704.190 [BuoyancyServo] Loaded
2019-04-03T16:41:44.191Z,1554309704.191 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-03T16:41:44.206Z,1554309704.206 [ElevatorServo] Loaded
2019-04-03T16:41:44.207Z,1554309704.207 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-03T16:41:44.221Z,1554309704.221 [MassServo] Loaded
2019-04-03T16:41:44.222Z,1554309704.222 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-03T16:41:44.237Z,1554309704.237 [RudderServo] Loaded
2019-04-03T16:41:44.237Z,1554309704.237 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-03T16:41:44.252Z,1554309704.252 [ThrusterServo] Loaded
2019-04-03T16:41:44.253Z,1554309704.253 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-03T16:41:44.253Z,1554309704.253 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-03T16:41:44.254Z,1554309704.254 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-03T16:41:44.509Z,1554309704.509 [CTD_NeilBrown] Loaded
2019-04-03T16:41:44.510Z,1554309704.510 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-04-03T16:41:44.511Z,1554309704.511 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2019-04-03T16:41:44.511Z,1554309704.511 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1181
2019-04-03T16:41:44.556Z,1554309704.556 [WetLabsSeaOWL_UV_A] Loaded
2019-04-03T16:41:44.556Z,1554309704.556 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-04-03T16:41:44.558Z,1554309704.558 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2019-04-03T16:41:44.558Z,1554309704.558 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1182
2019-04-03T16:41:44.559Z,1554309704.559 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-03T16:41:44.559Z,1554309704.559 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-03T16:41:44.832Z,1554309704.832 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-03T16:41:44.833Z,1554309704.833 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-03T16:41:44.874Z,1554309704.874 [DepthRateCalculator] Loaded
2019-04-03T16:41:44.875Z,1554309704.875 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-03T16:41:44.880Z,1554309704.880 [PitchRateCalculator] Loaded
2019-04-03T16:41:44.881Z,1554309704.881 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-03T16:41:44.893Z,1554309704.893 [SpeedCalculator] Loaded
2019-04-03T16:41:44.893Z,1554309704.893 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-03T16:41:44.915Z,1554309704.915 [TempGradientCalculator] Loaded
2019-04-03T16:41:44.915Z,1554309704.915 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-03T16:41:44.920Z,1554309704.920 [YawRateCalculator] Loaded
2019-04-03T16:41:44.921Z,1554309704.921 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-03T16:41:44.961Z,1554309704.961 [ElevatorOffsetCalculator] Loaded
2019-04-03T16:41:44.962Z,1554309704.962 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-03T16:41:44.962Z,1554309704.962 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-03T16:41:44.963Z,1554309704.963 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-03T16:41:45.097Z,1554309705.097 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-03T16:41:45.119Z,1554309705.119 [SBIT] Loaded
2019-04-03T16:41:45.120Z,1554309705.120 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-03T16:41:45.120Z,1554309705.120 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-03T16:41:45.132Z,1554309705.132 [IBIT] Loaded
2019-04-03T16:41:45.133Z,1554309705.133 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-03T16:41:45.136Z,1554309705.136 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-03T16:41:45.276Z,1554309705.276 [CBIT] Loaded
2019-04-03T16:41:45.276Z,1554309705.276 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-03T16:41:45.277Z,1554309705.277 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-03T16:41:45.277Z,1554309705.277 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-03T16:41:45.351Z,1554309705.351 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-03T16:41:45.352Z,1554309705.352 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-03T16:41:45.451Z,1554309705.451 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-03T16:41:45.451Z,1554309705.451 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-03T16:41:45.518Z,1554309705.518 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-03T16:41:45.604Z,1554309705.604 [VerticalControl] Loaded
2019-04-03T16:41:45.604Z,1554309705.604 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-03T16:41:45.605Z,1554309705.605 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-03T16:41:45.663Z,1554309705.663 [HorizontalControl] Loaded
2019-04-03T16:41:45.663Z,1554309705.663 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-03T16:41:45.664Z,1554309705.664 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-03T16:41:45.666Z,1554309705.666 [SpeedControl] Loaded
2019-04-03T16:41:45.666Z,1554309705.666 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-03T16:41:45.667Z,1554309705.667 [LoopControl](DEBUG): Construct LoopControl.
2019-04-03T16:41:45.667Z,1554309705.667 [LoopControl] Loaded
2019-04-03T16:41:45.667Z,1554309705.667 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-03T16:41:45.668Z,1554309705.668 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-03T16:41:45.668Z,1554309705.668 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-03T16:41:45.697Z,1554309705.697 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-03T16:41:45.701Z,1554309705.701 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-03T16:41:45.702Z,1554309705.702 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-03T16:41:45.709Z,1554309705.709 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-03T16:41:45.710Z,1554309705.710 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD04E0
2019-04-03T16:41:45.711Z,1554309705.711 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1183
2019-04-03T16:41:45.716Z,1554309705.716 [Supervisor](INFO): Main Thread ID is 1095
2019-04-03T16:41:45.716Z,1554309705.716 [Supervisor](DEBUG): Running supervisor.
2019-04-03T16:41:45.717Z,1554309705.717 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1184
2019-04-03T16:41:45.719Z,1554309705.719 [controlThread ThreadHandler](INFO): Handler Thread ID is 1185
2019-04-03T16:41:45.719Z,1554309705.719 [controlThread](DEBUG): Initializing ControlThread
2019-04-03T16:41:45.725Z,1554309705.725 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-03T16:41:45.725Z,1554309705.725 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-03T16:41:45.727Z,1554309705.727 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-03T16:41:45.727Z,1554309705.727 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-03T16:41:45.727Z,1554309705.727 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-03T16:41:45.728Z,1554309705.728 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-03T16:41:45.728Z,1554309705.728 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-03T16:41:45.728Z,1554309705.728 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-03T16:41:45.729Z,1554309705.729 [SBIT](INFO): Initialize SBIT Component.
2019-04-03T16:41:45.729Z,1554309705.729 [SBIT](IMPORTANT): git: 2019-03-18-45-g4133750
2019-04-03T16:41:45.730Z,1554309705.730 [SBIT](INFO): git hash: 4133750a826247d6fa9b310476a882604c466498
2019-04-03T16:41:45.730Z,1554309705.730 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-03T16:41:45.730Z,1554309705.730 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-04-03T16:41:45.731Z,1554309705.731 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-04-03T16:41:45.732Z,1554309705.732 [IBIT](INFO): Initialize IBIT Component.
2019-04-03T16:41:45.733Z,1554309705.733 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-03T16:41:45.734Z,1554309705.734 [logger ThreadHandler](INFO): Handler Thread ID is 1186
2019-04-03T16:41:45.746Z,1554309705.746 [CBIT](DEBUG): Initialized mux pins.
2019-04-03T16:41:45.746Z,1554309705.746 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-03T16:41:45.754Z,1554309705.754 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1187
2019-04-03T16:41:45.755Z,1554309705.755 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-03T16:41:45.766Z,1554309705.766 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1188
2019-04-03T16:41:45.770Z,1554309705.770 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-03T16:41:45.770Z,1554309705.770 [CBIT](DEBUG): Initializing heartbeat.
2019-04-03T16:41:45.778Z,1554309705.778 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1189
2019-04-03T16:41:45.779Z,1554309705.779 [CTD_NeilBrown](INFO): Powering down
2019-04-03T16:41:45.818Z,1554309705.818 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1190
2019-04-03T16:41:45.819Z,1554309705.819 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-04-03T16:41:45.839Z,1554309705.839 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1191
2019-04-03T16:41:45.842Z,1554309705.842 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-03T16:41:45.842Z,1554309705.842 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-03T16:41:45.843Z,1554309705.843 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-03T16:41:45.843Z,1554309705.843 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-03T16:41:45.843Z,1554309705.843 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-03T16:41:45.843Z,1554309705.843 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-03T16:41:45.843Z,1554309705.843 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-03T16:41:45.844Z,1554309705.844 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-03T16:41:45.844Z,1554309705.844 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-03T16:41:45.844Z,1554309705.844 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-03T16:41:45.844Z,1554309705.844 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-03T16:41:45.844Z,1554309705.844 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-03T16:41:45.844Z,1554309705.844 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-03T16:41:45.845Z,1554309705.845 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-03T16:41:45.845Z,1554309705.845 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-03T16:41:45.845Z,1554309705.845 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-03T16:41:45.846Z,1554309705.846 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-03T16:41:45.846Z,1554309705.846 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-03T16:41:45.882Z,1554309705.882 [CBIT](DEBUG): Backplane powered.
2019-04-03T16:41:45.882Z,1554309705.882 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-03T16:41:45.884Z,1554309705.884 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-03T16:41:45.885Z,1554309705.885 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-03T16:41:45.885Z,1554309705.885 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-03T16:41:45.886Z,1554309705.886 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-03T16:41:45.897Z,1554309705.897 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-03T16:41:45.920Z,1554309705.920 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-04-03T16:41:45.921Z,1554309705.921 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-03T16:41:45.993Z,1554309705.993 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-03T16:41:45.994Z,1554309705.994 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-03T16:41:45.996Z,1554309705.996 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-03T16:41:46.043Z,1554309706.043 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-03T16:41:46.045Z,1554309706.045 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-03T16:41:46.067Z,1554309706.067 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-03T16:41:46.070Z,1554309706.070 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-04-03T16:41:46.082Z,1554309706.082 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-03T16:41:46.104Z,1554309706.104 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-03T16:41:46.144Z,1554309706.144 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-04-03T16:41:46.145Z,1554309706.145 [DUSBL_Hydroid](INFO): Powering up
2019-04-03T16:41:46.145Z,1554309706.145 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-04-03T16:41:46.170Z,1554309706.170 [Radio_Surface](INFO): Powering up
2019-04-03T16:41:46.230Z,1554309706.230 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-03T16:41:46.275Z,1554309706.275 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-03T16:41:46.298Z,1554309706.298 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-03T16:41:46.299Z,1554309706.299 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-03T16:41:46.306Z,1554309706.306 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-03T16:41:46.307Z,1554309706.307 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-03T16:41:46.315Z,1554309706.315 [MassServo](DEBUG): Initializing MassServo.
2019-04-03T16:41:46.315Z,1554309706.315 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-03T16:41:46.322Z,1554309706.322 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-03T16:41:46.323Z,1554309706.323 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-03T16:41:46.330Z,1554309706.330 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-03T16:41:46.511Z,1554309706.511 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-04-03T16:41:46.511Z,1554309706.511 [DropWeight] Hardware Fault, FailCount= 1
2019-04-03T16:41:46.511Z,1554309706.511 [DropWeight](ERROR): Hardware Fault
2019-04-03T16:41:46.512Z,1554309706.512 [Micromodem](INFO): Powering up
2019-04-03T16:41:46.513Z,1554309706.513 [Micromodem](DEBUG): Initializing Micromodem.
2019-04-03T16:41:46.546Z,1554309706.546 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-03T16:41:46.595Z,1554309706.595 [CommandLine](FAULT): Scheduling is paused
2019-04-03T16:41:46.595Z,1554309706.595 [CBIT](INFO): Critical error at 20190403T164146
2019-04-03T16:41:46.595Z,1554309706.595 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-03T16:41:46.598Z,1554309706.598 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-04-03T16:41:46.598Z,1554309706.598 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-04-03T16:41:47.238Z,1554309707.238 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-03T16:41:47.238Z,1554309707.238 [RudderServo](FAULT): Rudder failed to initialize
2019-04-03T16:41:47.238Z,1554309707.238 [RudderServo] Communications Fault, FailCount= 1
2019-04-03T16:41:47.238Z,1554309707.238 [RudderServo](ERROR): Communications Fault
2019-04-03T16:41:47.347Z,1554309707.347 [CBIT](INFO): Critical error at 20190403T164146
2019-04-03T16:41:47.350Z,1554309707.350 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-03T16:41:47.523Z,1554309707.523 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-03T16:41:47.523Z,1554309707.523 [RudderServo](INFO): Powering down
2019-04-03T16:41:48.210Z,1554309708.210 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-03T16:41:48.331Z,1554309708.331 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-03T16:41:48.335Z,1554309708.335 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-03T16:41:48.335Z,1554309708.335 [RudderServo] No Fault, FailCount= 1
2019-04-03T16:41:51.437Z,1554309711.437 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-04-03T16:41:57.783Z,1554309717.783 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004403
2019-04-03T16:41:59.506Z,1554309719.506 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-04-03T16:42:00.692Z,1554309720.692 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-04-03T16:42:04.327Z,1554309724.327 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-04-03T16:42:04.378Z,1554309724.378 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-04-03T16:42:04.378Z,1554309724.378 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.   +0.00,   0.00,  5.00
2019-04-03T16:42:10.792Z,1554309730.792 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-04-03T16:42:12.004Z,1554309732.004 [NAL9602](INFO): Powering up NAL9602
2019-04-03T16:42:14.485Z,1554309734.485 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-03T16:42:14.489Z,1554309734.489 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-03T16:42:20.620Z,1554309740.620 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-04-03T16:42:23.031Z,1554309743.031 [NAL9602](INFO): NAL9602 initialized
2019-04-03T16:42:23.854Z,1554309743.854 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:42:25.543Z,1554309745.543 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010807
CHAN A1 (24V): -0.026666
CHAN A2 (12V): -0.007456
CHAN A3 (5V): -0.001990
CHAN B0 (3.3V): 0.000361
CHAN B1 (3.15aV): 0.000174
CHAN B2 (3.15bV): 0.000263
CHAN B3 (GND): 0.002071
OPEN: 0.005182
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-03T16:42:30.484Z,1554309750.484 [Micromodem](INFO): Nmea out: $CCTMS,2019-04-03T16:42:31Z,0*7A

2019-04-03T16:42:31.740Z,1554309751.740 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-04-03T16:42:31.741Z,1554309751.741 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,  BI,-32768,-32768,-32768,-32768,V
2019-04-03T16:42:59.261Z,1554309779.261 [NAL9602](INFO): SBD MO Status=2, MOMSN=2595, MT Status=2, MTMSN=0
2019-04-03T16:42:59.261Z,1554309779.261 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-04-03T16:43:03.738Z,1554309783.738 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-04-03T16:43:03.738Z,1554309783.738 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   I,-32768,-32768,-32768,-32768,V
2019-04-03T16:43:08.180Z,1554309788.180 [SBIT](IMPORTANT): SBIT PASSED
2019-04-03T16:43:08.251Z,1554309788.251 [CommandLine](IMPORTANT): got command configSet list
2019-04-03T16:43:08.252Z,1554309788.252 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-03T16:43:08.252Z,1554309788.252 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=20 count;

2019-04-03T16:43:08.253Z,1554309788.253 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.range 2.000000 meter;

2019-04-03T16:43:08.253Z,1554309788.253 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 angular_degree;

2019-04-03T16:43:08.253Z,1554309788.253 [CommandLine](IMPORTANT): Express linearApproximation TrackAcousticContact.contact_latitude 0.000500 angular_degree;

2019-04-03T16:43:08.253Z,1554309788.253 [CommandLine](IMPORTANT): Express linearApproximation TrackAcousticContact.contact_longitude 0.000500 angular_degree;

2019-04-03T16:43:08.253Z,1554309788.253 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 1.000000 meter;

2019-04-03T16:43:08.253Z,1554309788.253 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-04-03T16:43:08.589Z,1554309788.589 [MissionManager](IMPORTANT): Started mission Startup
2019-04-03T16:43:08.590Z,1554309788.590 [Startup] Running Loop=1
2019-04-03T16:43:08.590Z,1554309788.590 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-03T16:43:08.590Z,1554309788.590 [Startup:A.GoToSurface] Running Loop=1
2019-04-03T16:43:08.590Z,1554309788.590 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-03T16:43:08.591Z,1554309788.591 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-03T16:43:08.591Z,1554309788.591 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-03T16:43:08.592Z,1554309788.592 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-03T16:43:08.592Z,1554309788.592 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-03T16:43:08.592Z,1554309788.592 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-03T16:43:08.594Z,1554309788.594 [Startup:StartupSatComms] Running Loop=1
2019-04-03T16:43:08.594Z,1554309788.594 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-03T16:43:08.594Z,1554309788.594 [Startup:StartupSatComms:A] Running Loop=1
2019-04-03T16:43:09.008Z,1554309789.008 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-03T16:43:29.557Z,1554309809.557 [NAL9602](INFO): SBD MO Status=2, MOMSN=2595, MT Status=2, MTMSN=0
2019-04-03T16:43:29.558Z,1554309809.558 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-04-03T16:44:08.789Z,1554309848.789 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-03T16:43:08.6Z
2019-04-03T16:44:08.794Z,1554309848.794 [Startup:StartupSatComms:A] Stopped
2019-04-03T16:44:08.794Z,1554309848.794 [Startup:StartupSatComms:B] Running Loop=1
2019-04-03T16:44:09.181Z,1554309849.181 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-03T16:44:11.810Z,1554309851.810 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190403T010731/Courier0034.lzma
2019-04-03T16:44:12.624Z,1554309852.624 [DataOverHttps](INFO): Moved sent file to Logs/20190403T010731/Courier0034.lzma.bak
2019-04-03T16:44:12.624Z,1554309852.624 [DataOverHttps](INFO): SBD MOMSN=10401307
2019-04-03T16:44:24.597Z,1554309864.597 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190403T164135/Courier0000.lzma
2019-04-03T16:44:25.407Z,1554309865.407 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0000.lzma.bak
2019-04-03T16:44:25.407Z,1554309865.407 [DataOverHttps](INFO): SBD MOMSN=10401309
2019-04-03T16:44:34.377Z,1554309874.377 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-04-03T16:44:34.377Z,1554309874.377 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3268,V
2019-04-03T16:44:38.022Z,1554309878.022 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20190403T010731/Express0035.lzma
2019-04-03T16:44:38.824Z,1554309878.824 [DataOverHttps](INFO): Moved sent file to Logs/20190403T010731/Express0035.lzma.bak
2019-04-03T16:44:38.824Z,1554309878.824 [DataOverHttps](INFO): SBD MOMSN=10401314
2019-04-03T16:44:46.078Z,1554309886.078 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-03T16:44:46.078Z,1554309886.078 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-03T16:44:46.104Z,1554309886.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-03T16:44:46.489Z,1554309886.489 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-03T16:44:46.489Z,1554309886.489 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-03T16:44:52.426Z,1554309892.426 [DataOverHttps](INFO): Sending 631 bytes from file Logs/20190403T013801/Express0001.lzma
2019-04-03T16:44:53.231Z,1554309893.231 [DataOverHttps](INFO): Moved sent file to Logs/20190403T013801/Express0001.lzma.bak
2019-04-03T16:44:53.232Z,1554309893.232 [DataOverHttps](INFO): SBD MOMSN=10401322
2019-04-03T16:44:57.365Z,1554309897.365 [NAL9602](INFO): SBD MO Status=0, MOMSN=2595, MT Status=0, MTMSN=0
2019-04-03T16:44:57.366Z,1554309897.366 [NAL9602](INFO): No messages in MT queue
2019-04-03T16:44:58.198Z,1554309898.198 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:01.414Z,1554309901.414 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:04.250Z,1554309904.250 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:06.563Z,1554309906.563 [DataOverHttps](INFO): Sending 855 bytes from file Logs/20190403T164135/Express0001.lzma
2019-04-03T16:45:07.368Z,1554309907.368 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0001.lzma.bak
2019-04-03T16:45:07.368Z,1554309907.368 [DataOverHttps](INFO): SBD MOMSN=10401335
2019-04-03T16:45:07.478Z,1554309907.478 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:08.895Z,1554309908.895 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-03T16:44:08.8Z
2019-04-03T16:45:08.895Z,1554309908.895 [Startup:StartupSatComms:B] Stopped
2019-04-03T16:45:08.896Z,1554309908.896 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-03T16:45:08.896Z,1554309908.896 [Startup:StartupSatComms] Stopped
2019-04-03T16:45:08.896Z,1554309908.896 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-03T16:45:08.896Z,1554309908.896 [Startup](INFO): Completed Startup
2019-04-03T16:45:08.897Z,1554309908.897 [MissionManager](INFO): Startup is completed.
2019-04-03T16:45:08.897Z,1554309908.897 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-03T16:45:08.897Z,1554309908.897 [Startup] Stopped
2019-04-03T16:45:08.897Z,1554309908.897 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-03T16:45:08.897Z,1554309908.897 [Startup:A.GoToSurface] Stopped
2019-04-03T16:45:08.897Z,1554309908.897 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-03T16:45:09.135Z,1554309909.135 [MissionManager](IMPORTANT): Started mission Default
2019-04-03T16:45:09.135Z,1554309909.135 [Default] Running Loop=1
2019-04-03T16:45:09.135Z,1554309909.135 [Default](DEBUG): Aggregate::initialize Default
2019-04-03T16:45:09.135Z,1554309909.135 [Default:B.GoToSurface] Running Loop=1
2019-04-03T16:45:09.135Z,1554309909.135 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-03T16:45:09.136Z,1554309909.136 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-03T16:45:09.136Z,1554309909.136 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-03T16:45:09.136Z,1554309909.136 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-03T16:45:09.136Z,1554309909.136 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-03T16:45:09.137Z,1554309909.137 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-03T16:45:09.137Z,1554309909.137 [Default:A.Wait] Running Loop=1
2019-04-03T16:45:09.137Z,1554309909.137 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-03T16:45:10.310Z,1554309910.310 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:13.534Z,1554309913.534 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:16.371Z,1554309916.371 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:19.598Z,1554309919.598 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:22.422Z,1554309922.422 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:22.508Z,1554309922.508 [Default:A.Wait](INFO): Done Waiting.
2019-04-03T16:45:22.508Z,1554309922.508 [Default:A.Wait] Stopped
2019-04-03T16:45:22.508Z,1554309922.508 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T16:45:22.871Z,1554309922.871 [Default:CheckIn] Running Loop=1
2019-04-03T16:45:22.871Z,1554309922.871 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T16:45:22.871Z,1554309922.871 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T16:45:23.275Z,1554309923.275 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-03T16:45:25.666Z,1554309925.666 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:28.478Z,1554309928.478 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:31.318Z,1554309931.318 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:34.134Z,1554309934.134 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:37.366Z,1554309937.366 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:40.194Z,1554309940.194 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:43.430Z,1554309943.430 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:46.266Z,1554309946.266 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:49.494Z,1554309949.494 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:50.725Z,1554309950.725 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-04-03T16:45:50.725Z,1554309950.725 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19040309542893,35.0, 0.0,1448.9,  0
2019-04-03T16:45:52.339Z,1554309952.339 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:55.570Z,1554309955.570 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:45:58.374Z,1554309958.374 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:01.606Z,1554309961.606 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:04.434Z,1554309964.434 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:07.670Z,1554309967.670 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:10.502Z,1554309970.502 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:13.362Z,1554309973.362 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:16.154Z,1554309976.154 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:19.386Z,1554309979.386 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:22.218Z,1554309982.218 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:25.442Z,1554309985.442 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:28.289Z,1554309988.289 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:31.502Z,1554309991.502 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:34.342Z,1554309994.342 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:37.566Z,1554309997.566 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:40.390Z,1554310000.390 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:43.626Z,1554310003.626 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:46.450Z,1554310006.450 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:49.282Z,1554310009.282 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:52.510Z,1554310012.510 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:55.338Z,1554310015.338 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:46:58.178Z,1554310018.178 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:01.814Z,1554310021.814 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:04.230Z,1554310024.230 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:07.458Z,1554310027.458 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:10.290Z,1554310030.290 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:13.518Z,1554310033.518 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:16.346Z,1554310036.346 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:19.578Z,1554310039.578 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:22.406Z,1554310042.406 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:25.237Z,1554310045.237 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:28.474Z,1554310048.474 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:31.322Z,1554310051.322 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:34.538Z,1554310054.538 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:37.366Z,1554310057.366 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:40.594Z,1554310060.594 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:47:40.982Z,1554310060.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164739.00,A,3648.16388,N,12147.29347,W,0.583,0.00,030419,,,A*74
2019-04-03T16:47:40.985Z,1554310060.985 [NAL9602](INFO): GPS fix at 20190403T164739: (36.802731, -121.788224)
2019-04-03T16:47:41.025Z,1554310061.025 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T16:47:41.025Z,1554310061.025 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T16:47:41.468Z,1554310061.468 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-03T16:47:49.778Z,1554310069.778 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20190403T164135/Courier0004.lzma
2019-04-03T16:47:51.532Z,1554310071.532 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0004.lzma.bak
2019-04-03T16:47:51.532Z,1554310071.532 [DataOverHttps](INFO): SBD MOMSN=10401367
2019-04-03T16:48:06.113Z,1554310086.114 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20190403T164135/Express0005.lzma
2019-04-03T16:48:06.919Z,1554310086.919 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0005.lzma.bak
2019-04-03T16:48:06.920Z,1554310086.920 [DataOverHttps](INFO): SBD MOMSN=10401370
2019-04-03T16:48:09.017Z,1554310089.017 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T16:48:09.017Z,1554310089.017 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T16:48:09.017Z,1554310089.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T16:48:13.428Z,1554310093.428 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T16:49:09.622Z,1554310149.622 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2019-04-03T16:49:09.625Z,1554310149.625 [BPC1](INFO): Received data from all battery sticks.
2019-04-03T16:53:09.609Z,1554310389.609 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T16:53:09.609Z,1554310389.609 [Default:CheckIn:C.Wait] Stopped
2019-04-03T16:53:09.609Z,1554310389.609 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T16:53:09.610Z,1554310389.610 [Default:CheckIn:D] Running Loop=1
2019-04-03T16:53:09.997Z,1554310389.997 [Default:CheckIn:D] Stopped
2019-04-03T16:53:09.997Z,1554310389.997 [Default:CheckIn:E] Running Loop=1
2019-04-03T16:53:10.408Z,1554310390.408 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.014364 min 
2019-04-03T16:53:10.408Z,1554310390.408 [Default:CheckIn:E] Stopped
2019-04-03T16:53:10.408Z,1554310390.408 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T16:53:10.408Z,1554310390.408 [Default:CheckIn] Stopped
2019-04-03T16:53:10.408Z,1554310390.408 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T16:53:10.408Z,1554310390.408 [Default:CheckIn](INFO): Running loop #2
2019-04-03T16:53:10.409Z,1554310390.409 [Default:CheckIn] Running Loop=2
2019-04-03T16:53:10.409Z,1554310390.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T16:53:10.409Z,1554310390.409 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T16:53:12.002Z,1554310392.002 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:53:12.393Z,1554310392.393 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165310.00,A,3648.19468,N,12147.30642,W,0.758,331.25,030419,,,A*76
2019-04-03T16:53:12.395Z,1554310392.395 [NAL9602](INFO): GPS fix at 20190403T165310: (36.803245, -121.788440)
2019-04-03T16:53:12.456Z,1554310392.456 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T16:53:12.456Z,1554310392.456 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T16:53:19.045Z,1554310399.045 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20190403T164135/Courier0007.lzma
2019-04-03T16:53:19.851Z,1554310399.851 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0007.lzma.bak
2019-04-03T16:53:19.852Z,1554310399.852 [DataOverHttps](INFO): SBD MOMSN=10401645
2019-04-03T16:53:30.169Z,1554310410.169 [NAL9602](INFO): SBD MO Status=2, MOMSN=2596, MT Status=2, MTMSN=0
2019-04-03T16:53:30.169Z,1554310410.169 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-04-03T16:53:34.021Z,1554310414.021 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20190403T164135/Express0008.lzma
2019-04-03T16:53:34.827Z,1554310414.827 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0008.lzma.bak
2019-04-03T16:53:34.828Z,1554310414.828 [DataOverHttps](INFO): SBD MOMSN=10401648
2019-04-03T16:53:37.061Z,1554310417.061 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T16:53:37.061Z,1554310417.061 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T16:53:37.061Z,1554310417.061 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T16:53:38.703Z,1554310418.703 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-04-03T16:53:38.704Z,1554310418.704 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-04-03T16:54:24.310Z,1554310464.310 [NAL9602](INFO): SBD MO Status=2, MOMSN=2596, MT Status=2, MTMSN=0
2019-04-03T16:54:24.310Z,1554310464.310 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-04-03T16:54:46.122Z,1554310486.122 [NAL9602](INFO): SBD MO Status=2, MOMSN=2596, MT Status=2, MTMSN=0
2019-04-03T16:54:46.122Z,1554310486.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-04-03T16:54:58.762Z,1554310498.762 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2019-04-03T16:54:58.762Z,1554310498.762 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-04-03T16:54:58.762Z,1554310498.762 [RDI_Pathfinder](ERROR): Communications Fault
2019-04-03T16:54:58.762Z,1554310498.762 [RDI_Pathfinder](ERROR): Failed to parse:
2019-04-03T16:54:58.786Z,1554310498.786 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-04-03T16:54:59.134Z,1554310499.134 [RDI_Pathfinder](INFO): Powering down
2019-04-03T16:54:59.898Z,1554310499.898 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-04-03T16:54:59.898Z,1554310499.898 [RDI_Pathfinder] No Fault, FailCount= 1
2019-04-03T16:55:13.228Z,1554310513.228 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-04-03T16:56:46.580Z,1554310606.580 [CBIT](INFO): Clearing failed state for component DropWeight
2019-04-03T16:56:46.580Z,1554310606.580 [DropWeight] No Fault, FailCount= 1
2019-04-03T16:57:07.572Z,1554310627.572 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-04-03T16:57:07.572Z,1554310627.572 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19040310054577,35.0, -0.1,   0.0,1448.9,,  0.00,   0.00,   0.00,   0.00
2019-04-03T16:58:14.197Z,1554310694.197 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-03T16:58:37.662Z,1554310717.662 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T16:58:37.662Z,1554310717.662 [Default:CheckIn:C.Wait] Stopped
2019-04-03T16:58:37.662Z,1554310717.662 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T16:58:37.662Z,1554310717.662 [Default:CheckIn:D] Running Loop=1
2019-04-03T16:58:38.064Z,1554310718.064 [Default:CheckIn:D] Stopped
2019-04-03T16:58:38.064Z,1554310718.064 [Default:CheckIn:E] Running Loop=1
2019-04-03T16:58:38.497Z,1554310718.497 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.482152 min 
2019-04-03T16:58:38.497Z,1554310718.497 [Default:CheckIn:E] Stopped
2019-04-03T16:58:38.502Z,1554310718.502 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T16:58:38.502Z,1554310718.502 [Default:CheckIn] Stopped
2019-04-03T16:58:38.502Z,1554310718.502 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T16:58:38.502Z,1554310718.502 [Default:CheckIn](INFO): Running loop #3
2019-04-03T16:58:38.502Z,1554310718.502 [Default:CheckIn] Running Loop=3
2019-04-03T16:58:38.502Z,1554310718.502 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T16:58:38.502Z,1554310718.502 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T16:58:40.066Z,1554310720.066 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:58:42.482Z,1554310722.482 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:58:45.718Z,1554310725.718 [NAL9602](DEBUG): Fix Requested
2019-04-03T16:58:46.110Z,1554310726.110 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165845.00,A,3648.15991,N,12147.28919,W,1.516,51.11,030419,,,A*49
2019-04-03T16:58:46.112Z,1554310726.112 [NAL9602](INFO): GPS fix at 20190403T165845: (36.802665, -121.788153)
2019-04-03T16:58:46.182Z,1554310726.182 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T16:58:46.182Z,1554310726.182 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T16:58:53.033Z,1554310733.033 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190403T164135/Courier0010.lzma
2019-04-03T16:58:53.840Z,1554310733.840 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0010.lzma.bak
2019-04-03T16:58:53.840Z,1554310733.840 [DataOverHttps](INFO): SBD MOMSN=10401651
2019-04-03T16:59:06.130Z,1554310746.130 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20190403T164135/Express0011.lzma
2019-04-03T16:59:06.935Z,1554310746.935 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0011.lzma.bak
2019-04-03T16:59:06.936Z,1554310746.936 [DataOverHttps](INFO): SBD MOMSN=10401654
2019-04-03T16:59:08.761Z,1554310748.761 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T16:59:08.761Z,1554310748.761 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T16:59:08.761Z,1554310748.761 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T16:59:18.423Z,1554310758.423 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T17:00:27.532Z,1554310827.532 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-04-03T17:00:34.399Z,1554310834.399 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-04-03T17:04:09.360Z,1554311049.360 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T17:04:09.360Z,1554311049.360 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:04:09.360Z,1554311049.360 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:04:09.360Z,1554311049.360 [Default:CheckIn:D] Running Loop=1
2019-04-03T17:04:09.754Z,1554311049.754 [Default:CheckIn:D] Stopped
2019-04-03T17:04:09.754Z,1554311049.754 [Default:CheckIn:E] Running Loop=1
2019-04-03T17:04:10.165Z,1554311050.165 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.010311 min 
2019-04-03T17:04:10.165Z,1554311050.165 [Default:CheckIn:E] Stopped
2019-04-03T17:04:10.165Z,1554311050.165 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T17:04:10.165Z,1554311050.165 [Default:CheckIn] Stopped
2019-04-03T17:04:10.165Z,1554311050.165 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:04:10.166Z,1554311050.166 [Default:CheckIn](INFO): Running loop #4
2019-04-03T17:04:10.166Z,1554311050.166 [Default:CheckIn] Running Loop=4
2019-04-03T17:04:10.166Z,1554311050.166 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:04:10.166Z,1554311050.166 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:04:11.766Z,1554311051.766 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:04:12.158Z,1554311052.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170411.00,A,3648.17884,N,12147.28977,W,0.428,337.07,030419,,,A*76
2019-04-03T17:04:12.160Z,1554311052.160 [NAL9602](INFO): GPS fix at 20190403T170411: (36.802981, -121.788163)
2019-04-03T17:04:12.183Z,1554311052.183 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:04:12.183Z,1554311052.183 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:04:18.873Z,1554311058.873 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190403T164135/Courier0013.lzma
2019-04-03T17:04:19.679Z,1554311059.679 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0013.lzma.bak
2019-04-03T17:04:19.680Z,1554311059.680 [DataOverHttps](INFO): SBD MOMSN=10401923
2019-04-03T17:04:27.523Z,1554311067.523 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-04-03T17:04:27.524Z,1554311067.524 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,1904031013-32768,V
2019-04-03T17:04:33.049Z,1554311073.049 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190403T164135/Express0014.lzma
2019-04-03T17:04:33.855Z,1554311073.855 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0014.lzma.bak
2019-04-03T17:04:33.856Z,1554311073.856 [DataOverHttps](INFO): SBD MOMSN=10401927
2019-04-03T17:04:35.613Z,1554311075.613 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:04:35.613Z,1554311075.613 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:04:35.613Z,1554311075.613 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:04:42.866Z,1554311082.866 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-04-03T17:04:42.950Z,1554311082.950 [NAL9602](FAULT): received: 
+CSQ:0

OK96, 2, 0, 0, 0

OK
2019-04-03T17:04:42.950Z,1554311082.950 [NAL9602] Data Fault, FailCount= 1
2019-04-03T17:04:42.950Z,1554311082.950 [NAL9602](ERROR): Data Fault
2019-04-03T17:04:43.040Z,1554311083.040 [CBIT](ERROR): Data Fault in component: NAL9602
2019-04-03T17:04:43.263Z,1554311083.263 [NAL9602](INFO): Powering down
2019-04-03T17:04:44.163Z,1554311084.163 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-03T17:04:44.163Z,1554311084.163 [NAL9602] No Fault, FailCount= 1
2019-04-03T17:05:13.564Z,1554311113.564 [NAL9602](INFO): Powering up NAL9602
2019-04-03T17:05:24.468Z,1554311124.468 [NAL9602](INFO): NAL9602 initialized
2019-04-03T17:05:55.580Z,1554311155.580 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T17:09:36.226Z,1554311376.226 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T17:09:36.226Z,1554311376.226 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:09:36.226Z,1554311376.226 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:09:36.226Z,1554311376.226 [Default:CheckIn:D] Running Loop=1
2019-04-03T17:09:36.597Z,1554311376.597 [Default:CheckIn:D] Stopped
2019-04-03T17:09:36.597Z,1554311376.597 [Default:CheckIn:E] Running Loop=1
2019-04-03T17:09:36.994Z,1554311376.994 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.457697 min 
2019-04-03T17:09:36.994Z,1554311376.994 [Default:CheckIn:E] Stopped
2019-04-03T17:09:36.994Z,1554311376.994 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T17:09:36.994Z,1554311376.994 [Default:CheckIn] Stopped
2019-04-03T17:09:36.995Z,1554311376.995 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:09:36.995Z,1554311376.995 [Default:CheckIn](INFO): Running loop #5
2019-04-03T17:09:36.995Z,1554311376.995 [Default:CheckIn] Running Loop=5
2019-04-03T17:09:36.995Z,1554311376.995 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:09:36.995Z,1554311376.995 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:09:38.594Z,1554311378.594 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:09:38.993Z,1554311378.993 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170938.00,A,3648.17296,N,12147.28452,W,0.272,160.43,030419,,,A*7A
2019-04-03T17:09:38.996Z,1554311378.996 [NAL9602](INFO): GPS fix at 20190403T170938: (36.802883, -121.788075)
2019-04-03T17:09:39.042Z,1554311379.042 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:09:39.042Z,1554311379.042 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:09:41.013Z,1554311381.013 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-03T17:09:45.725Z,1554311385.725 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190403T164135/Courier0016.lzma
2019-04-03T17:09:46.531Z,1554311386.531 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0016.lzma.bak
2019-04-03T17:09:46.532Z,1554311386.532 [DataOverHttps](INFO): SBD MOMSN=10401966
2019-04-03T17:09:59.133Z,1554311399.133 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20190403T164135/Express0017.lzma
2019-04-03T17:09:59.939Z,1554311399.939 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0017.lzma.bak
2019-04-03T17:09:59.940Z,1554311399.940 [DataOverHttps](INFO): SBD MOMSN=10401969
2019-04-03T17:10:02.041Z,1554311402.041 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:10:02.041Z,1554311402.041 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:10:02.041Z,1554311402.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:10:11.715Z,1554311411.715 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T17:14:35.563Z,1554311675.563 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-04-03T17:14:35.563Z,1554311675.563 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-04-03T17:15:02.629Z,1554311702.629 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T17:15:02.629Z,1554311702.629 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:15:02.629Z,1554311702.629 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:15:02.630Z,1554311702.630 [Default:CheckIn:D] Running Loop=1
2019-04-03T17:15:03.038Z,1554311703.038 [Default:CheckIn:D] Stopped
2019-04-03T17:15:03.039Z,1554311703.039 [Default:CheckIn:E] Running Loop=1
2019-04-03T17:15:03.453Z,1554311703.453 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.898391 min 
2019-04-03T17:15:03.453Z,1554311703.453 [Default:CheckIn:E] Stopped
2019-04-03T17:15:03.453Z,1554311703.453 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T17:15:03.453Z,1554311703.453 [Default:CheckIn] Stopped
2019-04-03T17:15:03.453Z,1554311703.453 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:15:03.453Z,1554311703.453 [Default:CheckIn](INFO): Running loop #6
2019-04-03T17:15:03.453Z,1554311703.453 [Default:CheckIn] Running Loop=6
2019-04-03T17:15:03.454Z,1554311703.454 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:15:03.454Z,1554311703.454 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:15:05.030Z,1554311705.030 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:15:05.429Z,1554311705.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171504.00,A,3648.16182,N,12147.28548,W,0.972,160.43,030419,,,A*7E
2019-04-03T17:15:05.432Z,1554311705.432 [NAL9602](INFO): GPS fix at 20190403T171504: (36.802697, -121.788091)
2019-04-03T17:15:05.465Z,1554311705.465 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:15:05.465Z,1554311705.465 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:15:12.653Z,1554311712.653 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190403T164135/Courier0019.lzma
2019-04-03T17:15:13.459Z,1554311713.459 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0019.lzma.bak
2019-04-03T17:15:13.460Z,1554311713.460 [DataOverHttps](INFO): SBD MOMSN=10402286
2019-04-03T17:15:26.406Z,1554311726.406 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20190403T164135/Express0020.lzma
2019-04-03T17:15:27.211Z,1554311727.211 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0020.lzma.bak
2019-04-03T17:15:27.212Z,1554311727.212 [DataOverHttps](INFO): SBD MOMSN=10402294
2019-04-03T17:15:29.311Z,1554311729.311 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:15:29.311Z,1554311729.311 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:15:29.311Z,1554311729.311 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:16:54.177Z,1554311814.177 [RDI_Pathfinder](ERROR): Failed to parse:
:8-32768,V
2019-04-03T17:20:07.228Z,1554312007.228 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-03T17:20:29.897Z,1554312029.897 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T17:20:29.897Z,1554312029.897 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:20:29.897Z,1554312029.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:20:29.897Z,1554312029.897 [Default:CheckIn:D] Running Loop=1
2019-04-03T17:20:30.280Z,1554312030.280 [Default:CheckIn:D] Stopped
2019-04-03T17:20:30.280Z,1554312030.280 [Default:CheckIn:E] Running Loop=1
2019-04-03T17:20:30.705Z,1554312030.705 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.352417 min 
2019-04-03T17:20:30.705Z,1554312030.705 [Default:CheckIn:E] Stopped
2019-04-03T17:20:30.705Z,1554312030.705 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T17:20:30.705Z,1554312030.705 [Default:CheckIn] Stopped
2019-04-03T17:20:30.705Z,1554312030.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:20:30.706Z,1554312030.706 [Default:CheckIn](INFO): Running loop #7
2019-04-03T17:20:30.706Z,1554312030.706 [Default:CheckIn] Running Loop=7
2019-04-03T17:20:30.706Z,1554312030.706 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:20:30.706Z,1554312030.706 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:20:32.322Z,1554312032.322 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:20:32.682Z,1554312032.682 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172032.00,A,3648.15704,N,12147.27695,W,1.497,169.88,030419,,,A*73
2019-04-03T17:20:32.684Z,1554312032.684 [NAL9602](INFO): GPS fix at 20190403T172032: (36.802617, -121.787949)
2019-04-03T17:20:32.750Z,1554312032.750 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:20:32.751Z,1554312032.751 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:20:42.333Z,1554312042.333 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190403T164135/Courier0022.lzma
2019-04-03T17:20:43.140Z,1554312043.140 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0022.lzma.bak
2019-04-03T17:20:43.140Z,1554312043.140 [DataOverHttps](INFO): SBD MOMSN=10402309
2019-04-03T17:20:45.903Z,1554312045.903 [CommandLine](IMPORTANT): got command ibit
2019-04-03T17:20:46.214Z,1554312046.214 [IBIT](IMPORTANT): Beginning Initiated BIT
2019-04-03T17:20:46.214Z,1554312046.214 [IBIT](IMPORTANT): Beginning control surface checks.
2019-04-03T17:20:46.217Z,1554312046.217 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-03T17:20:47.385Z,1554312047.385 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:20:47.750Z,1554312047.750 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172047.00,A,3648.16090,N,12147.27837,W,0.855,169.88,030419,,,A*7D
2019-04-03T17:20:47.752Z,1554312047.752 [NAL9602](INFO): GPS fix at 20190403T172047: (36.802682, -121.787973)
2019-04-03T17:20:50.590Z,1554312050.590 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:20:50.987Z,1554312050.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172050.00,A,3648.16109,N,12147.27833,W,0.408,169.88,030419,,,A*7A
2019-04-03T17:20:50.989Z,1554312050.989 [NAL9602](INFO): GPS fix at 20190403T172050: (36.802685, -121.787972)
2019-04-03T17:20:53.574Z,1554312053.574 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:20:53.965Z,1554312053.965 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172053.00,A,3648.15999,N,12147.27776,W,0.778,169.88,030419,,,A*71
2019-04-03T17:20:53.968Z,1554312053.968 [NAL9602](INFO): GPS fix at 20190403T172053: (36.802667, -121.787963)
2019-04-03T17:20:56.406Z,1554312056.406 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:20:56.814Z,1554312056.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172056.00,A,3648.15955,N,12147.27812,W,0.564,169.88,030419,,,A*76
2019-04-03T17:20:56.816Z,1554312056.816 [NAL9602](INFO): GPS fix at 20190403T172056: (36.802659, -121.787969)
2019-04-03T17:20:57.291Z,1554312057.291 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.126034
CHAN A1 (24V): -0.026734
CHAN A2 (12V): -0.007233
CHAN A3 (5V): -0.001397
CHAN B0 (3.3V): 0.000123
CHAN B1 (3.15aV): 0.000102
CHAN B2 (3.15bV): 0.000349
CHAN B3 (GND): 0.002277
OPEN: 0.005508
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-03T17:20:59.634Z,1554312059.634 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:00.030Z,1554312060.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172059.00,A,3648.16014,N,12147.27885,W,0.408,169.88,030419,,,A*73
2019-04-03T17:21:00.032Z,1554312060.032 [NAL9602](INFO): GPS fix at 20190403T172059: (36.802669, -121.787981)
2019-04-03T17:21:01.390Z,1554312061.390 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20190403T164135/Express0023.lzma
2019-04-03T17:21:02.195Z,1554312062.195 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0023.lzma.bak
2019-04-03T17:21:02.196Z,1554312062.196 [DataOverHttps](INFO): SBD MOMSN=10402315
2019-04-03T17:21:02.458Z,1554312062.458 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:02.854Z,1554312062.854 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172102.00,A,3648.16082,N,12147.27972,W,0.700,169.88,030419,,,A*71
2019-04-03T17:21:02.857Z,1554312062.857 [NAL9602](INFO): GPS fix at 20190403T172102: (36.802680, -121.787995)
2019-04-03T17:21:05.698Z,1554312065.698 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:06.090Z,1554312066.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172105.00,A,3648.15943,N,12147.27931,W,0.467,169.88,030419,,,A*74
2019-04-03T17:21:06.092Z,1554312066.092 [NAL9602](INFO): GPS fix at 20190403T172105: (36.802657, -121.787988)
2019-04-03T17:21:08.526Z,1554312068.526 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:08.919Z,1554312068.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172108.00,A,3648.15927,N,12147.27920,W,0.292,169.88,030419,,,A*77
2019-04-03T17:21:08.921Z,1554312068.921 [NAL9602](INFO): GPS fix at 20190403T172108: (36.802655, -121.787987)
2019-04-03T17:21:11.758Z,1554312071.758 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:12.150Z,1554312072.150 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172111.00,A,3648.15906,N,12147.27858,W,0.369,169.88,030419,,,A*77
2019-04-03T17:21:12.153Z,1554312072.153 [NAL9602](INFO): GPS fix at 20190403T172111: (36.802651, -121.787976)
2019-04-03T17:21:14.594Z,1554312074.594 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:14.979Z,1554312074.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172114.00,A,3648.15852,N,12147.27819,W,0.641,169.88,030419,,,A*78
2019-04-03T17:21:14.981Z,1554312074.981 [NAL9602](INFO): GPS fix at 20190403T172114: (36.802642, -121.787970)
2019-04-03T17:21:17.414Z,1554312077.414 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:17.809Z,1554312077.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172117.00,A,3648.15831,N,12147.27842,W,0.641,169.88,030419,,,A*70
2019-04-03T17:21:17.816Z,1554312077.816 [NAL9602](INFO): GPS fix at 20190403T172117: (36.802639, -121.787974)
2019-04-03T17:21:20.646Z,1554312080.646 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:21.040Z,1554312081.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172120.00,A,3648.15975,N,12147.27911,W,0.797,169.88,030419,,,A*78
2019-04-03T17:21:21.046Z,1554312081.046 [NAL9602](INFO): GPS fix at 20190403T172120: (36.802662, -121.787985)
2019-04-03T17:21:23.474Z,1554312083.474 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:23.861Z,1554312083.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172123.00,A,3648.16040,N,12147.27969,W,0.622,169.88,030419,,,A*77
2019-04-03T17:21:23.868Z,1554312083.868 [NAL9602](INFO): GPS fix at 20190403T172123: (36.802673, -121.787995)
2019-04-03T17:21:26.706Z,1554312086.706 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:27.098Z,1554312087.098 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172126.00,A,3648.15937,N,12147.27932,W,0.467,169.88,030419,,,A*75
2019-04-03T17:21:27.101Z,1554312087.101 [NAL9602](INFO): GPS fix at 20190403T172126: (36.802656, -121.787989)
2019-04-03T17:21:29.534Z,1554312089.534 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:29.921Z,1554312089.921 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172129.00,A,3648.16105,N,12147.27960,W,0.778,169.88,030419,,,A*7A
2019-04-03T17:21:29.928Z,1554312089.928 [NAL9602](INFO): GPS fix at 20190403T172129: (36.802684, -121.787993)
2019-04-03T17:21:32.770Z,1554312092.770 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:33.194Z,1554312093.194 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172132.00,A,3648.16084,N,12147.27957,W,0.272,169.88,030419,,,A*73
2019-04-03T17:21:33.196Z,1554312093.196 [NAL9602](INFO): GPS fix at 20190403T172132: (36.802681, -121.787993)
2019-04-03T17:21:35.594Z,1554312095.594 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:35.982Z,1554312095.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172135.00,A,3648.15812,N,12147.27759,W,1.341,169.88,030419,,,A*70
2019-04-03T17:21:35.994Z,1554312095.994 [NAL9602](INFO): GPS fix at 20190403T172135: (36.802635, -121.787960)
2019-04-03T17:21:38.418Z,1554312098.418 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:38.814Z,1554312098.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172138.00,A,3648.15793,N,12147.27767,W,0.758,169.88,030419,,,A*7B
2019-04-03T17:21:38.816Z,1554312098.816 [NAL9602](INFO): GPS fix at 20190403T172138: (36.802632, -121.787961)
2019-04-03T17:21:41.654Z,1554312101.654 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:42.042Z,1554312102.042 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172141.00,A,3648.15757,N,12147.27760,W,0.758,169.88,030419,,,A*7A
2019-04-03T17:21:42.049Z,1554312102.049 [NAL9602](INFO): GPS fix at 20190403T172141: (36.802626, -121.787960)
2019-04-03T17:21:44.478Z,1554312104.478 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:44.869Z,1554312104.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172144.00,A,3648.15814,N,12147.27796,W,0.233,169.88,030419,,,A*76
2019-04-03T17:21:44.872Z,1554312104.872 [NAL9602](INFO): GPS fix at 20190403T172144: (36.802636, -121.787966)
2019-04-03T17:21:47.714Z,1554312107.714 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:48.102Z,1554312108.102 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172147.00,A,3648.15899,N,12147.27868,W,0.564,169.88,030419,,,A*7B
2019-04-03T17:21:48.104Z,1554312108.104 [NAL9602](INFO): GPS fix at 20190403T172147: (36.802650, -121.787978)
2019-04-03T17:21:50.538Z,1554312110.538 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:50.943Z,1554312110.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172150.00,A,3648.15917,N,12147.27909,W,0.408,169.88,030419,,,A*77
2019-04-03T17:21:50.945Z,1554312110.945 [NAL9602](INFO): GPS fix at 20190403T172150: (36.802653, -121.787985)
2019-04-03T17:21:53.371Z,1554312113.371 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:53.762Z,1554312113.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172153.00,A,3648.15946,N,12147.27974,W,0.389,169.88,030419,,,A*74
2019-04-03T17:21:53.764Z,1554312113.764 [NAL9602](INFO): GPS fix at 20190403T172153: (36.802658, -121.787996)
2019-04-03T17:21:56.598Z,1554312116.598 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:56.994Z,1554312116.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172156.00,A,3648.15980,N,12147.27992,W,0.369,169.88,030419,,,A*7D
2019-04-03T17:21:56.996Z,1554312116.996 [NAL9602](INFO): GPS fix at 20190403T172156: (36.802663, -121.787999)
2019-04-03T17:21:59.430Z,1554312119.430 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:21:59.822Z,1554312119.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172159.00,A,3648.15890,N,12147.27954,W,0.350,169.88,030419,,,A*72
2019-04-03T17:21:59.824Z,1554312119.824 [NAL9602](INFO): GPS fix at 20190403T172159: (36.802648, -121.787992)
2019-04-03T17:22:02.658Z,1554312122.658 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:03.054Z,1554312123.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172202.00,A,3648.15851,N,12147.27942,W,0.233,169.88,030419,,,A*71
2019-04-03T17:22:03.056Z,1554312123.056 [NAL9602](INFO): GPS fix at 20190403T172202: (36.802642, -121.787990)
2019-04-03T17:22:05.491Z,1554312125.491 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:05.882Z,1554312125.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172205.00,A,3648.15908,N,12147.27992,W,0.233,169.88,030419,,,A*76
2019-04-03T17:22:05.884Z,1554312125.884 [NAL9602](INFO): GPS fix at 20190403T172205: (36.802651, -121.787999)
2019-04-03T17:22:08.726Z,1554312128.726 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:09.114Z,1554312129.114 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172208.00,A,3648.16028,N,12147.28045,W,0.797,169.88,030419,,,A*74
2019-04-03T17:22:09.116Z,1554312129.116 [NAL9602](INFO): GPS fix at 20190403T172208: (36.802671, -121.788008)
2019-04-03T17:22:11.440Z,1554312131.440 [CommandLine](IMPORTANT): got command get 
2019-04-03T17:22:11.440Z,1554312131.440 [CommandLine](FAULT): Incomplete syntax. Try: help get
2019-04-03T17:22:11.570Z,1554312131.570 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:11.954Z,1554312131.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172211.00,A,3648.16171,N,12147.28122,W,1.030,169.88,030419,,,A*7A
2019-04-03T17:22:11.957Z,1554312131.957 [NAL9602](INFO): GPS fix at 20190403T172211: (36.802695, -121.788020)
2019-04-03T17:22:14.394Z,1554312134.394 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:14.781Z,1554312134.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172214.00,A,3648.16205,N,12147.28127,W,0.836,169.88,030419,,,A*75
2019-04-03T17:22:14.793Z,1554312134.793 [NAL9602](INFO): GPS fix at 20190403T172214: (36.802701, -121.788021)
2019-04-03T17:22:17.622Z,1554312137.622 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:18.019Z,1554312138.019 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172217.00,A,3648.16320,N,12147.28182,W,0.778,169.88,030419,,,A*7A
2019-04-03T17:22:18.021Z,1554312138.021 [NAL9602](INFO): GPS fix at 20190403T172217: (36.802720, -121.788030)
2019-04-03T17:22:20.450Z,1554312140.450 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:20.841Z,1554312140.841 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172220.00,A,3648.16393,N,12147.28222,W,0.816,169.88,030419,,,A*78
2019-04-03T17:22:20.844Z,1554312140.844 [NAL9602](INFO): GPS fix at 20190403T172220: (36.802732, -121.788037)
2019-04-03T17:22:23.682Z,1554312143.682 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:24.075Z,1554312144.075 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172223.00,A,3648.16354,N,12147.28185,W,0.564,169.88,030419,,,A*76
2019-04-03T17:22:24.077Z,1554312144.077 [NAL9602](INFO): GPS fix at 20190403T172223: (36.802726, -121.788031)
2019-04-03T17:22:26.522Z,1554312146.522 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:26.906Z,1554312146.906 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172226.00,A,3648.16280,N,12147.28127,W,0.136,169.88,030419,,,A*70
2019-04-03T17:22:26.909Z,1554312146.909 [NAL9602](INFO): GPS fix at 20190403T172226: (36.802713, -121.788021)
2019-04-03T17:22:29.742Z,1554312149.742 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:30.138Z,1554312150.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172229.00,A,3648.16786,N,12147.28458,W,0.117,169.88,030419,,,A*72
2019-04-03T17:22:30.141Z,1554312150.141 [NAL9602](INFO): GPS fix at 20190403T172229: (36.802798, -121.788076)
2019-04-03T17:22:32.570Z,1554312152.570 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:32.962Z,1554312152.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172232.00,A,3648.17586,N,12147.28946,W,1.711,294.01,030419,,,A*78
2019-04-03T17:22:32.964Z,1554312152.964 [NAL9602](INFO): GPS fix at 20190403T172232: (36.802931, -121.788158)
2019-04-03T17:22:35.401Z,1554312155.401 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:22:35.792Z,1554312155.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172235.00,A,3648.18215,N,12147.29337,W,1.730,328.39,030419,,,A*7E
2019-04-03T17:22:35.797Z,1554312155.797 [NAL9602](INFO): GPS fix at 20190403T172235: (36.803036, -121.788223)
2019-04-03T17:22:36.663Z,1554312156.663 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2019-04-03T17:22:37.102Z,1554312157.102 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2019-04-03T17:22:37.103Z,1554312157.103 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2019-04-03T17:22:37.103Z,1554312157.103 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2019-04-03T17:22:37.534Z,1554312157.534 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2019-04-03T17:22:37.535Z,1554312157.535 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2019-04-03T17:22:37.535Z,1554312157.535 [IBIT](IMPORTANT): Pressure:9.905114 PSI
2019-04-03T17:22:37.535Z,1554312157.535 [IBIT](IMPORTANT): Humidity:11.341717 %
2019-04-03T17:22:37.851Z,1554312157.851 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2019-04-03T17:22:37.851Z,1554312157.851 [IBIT](IMPORTANT): buoyancyNeutral: 410.000000 cc
2019-04-03T17:22:37.851Z,1554312157.851 [IBIT](IMPORTANT): massDefault: 0.200000 cm
2019-04-03T17:22:37.852Z,1554312157.852 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2019-04-03T17:22:37.852Z,1554312157.852 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2019-04-03T17:22:37.852Z,1554312157.852 [IBIT](IMPORTANT): IBIT FAILED
2019-04-03T17:22:38.263Z,1554312158.263 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:22:38.263Z,1554312158.263 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:22:38.263Z,1554312158.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:23:08.516Z,1554312188.516 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T17:24:10.127Z,1554312250.127 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault millimeter
2019-04-03T17:24:10.128Z,1554312250.128 [CommandLine](IMPORTANT): VerticalControl.massDefault 2.000000 mm
2019-04-03T17:24:59.460Z,1554312299.460 [CommandLine](IMPORTANT): got command get 
2019-04-03T17:24:59.460Z,1554312299.460 [CommandLine](FAULT): Incomplete syntax. Try: help get
2019-04-03T17:25:09.643Z,1554312309.643 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2019-04-03T17:25:09.643Z,1554312309.643 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 33.000000 mm
2019-04-03T17:25:22.064Z,1554312322.064 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2019-04-03T17:25:22.064Z,1554312322.064 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -22.000000 mm
2019-04-03T17:25:22.653Z,1554312322.653 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2019-04-03T17:25:33.202Z,1554312333.202 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-04-03T17:25:33.203Z,1554312333.203 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3278,-32768,V
2019-04-03T17:25:48.215Z,1554312348.215 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2019-04-03T17:25:48.215Z,1554312348.215 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2019-04-03T17:25:48.729Z,1554312348.729 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool
2019-04-03T17:25:48.736Z,1554312348.736 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg
2019-04-03T17:25:48.744Z,1554312348.744 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg
2019-04-03T17:25:48.751Z,1554312348.751 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2019-04-03T17:25:48.763Z,1554312348.763 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2019-04-03T17:25:48.770Z,1554312348.770 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2019-04-03T17:25:48.773Z,1554312348.773 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2019-04-03T17:25:48.781Z,1554312348.781 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2019-04-03T17:25:48.788Z,1554312348.788 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2019-04-03T17:25:48.796Z,1554312348.796 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2019-04-03T17:25:48.811Z,1554312348.811 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2019-04-03T17:25:48.839Z,1554312348.839 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2019-04-03T17:25:48.842Z,1554312348.842 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2019-04-03T17:25:48.853Z,1554312348.853 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2019-04-03T17:25:48.865Z,1554312348.865 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool
2019-04-03T17:25:48.881Z,1554312348.881 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 1.000000 bool
2019-04-03T17:25:48.925Z,1554312348.925 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 0.000000 bool
2019-04-03T17:25:48.929Z,1554312348.929 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool
2019-04-03T17:25:48.973Z,1554312348.973 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 0.000000 bool
2019-04-03T17:25:48.977Z,1554312348.977 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2019-04-03T17:25:49.010Z,1554312349.010 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool
2019-04-03T17:25:49.051Z,1554312349.051 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool
2019-04-03T17:25:49.054Z,1554312349.054 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2019-04-03T17:25:49.057Z,1554312349.057 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2019-04-03T17:25:49.081Z,1554312349.081 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2019-04-03T17:25:49.084Z,1554312349.084 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2019-04-03T17:25:49.107Z,1554312349.107 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2019-04-03T17:25:49.110Z,1554312349.110 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2019-04-03T17:25:49.121Z,1554312349.121 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2019-04-03T17:25:49.124Z,1554312349.124 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2019-04-03T17:25:49.152Z,1554312349.152 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2019-04-03T17:25:49.158Z,1554312349.158 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2019-04-03T17:25:49.177Z,1554312349.177 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2019-04-03T17:25:49.216Z,1554312349.216 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2019-04-03T17:25:49.235Z,1554312349.235 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2019-04-03T17:25:49.275Z,1554312349.275 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2019-04-03T17:25:49.278Z,1554312349.278 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2019-04-03T17:25:49.282Z,1554312349.282 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2019-04-03T17:25:49.341Z,1554312349.341 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2019-04-03T17:25:49.353Z,1554312349.353 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2019-04-03T17:25:49.388Z,1554312349.388 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2019-04-03T17:25:49.404Z,1554312349.404 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2019-04-03T17:25:49.423Z,1554312349.423 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2019-04-03T17:25:49.435Z,1554312349.435 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2019-04-03T17:25:49.454Z,1554312349.454 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2019-04-03T17:25:49.458Z,1554312349.458 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2019-04-03T17:25:49.473Z,1554312349.473 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2019-04-03T17:25:49.489Z,1554312349.489 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2019-04-03T17:25:49.508Z,1554312349.508 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2019-04-03T17:25:49.512Z,1554312349.512 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2019-04-03T17:25:49.520Z,1554312349.520 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2019-04-03T17:25:49.610Z,1554312349.610 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-04-03T17:25:49.652Z,1554312349.652 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2019-04-03T17:25:49.773Z,1554312349.773 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-04-03T17:25:49.812Z,1554312349.812 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-04-03T17:25:49.847Z,1554312349.847 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-04-03T17:25:49.949Z,1554312349.949 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Science="Tethys/Science"
           xmlns:Universal="Tethys/Universal"
           xmlns:Trigger="Tethys/Trigger"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd"
       Id="Science">

    <DefineArg Name="SampleISUS"><Description>
        Set either this or PeakDetectNO3Active to True in order to sample
        Nitrate. EnabledISUS must also be true.
    </Description><False/></DefineArg>

    <DefineArg Name="SampleISUSmaxPitch"><Description>
        Maximum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>60.0</Value></DefineArg>

    <DefineArg Name="SampleISUSminPitch"><Description>
        Minimum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>-60.0</Value></DefineArg>

    <DefineArg Name="PeakDetectChlActive"><Description>
        Turns on peak detection of Cholorphyll.
    </Description><False/></DefineArg>

    <DefineArg Name="TimeWindowPeakReport"><Description>
        If greater than zero, report a peak every window. If NaN or zero, this
        variable is ignored.
    </Description><Units:minute/><Value>NaN</Value></DefineArg>

    <DefineArg Name="HighestChlPeakReportActive"><Description>
        Turns on reporting of the highest peak value on yo-yo profiles in a
        horizontal sliding window (of length numProfilesSlidingwindow)
    </Description><False/></DefineArg>

    <DefineArg Name="PatchTracking"><Description>
        If tracking a patch
    </Description><False/></DefineArg>

<!--debug
    </Description><True/></DefineArg>
-->

    <DefineArg Name="FilterWidthHorizontal"><Description>
        Width of boxcar filter applied to yoyo-wise chl peaks to pick out the
        highest peak.
    </Description><Units:count/><Value>3</Value></DefineArg>

    <DefineArg Name="NumProfilesSlidingwindow"><Description>
        Length of horizontal sliding window. The highest yoyo-wise chl peak
        (after low-pass filtering by a filter of length FilterWidthHorizontal)
        within this window.
    </Description><Units:count/><Value>100</Value></DefineArg>

    <DefineArg Name="OffPeakFractionHorizontal"><Description>
        When filtered horizontal value is this fraction of the peak, consider it
        outside the patch.
    </Description><Units:percent/><Value>80</Value></DefineArg>

    <DefineArg Name="PeakDetectNO3Active"><Description>
        Turns on peak detection of Nitrate (and turns on ISUS).
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectOilActive"><Description>
        Turns on peak detection of Dissolved Oil.
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectFDOMActive"><Description>
        Turns on peak detection of concentration_of_chromophoric_dissolved_organic_matter_in_sea_water from FDOM sensor.
    </Description><False/></DefineArg>
    
    <DefineArg Name="UpwardDerivativeOfTemperatureActive"><Description>
        Turns on seawater temperature derivative.
    </Description><False/></DefineArg>

    <DefineArg Name="EnabledAanderaaO2"><Description>
        Automatically set to True if the Aanderaa O2 sensor is installed. Set to
        false to disable reading Aandera O2.
    </Description><Science:Aanderaa_O2.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledNeilBrown"><Description>
        Automatically set to true if the Neil Brown CTD is enabled. Set to false
        to disable reading from the Neil Brown.
    </Description><Science:CTD_NeilBrown.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledSeabird"><Description>
        Automatically set to true if the CTD is enabled. Set to false to disable
        reading from the CTD.
    </Description><Science:CTD_Seabird.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledTurbulence_NPS"><Description>
        Automatically set to true if the NPS Turbulence sensor is enabled. Set
        to false to disable reading from the NPS Turbulence sensor.
    </Description><Science:Turbulence_NPS.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsBB2FL"><Description>
        Automatically set to true if the the WetLabs BB2FL is enabled. Set to
        false to disable the WetLabs BB2FL.
    </Description><Science:WetLabsBB2FL.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsSeaOWL_UV_A"><Description>
        Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set
        to false to disable the WetLabs SeaOWL UV-A.
    </Description><Science:WetLabsSeaOWL_UV_A.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsUBAT"><Description>
        Automatically set to true if the the WetLabs UBAT is enabled. Set
        to false to disable the WetLabs UBAT.
    </Description><Science:WetLabsUBAT.loadAtStartup/></DefineArg>    

    <DefineArg Name="EnabledISUS"><Description>
        Automatically set to True if ISUS is enabled. Set to false to disable
        ISUS.
    </Description><Science:ISUS.loadAtStartup/></DefineArg>

    <DefineArg Name="LowPassWindowLength"><Description>
        Low-pass window length (based on depth sensor sampling interval 0.4
        second) for low-pass filtering.
    </Description><Units:count/><Value>20</Value></DefineArg>

    <DefineArg Name="MedianFilterLen"><Description>
        Median filter length (only for chlorophyll fluorescence which tends to have spikes)
    </Description><Units:count/><Value>5</Value></DefineArg>

    <DefineArg Name="PeakChlShallowBound"><Description>
        Shallow depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

<!--debug
    </Description><Units:meter/><Value>10</Value></DefineArg>
-->

    <DefineArg Name="PeakChlDeepBound"><Description>
        Deep depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

<!--debug
    </Description><Units:meter/><Value>20</Value></DefineArg>
-->

    <DefineArg Name="PeakShallowBound"><Description>
        Shallow depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="PeakDeepBound"><Description>
        Deep depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineOutput Name="PeakChl"><Units:microgram_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlTemperature"><Units:celsius/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3"><Units:micromole_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Depth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Latitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Longitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChl"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlDistance"><Units:meter/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOil"><Units:kilogram_per_cubic_meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOilLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOM"><Units:part_per_billion/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>
    
    <DefineOutput Name="PatchFDOMDistance"><Units:meter/><Value>NaN</Value></DefineOutput>    
    
    <Parallel/>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledAanderaaO2"/>
        </While>
        <Universal:mass_concentration_of_oxygen_in_sea_water/>
    </ReadData>

    <ReadData>
        <While>
            <Arg Name="EnabledISUS"/>
            <And><Universal:platform_pitch_angle/><Lt><Arg Name="SampleISUSmaxPitch"/></Lt><And><Universal:platform_pitch_angle/><Gt><Arg Name="SampleISUSminPitch"/></Gt><And><Arg Name="SampleISUS"/><Or><Arg Name="PeakDetectNO3Active"/></Or></And></And></And>
        </While>
        <Universal:mole_concentration_of_nitrate_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
        </While>
        <Universal:sea_water_temperature/>
        <Universal:sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledSeabird"/>
        </While>
        <Science:CTD_Seabird.sea_water_temperature/>
        <Science:CTD_Seabird.sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
            <And><Arg Name="UpwardDerivativeOfTemperatureActive"/></And>
        </While>
        <Universal:upward_derivative_of_sea_water_temperature/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledTurbulence_NPS"/>
        </While>
        <Science:Turbulence_NPS.MicroCond/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsBB2FL"/>
            <Or><Arg Name="PeakDetectChlActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsUBAT"/>
        </While>
        <Science:WetLabsUBAT.biolum_potential/>
    </ReadData>

<!--debug-->

    <ReadData Id="Read_Oil" Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsSeaOWL_UV_A"/>
            <Or><Arg Name="PeakDetectOilActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <Aggregate Id="PeakDetectChl">

        <While>
            <Arg Name="PeakDetectChlActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_chlorophyll_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.medianFilterLength/><Arg Name="MedianFilterLen"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakChlShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakChlDeepBound"/></Setting>
            <OutputArg Name="PeakChl"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakChlDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakChlTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakChlLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakChlLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

<!--debug
        <Syslog Severity="Important">PeakChl, PeakChlLatitude, PeakChlLongitude = <Arg Name="PeakChl"/><Units:microgram_per_liter/>, <Arg Name="PeakChlLatitude"/><Units:degree/>, <Arg Name="PeakChlLongitude"/><Units:degree/></Syslog>
-->

        <SendData Service="Express"><Arg Name="PeakChl"/><Arg Name="PeakChlDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="HighestChlPeakReport">

        <While>
            <Arg Name="HighestChlPeakReportActive"/>
        </While>

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakChl"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakChlDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakChlLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakChlLongitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.patchTracking/><Arg Name="PatchTracking"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.filterWidth/><Arg Name="FilterWidthHorizontal"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.numProfilesSlidingwindow/><Arg Name="NumProfilesSlidingwindow"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.offPeakFraction/><Arg Name="OffPeakFractionHorizontal"/></Setting>
            <OutputArg Name="PatchChl"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchChlDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchChlLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchChlLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchChlDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </Aggregate>

    <Aggregate Id="PeakDetectNO3">

        <While>
            <Arg Name="PeakDetectNO3Active"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mole_concentration_of_nitrate_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakNO3"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakNO3Depth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakNO3Latitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakNO3Longitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakNO3"/><Arg Name="PeakNO3Depth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectOil">

        <While>
            <Arg Name="PeakDetectOilActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_petroleum_hydrocarbons_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakOil"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakOilDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakOilLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakOilLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakOil"/><Arg Name="PeakOilDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectFDOM">

        <While>
            <Arg Name="PeakDetectFDOMActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:concentration_of_chromophoric_dissolved_organic_matter_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakFDOM"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakFDOMDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakFDOMTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakFDOMLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakFDOMLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakFDOM"/><Arg Name="PeakFDOMDepth"/>
        </SendData>

    </Aggregate>

</Aggregate>


2019-04-03T17:25:49.964Z,1554312349.964 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2019-04-03T17:25:50.142Z,1554312350.142 [Default] Stopped
2019-04-03T17:25:50.142Z,1554312350.142 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-03T17:25:50.142Z,1554312350.142 [Default:B.GoToSurface] Stopped
2019-04-03T17:25:50.142Z,1554312350.142 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-03T17:25:50.142Z,1554312350.142 [Default:CheckIn] Stopped
2019-04-03T17:25:50.142Z,1554312350.142 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:25:50.142Z,1554312350.142 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:25:50.142Z,1554312350.142 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:25:50.143Z,1554312350.143 [MissionManager](IMPORTANT): Started mission Science
2019-04-03T17:25:50.143Z,1554312350.143 [Science] Running Loop=1
2019-04-03T17:25:50.143Z,1554312350.143 [Science](DEBUG): Aggregate::initialize Science
2019-04-03T17:25:50.143Z,1554312350.143 [Science:A] Running Loop=1
2019-04-03T17:25:50.143Z,1554312350.143 [Science:B] Running Loop=1
2019-04-03T17:25:50.143Z,1554312350.143 [Science:C] Running Loop=1
2019-04-03T17:25:50.143Z,1554312350.143 [Science:D] Running Loop=1
2019-04-03T17:25:50.143Z,1554312350.143 [Science:E] Running Loop=1
2019-04-03T17:25:50.143Z,1554312350.143 [Science:F] Running Loop=1
2019-04-03T17:25:50.143Z,1554312350.143 [Science:G] Running Loop=1
2019-04-03T17:25:50.144Z,1554312350.144 [Science:H] Running Loop=1
2019-04-03T17:25:50.144Z,1554312350.144 [Science:Read_Oil] Running Loop=1
2019-04-03T17:25:50.144Z,1554312350.144 [Science:PeakDetectChl] Running Loop=1
2019-04-03T17:25:50.144Z,1554312350.144 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2019-04-03T17:25:50.144Z,1554312350.144 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2019-04-03T17:25:50.144Z,1554312350.144 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-04-03T17:25:50.146Z,1554312350.146 [Science:HighestChlPeakReport] Running Loop=1
2019-04-03T17:25:50.146Z,1554312350.146 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2019-04-03T17:25:50.146Z,1554312350.146 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2019-04-03T17:25:50.146Z,1554312350.146 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2019-04-03T17:25:50.147Z,1554312350.147 [Science:PeakDetectNO3] Running Loop=1
2019-04-03T17:25:50.147Z,1554312350.147 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2019-04-03T17:25:50.147Z,1554312350.147 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2019-04-03T17:25:50.147Z,1554312350.147 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-04-03T17:25:50.149Z,1554312350.149 [Science:PeakDetectOil] Running Loop=1
2019-04-03T17:25:50.149Z,1554312350.149 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2019-04-03T17:25:50.150Z,1554312350.150 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2019-04-03T17:25:50.150Z,1554312350.150 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-04-03T17:25:50.151Z,1554312350.151 [Science:PeakDetectFDOM] Running Loop=1
2019-04-03T17:25:50.151Z,1554312350.151 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2019-04-03T17:25:50.152Z,1554312350.152 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2019-04-03T17:25:50.152Z,1554312350.152 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-04-03T17:25:50.176Z,1554312350.176 [Science:PeakDetectFDOM] Stopped
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectOil] Stopped
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectNO3] Stopped
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2019-04-03T17:25:50.177Z,1554312350.177 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-04-03T17:25:50.178Z,1554312350.178 [Science:HighestChlPeakReport] Stopped
2019-04-03T17:25:50.178Z,1554312350.178 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2019-04-03T17:25:50.178Z,1554312350.178 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2019-04-03T17:25:50.178Z,1554312350.178 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2019-04-03T17:25:50.178Z,1554312350.178 [Science:PeakDetectChl] Stopped
2019-04-03T17:25:50.178Z,1554312350.178 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2019-04-03T17:25:50.178Z,1554312350.178 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-04-03T17:25:50.178Z,1554312350.178 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-04-03T17:25:50.178Z,1554312350.178 [Science:Read_Oil] Running Loop=1
2019-04-03T17:25:50.190Z,1554312350.190 [Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2019-04-03T17:25:50.190Z,1554312350.190 [Science:H] Stopped
2019-04-03T17:25:50.191Z,1554312350.191 [Science:G] Stopped
2019-04-03T17:25:50.191Z,1554312350.191 [Science:F] Stopped
2019-04-03T17:25:50.191Z,1554312350.191 [Science:E] Stopped
2019-04-03T17:25:50.191Z,1554312350.191 [Science:D] Stopped
2019-04-03T17:25:50.191Z,1554312350.191 [Science:C] Running Loop=1
2019-04-03T17:25:50.191Z,1554312350.191 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2019-04-03T17:25:50.192Z,1554312350.192 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2019-04-03T17:25:50.192Z,1554312350.192 [Science:B] Stopped
2019-04-03T17:25:50.192Z,1554312350.192 [Science:A] Stopped
2019-04-03T17:25:50.262Z,1554312350.262 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2019-04-03T17:25:50.263Z,1554312350.263 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2019-04-03T17:25:50.520Z,1554312350.520 [WetLabsSeaOWL_UV_A](INFO): Opening uart, block timeout 10ths=4
2019-04-03T17:25:50.521Z,1554312350.521 [WetLabsSeaOWL_UV_A](INFO): Powering up
2019-04-03T17:25:51.716Z,1554312351.716 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.174000 psu
2019-04-03T17:25:53.085Z,1554312353.085 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:53.135Z,1554312353.135 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172000 psu
2019-04-03T17:25:53.147Z,1554312353.147 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:53.339Z,1554312353.339 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168200 psu
2019-04-03T17:25:53.342Z,1554312353.342 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:53.543Z,1554312353.543 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169700 psu
2019-04-03T17:25:53.546Z,1554312353.546 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:53.747Z,1554312353.747 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172700 psu
2019-04-03T17:25:53.750Z,1554312353.750 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:53.951Z,1554312353.951 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171100 psu
2019-04-03T17:25:53.955Z,1554312353.955 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:54.157Z,1554312354.157 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171600 psu
2019-04-03T17:25:54.171Z,1554312354.171 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:54.359Z,1554312354.359 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168200 psu
2019-04-03T17:25:54.362Z,1554312354.362 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:54.563Z,1554312354.563 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169100 psu
2019-04-03T17:25:54.567Z,1554312354.567 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:54.767Z,1554312354.767 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173400 psu
2019-04-03T17:25:54.776Z,1554312354.776 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:54.975Z,1554312354.975 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173600 psu
2019-04-03T17:25:54.983Z,1554312354.983 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:55.179Z,1554312355.179 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170800 psu
2019-04-03T17:25:55.182Z,1554312355.182 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:55.383Z,1554312355.383 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173100 psu
2019-04-03T17:25:55.387Z,1554312355.387 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:55.587Z,1554312355.587 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173500 psu
2019-04-03T17:25:55.590Z,1554312355.590 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:55.800Z,1554312355.800 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172700 psu
2019-04-03T17:25:55.805Z,1554312355.805 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:56.003Z,1554312356.003 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170200 psu
2019-04-03T17:25:56.006Z,1554312356.006 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:56.203Z,1554312356.203 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.175100 psu
2019-04-03T17:25:56.206Z,1554312356.206 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:56.406Z,1554312356.406 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172700 psu
2019-04-03T17:25:56.409Z,1554312356.409 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:56.615Z,1554312356.615 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173100 psu
2019-04-03T17:25:56.618Z,1554312356.618 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:56.814Z,1554312356.814 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173000 psu
2019-04-03T17:25:56.817Z,1554312356.817 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:57.021Z,1554312357.021 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171600 psu
2019-04-03T17:25:57.034Z,1554312357.034 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:57.226Z,1554312357.226 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171700 psu
2019-04-03T17:25:57.229Z,1554312357.229 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:57.427Z,1554312357.427 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171300 psu
2019-04-03T17:25:57.430Z,1554312357.430 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:57.630Z,1554312357.630 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171200 psu
2019-04-03T17:25:57.634Z,1554312357.634 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:57.835Z,1554312357.835 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168300 psu
2019-04-03T17:25:57.838Z,1554312357.838 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:58.043Z,1554312358.043 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173600 psu
2019-04-03T17:25:58.046Z,1554312358.046 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:58.243Z,1554312358.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169200 psu
2019-04-03T17:25:58.246Z,1554312358.246 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:58.447Z,1554312358.447 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170800 psu
2019-04-03T17:25:58.450Z,1554312358.450 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:58.651Z,1554312358.651 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169000 psu
2019-04-03T17:25:58.654Z,1554312358.654 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:58.854Z,1554312358.854 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170100 psu
2019-04-03T17:25:58.857Z,1554312358.857 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:59.059Z,1554312359.059 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.174400 psu
2019-04-03T17:25:59.062Z,1554312359.062 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:59.266Z,1554312359.266 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171900 psu
2019-04-03T17:25:59.270Z,1554312359.270 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:59.466Z,1554312359.466 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169800 psu
2019-04-03T17:25:59.470Z,1554312359.470 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:59.675Z,1554312359.675 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170300 psu
2019-04-03T17:25:59.678Z,1554312359.678 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:25:59.875Z,1554312359.875 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169500 psu
2019-04-03T17:25:59.878Z,1554312359.878 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:00.079Z,1554312360.079 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172600 psu
2019-04-03T17:26:00.082Z,1554312360.082 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:00.284Z,1554312360.284 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.174000 psu
2019-04-03T17:26:00.288Z,1554312360.288 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:00.491Z,1554312360.491 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.174600 psu
2019-04-03T17:26:00.494Z,1554312360.494 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:00.691Z,1554312360.691 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171800 psu
2019-04-03T17:26:00.694Z,1554312360.694 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:00.895Z,1554312360.895 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173700 psu
2019-04-03T17:26:00.898Z,1554312360.898 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:01.099Z,1554312361.099 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169900 psu
2019-04-03T17:26:01.103Z,1554312361.103 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:01.303Z,1554312361.303 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172900 psu
2019-04-03T17:26:01.306Z,1554312361.306 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:01.512Z,1554312361.512 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171900 psu
2019-04-03T17:26:01.521Z,1554312361.521 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:01.711Z,1554312361.711 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171800 psu
2019-04-03T17:26:01.714Z,1554312361.714 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:01.915Z,1554312361.915 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171900 psu
2019-04-03T17:26:01.918Z,1554312361.918 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:02.119Z,1554312362.119 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172200 psu
2019-04-03T17:26:02.122Z,1554312362.122 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:02.323Z,1554312362.323 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172300 psu
2019-04-03T17:26:02.326Z,1554312362.326 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:02.531Z,1554312362.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168200 psu
2019-04-03T17:26:02.534Z,1554312362.534 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:02.731Z,1554312362.731 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172700 psu
2019-04-03T17:26:02.734Z,1554312362.734 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:02.935Z,1554312362.935 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172000 psu
2019-04-03T17:26:02.938Z,1554312362.938 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:03.139Z,1554312363.139 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173100 psu
2019-04-03T17:26:03.149Z,1554312363.149 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:03.343Z,1554312363.343 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.166300 psu
2019-04-03T17:26:03.346Z,1554312363.346 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:03.547Z,1554312363.547 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168800 psu
2019-04-03T17:26:03.550Z,1554312363.550 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:03.751Z,1554312363.751 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170100 psu
2019-04-03T17:26:03.754Z,1554312363.754 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:03.955Z,1554312363.955 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.178300 psu
2019-04-03T17:26:03.958Z,1554312363.958 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:04.160Z,1554312364.160 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169900 psu
2019-04-03T17:26:04.169Z,1554312364.169 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:04.363Z,1554312364.363 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173900 psu
2019-04-03T17:26:04.366Z,1554312364.366 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:04.566Z,1554312364.566 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.175200 psu
2019-04-03T17:26:04.570Z,1554312364.570 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:04.771Z,1554312364.771 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172100 psu
2019-04-03T17:26:04.774Z,1554312364.774 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:04.974Z,1554312364.974 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173000 psu
2019-04-03T17:26:04.978Z,1554312364.978 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:05.182Z,1554312365.182 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170600 psu
2019-04-03T17:26:05.186Z,1554312365.186 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:05.382Z,1554312365.382 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173900 psu
2019-04-03T17:26:05.386Z,1554312365.386 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:05.587Z,1554312365.587 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172600 psu
2019-04-03T17:26:05.590Z,1554312365.590 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:05.791Z,1554312365.791 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173400 psu
2019-04-03T17:26:05.794Z,1554312365.794 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:05.995Z,1554312365.995 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172900 psu
2019-04-03T17:26:05.999Z,1554312365.999 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:06.199Z,1554312366.199 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173100 psu
2019-04-03T17:26:06.203Z,1554312366.203 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:06.404Z,1554312366.404 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170600 psu
2019-04-03T17:26:06.409Z,1554312366.409 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:06.609Z,1554312366.609 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173800 psu
2019-04-03T17:26:06.613Z,1554312366.613 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:06.811Z,1554312366.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.174200 psu
2019-04-03T17:26:06.814Z,1554312366.814 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:07.014Z,1554312367.014 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170400 psu
2019-04-03T17:26:07.018Z,1554312367.018 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:07.218Z,1554312367.218 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169100 psu
2019-04-03T17:26:07.222Z,1554312367.222 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:07.422Z,1554312367.422 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173400 psu
2019-04-03T17:26:07.426Z,1554312367.426 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:07.626Z,1554312367.626 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172800 psu
2019-04-03T17:26:07.630Z,1554312367.630 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:07.830Z,1554312367.830 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168900 psu
2019-04-03T17:26:07.834Z,1554312367.834 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:08.034Z,1554312368.034 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168900 psu
2019-04-03T17:26:08.038Z,1554312368.038 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:08.238Z,1554312368.238 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170100 psu
2019-04-03T17:26:08.242Z,1554312368.242 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:08.442Z,1554312368.442 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.174400 psu
2019-04-03T17:26:08.446Z,1554312368.446 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:08.646Z,1554312368.646 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.177800 psu
2019-04-03T17:26:08.650Z,1554312368.650 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:08.850Z,1554312368.850 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168800 psu
2019-04-03T17:26:08.854Z,1554312368.854 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:09.054Z,1554312369.054 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.170500 psu
2019-04-03T17:26:09.058Z,1554312369.058 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:09.262Z,1554312369.262 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172400 psu
2019-04-03T17:26:09.266Z,1554312369.266 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:09.463Z,1554312369.463 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172600 psu
2019-04-03T17:26:09.466Z,1554312369.466 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:09.667Z,1554312369.667 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173400 psu
2019-04-03T17:26:09.678Z,1554312369.678 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:09.871Z,1554312369.871 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171600 psu
2019-04-03T17:26:09.874Z,1554312369.874 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:10.075Z,1554312370.075 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171500 psu
2019-04-03T17:26:10.078Z,1554312370.078 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:10.279Z,1554312370.279 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169600 psu
2019-04-03T17:26:10.286Z,1554312370.286 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:10.494Z,1554312370.494 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.166500 psu
2019-04-03T17:26:10.497Z,1554312370.497 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:10.698Z,1554312370.698 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.174000 psu
2019-04-03T17:26:10.701Z,1554312370.701 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:10.902Z,1554312370.902 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.168200 psu
2019-04-03T17:26:10.905Z,1554312370.905 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:11.106Z,1554312371.106 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173700 psu
2019-04-03T17:26:11.110Z,1554312371.110 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:11.310Z,1554312371.310 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171000 psu
2019-04-03T17:26:11.314Z,1554312371.314 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:11.527Z,1554312371.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173600 psu
2019-04-03T17:26:11.530Z,1554312371.530 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:11.718Z,1554312371.718 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171400 psu
2019-04-03T17:26:11.723Z,1554312371.723 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:11.926Z,1554312371.926 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173900 psu
2019-04-03T17:26:11.930Z,1554312371.930 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:12.126Z,1554312372.126 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.175400 psu
2019-04-03T17:26:12.129Z,1554312372.129 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:12.335Z,1554312372.335 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171300 psu
2019-04-03T17:26:12.338Z,1554312372.338 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:12.534Z,1554312372.534 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172600 psu
2019-04-03T17:26:12.537Z,1554312372.537 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:12.739Z,1554312372.739 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.174100 psu
2019-04-03T17:26:12.744Z,1554312372.744 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:12.942Z,1554312372.942 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169800 psu
2019-04-03T17:26:12.945Z,1554312372.945 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:13.148Z,1554312373.148 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.176000 psu
2019-04-03T17:26:13.177Z,1554312373.177 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:13.350Z,1554312373.350 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171500 psu
2019-04-03T17:26:13.353Z,1554312373.353 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:13.554Z,1554312373.554 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172000 psu
2019-04-03T17:26:13.557Z,1554312373.557 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:13.758Z,1554312373.758 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171800 psu
2019-04-03T17:26:13.762Z,1554312373.762 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:13.955Z,1554312373.955 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173400 psu
2019-04-03T17:26:13.958Z,1554312373.958 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:14.160Z,1554312374.160 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.175600 psu
2019-04-03T17:26:14.189Z,1554312374.189 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:14.370Z,1554312374.370 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173600 psu
2019-04-03T17:26:14.374Z,1554312374.374 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:14.574Z,1554312374.574 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.177100 psu
2019-04-03T17:26:14.577Z,1554312374.577 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:14.779Z,1554312374.779 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.175100 psu
2019-04-03T17:26:14.782Z,1554312374.782 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:14.980Z,1554312374.980 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.172000 psu
2019-04-03T17:26:14.984Z,1554312374.984 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:15.180Z,1554312375.180 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169700 psu
2019-04-03T17:26:15.185Z,1554312375.185 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:15.387Z,1554312375.387 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173200 psu
2019-04-03T17:26:15.390Z,1554312375.390 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:15.791Z,1554312375.791 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.169400 psu
2019-04-03T17:26:15.807Z,1554312375.807 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:15.999Z,1554312375.999 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171800 psu
2019-04-03T17:26:16.002Z,1554312376.002 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:16.202Z,1554312376.202 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.173100 psu
2019-04-03T17:26:16.206Z,1554312376.206 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:16.363Z,1554312376.363 [CommandLine](IMPORTANT): got command stop
2019-04-03T17:26:16.363Z,1554312376.363 [CommandLine](IMPORTANT): Scheduling is paused
2019-04-03T17:26:16.364Z,1554312376.364 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-04-03T17:26:16.411Z,1554312376.411 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.171800 psu
2019-04-03T17:26:16.414Z,1554312376.414 [CTD_NeilBrown](INFO): some bad data, not updating bins
2019-04-03T17:26:16.427Z,1554312376.427 [MissionManager](INFO): MissionManager is completed.
2019-04-03T17:26:16.427Z,1554312376.427 [MissionManager](INFO): Uninitializing Mission Science
2019-04-03T17:26:16.427Z,1554312376.427 [Science] Stopped
2019-04-03T17:26:16.427Z,1554312376.427 [Science](DEBUG): Aggregate::uninitialize Science
2019-04-03T17:26:16.427Z,1554312376.427 [Science:C] Stopped
2019-04-03T17:26:16.427Z,1554312376.427 [Science:Read_Oil] Stopped
2019-04-03T17:26:16.602Z,1554312376.602 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-04-03T17:26:16.822Z,1554312376.822 [MissionManager](IMPORTANT): Started mission Default
2019-04-03T17:26:16.822Z,1554312376.822 [Default] Running Loop=1
2019-04-03T17:26:16.822Z,1554312376.822 [Default](DEBUG): Aggregate::initialize Default
2019-04-03T17:26:16.822Z,1554312376.822 [Default:B.GoToSurface] Running Loop=1
2019-04-03T17:26:16.822Z,1554312376.822 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-03T17:26:16.823Z,1554312376.823 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-03T17:26:16.823Z,1554312376.823 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-03T17:26:16.823Z,1554312376.823 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-03T17:26:16.824Z,1554312376.824 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-03T17:26:16.824Z,1554312376.824 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-03T17:26:16.824Z,1554312376.824 [Default:A.Wait] Running Loop=1
2019-04-03T17:26:16.824Z,1554312376.824 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:26:30.134Z,1554312390.134 [Default:A.Wait](INFO): Done Waiting.
2019-04-03T17:26:30.134Z,1554312390.134 [Default:A.Wait] Stopped
2019-04-03T17:26:30.134Z,1554312390.134 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:26:30.556Z,1554312390.556 [Default:CheckIn] Running Loop=1
2019-04-03T17:26:30.556Z,1554312390.556 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:26:30.556Z,1554312390.556 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:26:32.146Z,1554312392.146 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:26:32.542Z,1554312392.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172631.00,A,3648.17596,N,12147.28674,W,0.292,337.19,030419,,,A*7E
2019-04-03T17:26:32.544Z,1554312392.544 [NAL9602](INFO): GPS fix at 20190403T172631: (36.802933, -121.788112)
2019-04-03T17:26:32.599Z,1554312392.599 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:26:32.599Z,1554312392.599 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:26:38.869Z,1554312398.869 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190403T164135/Courier0025.lzma
2019-04-03T17:26:39.675Z,1554312399.675 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0025.lzma.bak
2019-04-03T17:26:39.676Z,1554312399.676 [DataOverHttps](INFO): SBD MOMSN=10402588
2019-04-03T17:26:53.195Z,1554312413.195 [DataOverHttps](INFO): Sending 1012 bytes from file Logs/20190403T164135/Express0026.lzma
2019-04-03T17:26:53.963Z,1554312413.963 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0026.lzma.bak
2019-04-03T17:26:53.964Z,1554312413.964 [DataOverHttps](INFO): SBD MOMSN=10402591
2019-04-03T17:26:56.005Z,1554312416.005 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:26:56.005Z,1554312416.005 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:26:56.005Z,1554312416.005 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:27:03.240Z,1554312423.240 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-04-03T17:27:03.314Z,1554312423.314 [NAL9602](FAULT): received: 
+CSQ:0

OK96, 2, 0, 0, 0

OK
2019-04-03T17:27:03.314Z,1554312423.314 [NAL9602] Data Fault, FailCount= 1
2019-04-03T17:27:03.314Z,1554312423.314 [NAL9602](ERROR): Data Fault
2019-04-03T17:27:03.418Z,1554312423.418 [CBIT](ERROR): Data Fault in component: NAL9602
2019-04-03T17:27:03.656Z,1554312423.656 [NAL9602](INFO): Powering down
2019-04-03T17:27:04.528Z,1554312424.528 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-03T17:27:04.528Z,1554312424.528 [NAL9602] No Fault, FailCount= 1
2019-04-03T17:27:33.944Z,1554312453.944 [NAL9602](INFO): Powering up NAL9602
2019-04-03T17:27:44.856Z,1554312464.856 [NAL9602](INFO): NAL9602 initialized
2019-04-03T17:28:15.960Z,1554312495.960 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T17:28:24.804Z,1554312504.804 [CommandLine](IMPORTANT): got command get Aanderaa_O2.loadAtStartup
2019-04-03T17:28:24.804Z,1554312504.804 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup 0 bool
2019-04-03T17:30:10.344Z,1554312610.344 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-04-03T17:30:10.344Z,1554312610.344 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19040310384877,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-04-03T17:31:15.366Z,1554312675.366 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2019-04-03T17:31:39.603Z,1554312699.603 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-04-03T17:31:39.604Z,1554312699.604 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19040310401777,35.0, -0.1,   0.0,10
2019-04-03T17:31:56.585Z,1554312716.585 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T17:31:56.585Z,1554312716.585 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:31:56.585Z,1554312716.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:31:56.585Z,1554312716.585 [Default:CheckIn:D] Running Loop=1
2019-04-03T17:31:56.985Z,1554312716.985 [Default:CheckIn:D] Stopped
2019-04-03T17:31:56.986Z,1554312716.986 [Default:CheckIn:E] Running Loop=1
2019-04-03T17:31:57.421Z,1554312717.421 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.669386 min 
2019-04-03T17:31:57.421Z,1554312717.421 [Default:CheckIn:E] Stopped
2019-04-03T17:31:57.421Z,1554312717.421 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T17:31:57.421Z,1554312717.421 [Default:CheckIn] Stopped
2019-04-03T17:31:57.426Z,1554312717.426 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:31:57.426Z,1554312717.426 [Default:CheckIn](INFO): Running loop #2
2019-04-03T17:31:57.426Z,1554312717.426 [Default:CheckIn] Running Loop=2
2019-04-03T17:31:57.426Z,1554312717.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:31:57.426Z,1554312717.426 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:31:58.990Z,1554312718.990 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:31:59.381Z,1554312719.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173158.00,A,3648.18759,N,12147.28866,W,3.130,342.68,030419,,,A*78
2019-04-03T17:31:59.384Z,1554312719.384 [NAL9602](INFO): GPS fix at 20190403T173158: (36.803126, -121.788144)
2019-04-03T17:31:59.417Z,1554312719.417 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:31:59.417Z,1554312719.417 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:32:01.046Z,1554312721.046 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-03T17:32:05.985Z,1554312725.985 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190403T164135/Courier0028.lzma
2019-04-03T17:32:06.791Z,1554312726.791 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0028.lzma.bak
2019-04-03T17:32:06.792Z,1554312726.792 [DataOverHttps](INFO): SBD MOMSN=10402635
2019-04-03T17:32:20.542Z,1554312740.542 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20190403T164135/Express0029.lzma
2019-04-03T17:32:21.348Z,1554312741.348 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0029.lzma.bak
2019-04-03T17:32:21.348Z,1554312741.348 [DataOverHttps](INFO): SBD MOMSN=10402638
2019-04-03T17:32:23.325Z,1554312743.325 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:32:23.326Z,1554312743.326 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:32:23.334Z,1554312743.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:32:31.748Z,1554312751.748 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T17:33:25.221Z,1554312805.221 [CommandLine](IMPORTANT): got command get GobyModem.loadAtStartup
2019-04-03T17:33:25.223Z,1554312805.223 [CommandLine](IMPORTANT): GobyModem.loadAtStartup 0 bool
2019-04-03T17:33:38.228Z,1554312818.228 [CommandLine](IMPORTANT): got command get Micromodem.loadAtStartup
2019-04-03T17:33:38.228Z,1554312818.228 [CommandLine](IMPORTANT): Micromodem.loadAtStartup 1 bool
2019-04-03T17:37:23.875Z,1554313043.875 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T17:37:23.875Z,1554313043.875 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:37:23.875Z,1554313043.875 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:37:23.876Z,1554313043.876 [Default:CheckIn:D] Running Loop=1
2019-04-03T17:37:24.300Z,1554313044.300 [Default:CheckIn:D] Stopped
2019-04-03T17:37:24.300Z,1554313044.300 [Default:CheckIn:E] Running Loop=1
2019-04-03T17:37:24.669Z,1554313044.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.124626 min 
2019-04-03T17:37:24.669Z,1554313044.669 [Default:CheckIn:E] Stopped
2019-04-03T17:37:24.669Z,1554313044.669 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T17:37:24.669Z,1554313044.669 [Default:CheckIn] Stopped
2019-04-03T17:37:24.669Z,1554313044.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:37:24.670Z,1554313044.670 [Default:CheckIn](INFO): Running loop #3
2019-04-03T17:37:24.670Z,1554313044.670 [Default:CheckIn] Running Loop=3
2019-04-03T17:37:24.670Z,1554313044.670 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:37:24.670Z,1554313044.670 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:37:26.286Z,1554313046.286 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:37:26.673Z,1554313046.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173726.00,A,3648.16190,N,12147.28550,W,0.622,342.68,030419,,,A*75
2019-04-03T17:37:26.685Z,1554313046.685 [NAL9602](INFO): GPS fix at 20190403T173726: (36.802698, -121.788092)
2019-04-03T17:37:26.713Z,1554313046.713 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:37:26.713Z,1554313046.713 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:37:40.465Z,1554313060.465 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190403T164135/Courier0031.lzma
2019-04-03T17:37:41.272Z,1554313061.272 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0031.lzma.bak
2019-04-03T17:37:41.272Z,1554313061.272 [DataOverHttps](INFO): SBD MOMSN=10402660
2019-04-03T17:37:54.246Z,1554313074.246 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20190403T164135/Express0032.lzma
2019-04-03T17:37:55.051Z,1554313075.051 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0032.lzma.bak
2019-04-03T17:37:55.052Z,1554313075.052 [DataOverHttps](INFO): SBD MOMSN=10402666
2019-04-03T17:37:57.009Z,1554313077.009 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:37:57.009Z,1554313077.009 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:37:57.009Z,1554313077.009 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:42:29.284Z,1554313349.284 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-03T17:42:57.595Z,1554313377.595 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T17:42:57.595Z,1554313377.595 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:42:57.595Z,1554313377.595 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:42:57.596Z,1554313377.596 [Default:CheckIn:D] Running Loop=1
2019-04-03T17:42:57.993Z,1554313377.993 [Default:CheckIn:D] Stopped
2019-04-03T17:42:57.993Z,1554313377.993 [Default:CheckIn:E] Running Loop=1
2019-04-03T17:42:58.405Z,1554313378.405 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.686180 min 
2019-04-03T17:42:58.405Z,1554313378.405 [Default:CheckIn:E] Stopped
2019-04-03T17:42:58.405Z,1554313378.405 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T17:42:58.406Z,1554313378.406 [Default:CheckIn] Stopped
2019-04-03T17:42:58.406Z,1554313378.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:42:58.406Z,1554313378.406 [Default:CheckIn](INFO): Running loop #4
2019-04-03T17:42:58.406Z,1554313378.406 [Default:CheckIn] Running Loop=4
2019-04-03T17:42:58.406Z,1554313378.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:42:58.406Z,1554313378.406 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:42:59.998Z,1554313379.998 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:43:00.402Z,1554313380.402 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174259.00,A,3648.16355,N,12147.28394,W,0.292,0.00,030419,,,A*7E
2019-04-03T17:43:00.404Z,1554313380.404 [NAL9602](INFO): GPS fix at 20190403T174259: (36.802726, -121.788066)
2019-04-03T17:43:00.437Z,1554313380.437 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:43:00.438Z,1554313380.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:43:07.154Z,1554313387.154 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190403T164135/Courier0034.lzma
2019-04-03T17:43:07.955Z,1554313387.955 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0034.lzma.bak
2019-04-03T17:43:07.956Z,1554313387.956 [DataOverHttps](INFO): SBD MOMSN=10402681
2019-04-03T17:43:20.886Z,1554313400.886 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20190403T164135/Express0035.lzma
2019-04-03T17:43:21.691Z,1554313401.691 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0035.lzma.bak
2019-04-03T17:43:21.692Z,1554313401.692 [DataOverHttps](INFO): SBD MOMSN=10402684
2019-04-03T17:43:23.499Z,1554313403.499 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:43:23.500Z,1554313403.500 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:43:23.500Z,1554313403.500 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:43:32.303Z,1554313412.303 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T17:47:14.538Z,1554313634.538 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-04-03T17:47:14.538Z,1554313634.538 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-04-03T17:48:11.509Z,1554313691.509 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-04-03T17:48:11.509Z,1554313691.509 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-04-03T17:48:24.023Z,1554313704.023 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-03T17:48:24.023Z,1554313704.023 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:48:24.023Z,1554313704.023 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:48:24.024Z,1554313704.024 [Default:CheckIn:D] Running Loop=1
2019-04-03T17:48:24.431Z,1554313704.431 [Default:CheckIn:D] Stopped
2019-04-03T17:48:24.431Z,1554313704.431 [Default:CheckIn:E] Running Loop=1
2019-04-03T17:48:24.837Z,1554313704.837 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.126807 min 
2019-04-03T17:48:24.837Z,1554313704.837 [Default:CheckIn:E] Stopped
2019-04-03T17:48:24.837Z,1554313704.837 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-03T17:48:24.837Z,1554313704.837 [Default:CheckIn] Stopped
2019-04-03T17:48:24.838Z,1554313704.838 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:48:24.838Z,1554313704.838 [Default:CheckIn](INFO): Running loop #5
2019-04-03T17:48:24.838Z,1554313704.838 [Default:CheckIn] Running Loop=5
2019-04-03T17:48:24.838Z,1554313704.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-03T17:48:24.838Z,1554313704.838 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-03T17:48:26.430Z,1554313706.430 [NAL9602](DEBUG): Fix Requested
2019-04-03T17:48:26.829Z,1554313706.829 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174826.00,A,3648.15907,N,12147.28718,W,0.311,0.00,030419,,,A*78
2019-04-03T17:48:26.832Z,1554313706.832 [NAL9602](INFO): GPS fix at 20190403T174826: (36.802651, -121.788120)
2019-04-03T17:48:26.855Z,1554313706.855 [Default:CheckIn:Read_GPS] Stopped
2019-04-03T17:48:26.855Z,1554313706.855 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-03T17:48:57.544Z,1554313737.544 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-04-03T17:48:57.618Z,1554313737.618 [NAL9602](FAULT): received: 
+CSQ:0

OK96, 2, 0, 0, 0

OK
2019-04-03T17:48:57.618Z,1554313737.618 [NAL9602] Data Fault, FailCount= 1
2019-04-03T17:48:57.618Z,1554313737.618 [NAL9602](ERROR): Data Fault
2019-04-03T17:48:57.670Z,1554313737.670 [CBIT](ERROR): Data Fault in component: NAL9602
2019-04-03T17:48:57.936Z,1554313737.936 [NAL9602](INFO): Powering down
2019-04-03T17:48:58.818Z,1554313738.818 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-03T17:48:58.818Z,1554313738.818 [NAL9602] No Fault, FailCount= 1
2019-04-03T17:49:18.377Z,1554313758.377 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190403T164135/Courier0037.lzma
2019-04-03T17:49:19.187Z,1554313759.187 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Courier0037.lzma.bak
2019-04-03T17:49:19.188Z,1554313759.188 [DataOverHttps](INFO): SBD MOMSN=10402974
2019-04-03T17:49:28.236Z,1554313768.236 [NAL9602](INFO): Powering up NAL9602
2019-04-03T17:49:31.978Z,1554313771.978 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190403T164135/Express0038.lzma
2019-04-03T17:49:32.784Z,1554313772.784 [DataOverHttps](INFO): Moved sent file to Logs/20190403T164135/Express0038.lzma.bak
2019-04-03T17:49:32.784Z,1554313772.784 [DataOverHttps](INFO): SBD MOMSN=10402982
2019-04-03T17:49:34.743Z,1554313774.743 [Default:CheckIn:Read_Iridium] Stopped
2019-04-03T17:49:34.744Z,1554313774.744 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-03T17:49:34.744Z,1554313774.744 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-03T17:49:35.545Z,1554313775.545 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-04-03T17:49:39.144Z,1554313779.144 [NAL9602](INFO): NAL9602 initialized
2019-04-03T17:50:10.255Z,1554313810.255 [NAL9602](INFO): Not Powering down - fast GPS
2019-04-03T17:51:38.361Z,1554313898.361 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-04-03T17:52:05.152Z,1554313925.152 [CommandLine](IMPORTANT): got command restart application
2019-04-03T17:52:06.162Z,1554313926.162 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-03T17:52:06.162Z,1554313926.162 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.190Z,1554313926.190 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-03T17:52:06.190Z,1554313926.190 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.191Z,1554313926.191 [CommandLine](INFO): Join timeout helper Thread ID is 1305
2019-04-03T17:52:06.198Z,1554313926.198 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-03T17:52:06.198Z,1554313926.198 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.199Z,1554313926.199 [NavChartDb](INFO): Join timeout helper Thread ID is 1306
2019-04-03T17:52:06.366Z,1554313926.366 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-03T17:52:06.366Z,1554313926.366 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.370Z,1554313926.370 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-04-03T17:52:06.370Z,1554313926.370 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.371Z,1554313926.371 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1307
2019-04-03T17:52:06.374Z,1554313926.374 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-03T17:52:06.374Z,1554313926.374 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-04-03T17:52:06.375Z,1554313926.375 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.390Z,1554313926.390 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-04-03T17:52:06.390Z,1554313926.390 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.390Z,1554313926.390 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1308
2019-04-03T17:52:06.434Z,1554313926.434 [CTD_NeilBrown](INFO): Powering down
2019-04-03T17:52:06.446Z,1554313926.446 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-03T17:52:06.446Z,1554313926.446 [CTD_NeilBrown](INFO): Powering down
2019-04-03T17:52:06.458Z,1554313926.458 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.459Z,1554313926.459 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-03T17:52:06.459Z,1554313926.459 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.459Z,1554313926.459 [Radio_Surface](INFO): Join timeout helper Thread ID is 1309
2019-04-03T17:52:06.774Z,1554313926.774 [Radio_Surface](INFO): Powering down
2019-04-03T17:52:06.775Z,1554313926.775 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-03T17:52:06.775Z,1554313926.775 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.787Z,1554313926.787 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-03T17:52:06.787Z,1554313926.787 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:06.787Z,1554313926.787 [DataOverHttps](INFO): Join timeout helper Thread ID is 1310
2019-04-03T17:52:07.269Z,1554313927.269 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-03T17:52:07.275Z,1554313927.275 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.295Z,1554313927.295 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-03T17:52:07.295Z,1554313927.295 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.295Z,1554313927.295 [logger](INFO): Join timeout helper Thread ID is 1311
2019-04-03T17:52:07.374Z,1554313927.374 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-03T17:52:07.374Z,1554313927.374 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.395Z,1554313927.395 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-03T17:52:07.395Z,1554313927.395 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.395Z,1554313927.395 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-03T17:52:07.395Z,1554313927.395 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.395Z,1554313927.395 [controlThread](INFO): Join timeout helper Thread ID is 1312
2019-04-03T17:52:07.398Z,1554313927.398 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-03T17:52:07.398Z,1554313927.398 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-03T17:52:07.399Z,1554313927.399 [AHRS_M2](INFO): Powering down
2019-04-03T17:52:07.471Z,1554313927.471 [DUSBL_Hydroid](INFO): Powering down
2019-04-03T17:52:07.562Z,1554313927.562 [Micromodem](INFO): Powering down
2019-04-03T17:52:07.658Z,1554313927.658 [NAL9602](INFO): Powering down
2019-04-03T17:52:07.730Z,1554313927.730 [RDI_Pathfinder](INFO): Powering down
2019-04-03T17:52:07.731Z,1554313927.731 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-03T17:52:07.732Z,1554313927.732 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-03T17:52:07.733Z,1554313927.733 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-03T17:52:07.733Z,1554313927.733 [MissionManager](INFO): Uninitializing Mission Default
2019-04-03T17:52:07.733Z,1554313927.733 [Default] Stopped
2019-04-03T17:52:07.734Z,1554313927.734 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-03T17:52:07.734Z,1554313927.734 [Default:B.GoToSurface] Stopped
2019-04-03T17:52:07.734Z,1554313927.734 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-03T17:52:07.734Z,1554313927.734 [Default:CheckIn] Stopped
2019-04-03T17:52:07.734Z,1554313927.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-03T17:52:07.734Z,1554313927.734 [Default:CheckIn:C.Wait] Stopped
2019-04-03T17:52:07.734Z,1554313927.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-03T17:52:07.736Z,1554313927.736 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-03T17:52:07.737Z,1554313927.737 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-03T17:52:07.737Z,1554313927.737 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-03T17:52:07.737Z,1554313927.737 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-03T17:52:07.738Z,1554313927.738 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-03T17:52:07.738Z,1554313927.738 [BuoyancyServo](INFO): Powering down
2019-04-03T17:52:07.750Z,1554313927.750 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-03T17:52:07.750Z,1554313927.750 [ElevatorServo](INFO): Powering down
2019-04-03T17:52:07.751Z,1554313927.751 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-03T17:52:07.751Z,1554313927.751 [MassServo](INFO): Powering down
2019-04-03T17:52:07.752Z,1554313927.752 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-03T17:52:07.752Z,1554313927.752 [RudderServo](INFO): Powering down
2019-04-03T17:52:07.753Z,1554313927.753 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-03T17:52:07.753Z,1554313927.753 [ThrusterServo](INFO): Powering down
2019-04-03T17:52:07.753Z,1554313927.753 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-03T17:52:07.754Z,1554313927.754 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-03T17:52:07.754Z,1554313927.754 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-03T17:52:07.754Z,1554313927.754 [CBIT](DEBUG): Powering off loads.
2019-04-03T17:52:07.766Z,1554313927.766 [CBIT](DEBUG): Disabling WDT.
2019-04-03T17:52:07.778Z,1554313927.778 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-03T17:52:07.779Z,1554313927.779 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.838Z,1554313927.838 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.847Z,1554313927.847 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.887Z,1554313927.887 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.889Z,1554313927.889 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:07.944Z,1554313927.944 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-03T17:52:08.016Z,1554313928.016 [logger ThreadHandler](INFO): Thread cancelled.