2021-04-07T16:37:29.197Z,1617813449.197 [Supervisor](DEBUG): Initializing supervisor.
2021-04-07T16:37:29.201Z,1617813449.201 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-04-07T16:37:29.201Z,1617813449.201 [SyncHandler](INFO): Protected caller Thread ID is 824
2021-04-07T16:37:29.202Z,1617813449.202 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-04-07T16:37:29.203Z,1617813449.203 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-04-07T16:37:29.203Z,1617813449.203 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 825
2021-04-07T16:37:29.207Z,1617813449.207 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-04-07T16:37:29.226Z,1617813449.226 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-04-07T16:37:29.227Z,1617813449.227 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-04-07T16:37:29.227Z,1617813449.227 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 826
2021-04-07T16:37:29.229Z,1617813449.229 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-04-07T16:37:29.230Z,1617813449.230 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-04-07T16:37:29.230Z,1617813449.230 [logger ThreadHandler](INFO): Protected caller Thread ID is 827
2021-04-07T16:37:29.234Z,1617813449.234 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-04-07T16:37:29.234Z,1617813449.234 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-04-07T16:37:29.238Z,1617813449.238 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-04-07T16:37:29.622Z,1617813449.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-04-07T16:37:29.623Z,1617813449.623 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-04-07T16:37:30.228Z,1617813450.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-04-07T16:37:30.229Z,1617813450.229 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-04-07T16:37:30.329Z,1617813450.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-04-07T16:37:30.331Z,1617813450.331 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-04-07T16:37:30.853Z,1617813450.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-04-07T16:37:30.855Z,1617813450.855 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-04-07T16:37:30.952Z,1617813450.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-04-07T16:37:30.954Z,1617813450.954 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-04-07T16:37:31.036Z,1617813451.036 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-04-07T16:37:31.390Z,1617813451.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-04-07T16:37:31.390Z,1617813451.390 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-04-07T16:37:31.538Z,1617813451.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-04-07T16:37:31.540Z,1617813451.540 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-04-07T16:37:31.665Z,1617813451.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-04-07T16:37:31.666Z,1617813451.666 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-04-07T16:37:31.893Z,1617813451.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-04-07T16:37:31.894Z,1617813451.894 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-04-07T16:37:32.358Z,1617813452.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-04-07T16:37:32.359Z,1617813452.359 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-04-07T16:37:33.304Z,1617813453.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-04-07T16:37:33.306Z,1617813453.306 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-04-07T16:37:33.428Z,1617813453.428 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-04-07T16:37:33.620Z,1617813453.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-04-07T16:37:33.622Z,1617813453.622 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-04-07T16:37:33.832Z,1617813453.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-04-07T16:37:33.834Z,1617813453.834 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-04-07T16:37:34.079Z,1617813454.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-04-07T16:37:34.082Z,1617813454.082 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2021-04-07T16:37:34.086Z,1617813454.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2021-04-07T16:37:34.183Z,1617813454.183 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2021-04-07T16:37:34.327Z,1617813454.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2021-04-07T16:37:34.414Z,1617813454.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2021-04-07T16:37:34.498Z,1617813454.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2021-04-07T16:37:34.606Z,1617813454.606 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2021-04-07T16:37:34.787Z,1617813454.787 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2021-04-07T16:37:35.040Z,1617813455.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-04-07T16:37:35.042Z,1617813455.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2021-04-07T16:37:35.162Z,1617813455.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2021-04-07T16:37:35.258Z,1617813455.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2021-04-07T16:37:35.364Z,1617813455.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2021-04-07T16:37:35.458Z,1617813455.458 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-04-07T16:37:35.469Z,1617813455.469 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-04-07T16:37:35.576Z,1617813455.576 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-04-07T16:37:35.578Z,1617813455.578 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-04-07T16:37:35.594Z,1617813455.594 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-04-07T16:37:35.595Z,1617813455.595 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-04-07T16:37:35.651Z,1617813455.651 [DepthRateCalculator] Loaded
2021-04-07T16:37:35.651Z,1617813455.651 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-04-07T16:37:35.656Z,1617813455.656 [PitchRateCalculator] Loaded
2021-04-07T16:37:35.657Z,1617813455.657 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-04-07T16:37:35.667Z,1617813455.667 [SpeedCalculator] Loaded
2021-04-07T16:37:35.667Z,1617813455.667 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-04-07T16:37:35.680Z,1617813455.680 [TempGradientCalculator] Loaded
2021-04-07T16:37:35.680Z,1617813455.680 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-04-07T16:37:35.685Z,1617813455.685 [YawRateCalculator] Loaded
2021-04-07T16:37:35.685Z,1617813455.685 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-04-07T16:37:35.704Z,1617813455.704 [ElevatorOffsetCalculator] Loaded
2021-04-07T16:37:35.705Z,1617813455.705 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-04-07T16:37:35.705Z,1617813455.705 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-04-07T16:37:35.706Z,1617813455.706 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-04-07T16:37:35.775Z,1617813455.775 [VerticalControl](DEBUG): Construct VerticalControl.
2021-04-07T16:37:35.831Z,1617813455.831 [VerticalControl] Loaded
2021-04-07T16:37:35.831Z,1617813455.831 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-04-07T16:37:35.834Z,1617813455.834 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-04-07T16:37:35.870Z,1617813455.870 [HorizontalControl] Loaded
2021-04-07T16:37:35.871Z,1617813455.871 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-04-07T16:37:35.873Z,1617813455.873 [SpeedControl](DEBUG): Construct SpeedControl.
2021-04-07T16:37:35.876Z,1617813455.876 [SpeedControl] Loaded
2021-04-07T16:37:35.876Z,1617813455.876 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-04-07T16:37:35.878Z,1617813455.878 [LoopControl](DEBUG): Construct LoopControl.
2021-04-07T16:37:35.879Z,1617813455.879 [LoopControl] Loaded
2021-04-07T16:37:35.879Z,1617813455.879 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-04-07T16:37:35.879Z,1617813455.879 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-04-07T16:37:35.880Z,1617813455.880 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-04-07T16:37:35.975Z,1617813455.975 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-04-07T16:37:35.976Z,1617813455.976 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-04-07T16:37:35.988Z,1617813455.988 [NavChart] Loaded
2021-04-07T16:37:35.988Z,1617813455.988 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-04-07T16:37:35.994Z,1617813455.994 [UniversalFixResidualReporter] Loaded
2021-04-07T16:37:35.994Z,1617813455.994 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-04-07T16:37:35.995Z,1617813455.995 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-04-07T16:37:35.996Z,1617813455.996 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-04-07T16:37:36.121Z,1617813456.121 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-04-07T16:37:36.122Z,1617813456.122 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-04-07T16:37:36.974Z,1617813456.974 [AHRS_M2] Loaded
2021-04-07T16:37:36.974Z,1617813456.974 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-04-07T16:37:37.729Z,1617813457.729 [BPC1] Loaded
2021-04-07T16:37:37.729Z,1617813457.729 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-04-07T16:37:38.226Z,1617813458.226 [DataOverHttps] Loaded
2021-04-07T16:37:38.226Z,1617813458.226 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-04-07T16:37:38.268Z,1617813458.268 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408674E0
2021-04-07T16:37:38.268Z,1617813458.268 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 909
2021-04-07T16:37:38.287Z,1617813458.287 [Depth_Keller] Loaded
2021-04-07T16:37:38.287Z,1617813458.287 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-04-07T16:37:38.292Z,1617813458.292 [DropWeight] Loaded
2021-04-07T16:37:38.292Z,1617813458.292 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-04-07T16:37:38.428Z,1617813458.428 [DVL_micro] Loaded
2021-04-07T16:37:38.428Z,1617813458.428 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2021-04-07T16:37:38.519Z,1617813458.519 [NAL9602] Loaded
2021-04-07T16:37:38.519Z,1617813458.519 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-04-07T16:37:38.550Z,1617813458.550 [Onboard] Loaded
2021-04-07T16:37:38.550Z,1617813458.550 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-04-07T16:37:38.551Z,1617813458.551 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408974E0
2021-04-07T16:37:38.551Z,1617813458.551 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 910
2021-04-07T16:37:38.564Z,1617813458.564 [Radio_Surface] Loaded
2021-04-07T16:37:38.564Z,1617813458.564 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-04-07T16:37:38.565Z,1617813458.565 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0
2021-04-07T16:37:38.566Z,1617813458.566 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 911
2021-04-07T16:37:38.837Z,1617813458.837 [DAT] Loaded
2021-04-07T16:37:38.838Z,1617813458.838 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-04-07T16:37:38.838Z,1617813458.838 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-04-07T16:37:38.839Z,1617813458.839 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-04-07T16:37:38.976Z,1617813458.976 [BuoyancyServo] Loaded
2021-04-07T16:37:38.976Z,1617813458.976 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-04-07T16:37:38.995Z,1617813458.995 [ElevatorServo] Loaded
2021-04-07T16:37:38.995Z,1617813458.995 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-04-07T16:37:39.013Z,1617813459.013 [MassServo] Loaded
2021-04-07T16:37:39.014Z,1617813459.014 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-04-07T16:37:39.031Z,1617813459.031 [RudderServo] Loaded
2021-04-07T16:37:39.031Z,1617813459.031 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-04-07T16:37:39.048Z,1617813459.048 [ThrusterServo] Loaded
2021-04-07T16:37:39.048Z,1617813459.048 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-04-07T16:37:39.049Z,1617813459.049 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-04-07T16:37:39.049Z,1617813459.049 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-04-07T16:37:39.126Z,1617813459.126 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-04-07T16:37:39.126Z,1617813459.126 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-04-07T16:37:39.168Z,1617813459.168 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-04-07T16:37:39.169Z,1617813459.169 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-04-07T16:37:39.526Z,1617813459.526 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-04-07T16:37:39.526Z,1617813459.526 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-04-07T16:37:39.787Z,1617813459.787 [CTD_Seabird] Loaded
2021-04-07T16:37:39.788Z,1617813459.788 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-04-07T16:37:39.789Z,1617813459.789 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0
2021-04-07T16:37:39.789Z,1617813459.789 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 913
2021-04-07T16:37:39.821Z,1617813459.821 [ESPComponent] Loaded
2021-04-07T16:37:39.821Z,1617813459.821 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-04-07T16:37:39.840Z,1617813459.840 [PAR_Licor] Loaded
2021-04-07T16:37:39.840Z,1617813459.840 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-04-07T16:37:39.871Z,1617813459.871 [WetLabsBB2FL] Loaded
2021-04-07T16:37:39.871Z,1617813459.871 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-04-07T16:37:39.872Z,1617813459.872 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2021-04-07T16:37:39.872Z,1617813459.872 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 914
2021-04-07T16:37:39.873Z,1617813459.873 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-04-07T16:37:39.874Z,1617813459.874 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-04-07T16:37:40.039Z,1617813460.039 [SBIT](DEBUG): Construct Startup Built In Test.
2021-04-07T16:37:40.048Z,1617813460.048 [SBIT] Loaded
2021-04-07T16:37:40.048Z,1617813460.048 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-04-07T16:37:40.051Z,1617813460.051 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-04-07T16:37:40.064Z,1617813460.064 [IBIT] Loaded
2021-04-07T16:37:40.064Z,1617813460.064 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-04-07T16:37:40.070Z,1617813460.070 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-04-07T16:37:40.169Z,1617813460.169 [CBIT] Loaded
2021-04-07T16:37:40.169Z,1617813460.169 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-04-07T16:37:40.170Z,1617813460.170 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-04-07T16:37:40.175Z,1617813460.175 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-04-07T16:37:40.178Z,1617813460.178 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-04-07T16:37:40.188Z,1617813460.188 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-04-07T16:37:40.189Z,1617813460.189 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0
2021-04-07T16:37:40.190Z,1617813460.190 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 915
2021-04-07T16:37:40.194Z,1617813460.194 [Supervisor](INFO): Main Thread ID is 820
2021-04-07T16:37:40.194Z,1617813460.194 [Supervisor](DEBUG): Running supervisor.
2021-04-07T16:37:40.195Z,1617813460.195 [CommandLine ThreadHandler](INFO): Handler Thread ID is 916
2021-04-07T16:37:40.197Z,1617813460.197 [controlThread ThreadHandler](INFO): Handler Thread ID is 917
2021-04-07T16:37:40.198Z,1617813460.198 [controlThread](DEBUG): Initializing ControlThread
2021-04-07T16:37:40.198Z,1617813460.198 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-04-07T16:37:40.199Z,1617813460.199 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-04-07T16:37:40.199Z,1617813460.199 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-04-07T16:37:40.199Z,1617813460.199 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-04-07T16:37:40.200Z,1617813460.200 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-04-07T16:37:40.200Z,1617813460.200 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-04-07T16:37:40.201Z,1617813460.201 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-04-07T16:37:40.203Z,1617813460.203 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-04-07T16:37:40.203Z,1617813460.203 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-04-07T16:37:40.204Z,1617813460.204 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-04-07T16:37:40.205Z,1617813460.205 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-04-07T16:37:40.205Z,1617813460.205 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-04-07T16:37:40.213Z,1617813460.213 [SBIT](INFO): Initialize SBIT Component.
2021-04-07T16:37:40.213Z,1617813460.213 [SBIT](IMPORTANT): git: 2021-04-07
2021-04-07T16:37:40.214Z,1617813460.214 [SBIT](INFO): git hash: 398ebe7363af9af2adca7b90347fff32b8d02d79
2021-04-07T16:37:40.214Z,1617813460.214 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-04-07T16:37:40.214Z,1617813460.214 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2021-04-07T16:37:40.215Z,1617813460.215 [SBIT](INFO): Beginning SBIT in 21.000000 seconds.
2021-04-07T16:37:40.216Z,1617813460.216 [IBIT](INFO): Initialize IBIT Component.
2021-04-07T16:37:40.217Z,1617813460.217 [CBIT](DEBUG): Initialize CBIT Component.
2021-04-07T16:37:40.218Z,1617813460.218 [logger ThreadHandler](INFO): Handler Thread ID is 918
2021-04-07T16:37:40.229Z,1617813460.229 [CBIT](DEBUG): Initialized mux pins.
2021-04-07T16:37:40.229Z,1617813460.229 [CBIT](DEBUG): Initializing the watchdog timer.
2021-04-07T16:37:40.237Z,1617813460.237 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 919
2021-04-07T16:37:40.238Z,1617813460.238 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-04-07T16:37:40.253Z,1617813460.253 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-04-07T16:37:40.253Z,1617813460.253 [CBIT](DEBUG): Initializing heartbeat.
2021-04-07T16:37:40.285Z,1617813460.285 [Onboard ThreadHandler](INFO): Handler Thread ID is 920
2021-04-07T16:37:40.315Z,1617813460.315 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 921
2021-04-07T16:37:40.325Z,1617813460.325 [CBIT](DEBUG): Deactivating GF circuits.
2021-04-07T16:37:40.325Z,1617813460.325 [CBIT](DEBUG): Deactivating emergency mode.
2021-04-07T16:37:40.325Z,1617813460.325 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 922
2021-04-07T16:37:40.326Z,1617813460.326 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-07T16:37:40.330Z,1617813460.330 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 924
2021-04-07T16:37:40.333Z,1617813460.333 [WetLabsBB2FL](INFO): Powering up
2021-04-07T16:37:40.334Z,1617813460.334 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 926
2021-04-07T16:37:40.346Z,1617813460.346 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-04-07T16:37:40.346Z,1617813460.346 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-04-07T16:37:40.346Z,1617813460.346 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-04-07T16:37:40.347Z,1617813460.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-04-07T16:37:40.347Z,1617813460.347 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-04-07T16:37:40.347Z,1617813460.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-04-07T16:37:40.347Z,1617813460.347 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-04-07T16:37:40.347Z,1617813460.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-04-07T16:37:40.347Z,1617813460.347 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-04-07T16:37:40.347Z,1617813460.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-04-07T16:37:40.348Z,1617813460.348 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-04-07T16:37:40.348Z,1617813460.348 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-04-07T16:37:40.348Z,1617813460.348 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-04-07T16:37:40.348Z,1617813460.348 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-04-07T16:37:40.348Z,1617813460.348 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-04-07T16:37:40.349Z,1617813460.349 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-04-07T16:37:40.361Z,1617813460.361 [CBIT](DEBUG): Backplane powered.
2021-04-07T16:37:40.362Z,1617813460.362 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-04-07T16:37:40.388Z,1617813460.388 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-07T16:37:40.433Z,1617813460.433 [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>


2021-04-07T16:37:40.433Z,1617813460.433 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-04-07T16:37:40.508Z,1617813460.508 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-04-07T16:37:40.510Z,1617813460.510 [Default:A.Wait](DEBUG): Construct Wait.
2021-04-07T16:37:40.512Z,1617813460.512 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-07T16:37:40.539Z,1617813460.539 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-04-07T16:37:40.542Z,1617813460.542 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-04-07T16:37:40.563Z,1617813460.563 [Default:E.Execute](DEBUG): Construct Execute.
2021-04-07T16:37:40.566Z,1617813460.566 [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>


2021-04-07T16:37:40.581Z,1617813460.581 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-04-07T16:37:40.593Z,1617813460.593 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-04-07T16:37:40.717Z,1617813460.717 [Radio_Surface](INFO): Powering up
2021-04-07T16:37:40.804Z,1617813460.804 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-07T16:37:40.804Z,1617813460.804 [DAT](INFO): Powering up
2021-04-07T16:37:40.804Z,1617813460.804 [DAT](DEBUG): Initializing DAT.
2021-04-07T16:37:40.890Z,1617813460.890 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-04-07T16:37:40.893Z,1617813460.893 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-07T16:37:40.921Z,1617813460.921 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-04-07T16:37:40.923Z,1617813460.923 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-04-07T16:37:40.933Z,1617813460.933 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-04-07T16:37:40.934Z,1617813460.934 [MassServo](DEBUG): Initializing EZServoServo.
2021-04-07T16:37:40.945Z,1617813460.945 [MassServo](DEBUG): Initializing MassServo.
2021-04-07T16:37:40.946Z,1617813460.946 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-07T16:37:40.957Z,1617813460.957 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-07T16:37:40.958Z,1617813460.958 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-04-07T16:37:40.969Z,1617813460.969 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-04-07T16:37:41.018Z,1617813461.018 [Depth_Keller](ERROR): Pressure reading out of range: 1829.799438 decibar
2021-04-07T16:37:42.945Z,1617813462.945 [WetLabsBB2FL](INFO): Powering down
2021-04-07T16:37:47.357Z,1617813467.357 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-07T16:37:55.138Z,1617813475.138 [DAT](INFO): commRate: 800
2021-04-07T16:37:57.155Z,1617813477.155 [DAT](INFO): entering command mode
2021-04-07T16:37:57.559Z,1617813477.559 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:37:57.967Z,1617813477.967 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:37:58.375Z,1617813478.375 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:37:58.771Z,1617813478.771 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:37:59.175Z,1617813479.175 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:37:59.583Z,1617813479.583 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:37:59.983Z,1617813479.983 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:00.399Z,1617813480.399 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:00.800Z,1617813480.800 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:01.199Z,1617813481.199 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:01.599Z,1617813481.599 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:01.635Z,1617813481.635 [SBIT](IMPORTANT): Beginning Startup BIT
2021-04-07T16:38:01.640Z,1617813481.640 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-07T16:38:02.003Z,1617813482.003 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:02.407Z,1617813482.407 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:02.811Z,1617813482.811 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:03.230Z,1617813483.230 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:03.619Z,1617813483.619 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:04.023Z,1617813484.023 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:04.430Z,1617813484.430 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:04.843Z,1617813484.843 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:05.235Z,1617813485.235 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:05.639Z,1617813485.639 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:06.053Z,1617813486.053 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:06.459Z,1617813486.459 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:06.851Z,1617813486.851 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:07.255Z,1617813487.255 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:07.659Z,1617813487.659 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:08.068Z,1617813488.068 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:08.498Z,1617813488.498 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:08.871Z,1617813488.871 [NAL9602](INFO): Powering up NAL9602
2021-04-07T16:38:08.872Z,1617813488.872 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:09.274Z,1617813489.274 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:09.683Z,1617813489.683 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:10.083Z,1617813490.083 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:10.499Z,1617813490.499 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:10.900Z,1617813490.900 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:11.295Z,1617813491.295 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:11.699Z,1617813491.699 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:12.103Z,1617813492.103 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:12.507Z,1617813492.507 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-07T16:38:12.507Z,1617813492.507 [DAT](FAULT): failed to enter command mode
2021-04-07T16:38:12.585Z,1617813492.585 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007670
CHAN A1 (24V): 0.012866
CHAN A2 (12V): 0.004961
CHAN A3 (5V): 0.000067
CHAN B0 (3.3V): -0.001206
CHAN B1 (3.15aV): -0.001489
CHAN B2 (3.15bV): -0.001541
CHAN B3 (GND): -0.003033
OPEN: -0.003984
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-07T16:38:12.911Z,1617813492.911 [DAT](INFO): entering command mode
2021-04-07T16:38:13.320Z,1617813493.320 [DAT](INFO): setting verbose to 3
2021-04-07T16:38:13.739Z,1617813493.739 [DAT](INFO): set verbose to 3
2021-04-07T16:38:13.739Z,1617813493.739 [DAT](INFO): setting DatVerbose to 27440
2021-04-07T16:38:14.124Z,1617813494.124 [DAT](INFO): set DatVerbose to 27440
2021-04-07T16:38:14.124Z,1617813494.124 [DAT](INFO): setting transmit power to 8
2021-04-07T16:38:14.528Z,1617813494.528 [DAT](INFO): set transmit power to 8
2021-04-07T16:38:14.529Z,1617813494.529 [DAT](INFO): setting local address to 8
2021-04-07T16:38:14.937Z,1617813494.937 [DAT](INFO): set local address to 8
2021-04-07T16:38:19.791Z,1617813499.791 [NAL9602](INFO): NAL9602 initialized
2021-04-07T16:38:55.752Z,1617813535.752 [SBIT](IMPORTANT): SBIT PASSED
2021-04-07T16:38:55.753Z,1617813535.753 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-07T16:38:55.754Z,1617813535.754 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-04-07T16:38:55.754Z,1617813535.754 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=211.883133 cubic_centimeter;

2021-04-07T16:38:55.754Z,1617813535.754 [SBIT](IMPORTANT): VerticalControl.massDefault=0.444172 millimeter;

2021-04-07T16:38:56.155Z,1617813536.155 [MissionManager](IMPORTANT): Started mission Startup
2021-04-07T16:38:56.155Z,1617813536.155 [Startup] Running Loop=1
2021-04-07T16:38:56.155Z,1617813536.155 [Startup](DEBUG): Aggregate::initialize Startup
2021-04-07T16:38:56.155Z,1617813536.155 [Startup:A.GoToSurface] Running Loop=1
2021-04-07T16:38:56.156Z,1617813536.156 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-07T16:38:56.156Z,1617813536.156 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-07T16:38:56.157Z,1617813536.157 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-07T16:38:56.157Z,1617813536.157 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-07T16:38:56.158Z,1617813536.158 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-07T16:38:56.158Z,1617813536.158 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-07T16:38:56.159Z,1617813536.159 [Startup:StartupSatComms] Running Loop=1
2021-04-07T16:38:56.159Z,1617813536.159 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-04-07T16:38:56.160Z,1617813536.160 [Startup:StartupSatComms:A] Running Loop=1
2021-04-07T16:38:56.567Z,1617813536.567 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-04-07T16:39:56.347Z,1617813596.347 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-07T16:38:56.2Z
2021-04-07T16:39:56.347Z,1617813596.347 [Startup:StartupSatComms:A] Stopped
2021-04-07T16:39:56.347Z,1617813596.347 [Startup:StartupSatComms:B] Running Loop=1
2021-04-07T16:39:56.773Z,1617813596.773 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-07T16:40:04.219Z,1617813604.219 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005069
2021-04-07T16:40:10.491Z,1617813610.491 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2021-04-07T16:40:11.396Z,1617813611.396 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210404T224912/Courier1359.lzma
2021-04-07T16:40:12.399Z,1617813612.399 [DataOverHttps](INFO): Moved sent file to Logs/20210404T224912/Courier1359.lzma.bak
2021-04-07T16:40:12.399Z,1617813612.399 [DataOverHttps](INFO): SBD MOMSN=15549823
2021-04-07T16:40:28.008Z,1617813628.008 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210407T163729/Courier0000.lzma
2021-04-07T16:40:29.010Z,1617813629.010 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0000.lzma.bak
2021-04-07T16:40:29.011Z,1617813629.011 [DataOverHttps](INFO): SBD MOMSN=15549860
2021-04-07T16:40:40.405Z,1617813640.405 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-04-07T16:40:40.405Z,1617813640.405 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-07T16:40:40.419Z,1617813640.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-07T16:40:40.932Z,1617813640.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-07T16:40:40.932Z,1617813640.932 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-04-07T16:40:44.692Z,1617813644.692 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210404T224912/Express1360.lzma
2021-04-07T16:40:45.695Z,1617813645.695 [DataOverHttps](INFO): Moved sent file to Logs/20210404T224912/Express1360.lzma.bak
2021-04-07T16:40:45.695Z,1617813645.695 [DataOverHttps](INFO): SBD MOMSN=15549863
2021-04-07T16:40:56.578Z,1617813656.578 [Startup:StartupSatComms:B](INFO): Timed out from 2021-04-07T16:39:56.3Z
2021-04-07T16:40:56.579Z,1617813656.579 [Startup:StartupSatComms:B] Stopped
2021-04-07T16:40:56.579Z,1617813656.579 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-04-07T16:40:56.579Z,1617813656.579 [Startup:StartupSatComms] Stopped
2021-04-07T16:40:56.579Z,1617813656.579 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-04-07T16:40:56.580Z,1617813656.580 [Startup](INFO): Completed Startup
2021-04-07T16:40:56.580Z,1617813656.580 [MissionManager](INFO): Startup is completed.
2021-04-07T16:40:56.580Z,1617813656.580 [MissionManager](INFO): Uninitializing Mission Startup
2021-04-07T16:40:56.580Z,1617813656.580 [Startup] Stopped
2021-04-07T16:40:56.580Z,1617813656.580 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-04-07T16:40:56.580Z,1617813656.580 [Startup:A.GoToSurface] Stopped
2021-04-07T16:40:56.580Z,1617813656.580 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-07T16:40:56.955Z,1617813656.955 [MissionManager](IMPORTANT): Started mission Default
2021-04-07T16:40:56.955Z,1617813656.955 [Default] Running Loop=1
2021-04-07T16:40:56.955Z,1617813656.955 [Default](DEBUG): Aggregate::initialize Default
2021-04-07T16:40:56.955Z,1617813656.955 [Default:B.GoToSurface] Running Loop=1
2021-04-07T16:40:56.955Z,1617813656.955 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-07T16:40:56.956Z,1617813656.956 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-07T16:40:56.956Z,1617813656.956 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-07T16:40:56.956Z,1617813656.956 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-07T16:40:56.957Z,1617813656.957 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-07T16:40:56.957Z,1617813656.957 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-07T16:40:56.957Z,1617813656.957 [Default:A.Wait] Running Loop=1
2021-04-07T16:40:56.957Z,1617813656.957 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-07T16:41:01.151Z,1617813661.151 [DataOverHttps](INFO): Sending 822 bytes from file Logs/20210407T163729/Express0001.lzma
2021-04-07T16:41:02.612Z,1617813662.612 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0001.lzma.bak
2021-04-07T16:41:02.612Z,1617813662.612 [DataOverHttps](INFO): SBD MOMSN=15549865
2021-04-07T16:41:10.319Z,1617813670.319 [Default:A.Wait](INFO): Done Waiting.
2021-04-07T16:41:10.319Z,1617813670.319 [Default:A.Wait] Stopped
2021-04-07T16:41:10.319Z,1617813670.319 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T16:41:10.722Z,1617813670.722 [Default:CheckIn] Running Loop=1
2021-04-07T16:41:10.722Z,1617813670.722 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T16:41:10.722Z,1617813670.722 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T16:41:11.120Z,1617813671.120 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-04-07T16:43:22.787Z,1617813802.787 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-07T16:43:41.402Z,1617813821.402 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-04-07T16:43:41.402Z,1617813821.402 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-07T16:43:41.412Z,1617813821.412 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-07T16:43:41.823Z,1617813821.823 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-07T16:43:41.823Z,1617813821.823 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-04-07T16:44:30.660Z,1617813870.660 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2021-04-07T16:44:30.666Z,1617813870.666 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2021-04-07T16:44:30.669Z,1617813870.669 [BPC1](INFO): Received data from all battery sticks.
2021-04-07T16:45:11.058Z,1617813911.058 [DVL_micro](ERROR): only read 2 of 4 data items
2021-04-07T16:45:11.058Z,1617813911.058 [DVL_micro](ERROR): Failed to parse:
:BI,-0536,+0070,I
2021-04-07T16:46:10.859Z,1617813970.859 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-07T16:41:10.7Z
2021-04-07T16:46:10.859Z,1617813970.859 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T16:46:10.859Z,1617813970.859 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T16:46:11.263Z,1617813971.263 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-07T16:46:17.740Z,1617813977.740 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20210407T163729/Courier0004.lzma
2021-04-07T16:46:18.742Z,1617813978.742 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0004.lzma.bak
2021-04-07T16:46:18.742Z,1617813978.742 [DataOverHttps](INFO): SBD MOMSN=15549897
2021-04-07T16:46:34.152Z,1617813994.152 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20210407T163729/Express0005.lzma
2021-04-07T16:46:35.154Z,1617813995.154 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0005.lzma.bak
2021-04-07T16:46:35.155Z,1617813995.155 [DataOverHttps](INFO): SBD MOMSN=15549900
2021-04-07T16:46:37.148Z,1617813997.148 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T16:46:37.148Z,1617813997.148 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T16:46:37.148Z,1617813997.148 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T16:46:42.383Z,1617814002.383 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-04-07T16:46:42.383Z,1617814002.383 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-07T16:46:42.414Z,1617814002.414 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-07T16:46:42.799Z,1617814002.799 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-07T16:46:42.799Z,1617814002.799 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-04-07T16:47:40.941Z,1617814060.941 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-07T16:47:40.941Z,1617814060.941 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.1,0000.0,1489:WI,+00625,-01538,+0073,A
2021-04-07T16:48:21.765Z,1617814101.765 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-04-07T16:48:21.765Z,1617814101.765 [NAL9602] Data Fault, FailCount= 1
2021-04-07T16:48:21.766Z,1617814101.766 [NAL9602](ERROR): Data Fault
2021-04-07T16:48:21.796Z,1617814101.796 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-07T16:48:22.147Z,1617814102.147 [NAL9602](INFO): Powering down
2021-04-07T16:48:22.980Z,1617814102.980 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-07T16:48:22.980Z,1617814102.980 [NAL9602] No Fault, FailCount= 1
2021-04-07T16:48:52.451Z,1617814132.451 [NAL9602](INFO): Powering up NAL9602
2021-04-07T16:49:03.354Z,1617814143.354 [NAL9602](INFO): NAL9602 initialized
2021-04-07T16:49:43.389Z,1617814183.389 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-04-07T16:49:43.389Z,1617814183.389 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-07T16:49:43.399Z,1617814183.399 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-07T16:49:43.775Z,1617814183.775 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-07T16:49:43.775Z,1617814183.775 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-04-07T16:51:25.160Z,1617814285.160 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165124.00,A,3648.15541,N,12147.27583,W,0.136,151.29,070421,,,A*74
2021-04-07T16:51:25.163Z,1617814285.163 [NAL9602](INFO): GPS fix at 20210407T165124: (36.802590, -121.787931)
2021-04-07T16:51:37.777Z,1617814297.777 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T16:51:37.777Z,1617814297.777 [Default:CheckIn:C.Wait] Stopped
2021-04-07T16:51:37.777Z,1617814297.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T16:51:37.777Z,1617814297.777 [Default:CheckIn:D] Running Loop=1
2021-04-07T16:51:38.133Z,1617814298.133 [Default:CheckIn:D] Stopped
2021-04-07T16:51:38.133Z,1617814298.133 [Default:CheckIn:E] Running Loop=1
2021-04-07T16:51:38.521Z,1617814298.521 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.685889 min 
2021-04-07T16:51:38.521Z,1617814298.521 [Default:CheckIn:E] Stopped
2021-04-07T16:51:38.521Z,1617814298.521 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T16:51:38.521Z,1617814298.521 [Default:CheckIn] Stopped
2021-04-07T16:51:38.521Z,1617814298.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T16:51:38.521Z,1617814298.521 [Default:CheckIn](INFO): Running loop #2
2021-04-07T16:51:38.522Z,1617814298.522 [Default:CheckIn] Running Loop=2
2021-04-07T16:51:38.522Z,1617814298.522 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T16:51:38.522Z,1617814298.522 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T16:51:40.560Z,1617814300.560 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165139.00,A,3648.15715,N,12147.27660,W,0.175,151.29,070421,,,A*72
2021-04-07T16:51:40.562Z,1617814300.562 [NAL9602](INFO): GPS fix at 20210407T165139: (36.802619, -121.787943)
2021-04-07T16:51:40.629Z,1617814300.629 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T16:51:40.630Z,1617814300.630 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T16:51:51.280Z,1617814311.280 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20210407T163729/Courier0007.lzma
2021-04-07T16:51:52.271Z,1617814312.271 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0007.lzma.bak
2021-04-07T16:51:52.271Z,1617814312.271 [DataOverHttps](INFO): SBD MOMSN=15549905
2021-04-07T16:52:11.061Z,1617814331.061 [DataOverHttps](INFO): Sending 323 bytes from file Logs/20210407T163729/Express0008.lzma
2021-04-07T16:52:12.062Z,1617814332.062 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0008.lzma.bak
2021-04-07T16:52:12.063Z,1617814332.063 [DataOverHttps](INFO): SBD MOMSN=15549908
2021-04-07T16:52:12.537Z,1617814332.537 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T16:52:13.756Z,1617814333.756 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T16:52:13.756Z,1617814333.756 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T16:52:13.756Z,1617814333.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T16:52:46.475Z,1617814366.475 [DVL_micro](ERROR): only read 0 of 4 data items
2021-04-07T16:52:46.475Z,1617814366.475 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999..99,+9999.99
2021-04-07T16:57:14.346Z,1617814634.346 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T16:57:14.346Z,1617814634.346 [Default:CheckIn:C.Wait] Stopped
2021-04-07T16:57:14.346Z,1617814634.346 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T16:57:14.346Z,1617814634.346 [Default:CheckIn:D] Running Loop=1
2021-04-07T16:57:14.756Z,1617814634.756 [Default:CheckIn:D] Stopped
2021-04-07T16:57:14.756Z,1617814634.756 [Default:CheckIn:E] Running Loop=1
2021-04-07T16:57:15.164Z,1617814635.164 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.296682 min 
2021-04-07T16:57:15.165Z,1617814635.165 [Default:CheckIn:E] Stopped
2021-04-07T16:57:15.165Z,1617814635.165 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T16:57:15.165Z,1617814635.165 [Default:CheckIn] Stopped
2021-04-07T16:57:15.165Z,1617814635.165 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T16:57:15.165Z,1617814635.165 [Default:CheckIn](INFO): Running loop #3
2021-04-07T16:57:15.165Z,1617814635.165 [Default:CheckIn] Running Loop=3
2021-04-07T16:57:15.165Z,1617814635.165 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T16:57:15.165Z,1617814635.165 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T16:57:17.172Z,1617814637.172 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165717.00,A,3648.16845,N,12147.28229,W,0.214,151.29,070421,,,A*73
2021-04-07T16:57:17.182Z,1617814637.182 [NAL9602](INFO): GPS fix at 20210407T165717: (36.802808, -121.788038)
2021-04-07T16:57:17.193Z,1617814637.193 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T16:57:17.193Z,1617814637.193 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T16:57:24.448Z,1617814644.448 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0010.lzma
2021-04-07T16:57:25.450Z,1617814645.450 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0010.lzma.bak
2021-04-07T16:57:25.451Z,1617814645.451 [DataOverHttps](INFO): SBD MOMSN=15549932
2021-04-07T16:57:41.312Z,1617814661.312 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210407T163729/Express0011.lzma
2021-04-07T16:57:42.314Z,1617814662.314 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0011.lzma.bak
2021-04-07T16:57:42.315Z,1617814662.315 [DataOverHttps](INFO): SBD MOMSN=15549935
2021-04-07T16:57:44.257Z,1617814664.257 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T16:57:44.257Z,1617814664.257 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T16:57:44.257Z,1617814664.257 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:01:17.656Z,1617814877.656 [CommandLine](IMPORTANT): got command burn on
2021-04-07T17:01:17.656Z,1617814877.656 [CommandLine](IMPORTANT): Activating dropweight wire
2021-04-07T17:01:27.714Z,1617814887.714 [CommandLine](IMPORTANT): got command burn off
2021-04-07T17:01:27.714Z,1617814887.714 [CommandLine](IMPORTANT): Deactivating dropweight wire
2021-04-07T17:01:48.228Z,1617814908.228 [CommandLine](IMPORTANT): got command failComponent
2021-04-07T17:01:48.228Z,1617814908.228 [CommandLine](IMPORTANT): Failed components:
2021-04-07T17:01:48.228Z,1617814908.228 [CommandLine](IMPORTANT): No failed Components.
2021-04-07T17:02:19.357Z,1617814939.357 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-07T17:02:44.824Z,1617814964.824 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:02:44.824Z,1617814964.824 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:02:44.824Z,1617814964.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:02:44.824Z,1617814964.824 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:02:45.243Z,1617814965.243 [Default:CheckIn:D] Stopped
2021-04-07T17:02:45.244Z,1617814965.244 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:02:45.632Z,1617814965.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.804806 min 
2021-04-07T17:02:45.633Z,1617814965.633 [Default:CheckIn:E] Stopped
2021-04-07T17:02:45.633Z,1617814965.633 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:02:45.633Z,1617814965.633 [Default:CheckIn] Stopped
2021-04-07T17:02:45.633Z,1617814965.633 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:02:45.633Z,1617814965.633 [Default:CheckIn](INFO): Running loop #4
2021-04-07T17:02:45.633Z,1617814965.633 [Default:CheckIn] Running Loop=4
2021-04-07T17:02:45.633Z,1617814965.633 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:02:45.633Z,1617814965.633 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:02:47.644Z,1617814967.644 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170247.00,A,3648.15742,N,12147.27727,W,0.214,119.87,070421,,,A*70
2021-04-07T17:02:47.646Z,1617814967.646 [NAL9602](INFO): GPS fix at 20210407T170247: (36.802624, -121.787954)
2021-04-07T17:02:47.657Z,1617814967.657 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:02:47.657Z,1617814967.657 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:02:55.592Z,1617814975.592 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0013.lzma
2021-04-07T17:02:56.599Z,1617814976.599 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0013.lzma.bak
2021-04-07T17:02:56.600Z,1617814976.600 [DataOverHttps](INFO): SBD MOMSN=15549976
2021-04-07T17:02:56.932Z,1617814976.932 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-07T17:02:56.932Z,1617814976.932 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35,0000.0,1489.0,000
2021-04-07T17:03:12.356Z,1617814992.356 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20210407T163729/Express0014.lzma
2021-04-07T17:03:13.358Z,1617814993.358 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0014.lzma.bak
2021-04-07T17:03:13.359Z,1617814993.359 [DataOverHttps](INFO): SBD MOMSN=15549979
2021-04-07T17:03:15.126Z,1617814995.126 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:03:15.126Z,1617814995.126 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:03:15.126Z,1617814995.126 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:03:19.563Z,1617814999.563 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:08:15.837Z,1617815295.837 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:08:15.837Z,1617815295.837 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:08:15.837Z,1617815295.837 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:08:15.837Z,1617815295.837 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:08:16.231Z,1617815296.231 [Default:CheckIn:D] Stopped
2021-04-07T17:08:16.231Z,1617815296.231 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:08:16.629Z,1617815296.629 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.321273 min 
2021-04-07T17:08:16.629Z,1617815296.629 [Default:CheckIn:E] Stopped
2021-04-07T17:08:16.629Z,1617815296.629 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:08:16.629Z,1617815296.629 [Default:CheckIn] Stopped
2021-04-07T17:08:16.629Z,1617815296.629 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:08:16.629Z,1617815296.629 [Default:CheckIn](INFO): Running loop #5
2021-04-07T17:08:16.629Z,1617815296.629 [Default:CheckIn] Running Loop=5
2021-04-07T17:08:16.630Z,1617815296.630 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:08:16.630Z,1617815296.630 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:08:18.649Z,1617815298.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170818.00,A,3648.15805,N,12147.28832,W,0.214,119.87,070421,,,A*78
2021-04-07T17:08:18.651Z,1617815298.651 [NAL9602](INFO): GPS fix at 20210407T170818: (36.802634, -121.788139)
2021-04-07T17:08:18.675Z,1617815298.675 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:08:18.675Z,1617815298.675 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:08:30.637Z,1617815310.637 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0016.lzma
2021-04-07T17:08:31.639Z,1617815311.639 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0016.lzma.bak
2021-04-07T17:08:31.639Z,1617815311.639 [DataOverHttps](INFO): SBD MOMSN=15550034
2021-04-07T17:08:49.375Z,1617815329.375 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-07T17:08:49.453Z,1617815329.453 [NAL9602](FAULT): received: 
+CSQ:0

OK
2021-04-07T17:08:49.453Z,1617815329.453 [NAL9602] Data Fault, FailCount= 2
2021-04-07T17:08:49.453Z,1617815329.453 [NAL9602](ERROR): Data Fault
2021-04-07T17:08:49.526Z,1617815329.526 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-07T17:08:49.778Z,1617815329.778 [NAL9602](INFO): Powering down
2021-04-07T17:08:50.630Z,1617815330.630 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-07T17:08:50.630Z,1617815330.630 [NAL9602] No Fault, FailCount= 2
2021-04-07T17:08:52.568Z,1617815332.568 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210407T163729/Express0017.lzma
2021-04-07T17:08:53.570Z,1617815333.570 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0017.lzma.bak
2021-04-07T17:08:53.571Z,1617815333.571 [DataOverHttps](INFO): SBD MOMSN=15550038
2021-04-07T17:08:55.905Z,1617815335.905 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:08:55.905Z,1617815335.905 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:08:55.905Z,1617815335.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:09:20.082Z,1617815360.082 [NAL9602](INFO): Powering up NAL9602
2021-04-07T17:09:31.005Z,1617815371.005 [NAL9602](INFO): NAL9602 initialized
2021-04-07T17:10:02.106Z,1617815402.106 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:13:56.569Z,1617815636.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:13:56.569Z,1617815636.569 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:13:56.569Z,1617815636.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:13:56.569Z,1617815636.569 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:13:56.932Z,1617815636.932 [Default:CheckIn:D] Stopped
2021-04-07T17:13:56.932Z,1617815636.932 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:13:57.340Z,1617815637.340 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.999618 min 
2021-04-07T17:13:57.340Z,1617815637.340 [Default:CheckIn:E] Stopped
2021-04-07T17:13:57.340Z,1617815637.340 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:13:57.340Z,1617815637.340 [Default:CheckIn] Stopped
2021-04-07T17:13:57.340Z,1617815637.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:13:57.345Z,1617815637.345 [Default:CheckIn](INFO): Running loop #6
2021-04-07T17:13:57.345Z,1617815637.345 [Default:CheckIn] Running Loop=6
2021-04-07T17:13:57.345Z,1617815637.345 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:13:57.345Z,1617815637.345 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:14:01.387Z,1617815641.387 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-07T17:14:02.588Z,1617815642.588 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171401.00,A,3648.15955,N,12147.28482,W,0.097,4.92,070421,,,A*7E
2021-04-07T17:14:02.590Z,1617815642.590 [NAL9602](INFO): GPS fix at 20210407T171401: (36.802659, -121.788080)
2021-04-07T17:14:02.638Z,1617815642.638 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:14:02.638Z,1617815642.638 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:14:12.260Z,1617815652.260 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0019.lzma
2021-04-07T17:14:13.262Z,1617815653.262 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0019.lzma.bak
2021-04-07T17:14:13.263Z,1617815653.263 [DataOverHttps](INFO): SBD MOMSN=15550078
2021-04-07T17:14:28.933Z,1617815668.933 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20210407T163729/Express0020.lzma
2021-04-07T17:14:29.926Z,1617815669.926 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0020.lzma.bak
2021-04-07T17:14:29.927Z,1617815669.927 [DataOverHttps](INFO): SBD MOMSN=15550081
2021-04-07T17:14:31.758Z,1617815671.758 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:14:31.758Z,1617815671.758 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:14:31.758Z,1617815671.758 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:14:34.556Z,1617815674.556 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:15:27.613Z,1617815727.613 [CommandLine](IMPORTANT): got command get 
2021-04-07T17:15:27.614Z,1617815727.614 [CommandLine](FAULT): Incomplete syntax. Try: help get
2021-04-07T17:15:30.607Z,1617815730.607 [CommandLine](IMPORTANT): got command get CBIT.stopDepth
2021-04-07T17:15:30.607Z,1617815730.607 [CommandLine](IMPORTANT): CBIT.stopDepth 265.000000 m
2021-04-07T17:15:52.801Z,1617815752.801 [CommandLine](IMPORTANT): got command get CBIT.abortDepth
2021-04-07T17:15:52.802Z,1617815752.802 [CommandLine](IMPORTANT): CBIT.abortDepth 300.000000 m
2021-04-07T17:16:20.365Z,1617815780.365 [CommandLine](IMPORTANT): got command get VerticalControl.buoyancyNeutral
2021-04-07T17:16:20.366Z,1617815780.366 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral 211.883133 cc
2021-04-07T17:16:39.564Z,1617815799.564 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault
2021-04-07T17:16:39.565Z,1617815799.565 [CommandLine](IMPORTANT): VerticalControl.massDefault 0.044417 cm
2021-04-07T17:16:44.093Z,1617815804.093 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault millimeter
2021-04-07T17:16:44.094Z,1617815804.094 [CommandLine](IMPORTANT): VerticalControl.massDefault 0.444172 mm
2021-04-07T17:17:11.573Z,1617815831.573 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2021-04-07T17:17:11.574Z,1617815831.574 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -27.000000 mm
2021-04-07T17:17:22.705Z,1617815842.705 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2021-04-07T17:17:22.706Z,1617815842.706 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 34.000000 mm
2021-04-07T17:17:40.880Z,1617815860.880 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2021-04-07T17:17:40.881Z,1617815860.881 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2021-04-07T17:17:41.063Z,1617815861.063 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2021-04-07T17:17:41.066Z,1617815861.066 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2021-04-07T17:17:41.069Z,1617815861.069 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2021-04-07T17:17:41.071Z,1617815861.071 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2021-04-07T17:17:41.074Z,1617815861.074 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2021-04-07T17:17:41.145Z,1617815861.145 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2021-04-07T17:17:41.149Z,1617815861.149 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2021-04-07T17:17:41.151Z,1617815861.151 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2021-04-07T17:17:41.173Z,1617815861.173 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2021-04-07T17:17:41.175Z,1617815861.175 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2021-04-07T17:17:41.203Z,1617815861.203 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-04-07T17:17:41.206Z,1617815861.206 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool
2021-04-07T17:17:41.210Z,1617815861.210 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool
2021-04-07T17:17:41.243Z,1617815861.243 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2021-04-07T17:17:41.247Z,1617815861.247 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-04-07T17:17:41.283Z,1617815861.283 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-04-07T17:17:41.295Z,1617815861.295 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool
2021-04-07T17:17:41.310Z,1617815861.310 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2021-04-07T17:17:41.333Z,1617815861.333 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2021-04-07T17:17:41.341Z,1617815861.341 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2021-04-07T17:17:41.344Z,1617815861.344 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2021-04-07T17:17:41.359Z,1617815861.359 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2021-04-07T17:17:41.364Z,1617815861.364 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2021-04-07T17:17:41.383Z,1617815861.383 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2021-04-07T17:17:41.388Z,1617815861.388 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2021-04-07T17:17:41.407Z,1617815861.407 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2021-04-07T17:17:41.411Z,1617815861.411 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2021-04-07T17:17:41.430Z,1617815861.430 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2021-04-07T17:17:41.438Z,1617815861.438 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2021-04-07T17:17:41.443Z,1617815861.443 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2021-04-07T17:17:41.456Z,1617815861.456 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2021-04-07T17:17:41.459Z,1617815861.459 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2021-04-07T17:17:41.464Z,1617815861.464 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2021-04-07T17:17:41.476Z,1617815861.476 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2021-04-07T17:17:41.479Z,1617815861.479 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2021-04-07T17:17:41.483Z,1617815861.483 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2021-04-07T17:17:41.502Z,1617815861.502 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2021-04-07T17:17:41.513Z,1617815861.513 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2021-04-07T17:17:41.516Z,1617815861.516 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2021-04-07T17:17:41.534Z,1617815861.534 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2021-04-07T17:17:41.542Z,1617815861.542 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2021-04-07T17:17:41.549Z,1617815861.549 [MissionManager](INFO): DefineOutput Science.PeakFDOMTemperature = nan degC
2021-04-07T17:17:41.552Z,1617815861.552 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2021-04-07T17:17:41.581Z,1617815861.581 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2021-04-07T17:17:41.584Z,1617815861.584 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2021-04-07T17:17:41.700Z,1617815861.700 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-07T17:17:41.752Z,1617815861.752 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-04-07T17:17:41.801Z,1617815861.801 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-07T17:17:41.828Z,1617815861.828 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-07T17:17:41.866Z,1617815861.866 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-07T17:17:41.939Z,1617815861.939 [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:Estimation="Tethys/Estimation"
           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/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.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="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>

    <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_colored_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="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="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="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="PeakFDOMTemperature"><Units:celsius/><Value>NaN</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 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="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>

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

    <Aggregate Id="OceanCurrent">

        <Estimation:CurrentEstimator>
            <Parallel/>
        </Estimation:CurrentEstimator>

    </Aggregate>

    <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="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></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_colored_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>


2021-04-07T17:17:41.945Z,1617815861.945 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2021-04-07T17:17:42.104Z,1617815862.104 [Default] Stopped
2021-04-07T17:17:42.104Z,1617815862.104 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-07T17:17:42.104Z,1617815862.104 [Default:B.GoToSurface] Stopped
2021-04-07T17:17:42.104Z,1617815862.104 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-07T17:17:42.104Z,1617815862.104 [Default:CheckIn] Stopped
2021-04-07T17:17:42.105Z,1617815862.105 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:17:42.105Z,1617815862.105 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:17:42.105Z,1617815862.105 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:17:42.105Z,1617815862.105 [MissionManager](IMPORTANT): Started mission Science
2021-04-07T17:17:42.105Z,1617815862.105 [Science] Running Loop=1
2021-04-07T17:17:42.105Z,1617815862.105 [Science](DEBUG): Aggregate::initialize Science
2021-04-07T17:17:42.105Z,1617815862.105 [Science:A] Running Loop=1
2021-04-07T17:17:42.105Z,1617815862.105 [Science:B] Running Loop=1
2021-04-07T17:17:42.105Z,1617815862.105 [Science:C] Running Loop=1
2021-04-07T17:17:42.105Z,1617815862.105 [Science:D] Running Loop=1
2021-04-07T17:17:42.106Z,1617815862.106 [Science:E] Running Loop=1
2021-04-07T17:17:42.106Z,1617815862.106 [Science:F] Running Loop=1
2021-04-07T17:17:42.106Z,1617815862.106 [Science:Read_Oil] Running Loop=1
2021-04-07T17:17:42.106Z,1617815862.106 [Science:PeakDetectChl] Running Loop=1
2021-04-07T17:17:42.106Z,1617815862.106 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2021-04-07T17:17:42.106Z,1617815862.106 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-04-07T17:17:42.106Z,1617815862.106 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-07T17:17:42.108Z,1617815862.108 [Science:HighestChlPeakReport] Running Loop=1
2021-04-07T17:17:42.108Z,1617815862.108 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2021-04-07T17:17:42.108Z,1617815862.108 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-04-07T17:17:42.108Z,1617815862.108 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-04-07T17:17:42.109Z,1617815862.109 [Science:PeakDetectNO3] Running Loop=1
2021-04-07T17:17:42.109Z,1617815862.109 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2021-04-07T17:17:42.109Z,1617815862.109 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-04-07T17:17:42.109Z,1617815862.109 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-07T17:17:42.111Z,1617815862.111 [Science:PeakDetectOil] Running Loop=1
2021-04-07T17:17:42.111Z,1617815862.111 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2021-04-07T17:17:42.111Z,1617815862.111 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-04-07T17:17:42.111Z,1617815862.111 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-07T17:17:42.129Z,1617815862.129 [Science:PeakDetectFDOM] Running Loop=1
2021-04-07T17:17:42.129Z,1617815862.129 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2021-04-07T17:17:42.129Z,1617815862.129 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-04-07T17:17:42.129Z,1617815862.129 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-07T17:17:42.132Z,1617815862.132 [Science:OceanCurrent] Running Loop=1
2021-04-07T17:17:42.132Z,1617815862.132 [Science:OceanCurrent](DEBUG): Aggregate::initialize Science:OceanCurrent
2021-04-07T17:17:42.132Z,1617815862.132 [Science:OceanCurrent:A.] Running Loop=1
2021-04-07T17:17:42.132Z,1617815862.132 [Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-04-07T17:17:42.149Z,1617815862.149 [Science:PeakDetectFDOM] Stopped
2021-04-07T17:17:42.149Z,1617815862.149 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2021-04-07T17:17:42.149Z,1617815862.149 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-04-07T17:17:42.149Z,1617815862.149 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-07T17:17:42.149Z,1617815862.149 [Science:PeakDetectOil] Stopped
2021-04-07T17:17:42.150Z,1617815862.150 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2021-04-07T17:17:42.150Z,1617815862.150 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-04-07T17:17:42.150Z,1617815862.150 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-07T17:17:42.150Z,1617815862.150 [Science:PeakDetectNO3] Stopped
2021-04-07T17:17:42.150Z,1617815862.150 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2021-04-07T17:17:42.150Z,1617815862.150 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-04-07T17:17:42.150Z,1617815862.150 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-07T17:17:42.150Z,1617815862.150 [Science:HighestChlPeakReport] Stopped
2021-04-07T17:17:42.150Z,1617815862.150 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2021-04-07T17:17:42.150Z,1617815862.150 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-04-07T17:17:42.151Z,1617815862.151 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-04-07T17:17:42.151Z,1617815862.151 [Science:PeakDetectChl] Stopped
2021-04-07T17:17:42.151Z,1617815862.151 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2021-04-07T17:17:42.151Z,1617815862.151 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-04-07T17:17:42.151Z,1617815862.151 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-07T17:17:42.151Z,1617815862.151 [Science:OceanCurrent:A.] Running Loop=1
2021-04-07T17:17:42.151Z,1617815862.151 [Science:Read_Oil] Stopped
2021-04-07T17:17:42.151Z,1617815862.151 [Science:F] Stopped
2021-04-07T17:17:42.151Z,1617815862.151 [Science:E] Running Loop=1
2021-04-07T17:17:42.152Z,1617815862.152 [Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-04-07T17:17:42.152Z,1617815862.152 [Science:D] Stopped
2021-04-07T17:17:42.152Z,1617815862.152 [Science:C] Running Loop=1
2021-04-07T17:17:42.153Z,1617815862.153 [Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-04-07T17:17:42.153Z,1617815862.153 [Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-04-07T17:17:42.153Z,1617815862.153 [Science:B] Running Loop=1
2021-04-07T17:17:42.154Z,1617815862.154 [Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-04-07T17:17:42.155Z,1617815862.155 [Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-04-07T17:17:42.155Z,1617815862.155 [Science:A] Stopped
2021-04-07T17:17:42.578Z,1617815862.578 [WetLabsBB2FL](INFO): Powering up
2021-04-07T17:17:45.434Z,1617815865.434 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-04-07T17:17:46.439Z,1617815866.439 [CTD_Seabird](ERROR): Salinity reading out of range: 2.819588 psu
2021-04-07T17:17:47.443Z,1617815867.443 [CTD_Seabird](ERROR): Salinity reading out of range: 2.741782 psu
2021-04-07T17:17:48.447Z,1617815868.447 [CTD_Seabird](ERROR): Salinity reading out of range: 2.730530 psu
2021-04-07T17:17:49.459Z,1617815869.459 [CTD_Seabird](ERROR): Salinity reading out of range: 2.722876 psu
2021-04-07T17:17:50.463Z,1617815870.463 [CTD_Seabird](ERROR): Salinity reading out of range: 2.712662 psu
2021-04-07T17:17:51.467Z,1617815871.467 [CTD_Seabird](ERROR): Salinity reading out of range: 2.708280 psu
2021-04-07T17:17:52.471Z,1617815872.471 [CTD_Seabird](ERROR): Salinity reading out of range: 2.707499 psu
2021-04-07T17:17:52.484Z,1617815872.484 [CommandLine](IMPORTANT): got command stop
2021-04-07T17:17:52.484Z,1617815872.484 [CommandLine](IMPORTANT): Scheduling is paused
2021-04-07T17:17:52.494Z,1617815872.494 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2021-04-07T17:17:52.641Z,1617815872.641 [MissionManager](INFO): MissionManager is completed.
2021-04-07T17:17:52.641Z,1617815872.641 [MissionManager](INFO): Uninitializing Mission Science
2021-04-07T17:17:52.641Z,1617815872.641 [Science] Stopped
2021-04-07T17:17:52.641Z,1617815872.641 [Science](DEBUG): Aggregate::uninitialize Science
2021-04-07T17:17:52.641Z,1617815872.641 [Science:B] Stopped
2021-04-07T17:17:52.641Z,1617815872.641 [Science:C] Stopped
2021-04-07T17:17:52.641Z,1617815872.641 [Science:E] Stopped
2021-04-07T17:17:52.641Z,1617815872.641 [Science:OceanCurrent] Stopped
2021-04-07T17:17:52.641Z,1617815872.641 [Science:OceanCurrent](DEBUG): Aggregate::uninitialize Science:OceanCurrent
2021-04-07T17:17:52.641Z,1617815872.641 [Science:OceanCurrent:A.] Stopped
2021-04-07T17:17:52.765Z,1617815872.765 [WetLabsBB2FL](INFO): Powering down
2021-04-07T17:17:53.048Z,1617815873.048 [MissionManager](IMPORTANT): Started mission Default
2021-04-07T17:17:53.048Z,1617815873.048 [Default] Running Loop=1
2021-04-07T17:17:53.048Z,1617815873.048 [Default](DEBUG): Aggregate::initialize Default
2021-04-07T17:17:53.073Z,1617815873.073 [Default:B.GoToSurface] Running Loop=1
2021-04-07T17:17:53.073Z,1617815873.073 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-07T17:17:53.073Z,1617815873.073 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-07T17:17:53.073Z,1617815873.073 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-07T17:17:53.074Z,1617815873.074 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-07T17:17:53.074Z,1617815873.074 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-07T17:17:53.074Z,1617815873.074 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-07T17:17:53.075Z,1617815873.075 [Default:A.Wait] Running Loop=1
2021-04-07T17:17:53.075Z,1617815873.075 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:18:03.912Z,1617815883.912 [CommandLine](IMPORTANT): got command show variable PAR
2021-04-07T17:18:04.020Z,1617815884.020 [CommandLine](IMPORTANT): PAR_Licor.loadControl (none)
2021-04-07T17:18:04.020Z,1617815884.020 [CommandLine](IMPORTANT): PAR_Licor.ad (none)
2021-04-07T17:18:04.020Z,1617815884.020 [CommandLine](IMPORTANT): PAR_Licor.adRes (bit)
2021-04-07T17:18:04.034Z,1617815884.034 [CommandLine](IMPORTANT): PAR_Licor.adTimeout (millisecond)
2021-04-07T17:18:04.034Z,1617815884.034 [CommandLine](IMPORTANT): PAR_Licor.adVref (volt)
2021-04-07T17:18:04.055Z,1617815884.055 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2021-04-07T17:18:04.056Z,1617815884.056 [CommandLine](IMPORTANT): PAR_Licor.simulateHardware (bool)
2021-04-07T17:18:04.056Z,1617815884.056 [CommandLine](IMPORTANT): PAR_Licor.adcCal (microampere_per_count)
2021-04-07T17:18:04.069Z,1617815884.069 [CommandLine](IMPORTANT): PAR_Licor.darkCount (count)
2021-04-07T17:18:04.069Z,1617815884.069 [CommandLine](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter)
2021-04-07T17:18:04.069Z,1617815884.069 [CommandLine](IMPORTANT): PAR_Licor.maxValidPitch (degree)
2021-04-07T17:18:04.070Z,1617815884.070 [CommandLine](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter)
2021-04-07T17:18:04.070Z,1617815884.070 [CommandLine](IMPORTANT): PAR_Licor.minValidPitch (degree)
2021-04-07T17:18:04.070Z,1617815884.070 [CommandLine](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere)
2021-04-07T17:18:04.071Z,1617815884.071 [CommandLine](IMPORTANT): PAR_Licor.parCal (none)
2021-04-07T17:18:04.071Z,1617815884.071 [CommandLine](IMPORTANT): PAR_Licor.serial (none)
2021-04-07T17:18:04.261Z,1617815884.261 [CommandLine](IMPORTANT): PAR_Licor.enableBroadcast (bool)
2021-04-07T17:18:04.261Z,1617815884.261 [CommandLine](IMPORTANT): PAR_Licor.component_voltage (volt)
2021-04-07T17:18:04.262Z,1617815884.262 [CommandLine](IMPORTANT): PAR_Licor.component_avgVoltage (volt)
2021-04-07T17:18:04.262Z,1617815884.262 [CommandLine](IMPORTANT): PAR_Licor.component_current (milliampere)
2021-04-07T17:18:04.262Z,1617815884.262 [CommandLine](IMPORTANT): PAR_Licor.component_avgCurrent (milliampere)
2021-04-07T17:18:04.263Z,1617815884.263 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter)
2021-04-07T17:18:04.263Z,1617815884.263 [CommandLine](IMPORTANT): PAR_Licor.adcCount (count)
2021-04-07T17:18:06.365Z,1617815886.365 [Default:A.Wait](INFO): Done Waiting.
2021-04-07T17:18:06.365Z,1617815886.365 [Default:A.Wait] Stopped
2021-04-07T17:18:06.365Z,1617815886.365 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:18:06.742Z,1617815886.742 [Default:CheckIn] Running Loop=1
2021-04-07T17:18:06.742Z,1617815886.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:18:06.742Z,1617815886.742 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:18:08.760Z,1617815888.760 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171807.00,A,3648.15614,N,12147.28200,W,0.214,4.92,070421,,,A*7B
2021-04-07T17:18:08.762Z,1617815888.762 [NAL9602](INFO): GPS fix at 20210407T171807: (36.802602, -121.788033)
2021-04-07T17:18:08.789Z,1617815888.789 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:18:08.789Z,1617815888.789 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:18:11.297Z,1617815891.297 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0022.lzma
2021-04-07T17:18:12.014Z,1617815892.014 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0022.lzma.bak
2021-04-07T17:18:12.015Z,1617815892.015 [DataOverHttps](INFO): SBD MOMSN=15550112
2021-04-07T17:18:15.525Z,1617815895.525 [CommandLine](IMPORTANT): got command get PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water
2021-04-07T17:18:15.526Z,1617815895.526 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 4.679165 umol/s/m2
2021-04-07T17:18:27.497Z,1617815907.497 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: -137
2021-04-07T17:18:27.499Z,1617815907.499 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2021-04-07T17:18:30.182Z,1617815910.182 [DataOverHttps](INFO): Sending 829 bytes from file Logs/20210407T163729/Express0023.lzma
2021-04-07T17:18:31.195Z,1617815911.195 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0023.lzma.bak
2021-04-07T17:18:31.195Z,1617815911.195 [DataOverHttps](INFO): SBD MOMSN=15550117
2021-04-07T17:18:33.157Z,1617815913.157 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:18:33.158Z,1617815913.158 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:18:33.158Z,1617815913.158 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:23:10.371Z,1617816190.371 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-07T17:23:33.802Z,1617816213.802 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:23:33.802Z,1617816213.802 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:23:33.802Z,1617816213.802 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:23:33.802Z,1617816213.802 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:23:34.208Z,1617816214.208 [Default:CheckIn:D] Stopped
2021-04-07T17:23:34.208Z,1617816214.208 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.685999 min 
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn:E] Stopped
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn] Stopped
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn](INFO): Running loop #2
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn] Running Loop=2
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:23:34.617Z,1617816214.617 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:23:36.628Z,1617816216.628 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172336.00,A,3648.16617,N,12147.28441,W,0.505,4.92,070421,,,A*75
2021-04-07T17:23:36.638Z,1617816216.638 [NAL9602](INFO): GPS fix at 20210407T172336: (36.802769, -121.788073)
2021-04-07T17:23:36.649Z,1617816216.649 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:23:36.649Z,1617816216.649 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:23:42.721Z,1617816222.721 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-07T17:23:42.721Z,1617816222.721 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2021-04-07T17:23:43.592Z,1617816223.592 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210407T163729/Courier0025.lzma
2021-04-07T17:23:44.594Z,1617816224.594 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0025.lzma.bak
2021-04-07T17:23:44.595Z,1617816224.595 [DataOverHttps](INFO): SBD MOMSN=15550178
2021-04-07T17:24:07.001Z,1617816247.001 [DataOverHttps](INFO): Sending 298 bytes from file Logs/20210407T163729/Express0026.lzma
2021-04-07T17:24:08.003Z,1617816248.003 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0026.lzma.bak
2021-04-07T17:24:08.003Z,1617816248.003 [DataOverHttps](INFO): SBD MOMSN=15550181
2021-04-07T17:24:08.283Z,1617816248.283 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:24:09.892Z,1617816249.892 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:24:09.893Z,1617816249.893 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:24:09.893Z,1617816249.893 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:26:22.874Z,1617816382.874 [DVL_micro](ERROR): Failed to parse:404,-0132,+00000,I
2021-04-07T17:27:14.120Z,1617816434.120 [CommandLine](IMPORTANT): got command ibit
2021-04-07T17:27:14.198Z,1617816434.198 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-04-07T17:27:14.198Z,1617816434.198 [IBIT](IMPORTANT): Beginning control surface checks.
2021-04-07T17:27:14.201Z,1617816434.201 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-07T17:27:15.784Z,1617816435.784 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172715.00,A,3648.17594,N,12147.27333,W,0.019,110.56,070421,,,A*70
2021-04-07T17:27:15.794Z,1617816435.794 [NAL9602](INFO): GPS fix at 20210407T172715: (36.802932, -121.787889)
2021-04-07T17:27:21.636Z,1617816441.636 [CommandLine](IMPORTANT): got command failComponent
2021-04-07T17:27:21.636Z,1617816441.636 [CommandLine](IMPORTANT): Failed components:
2021-04-07T17:27:21.636Z,1617816441.636 [CommandLine](IMPORTANT): No failed Components.
2021-04-07T17:27:25.153Z,1617816445.153 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001660
CHAN A1 (24V): 0.006826
CHAN A2 (12V): 0.003182
CHAN A3 (5V): 0.000372
CHAN B0 (3.3V): -0.000284
CHAN B1 (3.15aV): -0.000211
CHAN B2 (3.15bV): -0.000741
CHAN B3 (GND): -0.002314
OPEN: 0.003761
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-07T17:27:46.506Z,1617816466.506 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-07T17:27:46.581Z,1617816466.581 [NAL9602](FAULT): received: 
+CSQ:0

OK
2021-04-07T17:27:46.581Z,1617816466.581 [NAL9602] Data Fault, FailCount= 1
2021-04-07T17:27:46.581Z,1617816466.581 [NAL9602](ERROR): Data Fault
2021-04-07T17:27:46.674Z,1617816466.674 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-07T17:27:46.982Z,1617816466.982 [NAL9602](INFO): Powering down
2021-04-07T17:27:47.857Z,1617816467.857 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-07T17:27:47.857Z,1617816467.857 [NAL9602] No Fault, FailCount= 1
2021-04-07T17:28:17.291Z,1617816497.291 [NAL9602](INFO): Powering up NAL9602
2021-04-07T17:28:28.195Z,1617816508.195 [NAL9602](INFO): NAL9602 initialized
2021-04-07T17:29:04.986Z,1617816544.986 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2021-04-07T17:29:05.398Z,1617816545.398 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.532000
2021-04-07T17:29:05.398Z,1617816545.398 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2021-04-07T17:29:05.399Z,1617816545.399 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2021-04-07T17:29:05.818Z,1617816545.818 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-04-07T17:29:05.819Z,1617816545.819 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-04-07T17:29:05.819Z,1617816545.819 [IBIT](IMPORTANT): Pressure:8.072461 PSI
2021-04-07T17:29:05.819Z,1617816545.819 [IBIT](IMPORTANT): Humidity:14.686852 %
2021-04-07T17:29:06.202Z,1617816546.202 [IBIT](IMPORTANT): Vehicle Pitch:-0.798968 degrees
2021-04-07T17:29:06.202Z,1617816546.202 [IBIT](IMPORTANT): Vehicle  Roll:-2.078198 degrees
2021-04-07T17:29:06.202Z,1617816546.202 [IBIT](IMPORTANT): Vehicle  Heading:103.773163 degrees
2021-04-07T17:29:06.618Z,1617816546.618 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-04-07T17:29:06.618Z,1617816546.618 [IBIT](IMPORTANT): buoyancyNeutral: 211.883133 cc
2021-04-07T17:29:06.618Z,1617816546.618 [IBIT](IMPORTANT): massDefault: 0.044417 cm
2021-04-07T17:29:06.619Z,1617816546.619 [IBIT](IMPORTANT): stopDepth: 265.000000 m
2021-04-07T17:29:06.619Z,1617816546.619 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2021-04-07T17:29:06.619Z,1617816546.619 [IBIT](IMPORTANT): IBIT FAILED
2021-04-07T17:29:10.653Z,1617816550.653 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:29:10.653Z,1617816550.653 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:29:10.653Z,1617816550.653 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:29:10.654Z,1617816550.654 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:29:11.070Z,1617816551.070 [Default:CheckIn:D] Stopped
2021-04-07T17:29:11.070Z,1617816551.070 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:29:11.434Z,1617816551.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.300363 min 
2021-04-07T17:29:11.434Z,1617816551.434 [Default:CheckIn:E] Stopped
2021-04-07T17:29:11.434Z,1617816551.434 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:29:11.434Z,1617816551.434 [Default:CheckIn] Stopped
2021-04-07T17:29:11.434Z,1617816551.434 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:29:11.434Z,1617816551.434 [Default:CheckIn](INFO): Running loop #3
2021-04-07T17:29:11.435Z,1617816551.435 [Default:CheckIn] Running Loop=3
2021-04-07T17:29:11.435Z,1617816551.435 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:29:11.435Z,1617816551.435 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:29:16.060Z,1617816556.060 [CommandLine](IMPORTANT): got command ibit
2021-04-07T17:29:16.304Z,1617816556.304 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-04-07T17:29:16.304Z,1617816556.304 [IBIT](IMPORTANT): Beginning control surface checks.
2021-04-07T17:29:16.307Z,1617816556.307 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-07T17:29:27.233Z,1617816567.233 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002698
CHAN A1 (24V): 0.007950
CHAN A2 (12V): 0.003400
CHAN A3 (5V): 0.000223
CHAN B0 (3.3V): -0.000303
CHAN B1 (3.15aV): -0.000933
CHAN B2 (3.15bV): 0.000054
CHAN B3 (GND): -0.001434
OPEN: 0.003852
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-07T17:29:33.679Z,1617816573.679 [NAL9602](INFO): SBD MO Status=2, MOMSN=1696, MT Status=2, MTMSN=0
2021-04-07T17:29:33.679Z,1617816573.679 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-07T17:29:51.816Z,1617816591.816 [NAL9602](INFO): SBD MO Status=2, MOMSN=1696, MT Status=2, MTMSN=0
2021-04-07T17:29:51.816Z,1617816591.816 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-07T17:30:02.768Z,1617816602.768 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802933 Longitude: -121.787888
2021-04-07T17:30:03.151Z,1617816603.151 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.532000
2021-04-07T17:30:03.151Z,1617816603.151 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2021-04-07T17:30:03.151Z,1617816603.151 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2021-04-07T17:30:03.580Z,1617816603.580 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-04-07T17:30:03.580Z,1617816603.580 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-04-07T17:30:03.580Z,1617816603.580 [IBIT](IMPORTANT): Pressure:8.048637 PSI
2021-04-07T17:30:03.613Z,1617816603.613 [IBIT](IMPORTANT): Humidity:14.674643 %
2021-04-07T17:30:03.951Z,1617816603.951 [IBIT](IMPORTANT): Vehicle Pitch:-0.797251 degrees
2021-04-07T17:30:03.952Z,1617816603.952 [IBIT](IMPORTANT): Vehicle  Roll:-2.083290 degrees
2021-04-07T17:30:03.952Z,1617816603.952 [IBIT](IMPORTANT): Vehicle  Heading:103.769562 degrees
2021-04-07T17:30:04.369Z,1617816604.369 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-04-07T17:30:04.369Z,1617816604.369 [IBIT](IMPORTANT): buoyancyNeutral: 211.883133 cc
2021-04-07T17:30:04.369Z,1617816604.369 [IBIT](IMPORTANT): massDefault: 0.044417 cm
2021-04-07T17:30:04.370Z,1617816604.370 [IBIT](IMPORTANT): stopDepth: 265.000000 m
2021-04-07T17:30:04.370Z,1617816604.370 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2021-04-07T17:30:04.370Z,1617816604.370 [IBIT](IMPORTANT): IBIT PASSED
2021-04-07T17:30:30.604Z,1617816630.604 [NAL9602](INFO): SBD MO Status=2, MOMSN=1696, MT Status=2, MTMSN=0
2021-04-07T17:30:30.604Z,1617816630.604 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-07T17:30:50.009Z,1617816650.009 [NAL9602](INFO): SBD MO Status=0, MOMSN=1696, MT Status=0, MTMSN=0
2021-04-07T17:30:50.009Z,1617816650.009 [NAL9602](INFO): No messages in MT queue
2021-04-07T17:30:51.206Z,1617816651.206 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173050.00,A,3648.17453,N,12147.27352,W,0.253,69.43,070421,,,A*4D
2021-04-07T17:30:51.208Z,1617816651.208 [NAL9602](INFO): GPS fix at 20210407T173050: (36.802909, -121.787892)
2021-04-07T17:30:51.219Z,1617816651.219 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:30:51.219Z,1617816651.219 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:30:58.168Z,1617816658.168 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0028.lzma
2021-04-07T17:30:59.170Z,1617816659.170 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0028.lzma.bak
2021-04-07T17:30:59.171Z,1617816659.171 [DataOverHttps](INFO): SBD MOMSN=15550214
2021-04-07T17:31:15.034Z,1617816675.034 [DataOverHttps](INFO): Sending 1042 bytes from file Logs/20210407T163729/Express0029.lzma
2021-04-07T17:31:16.036Z,1617816676.036 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0029.lzma.bak
2021-04-07T17:31:16.036Z,1617816676.036 [DataOverHttps](INFO): SBD MOMSN=15550217
2021-04-07T17:31:17.907Z,1617816677.907 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:31:17.907Z,1617816677.907 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:31:17.907Z,1617816677.907 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:31:23.542Z,1617816683.542 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:31:40.924Z,1617816700.924 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-07T17:31:40.925Z,1617816700.925 [DVL_micro](ERROR): Failed to parse:
:BI,-00010,-01765,+00913,+

2021-04-07T17:31:42.614Z,1617816702.614 [CommandLine](IMPORTANT): got command get platform_battery_charge
2021-04-07T17:31:42.615Z,1617816702.615 [CommandLine](FAULT): Element has no value
2021-04-07T17:31:53.423Z,1617816713.423 [CommandLine](IMPORTANT): got command get platform_battery_voltage
2021-04-07T17:31:53.423Z,1617816713.423 [CommandLine](IMPORTANT): platform_battery_voltage 16.532000 V
2021-04-07T17:32:32.156Z,1617816752.156 [CommandLine](IMPORTANT): got command failComponent
2021-04-07T17:32:32.156Z,1617816752.156 [CommandLine](IMPORTANT): Failed components:
2021-04-07T17:32:32.156Z,1617816752.156 [CommandLine](IMPORTANT): No failed Components.
2021-04-07T17:34:21.000Z,1617816861.000 [DVL_micro](ERROR): Failed to parse:
:SA,-01.14,-02.26,171.7
2021-04-07T17:36:18.573Z,1617816978.573 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:36:18.573Z,1617816978.573 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:36:18.573Z,1617816978.573 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:36:18.574Z,1617816978.574 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:36:18.973Z,1617816978.973 [Default:CheckIn:D] Stopped
2021-04-07T17:36:18.973Z,1617816978.973 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:36:19.386Z,1617816979.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.432084 min 
2021-04-07T17:36:19.386Z,1617816979.386 [Default:CheckIn:E] Stopped
2021-04-07T17:36:19.386Z,1617816979.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:36:19.386Z,1617816979.386 [Default:CheckIn] Stopped
2021-04-07T17:36:19.386Z,1617816979.386 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:36:19.387Z,1617816979.387 [Default:CheckIn](INFO): Running loop #4
2021-04-07T17:36:19.387Z,1617816979.387 [Default:CheckIn] Running Loop=4
2021-04-07T17:36:19.387Z,1617816979.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:36:19.387Z,1617816979.387 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:36:21.384Z,1617816981.384 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173621.00,A,3648.16419,N,12147.21377,W,0.078,166.76,070421,,,A*70
2021-04-07T17:36:21.386Z,1617816981.386 [NAL9602](INFO): GPS fix at 20210407T173621: (36.802737, -121.786896)
2021-04-07T17:36:21.397Z,1617816981.397 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:36:21.397Z,1617816981.397 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:36:28.572Z,1617816988.572 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0031.lzma
2021-04-07T17:36:29.574Z,1617816989.574 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0031.lzma.bak
2021-04-07T17:36:29.575Z,1617816989.575 [DataOverHttps](INFO): SBD MOMSN=15550312
2021-04-07T17:36:38.132Z,1617816998.132 [CommandLine](IMPORTANT): got command get DAT.sbdAddress
2021-04-07T17:36:38.133Z,1617816998.133 [CommandLine](IMPORTANT): DAT.sbdAddress -1 enum
2021-04-07T17:36:46.197Z,1617817006.197 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20210407T163729/Express0032.lzma
2021-04-07T17:36:47.198Z,1617817007.198 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0032.lzma.bak
2021-04-07T17:36:47.199Z,1617817007.199 [DataOverHttps](INFO): SBD MOMSN=15550315
2021-04-07T17:36:49.284Z,1617817009.284 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:36:49.284Z,1617817009.284 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:36:49.289Z,1617817009.289 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:39:09.856Z,1617817149.856 [NAL9602](INFO): SBD MO Status=2, MOMSN=1697, MT Status=2, MTMSN=0
2021-04-07T17:39:09.856Z,1617817149.856 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-07T17:39:33.702Z,1617817173.702 [DVL_micro](ERROR): only read 1 of 4 data items
2021-04-07T17:39:33.702Z,1617817173.702 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,++9999.99,+

2021-04-07T17:39:38.938Z,1617817178.938 [DVL_micro](ERROR): Failed to parse:
:WI,-01558,-01843,+01318,+00000,A
2021-04-07T17:39:48.634Z,1617817188.634 [NAL9602](INFO): SBD MO Status=0, MOMSN=1697, MT Status=0, MTMSN=0
2021-04-07T17:39:48.635Z,1617817188.635 [NAL9602](INFO): No messages in MT queue
2021-04-07T17:40:19.403Z,1617817219.403 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:41:49.905Z,1617817309.905 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:41:49.905Z,1617817309.905 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:41:49.905Z,1617817309.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:41:49.905Z,1617817309.905 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:41:50.318Z,1617817310.318 [Default:CheckIn:D] Stopped
2021-04-07T17:41:50.318Z,1617817310.318 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:41:50.748Z,1617817310.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.954500 min 
2021-04-07T17:41:50.748Z,1617817310.748 [Default:CheckIn:E] Stopped
2021-04-07T17:41:50.748Z,1617817310.748 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:41:50.773Z,1617817310.773 [Default:CheckIn] Stopped
2021-04-07T17:41:50.773Z,1617817310.773 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:41:50.774Z,1617817310.774 [Default:CheckIn](INFO): Running loop #5
2021-04-07T17:41:50.775Z,1617817310.775 [Default:CheckIn] Running Loop=5
2021-04-07T17:41:50.775Z,1617817310.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:41:50.775Z,1617817310.775 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:41:52.715Z,1617817312.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174152.00,A,3648.16308,N,12147.21288,W,0.058,166.76,070421,,,D*75
2021-04-07T17:41:52.718Z,1617817312.718 [NAL9602](INFO): GPS fix at 20210407T174152: (36.802718, -121.786881)
2021-04-07T17:41:52.737Z,1617817312.737 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:41:52.737Z,1617817312.737 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:42:03.340Z,1617817323.340 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210407T163729/Courier0034.lzma
2021-04-07T17:42:04.343Z,1617817324.343 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0034.lzma.bak
2021-04-07T17:42:04.343Z,1617817324.343 [DataOverHttps](INFO): SBD MOMSN=15550347
2021-04-07T17:42:06.193Z,1617817326.193 [NAL9602](INFO): SBD MO Status=0, MOMSN=1698, MT Status=0, MTMSN=0
2021-04-07T17:42:06.193Z,1617817326.193 [NAL9602](INFO): No messages in MT queue
2021-04-07T17:42:13.886Z,1617817333.886 [DVL_micro](ERROR): Failed to parse:145,-0163,+00000,I
2021-04-07T17:42:25.920Z,1617817345.920 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20210407T163729/Express0035.lzma
2021-04-07T17:42:26.922Z,1617817346.922 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0035.lzma.bak
2021-04-07T17:42:26.923Z,1617817346.923 [DataOverHttps](INFO): SBD MOMSN=15550350
2021-04-07T17:42:28.832Z,1617817348.832 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:42:28.833Z,1617817348.833 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:42:28.833Z,1617817348.833 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:42:36.902Z,1617817356.902 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:44:59.129Z,1617817499.129 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-07T17:44:59.129Z,1617817499.129 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000+21.3,0000.0,1489.0,000
2021-04-07T17:47:29.473Z,1617817649.473 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:47:29.473Z,1617817649.473 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:47:29.473Z,1617817649.473 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:47:29.473Z,1617817649.473 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:47:29.880Z,1617817649.880 [Default:CheckIn:D] Stopped
2021-04-07T17:47:29.881Z,1617817649.881 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:47:30.288Z,1617817650.288 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.613867 min 
2021-04-07T17:47:30.288Z,1617817650.288 [Default:CheckIn:E] Stopped
2021-04-07T17:47:30.288Z,1617817650.288 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:47:30.288Z,1617817650.288 [Default:CheckIn] Stopped
2021-04-07T17:47:30.288Z,1617817650.288 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:47:30.288Z,1617817650.288 [Default:CheckIn](INFO): Running loop #6
2021-04-07T17:47:30.289Z,1617817650.289 [Default:CheckIn] Running Loop=6
2021-04-07T17:47:30.289Z,1617817650.289 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:47:30.289Z,1617817650.289 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:47:32.299Z,1617817652.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174732.00,A,3648.16232,N,12147.21427,W,0.039,166.76,070421,,,D*79
2021-04-07T17:47:32.301Z,1617817652.301 [NAL9602](INFO): GPS fix at 20210407T174732: (36.802705, -121.786905)
2021-04-07T17:47:32.328Z,1617817652.328 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:47:32.328Z,1617817652.328 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:47:39.532Z,1617817659.532 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210407T163729/Courier0037.lzma
2021-04-07T17:47:40.534Z,1617817660.534 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0037.lzma.bak
2021-04-07T17:47:40.535Z,1617817660.535 [DataOverHttps](INFO): SBD MOMSN=15550409
2021-04-07T17:47:49.085Z,1617817669.085 [DVL_micro](ERROR): only read 3 of 4 data items
2021-04-07T17:47:49.085Z,1617817669.085 [DVL_micro](ERROR): Failed to parse:
:BI,+00136,-01585,+00993,+0:BD,+00000000.00,+0000000000009.99,000.00
2021-04-07T17:47:52.302Z,1617817672.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=1699, MT Status=2, MTMSN=0
2021-04-07T17:47:52.302Z,1617817672.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-07T17:48:03.977Z,1617817683.977 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210407T163729/Express0038.lzma
2021-04-07T17:48:04.979Z,1617817684.979 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0038.lzma.bak
2021-04-07T17:48:04.979Z,1617817684.979 [DataOverHttps](INFO): SBD MOMSN=15550412
2021-04-07T17:48:08.058Z,1617817688.058 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:48:08.058Z,1617817688.058 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:48:08.058Z,1617817688.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:49:01.752Z,1617817741.752 [NAL9602](INFO): SBD MO Status=2, MOMSN=1699, MT Status=2, MTMSN=0
2021-04-07T17:49:01.752Z,1617817741.752 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-07T17:49:22.352Z,1617817762.352 [NAL9602](INFO): SBD MO Status=0, MOMSN=1699, MT Status=0, MTMSN=0
2021-04-07T17:49:22.352Z,1617817762.352 [NAL9602](INFO): No messages in MT queue
2021-04-07T17:49:53.051Z,1617817793.051 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:52:05.420Z,1617817925.420 [BPC1](ERROR): Battery stick #6 (s/n: 00CA) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2021-04-07T17:52:05.421Z,1617817925.421 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2021-04-07T17:52:05.423Z,1617817925.423 [BPC1](INFO): Received data from all battery sticks.
2021-04-07T17:53:08.844Z,1617817988.844 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:53:08.844Z,1617817988.844 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:53:08.844Z,1617817988.844 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:53:08.845Z,1617817988.845 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:53:09.280Z,1617817989.280 [Default:CheckIn:D] Stopped
2021-04-07T17:53:09.280Z,1617817989.280 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.270528 min 
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn:E] Stopped
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn] Stopped
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn](INFO): Running loop #7
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn] Running Loop=7
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:53:09.673Z,1617817989.673 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:53:11.664Z,1617817991.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175311.00,A,3648.42572,N,12147.11328,W,6.240,355.41,070421,,,D*78
2021-04-07T17:53:11.666Z,1617817991.666 [NAL9602](INFO): GPS fix at 20210407T175311: (36.807095, -121.785221)
2021-04-07T17:53:11.677Z,1617817991.677 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:53:11.677Z,1617817991.677 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:53:14.488Z,1617817994.488 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-07T17:53:14.488Z,1617817994.488 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000+21.4,000.0,000
2021-04-07T17:53:18.900Z,1617817998.900 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20210407T163729/Courier0040.lzma
2021-04-07T17:53:19.902Z,1617817999.902 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0040.lzma.bak
2021-04-07T17:53:19.903Z,1617817999.903 [DataOverHttps](INFO): SBD MOMSN=15550430
2021-04-07T17:53:21.760Z,1617818001.760 [NAL9602](INFO): SBD MO Status=0, MOMSN=1700, MT Status=0, MTMSN=0
2021-04-07T17:53:21.760Z,1617818001.760 [NAL9602](INFO): No messages in MT queue
2021-04-07T17:53:35.604Z,1617818015.604 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210407T163729/Express0041.lzma
2021-04-07T17:53:36.606Z,1617818016.606 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0041.lzma.bak
2021-04-07T17:53:36.607Z,1617818016.607 [DataOverHttps](INFO): SBD MOMSN=15550433
2021-04-07T17:53:38.333Z,1617818018.333 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:53:38.333Z,1617818018.333 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:53:38.334Z,1617818018.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:53:52.478Z,1617818032.478 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T17:55:54.484Z,1617818154.484 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-07T17:55:54.484Z,1617818154.484 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+2.0,1489.0,000
2021-04-07T17:58:38.980Z,1617818318.980 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T17:58:38.980Z,1617818318.980 [Default:CheckIn:C.Wait] Stopped
2021-04-07T17:58:38.980Z,1617818318.980 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T17:58:38.981Z,1617818318.981 [Default:CheckIn:D] Running Loop=1
2021-04-07T17:58:39.389Z,1617818319.389 [Default:CheckIn:D] Stopped
2021-04-07T17:58:39.389Z,1617818319.389 [Default:CheckIn:E] Running Loop=1
2021-04-07T17:58:39.825Z,1617818319.825 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.772351 min 
2021-04-07T17:58:39.825Z,1617818319.825 [Default:CheckIn:E] Stopped
2021-04-07T17:58:39.825Z,1617818319.825 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T17:58:39.825Z,1617818319.825 [Default:CheckIn] Stopped
2021-04-07T17:58:39.825Z,1617818319.825 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T17:58:39.826Z,1617818319.826 [Default:CheckIn](INFO): Running loop #8
2021-04-07T17:58:39.826Z,1617818319.826 [Default:CheckIn] Running Loop=8
2021-04-07T17:58:39.826Z,1617818319.826 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T17:58:39.826Z,1617818319.826 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T17:58:41.805Z,1617818321.805 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175841.00,A,3648.37308,N,12148.30866,W,17.728,279.44,070421,,,D*43
2021-04-07T17:58:41.807Z,1617818321.807 [NAL9602](INFO): GPS fix at 20210407T175841: (36.806218, -121.805144)
2021-04-07T17:58:41.818Z,1617818321.818 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T17:58:41.818Z,1617818321.818 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T17:58:49.052Z,1617818329.052 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0043.lzma
2021-04-07T17:58:50.054Z,1617818330.054 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0043.lzma.bak
2021-04-07T17:58:50.055Z,1617818330.055 [DataOverHttps](INFO): SBD MOMSN=15550453
2021-04-07T17:59:01.189Z,1617818341.189 [NAL9602](INFO): SBD MO Status=0, MOMSN=1701, MT Status=0, MTMSN=0
2021-04-07T17:59:01.190Z,1617818341.190 [NAL9602](INFO): No messages in MT queue
2021-04-07T17:59:05.864Z,1617818345.864 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20210407T163729/Express0044.lzma
2021-04-07T17:59:06.867Z,1617818346.867 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0044.lzma.bak
2021-04-07T17:59:06.867Z,1617818346.867 [DataOverHttps](INFO): SBD MOMSN=15550456
2021-04-07T17:59:08.899Z,1617818348.899 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T17:59:08.899Z,1617818348.899 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T17:59:08.899Z,1617818348.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T17:59:31.895Z,1617818371.895 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T18:01:09.743Z,1617818469.743 [DVL_micro](ERROR): Failed to parse:
:T00000000,35.0,+21.3,0000.0,1489.0,000
2021-04-07T18:02:46.537Z,1617818566.537 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.622894
2021-04-07T18:02:57.937Z,1617818577.937 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002928
2021-04-07T18:04:09.603Z,1617818649.603 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-07T18:04:09.603Z,1617818649.603 [Default:CheckIn:C.Wait] Stopped
2021-04-07T18:04:09.603Z,1617818649.603 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-07T18:04:09.603Z,1617818649.603 [Default:CheckIn:D] Running Loop=1
2021-04-07T18:04:09.982Z,1617818649.982 [Default:CheckIn:D] Stopped
2021-04-07T18:04:09.982Z,1617818649.982 [Default:CheckIn:E] Running Loop=1
2021-04-07T18:04:10.400Z,1617818650.400 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.282222 min 
2021-04-07T18:04:10.400Z,1617818650.400 [Default:CheckIn:E] Stopped
2021-04-07T18:04:10.400Z,1617818650.400 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-07T18:04:10.400Z,1617818650.400 [Default:CheckIn] Stopped
2021-04-07T18:04:10.400Z,1617818650.400 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-07T18:04:10.401Z,1617818650.401 [Default:CheckIn](INFO): Running loop #9
2021-04-07T18:04:10.401Z,1617818650.401 [Default:CheckIn] Running Loop=9
2021-04-07T18:04:10.401Z,1617818650.401 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-07T18:04:10.401Z,1617818650.401 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-07T18:04:12.400Z,1617818652.400 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180412.00,A,3648.50819,N,12149.16474,W,0.875,92.96,070421,,,A*4F
2021-04-07T18:04:12.403Z,1617818652.403 [NAL9602](INFO): GPS fix at 20210407T180412: (36.808470, -121.819412)
2021-04-07T18:04:12.437Z,1617818652.437 [Default:CheckIn:Read_GPS] Stopped
2021-04-07T18:04:12.437Z,1617818652.437 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-07T18:04:19.680Z,1617818659.680 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210407T163729/Courier0046.lzma
2021-04-07T18:04:20.682Z,1617818660.682 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Courier0046.lzma.bak
2021-04-07T18:04:20.683Z,1617818660.683 [DataOverHttps](INFO): SBD MOMSN=15550473
2021-04-07T18:04:27.366Z,1617818667.366 [NAL9602](INFO): SBD MO Status=0, MOMSN=1702, MT Status=0, MTMSN=0
2021-04-07T18:04:27.366Z,1617818667.366 [NAL9602](INFO): No messages in MT queue
2021-04-07T18:04:36.704Z,1617818676.704 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20210407T163729/Express0047.lzma
2021-04-07T18:04:37.707Z,1617818677.707 [DataOverHttps](INFO): Moved sent file to Logs/20210407T163729/Express0047.lzma.bak
2021-04-07T18:04:37.707Z,1617818677.707 [DataOverHttps](INFO): SBD MOMSN=15550476
2021-04-07T18:04:39.485Z,1617818679.485 [Default:CheckIn:Read_Iridium] Stopped
2021-04-07T18:04:39.485Z,1617818679.485 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-07T18:04:39.485Z,1617818679.485 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-07T18:04:58.044Z,1617818698.044 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-07T18:06:02.352Z,1617818762.352 [DVL_micro](ERROR): Failed to parse:
:WI,-00122,-00814,+00813,+00000,A
2021-04-07T18:07:45.074Z,1617818865.074 [DataOverHttps](IMPORTANT): SBD MTMSN=20210407T180744
2021-04-07T18:07:55.185Z,1617818875.185 [DataOverHttps](INFO): Received command:restart logs