2022-01-05T17:43:37.004Z,1641404617.004 [Supervisor](DEBUG): Initializing supervisor.
2022-01-05T17:43:37.009Z,1641404617.009 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-01-05T17:43:37.010Z,1641404617.010 [SyncHandler](INFO): Protected caller Thread ID is 835
2022-01-05T17:43:37.010Z,1641404617.010 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-01-05T17:43:37.011Z,1641404617.011 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-01-05T17:43:37.011Z,1641404617.011 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2022-01-05T17:43:37.016Z,1641404617.016 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-01-05T17:43:37.035Z,1641404617.035 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-01-05T17:43:37.037Z,1641404617.037 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-01-05T17:43:37.037Z,1641404617.037 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2022-01-05T17:43:37.041Z,1641404617.041 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-01-05T17:43:37.042Z,1641404617.042 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-01-05T17:43:37.043Z,1641404617.043 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2022-01-05T17:43:37.045Z,1641404617.045 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-01-05T17:43:37.046Z,1641404617.046 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-01-05T17:43:37.047Z,1641404617.047 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2022-01-05T17:43:37.051Z,1641404617.051 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-01-05T17:43:37.051Z,1641404617.051 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-01-05T17:43:37.055Z,1641404617.055 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-01-05T17:43:37.282Z,1641404617.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-01-05T17:43:37.284Z,1641404617.284 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-01-05T17:43:37.365Z,1641404617.365 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-01-05T17:43:37.866Z,1641404617.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-01-05T17:43:37.867Z,1641404617.867 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-01-05T17:43:38.187Z,1641404618.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-01-05T17:43:38.188Z,1641404618.188 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-01-05T17:43:38.295Z,1641404618.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-01-05T17:43:38.298Z,1641404618.298 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-01-05T17:43:38.656Z,1641404618.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-01-05T17:43:38.658Z,1641404618.658 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-01-05T17:43:38.859Z,1641404618.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-01-05T17:43:38.859Z,1641404618.859 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-01-05T17:43:39.309Z,1641404619.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-01-05T17:43:39.310Z,1641404619.310 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-01-05T17:43:39.437Z,1641404619.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-01-05T17:43:39.439Z,1641404619.439 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-01-05T17:43:39.519Z,1641404619.519 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-01-05T17:43:40.359Z,1641404620.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-01-05T17:43:40.660Z,1641404620.660 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-01-05T17:43:41.120Z,1641404621.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-01-05T17:43:41.121Z,1641404621.121 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-01-05T17:43:41.307Z,1641404621.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-01-05T17:43:41.309Z,1641404621.309 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-01-05T17:43:41.419Z,1641404621.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-01-05T17:43:41.421Z,1641404621.421 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-01-05T17:43:41.570Z,1641404621.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-01-05T17:43:41.572Z,1641404621.572 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-01-05T17:43:41.796Z,1641404621.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-01-05T17:43:41.798Z,1641404621.798 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2022-01-05T17:43:41.801Z,1641404621.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2022-01-05T17:43:41.891Z,1641404621.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2022-01-05T17:43:41.977Z,1641404621.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2022-01-05T17:43:42.078Z,1641404622.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2022-01-05T17:43:42.161Z,1641404622.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2022-01-05T17:43:42.258Z,1641404622.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2022-01-05T17:43:42.358Z,1641404622.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2022-01-05T17:43:42.535Z,1641404622.535 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2022-01-05T17:43:42.611Z,1641404622.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2022-01-05T17:43:42.755Z,1641404622.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2022-01-05T17:43:42.890Z,1641404622.890 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2022-01-05T17:43:43.005Z,1641404623.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2022-01-05T17:43:43.278Z,1641404623.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-01-05T17:43:43.279Z,1641404623.279 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-01-05T17:43:43.292Z,1641404623.292 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-01-05T17:43:43.366Z,1641404623.366 [VerticalControl](DEBUG): Construct VerticalControl.
2022-01-05T17:43:43.430Z,1641404623.430 [VerticalControl] Loaded
2022-01-05T17:43:43.430Z,1641404623.430 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-01-05T17:43:43.433Z,1641404623.433 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-01-05T17:43:43.473Z,1641404623.473 [HorizontalControl] Loaded
2022-01-05T17:43:43.473Z,1641404623.473 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-01-05T17:43:43.476Z,1641404623.476 [SpeedControl](DEBUG): Construct SpeedControl.
2022-01-05T17:43:43.479Z,1641404623.479 [SpeedControl] Loaded
2022-01-05T17:43:43.479Z,1641404623.479 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-01-05T17:43:43.482Z,1641404623.482 [LoopControl](DEBUG): Construct LoopControl.
2022-01-05T17:43:43.482Z,1641404623.482 [LoopControl] Loaded
2022-01-05T17:43:43.483Z,1641404623.483 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-01-05T17:43:43.483Z,1641404623.483 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-01-05T17:43:43.485Z,1641404623.485 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-01-05T17:43:43.501Z,1641404623.501 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-01-05T17:43:43.502Z,1641404623.502 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-01-05T17:43:43.633Z,1641404623.633 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-01-05T17:43:43.634Z,1641404623.634 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-01-05T17:43:43.812Z,1641404623.812 [BuoyancyServo] Loaded
2022-01-05T17:43:43.812Z,1641404623.812 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-01-05T17:43:43.832Z,1641404623.832 [ElevatorServo] Loaded
2022-01-05T17:43:43.833Z,1641404623.833 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-01-05T17:43:43.851Z,1641404623.851 [MassServo] Loaded
2022-01-05T17:43:43.851Z,1641404623.851 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-01-05T17:43:43.870Z,1641404623.870 [RudderServo] Loaded
2022-01-05T17:43:43.870Z,1641404623.870 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-01-05T17:43:43.888Z,1641404623.888 [ThrusterServo] Loaded
2022-01-05T17:43:43.888Z,1641404623.888 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-01-05T17:43:43.889Z,1641404623.889 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-01-05T17:43:43.889Z,1641404623.889 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-01-05T17:43:43.990Z,1641404623.990 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-01-05T17:43:43.990Z,1641404623.990 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-01-05T17:43:44.004Z,1641404624.004 [NavChart] Loaded
2022-01-05T17:43:44.004Z,1641404624.004 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-01-05T17:43:44.010Z,1641404624.010 [UniversalFixResidualReporter] Loaded
2022-01-05T17:43:44.010Z,1641404624.010 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-01-05T17:43:44.011Z,1641404624.011 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-01-05T17:43:44.012Z,1641404624.012 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-01-05T17:43:44.094Z,1641404624.094 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-01-05T17:43:44.095Z,1641404624.095 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-01-05T17:43:44.791Z,1641404624.791 [AHRS_M2] Loaded
2022-01-05T17:43:44.791Z,1641404624.791 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-01-05T17:43:45.597Z,1641404625.597 [BPC1] Loaded
2022-01-05T17:43:45.597Z,1641404625.597 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-01-05T17:43:45.741Z,1641404625.741 [DataOverHttps] Loaded
2022-01-05T17:43:45.741Z,1641404625.741 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-01-05T17:43:45.742Z,1641404625.742 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2022-01-05T17:43:45.742Z,1641404625.742 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2022-01-05T17:43:45.763Z,1641404625.763 [Depth_Keller] Loaded
2022-01-05T17:43:45.763Z,1641404625.763 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-01-05T17:43:45.768Z,1641404625.768 [DropWeight] Loaded
2022-01-05T17:43:45.768Z,1641404625.768 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-01-05T17:43:45.829Z,1641404625.829 [NAL9602] Loaded
2022-01-05T17:43:45.829Z,1641404625.829 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-01-05T17:43:45.859Z,1641404625.859 [Onboard] Loaded
2022-01-05T17:43:45.859Z,1641404625.859 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-01-05T17:43:45.860Z,1641404625.860 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2022-01-05T17:43:45.860Z,1641404625.860 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2022-01-05T17:43:45.873Z,1641404625.873 [Power24vConverter] Loaded
2022-01-05T17:43:45.874Z,1641404625.874 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-01-05T17:43:45.887Z,1641404625.887 [Radio_Surface] Loaded
2022-01-05T17:43:45.887Z,1641404625.887 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-01-05T17:43:45.888Z,1641404625.888 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409264E0
2022-01-05T17:43:45.888Z,1641404625.888 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2022-01-05T17:43:45.889Z,1641404625.889 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-01-05T17:43:45.890Z,1641404625.890 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-01-05T17:43:46.078Z,1641404626.078 [DepthRateCalculator] Loaded
2022-01-05T17:43:46.078Z,1641404626.078 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-01-05T17:43:46.083Z,1641404626.083 [PitchRateCalculator] Loaded
2022-01-05T17:43:46.083Z,1641404626.083 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-01-05T17:43:46.138Z,1641404626.138 [SpeedCalculator] Loaded
2022-01-05T17:43:46.138Z,1641404626.138 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-01-05T17:43:46.202Z,1641404626.202 [TempGradientCalculator] Loaded
2022-01-05T17:43:46.202Z,1641404626.202 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-01-05T17:43:46.272Z,1641404626.272 [YawRateCalculator] Loaded
2022-01-05T17:43:46.272Z,1641404626.272 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-01-05T17:43:46.291Z,1641404626.291 [ElevatorOffsetCalculator] Loaded
2022-01-05T17:43:46.292Z,1641404626.292 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-01-05T17:43:46.292Z,1641404626.292 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-01-05T17:43:46.293Z,1641404626.293 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-01-05T17:43:47.078Z,1641404627.078 [CANONSampler] Loaded
2022-01-05T17:43:47.078Z,1641404627.078 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2022-01-05T17:43:47.138Z,1641404627.138 [CTD_Seabird] Loaded
2022-01-05T17:43:47.138Z,1641404627.138 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-01-05T17:43:47.139Z,1641404627.139 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2022-01-05T17:43:47.140Z,1641404627.140 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2022-01-05T17:43:47.160Z,1641404627.160 [PAR_Licor] Loaded
2022-01-05T17:43:47.160Z,1641404627.160 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-01-05T17:43:47.193Z,1641404627.193 [WetLabsBB2FL] Loaded
2022-01-05T17:43:47.193Z,1641404627.193 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-01-05T17:43:47.194Z,1641404627.194 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0
2022-01-05T17:43:47.195Z,1641404627.195 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2022-01-05T17:43:47.195Z,1641404627.195 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-01-05T17:43:47.196Z,1641404627.196 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-01-05T17:43:47.239Z,1641404627.239 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-01-05T17:43:47.240Z,1641404627.240 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-01-05T17:43:47.618Z,1641404627.618 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-01-05T17:43:47.618Z,1641404627.618 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-01-05T17:43:47.795Z,1641404627.795 [SBIT](DEBUG): Construct Startup Built In Test.
2022-01-05T17:43:47.805Z,1641404627.805 [SBIT] Loaded
2022-01-05T17:43:47.805Z,1641404627.805 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-01-05T17:43:47.808Z,1641404627.808 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-01-05T17:43:47.822Z,1641404627.822 [IBIT] Loaded
2022-01-05T17:43:47.822Z,1641404627.822 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-01-05T17:43:47.828Z,1641404627.828 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-01-05T17:43:47.934Z,1641404627.934 [CBIT] Loaded
2022-01-05T17:43:47.934Z,1641404627.934 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-01-05T17:43:47.935Z,1641404627.935 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-01-05T17:43:47.935Z,1641404627.935 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-01-05T17:43:48.054Z,1641404628.054 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-01-05T17:43:48.061Z,1641404628.061 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-01-05T17:43:48.064Z,1641404628.064 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-01-05T17:43:48.075Z,1641404628.075 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-01-05T17:43:48.076Z,1641404628.076 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B414E0
2022-01-05T17:43:48.077Z,1641404628.077 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2022-01-05T17:43:48.081Z,1641404628.081 [Supervisor](INFO): Main Thread ID is 830
2022-01-05T17:43:48.081Z,1641404628.081 [Supervisor](DEBUG): Running supervisor.
2022-01-05T17:43:48.082Z,1641404628.082 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2022-01-05T17:43:48.083Z,1641404628.083 [CommandExec](INFO): Initializing the command executive.
2022-01-05T17:43:48.084Z,1641404628.084 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2022-01-05T17:43:48.086Z,1641404628.086 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2022-01-05T17:43:48.087Z,1641404628.087 [controlThread](DEBUG): Initializing ControlThread
2022-01-05T17:43:48.088Z,1641404628.088 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-01-05T17:43:48.090Z,1641404628.090 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-01-05T17:43:48.090Z,1641404628.090 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-01-05T17:43:48.091Z,1641404628.091 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-01-05T17:43:48.093Z,1641404628.093 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-01-05T17:43:48.093Z,1641404628.093 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-01-05T17:43:48.098Z,1641404628.098 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-01-05T17:43:48.098Z,1641404628.098 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-01-05T17:43:48.098Z,1641404628.098 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-01-05T17:43:48.099Z,1641404628.099 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-01-05T17:43:48.099Z,1641404628.099 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-01-05T17:43:48.100Z,1641404628.100 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-01-05T17:43:48.104Z,1641404628.104 [SBIT](INFO): Initialize SBIT Component.
2022-01-05T17:43:48.105Z,1641404628.105 [SBIT](IMPORTANT): git: 2022-01-05
2022-01-05T17:43:48.105Z,1641404628.105 [SBIT](INFO): git hash: 4648efb7c0362dd4a6331fa887af857d1a6abffc
2022-01-05T17:43:48.105Z,1641404628.105 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-01-05T17:43:48.106Z,1641404628.106 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-01-05T17:43:48.108Z,1641404628.108 [logger ThreadHandler](INFO): Handler Thread ID is 933
2022-01-05T17:43:48.124Z,1641404628.124 [SBIT](INFO): Beginning SBIT in 79.000000 seconds.
2022-01-05T17:43:48.124Z,1641404628.124 [IBIT](INFO): Initialize IBIT Component.
2022-01-05T17:43:48.125Z,1641404628.125 [CBIT](DEBUG): Initialize CBIT Component.
2022-01-05T17:43:48.132Z,1641404628.132 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2022-01-05T17:43:48.133Z,1641404628.133 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-01-05T17:43:48.140Z,1641404628.140 [CBIT](DEBUG): Initialized mux pins.
2022-01-05T17:43:48.140Z,1641404628.140 [CBIT](DEBUG): Initializing the watchdog timer.
2022-01-05T17:43:48.144Z,1641404628.144 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2022-01-05T17:43:48.161Z,1641404628.161 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2022-01-05T17:43:48.164Z,1641404628.164 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-01-05T17:43:48.164Z,1641404628.164 [CBIT](DEBUG): Initializing heartbeat.
2022-01-05T17:43:48.177Z,1641404628.177 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 937
2022-01-05T17:43:48.178Z,1641404628.178 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-05T17:43:48.181Z,1641404628.181 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 939
2022-01-05T17:43:48.184Z,1641404628.184 [WetLabsBB2FL](INFO): Powering up
2022-01-05T17:43:48.186Z,1641404628.186 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941
2022-01-05T17:43:48.194Z,1641404628.194 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-01-05T17:43:48.194Z,1641404628.194 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-01-05T17:43:48.195Z,1641404628.195 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-01-05T17:43:48.195Z,1641404628.195 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-01-05T17:43:48.195Z,1641404628.195 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-01-05T17:43:48.195Z,1641404628.195 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-01-05T17:43:48.195Z,1641404628.195 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-01-05T17:43:48.196Z,1641404628.196 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-01-05T17:43:48.196Z,1641404628.196 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-01-05T17:43:48.196Z,1641404628.196 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-01-05T17:43:48.197Z,1641404628.197 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-01-05T17:43:48.197Z,1641404628.197 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-01-05T17:43:48.197Z,1641404628.197 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-01-05T17:43:48.197Z,1641404628.197 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-01-05T17:43:48.197Z,1641404628.197 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-01-05T17:43:48.198Z,1641404628.198 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-01-05T17:43:48.236Z,1641404628.236 [CBIT](DEBUG): Deactivating GF circuits.
2022-01-05T17:43:48.236Z,1641404628.236 [CBIT](DEBUG): Deactivating emergency mode.
2022-01-05T17:43:48.272Z,1641404628.272 [CBIT](DEBUG): Backplane powered.
2022-01-05T17:43:48.273Z,1641404628.273 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-01-05T17:43:48.305Z,1641404628.305 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-01-05T17:43:48.326Z,1641404628.326 [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>


2022-01-05T17:43:48.326Z,1641404628.326 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-01-05T17:43:48.426Z,1641404628.426 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-01-05T17:43:48.427Z,1641404628.427 [Default:A.Wait](DEBUG): Construct Wait.
2022-01-05T17:43:48.430Z,1641404628.430 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-01-05T17:43:48.463Z,1641404628.463 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-01-05T17:43:48.466Z,1641404628.466 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-01-05T17:43:48.487Z,1641404628.487 [Default:E.Execute](DEBUG): Construct Execute.
2022-01-05T17:43:48.491Z,1641404628.491 [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>


2022-01-05T17:43:48.504Z,1641404628.504 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,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,
2022-01-05T17:43:48.516Z,1641404628.516 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-01-05T17:43:48.568Z,1641404628.568 [Radio_Surface](INFO): Powering up
2022-01-05T17:43:48.659Z,1641404628.659 [Power24vConverter](INFO): Powering up.
2022-01-05T17:43:48.664Z,1641404628.664 [CANONSampler](INFO): Powering down
2022-01-05T17:43:48.754Z,1641404628.754 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-01-05T17:43:48.761Z,1641404628.761 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-01-05T17:43:48.762Z,1641404628.762 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-01-05T17:43:48.768Z,1641404628.768 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-01-05T17:43:48.769Z,1641404628.769 [MassServo](DEBUG): Initializing EZServoServo.
2022-01-05T17:43:48.776Z,1641404628.776 [MassServo](DEBUG): Initializing MassServo.
2022-01-05T17:43:48.777Z,1641404628.777 [RudderServo](DEBUG): Initializing EZServoServo.
2022-01-05T17:43:48.784Z,1641404628.784 [RudderServo](DEBUG): Initializing RudderServo.
2022-01-05T17:43:48.785Z,1641404628.785 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-01-05T17:43:48.792Z,1641404628.792 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-01-05T17:43:49.684Z,1641404629.684 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-01-05T17:43:49.684Z,1641404629.684 [RudderServo](FAULT): Rudder failed to initialize
2022-01-05T17:43:49.684Z,1641404629.684 [RudderServo] Communications Fault, FailCount= 1
2022-01-05T17:43:49.684Z,1641404629.684 [RudderServo](ERROR): Communications Fault
2022-01-05T17:43:49.787Z,1641404629.787 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-01-05T17:43:49.945Z,1641404629.945 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-01-05T17:43:49.945Z,1641404629.945 [RudderServo](INFO): Powering down
2022-01-05T17:43:50.292Z,1641404630.292 [WetLabsBB2FL](INFO): Powering down
2022-01-05T17:43:50.625Z,1641404630.625 [RudderServo](DEBUG): Initializing EZServoServo.
2022-01-05T17:43:50.745Z,1641404630.745 [RudderServo](DEBUG): Initializing RudderServo.
2022-01-05T17:43:50.749Z,1641404630.749 [CBIT](INFO): Clearing failed state for component RudderServo
2022-01-05T17:43:50.749Z,1641404630.749 [RudderServo] No Fault, FailCount= 1
2022-01-05T17:44:16.062Z,1641404656.062 [NAL9602](INFO): Powering up NAL9602
2022-01-05T17:44:26.970Z,1641404666.970 [NAL9602](INFO): NAL9602 initialized
2022-01-05T17:45:03.440Z,1641404703.440 [CommandExec](IMPORTANT): got command 
2022-01-05T17:45:03.440Z,1641404703.440 [CommandExec](FAULT): Incomplete syntax. Try: help
2022-01-05T17:45:05.732Z,1641404705.732 [CommandExec](IMPORTANT): got command 
2022-01-05T17:45:05.732Z,1641404705.732 [CommandExec](FAULT): Incomplete syntax. Try: help
2022-01-05T17:45:07.831Z,1641404707.831 [SBIT](IMPORTANT): Beginning Startup BIT
2022-01-05T17:45:07.835Z,1641404707.835 [CBIT](IMPORTANT): Beginning ground fault scan
2022-01-05T17:45:18.733Z,1641404718.733 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-01-05T17:45:19.133Z,1641404719.133 [CBIT](IMPORTANT): Beginning ground fault scan
2022-01-05T17:45:30.117Z,1641404730.117 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-01-05T17:45:30.529Z,1641404730.529 [CBIT](IMPORTANT): Beginning ground fault scan
2022-01-05T17:45:41.328Z,1641404741.328 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-01-05T17:45:41.329Z,1641404741.329 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2022-01-05T17:46:01.989Z,1641404761.989 [SBIT](IMPORTANT): SBIT PASSED
2022-01-05T17:46:01.989Z,1641404761.989 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-01-05T17:46:01.990Z,1641404761.990 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-01-05T17:46:01.990Z,1641404761.990 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-01-05T17:46:01.990Z,1641404761.990 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-01-05T17:46:01.990Z,1641404761.990 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-01-05T17:46:01.990Z,1641404761.990 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=360.755759 cubic_centimeter;

2022-01-05T17:46:01.991Z,1641404761.991 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.821782 millimeter;

2022-01-05T17:46:02.394Z,1641404762.394 [MissionManager](IMPORTANT): Started mission Startup
2022-01-05T17:46:02.395Z,1641404762.395 [Startup] Running Loop=1
2022-01-05T17:46:02.395Z,1641404762.395 [Startup](DEBUG): Aggregate::initialize Startup
2022-01-05T17:46:02.395Z,1641404762.395 [Startup:A.GoToSurface] Running Loop=1
2022-01-05T17:46:02.395Z,1641404762.395 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-05T17:46:02.396Z,1641404762.396 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-05T17:46:02.396Z,1641404762.396 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-05T17:46:02.397Z,1641404762.397 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-05T17:46:02.397Z,1641404762.397 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-05T17:46:02.398Z,1641404762.398 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-05T17:46:02.399Z,1641404762.399 [Startup:StartupSatComms] Running Loop=1
2022-01-05T17:46:02.399Z,1641404762.399 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-01-05T17:46:02.399Z,1641404762.399 [Startup:StartupSatComms:A] Running Loop=1
2022-01-05T17:46:02.768Z,1641404762.768 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-01-05T17:46:30.131Z,1641404790.131 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005356
2022-01-05T17:46:48.388Z,1641404808.388 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-01-05T17:46:48.388Z,1641404808.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T17:46:48.399Z,1641404808.399 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T17:46:48.815Z,1641404808.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T17:46:48.815Z,1641404808.815 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-01-05T17:47:02.547Z,1641404822.547 [Startup:StartupSatComms:A](INFO): Timed out from 2022-01-05T17:46:02.4Z
2022-01-05T17:47:02.547Z,1641404822.547 [Startup:StartupSatComms:A] Stopped
2022-01-05T17:47:02.547Z,1641404822.547 [Startup:StartupSatComms:B] Running Loop=1
2022-01-05T17:47:02.935Z,1641404822.935 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-01-05T17:47:10.228Z,1641404830.228 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220105T174336/Courier0000.lzma
2022-01-05T17:47:11.230Z,1641404831.230 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0000.lzma.bak
2022-01-05T17:47:11.230Z,1641404831.230 [DataOverHttps](INFO): SBD MOMSN=16413908
2022-01-05T17:47:26.821Z,1641404846.821 [DataOverHttps](INFO): Sending 758 bytes from file Logs/20220105T174336/Express0001.lzma
2022-01-05T17:47:27.822Z,1641404847.822 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0001.lzma.bak
2022-01-05T17:47:27.822Z,1641404847.822 [DataOverHttps](INFO): SBD MOMSN=16413911
2022-01-05T17:47:29.190Z,1641404849.190 [Startup:StartupSatComms:B] Stopped
2022-01-05T17:47:29.190Z,1641404849.190 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-01-05T17:47:29.190Z,1641404849.190 [Startup:StartupSatComms] Stopped
2022-01-05T17:47:29.190Z,1641404849.190 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-01-05T17:47:29.191Z,1641404849.191 [Startup](INFO): Completed Startup
2022-01-05T17:47:29.191Z,1641404849.191 [MissionManager](INFO): Startup is completed.
2022-01-05T17:47:29.192Z,1641404849.192 [MissionManager](INFO): Uninitializing Mission Startup
2022-01-05T17:47:29.192Z,1641404849.192 [Startup] Stopped
2022-01-05T17:47:29.192Z,1641404849.192 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-01-05T17:47:29.192Z,1641404849.192 [Startup:A.GoToSurface] Stopped
2022-01-05T17:47:29.192Z,1641404849.192 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-01-05T17:47:29.605Z,1641404849.605 [MissionManager](IMPORTANT): Started mission Default
2022-01-05T17:47:29.605Z,1641404849.605 [Default] Running Loop=1
2022-01-05T17:47:29.605Z,1641404849.605 [Default](DEBUG): Aggregate::initialize Default
2022-01-05T17:47:29.606Z,1641404849.606 [Default:B.GoToSurface] Running Loop=1
2022-01-05T17:47:29.606Z,1641404849.606 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-05T17:47:29.606Z,1641404849.606 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-05T17:47:29.606Z,1641404849.606 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-05T17:47:29.607Z,1641404849.607 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-05T17:47:29.607Z,1641404849.607 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-05T17:47:29.607Z,1641404849.607 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-05T17:47:29.607Z,1641404849.607 [Default:A.Wait] Running Loop=1
2022-01-05T17:47:29.608Z,1641404849.608 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-01-05T17:47:42.940Z,1641404862.940 [Default:A.Wait](INFO): Done Waiting.
2022-01-05T17:47:42.940Z,1641404862.940 [Default:A.Wait] Stopped
2022-01-05T17:47:42.940Z,1641404862.940 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T17:47:43.389Z,1641404863.389 [Default:CheckIn] Running Loop=1
2022-01-05T17:47:43.389Z,1641404863.389 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T17:47:43.389Z,1641404863.389 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T17:47:43.755Z,1641404863.755 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-01-05T17:49:30.386Z,1641404970.386 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-01-05T17:49:49.402Z,1641404989.402 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-01-05T17:49:49.402Z,1641404989.402 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T17:49:49.413Z,1641404989.413 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T17:49:49.819Z,1641404989.819 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T17:49:49.819Z,1641404989.819 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-01-05T17:50:35.419Z,1641405035.419 [CommandExec](IMPORTANT): got command burn on
2022-01-05T17:50:35.419Z,1641405035.419 [CommandExec](IMPORTANT): Activating dropweight wire
2022-01-05T17:50:41.249Z,1641405041.249 [CommandExec](IMPORTANT): got command burn off
2022-01-05T17:50:41.249Z,1641405041.249 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-01-05T17:50:47.761Z,1641405047.761 [CommandExec](IMPORTANT): got command burn on
2022-01-05T17:50:47.761Z,1641405047.761 [CommandExec](IMPORTANT): Activating dropweight wire
2022-01-05T17:51:00.625Z,1641405060.625 [CommandExec](IMPORTANT): got command burn off
2022-01-05T17:51:00.625Z,1641405060.625 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-01-05T17:51:20.547Z,1641405080.547 [CommandExec](IMPORTANT): got command get platform_orientation degree
2022-01-05T17:51:20.547Z,1641405080.547 [CommandExec](IMPORTANT): platform_orientation 191.466363 arcdeg
2022-01-05T17:51:35.379Z,1641405095.379 [CommandExec](IMPORTANT): got command get platform_orientation degree
2022-01-05T17:51:35.379Z,1641405095.379 [CommandExec](IMPORTANT): platform_orientation 191.739024 arcdeg
2022-01-05T17:52:16.831Z,1641405136.831 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-01-05T17:52:16.834Z,1641405136.834 [BPC1](INFO): Received data from all battery sticks.
2022-01-05T17:52:43.515Z,1641405163.515 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-01-05T17:47:43.4Z
2022-01-05T17:52:43.515Z,1641405163.515 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T17:52:43.515Z,1641405163.515 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T17:52:43.903Z,1641405163.903 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-01-05T17:52:50.167Z,1641405170.167 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220105T174336/Courier0004.lzma
2022-01-05T17:52:50.367Z,1641405170.367 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-01-05T17:52:50.367Z,1641405170.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T17:52:50.394Z,1641405170.394 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T17:52:50.784Z,1641405170.784 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T17:52:50.784Z,1641405170.784 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-01-05T17:52:51.170Z,1641405171.170 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0004.lzma.bak
2022-01-05T17:52:51.170Z,1641405171.170 [DataOverHttps](INFO): SBD MOMSN=16413935
2022-01-05T17:53:06.688Z,1641405186.688 [DataOverHttps](INFO): Sending 334 bytes from file Logs/20220105T174336/Express0005.lzma
2022-01-05T17:53:07.690Z,1641405187.690 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0005.lzma.bak
2022-01-05T17:53:07.690Z,1641405187.690 [DataOverHttps](INFO): SBD MOMSN=16413937
2022-01-05T17:53:08.979Z,1641405188.979 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T17:53:08.979Z,1641405188.979 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T17:53:08.979Z,1641405188.979 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T17:53:49.341Z,1641405229.341 [Power24vConverter](INFO): Powering down.
2022-01-05T17:54:17.737Z,1641405257.737 [DataOverHttps](IMPORTANT): SBD MTMSN=20220105T175416
2022-01-05T17:54:27.460Z,1641405267.460 [DataOverHttps](INFO): Received command: strobe off
2022-01-05T17:54:27.489Z,1641405267.489 [CommandExec](IMPORTANT): got command strobe off
2022-01-05T17:54:27.490Z,1641405267.490 [CommandExec](IMPORTANT): Deactivating strobe
2022-01-05T17:54:30.189Z,1641405270.189 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-01-05T17:54:30.190Z,1641405270.190 [NAL9602] Data Fault, FailCount= 1
2022-01-05T17:54:30.190Z,1641405270.190 [NAL9602](ERROR): Data Fault
2022-01-05T17:54:30.251Z,1641405270.251 [CBIT](ERROR): Data Fault in component: NAL9602
2022-01-05T17:54:30.591Z,1641405270.591 [NAL9602](INFO): Powering down
2022-01-05T17:54:31.429Z,1641405271.429 [CBIT](INFO): Clearing failed state for component NAL9602
2022-01-05T17:54:31.429Z,1641405271.429 [NAL9602] No Fault, FailCount= 1
2022-01-05T17:55:00.944Z,1641405300.944 [NAL9602](INFO): Powering up NAL9602
2022-01-05T17:55:11.858Z,1641405311.858 [NAL9602](INFO): NAL9602 initialized
2022-01-05T17:55:51.474Z,1641405351.474 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-01-05T17:55:51.475Z,1641405351.475 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T17:55:51.486Z,1641405351.486 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T17:55:51.878Z,1641405351.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T17:55:51.878Z,1641405351.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-01-05T17:58:09.670Z,1641405489.670 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T17:58:09.670Z,1641405489.670 [Default:CheckIn:C.Wait] Stopped
2022-01-05T17:58:09.671Z,1641405489.671 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T17:58:09.671Z,1641405489.671 [Default:CheckIn:D] Running Loop=1
2022-01-05T17:58:10.074Z,1641405490.074 [Default:CheckIn:D] Stopped
2022-01-05T17:58:10.074Z,1641405490.074 [Default:CheckIn:E] Running Loop=1
2022-01-05T17:58:10.503Z,1641405490.503 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.674482 min 
2022-01-05T17:58:10.503Z,1641405490.503 [Default:CheckIn:E] Stopped
2022-01-05T17:58:10.503Z,1641405490.503 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T17:58:10.503Z,1641405490.503 [Default:CheckIn] Stopped
2022-01-05T17:58:10.503Z,1641405490.503 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T17:58:10.504Z,1641405490.504 [Default:CheckIn](INFO): Running loop #2
2022-01-05T17:58:10.504Z,1641405490.504 [Default:CheckIn] Running Loop=2
2022-01-05T17:58:10.504Z,1641405490.504 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T17:58:10.504Z,1641405490.504 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T17:58:52.487Z,1641405532.487 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-01-05T17:58:52.487Z,1641405532.487 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T17:58:52.507Z,1641405532.507 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T17:58:52.912Z,1641405532.912 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T17:58:52.912Z,1641405532.912 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-01-05T18:01:53.535Z,1641405713.535 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-01-05T18:01:53.535Z,1641405713.535 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:01:53.579Z,1641405713.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:01:53.939Z,1641405713.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:01:53.939Z,1641405713.939 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-01-05T18:03:10.690Z,1641405790.690 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-01-05T17:58:10.5Z
2022-01-05T18:03:10.690Z,1641405790.690 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T18:03:10.690Z,1641405790.690 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T18:03:17.999Z,1641405797.999 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220105T174336/Courier0007.lzma
2022-01-05T18:03:19.001Z,1641405799.001 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0007.lzma.bak
2022-01-05T18:03:19.002Z,1641405799.002 [DataOverHttps](INFO): SBD MOMSN=16413955
2022-01-05T18:03:34.556Z,1641405814.556 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20220105T174336/Express0008.lzma
2022-01-05T18:03:35.558Z,1641405815.558 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0008.lzma.bak
2022-01-05T18:03:35.558Z,1641405815.558 [DataOverHttps](INFO): SBD MOMSN=16413957
2022-01-05T18:03:51.111Z,1641405831.111 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220105T174336/Express0011.lzma
2022-01-05T18:03:52.114Z,1641405832.114 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0011.lzma.bak
2022-01-05T18:03:52.114Z,1641405832.114 [DataOverHttps](INFO): SBD MOMSN=16413968
2022-01-05T18:03:53.129Z,1641405833.129 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T18:03:53.129Z,1641405833.129 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T18:03:53.129Z,1641405833.129 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T18:04:54.534Z,1641405894.534 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-01-05T18:04:54.534Z,1641405894.534 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:04:54.578Z,1641405894.578 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:04:54.952Z,1641405894.952 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:04:54.952Z,1641405894.952 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-01-05T18:05:14.706Z,1641405914.706 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-01-05T18:05:14.706Z,1641405914.706 [NAL9602] Data Fault, FailCount= 2
2022-01-05T18:05:14.706Z,1641405914.706 [NAL9602](ERROR): Data Fault
2022-01-05T18:05:14.755Z,1641405914.755 [CBIT](ERROR): Data Fault in component: NAL9602
2022-01-05T18:05:15.109Z,1641405915.109 [NAL9602](INFO): Powering down
2022-01-05T18:05:15.939Z,1641405915.939 [CBIT](INFO): Clearing failed state for component NAL9602
2022-01-05T18:05:15.939Z,1641405915.939 [NAL9602] No Fault, FailCount= 2
2022-01-05T18:05:45.418Z,1641405945.418 [NAL9602](INFO): Powering up NAL9602
2022-01-05T18:05:56.322Z,1641405956.322 [NAL9602](INFO): NAL9602 initialized
2022-01-05T18:07:55.508Z,1641406075.508 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-01-05T18:07:55.508Z,1641406075.508 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:07:55.519Z,1641406075.519 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:07:55.928Z,1641406075.928 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:07:55.929Z,1641406075.929 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-01-05T18:08:53.699Z,1641406133.699 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T18:08:53.699Z,1641406133.699 [Default:CheckIn:C.Wait] Stopped
2022-01-05T18:08:53.699Z,1641406133.699 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T18:08:53.699Z,1641406133.699 [Default:CheckIn:D] Running Loop=1
2022-01-05T18:08:54.099Z,1641406134.099 [Default:CheckIn:D] Stopped
2022-01-05T18:08:54.099Z,1641406134.099 [Default:CheckIn:E] Running Loop=1
2022-01-05T18:08:54.499Z,1641406134.499 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.408223 min 
2022-01-05T18:08:54.499Z,1641406134.499 [Default:CheckIn:E] Stopped
2022-01-05T18:08:54.499Z,1641406134.499 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T18:08:54.499Z,1641406134.499 [Default:CheckIn] Stopped
2022-01-05T18:08:54.499Z,1641406134.499 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T18:08:54.499Z,1641406134.499 [Default:CheckIn](INFO): Running loop #3
2022-01-05T18:08:54.499Z,1641406134.499 [Default:CheckIn] Running Loop=3
2022-01-05T18:08:54.520Z,1641406134.520 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T18:08:54.520Z,1641406134.520 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T18:10:56.516Z,1641406256.516 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-01-05T18:10:56.516Z,1641406256.516 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:10:56.527Z,1641406256.527 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:10:56.915Z,1641406256.915 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:10:56.915Z,1641406256.915 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-01-05T18:13:54.735Z,1641406434.735 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-01-05T18:08:54.5Z
2022-01-05T18:13:54.735Z,1641406434.735 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T18:13:54.735Z,1641406434.735 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T18:13:57.538Z,1641406437.538 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-01-05T18:13:57.538Z,1641406437.538 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:13:57.549Z,1641406437.549 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:13:57.969Z,1641406437.969 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:13:57.969Z,1641406437.969 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-01-05T18:14:02.307Z,1641406442.307 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220105T174336/Courier0013.lzma
2022-01-05T18:14:03.310Z,1641406443.310 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0013.lzma.bak
2022-01-05T18:14:03.310Z,1641406443.310 [DataOverHttps](INFO): SBD MOMSN=16414023
2022-01-05T18:14:19.807Z,1641406459.807 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220105T174336/Express0014.lzma
2022-01-05T18:14:20.810Z,1641406460.810 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0014.lzma.bak
2022-01-05T18:14:20.810Z,1641406460.810 [DataOverHttps](INFO): SBD MOMSN=16414025
2022-01-05T18:14:21.869Z,1641406461.869 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T18:14:21.870Z,1641406461.870 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T18:14:21.870Z,1641406461.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T18:15:57.514Z,1641406557.514 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-01-05T18:15:57.514Z,1641406557.514 [NAL9602] Data Fault, FailCount= 3
2022-01-05T18:15:57.514Z,1641406557.514 [NAL9602](ERROR): Data Fault
2022-01-05T18:15:57.601Z,1641406557.601 [CBIT](ERROR): Data Fault in component: NAL9602
2022-01-05T18:15:57.917Z,1641406557.917 [NAL9602](INFO): Powering down
2022-01-05T18:15:58.749Z,1641406558.749 [CBIT](INFO): Clearing failed state for component NAL9602
2022-01-05T18:15:58.749Z,1641406558.749 [NAL9602] No Fault, FailCount= 3
2022-01-05T18:16:28.217Z,1641406588.217 [NAL9602](INFO): Powering up NAL9602
2022-01-05T18:16:39.126Z,1641406599.126 [NAL9602](INFO): NAL9602 initialized
2022-01-05T18:16:58.526Z,1641406618.526 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-01-05T18:16:58.526Z,1641406618.526 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:16:58.553Z,1641406618.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:16:58.944Z,1641406618.944 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:16:58.944Z,1641406618.944 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-01-05T18:19:22.363Z,1641406762.363 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T18:19:22.363Z,1641406762.363 [Default:CheckIn:C.Wait] Stopped
2022-01-05T18:19:22.363Z,1641406762.363 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T18:19:22.363Z,1641406762.363 [Default:CheckIn:D] Running Loop=1
2022-01-05T18:19:22.758Z,1641406762.758 [Default:CheckIn:D] Stopped
2022-01-05T18:19:22.759Z,1641406762.759 [Default:CheckIn:E] Running Loop=1
2022-01-05T18:19:23.163Z,1641406763.163 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.885885 min 
2022-01-05T18:19:23.163Z,1641406763.163 [Default:CheckIn:E] Stopped
2022-01-05T18:19:23.163Z,1641406763.163 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T18:19:23.163Z,1641406763.163 [Default:CheckIn] Stopped
2022-01-05T18:19:23.163Z,1641406763.163 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T18:19:23.164Z,1641406763.164 [Default:CheckIn](INFO): Running loop #4
2022-01-05T18:19:23.164Z,1641406763.164 [Default:CheckIn] Running Loop=4
2022-01-05T18:19:23.164Z,1641406763.164 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T18:19:23.164Z,1641406763.164 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T18:19:59.533Z,1641406799.533 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-01-05T18:19:59.533Z,1641406799.533 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:19:59.552Z,1641406799.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:20:00.035Z,1641406800.035 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:20:00.036Z,1641406800.036 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-01-05T18:23:00.541Z,1641406980.541 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-01-05T18:23:00.541Z,1641406980.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:23:00.552Z,1641406980.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:23:00.971Z,1641406980.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:23:00.971Z,1641406980.971 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-01-05T18:24:23.355Z,1641407063.355 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-01-05T18:19:23.2Z
2022-01-05T18:24:23.355Z,1641407063.355 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T18:24:23.355Z,1641407063.355 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T18:24:31.035Z,1641407071.035 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220105T174336/Courier0016.lzma
2022-01-05T18:24:32.038Z,1641407072.038 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0016.lzma.bak
2022-01-05T18:24:32.038Z,1641407072.038 [DataOverHttps](INFO): SBD MOMSN=16414064
2022-01-05T18:24:47.611Z,1641407087.611 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20220105T174336/Express0017.lzma
2022-01-05T18:24:48.614Z,1641407088.614 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0017.lzma.bak
2022-01-05T18:24:48.614Z,1641407088.614 [DataOverHttps](INFO): SBD MOMSN=16414066
2022-01-05T18:24:49.645Z,1641407089.645 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T18:24:49.645Z,1641407089.645 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T18:24:49.645Z,1641407089.645 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T18:25:26.889Z,1641407126.889 [DataOverHttps](IMPORTANT): SBD MTMSN=20220105T182525
2022-01-05T18:25:34.172Z,1641407134.172 [DataOverHttps](INFO): Received command: run Insert/Science.xml
2022-01-05T18:25:34.189Z,1641407134.189 [CommandExec](IMPORTANT): got command run ./Missions/Insert/Science.xml
2022-01-05T18:25:34.190Z,1641407134.190 [MissionManager](INFO): Loading Mission from file: ./Missions/Insert/Science.xml
2022-01-05T18:25:34.474Z,1641407134.474 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2022-01-05T18:25:34.478Z,1641407134.478 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2022-01-05T18:25:34.481Z,1641407134.481 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2022-01-05T18:25:34.556Z,1641407134.556 [MissionManager](INFO): DefineArg Science.HighestSaltPeakReportActive = 0 bool
2022-01-05T18:25:34.559Z,1641407134.559 [MissionManager](INFO): DefineArg Science.HighestOilPeakReportActive = 0 bool
2022-01-05T18:25:34.583Z,1641407134.583 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2022-01-05T18:25:34.598Z,1641407134.598 [MissionManager](INFO): DefineArg Science.DetectTrough = 0 bool
2022-01-05T18:25:34.601Z,1641407134.601 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2022-01-05T18:25:34.605Z,1641407134.605 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2022-01-05T18:25:34.617Z,1641407134.617 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2022-01-05T18:25:34.624Z,1641407134.624 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2022-01-05T18:25:34.627Z,1641407134.627 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2022-01-05T18:25:34.635Z,1641407134.635 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2022-01-05T18:25:34.638Z,1641407134.638 [MissionManager](INFO): DefineArg Science.PeakDetectSalinityActive = 0 bool
2022-01-05T18:25:34.658Z,1641407134.658 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2022-01-05T18:25:34.662Z,1641407134.662 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool
2022-01-05T18:25:34.674Z,1641407134.674 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool
2022-01-05T18:25:34.697Z,1641407134.697 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2022-01-05T18:25:34.701Z,1641407134.701 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2022-01-05T18:25:34.714Z,1641407134.714 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2022-01-05T18:25:34.717Z,1641407134.717 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool
2022-01-05T18:25:34.721Z,1641407134.721 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2022-01-05T18:25:34.750Z,1641407134.750 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2022-01-05T18:25:34.754Z,1641407134.754 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2022-01-05T18:25:34.757Z,1641407134.757 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2022-01-05T18:25:34.779Z,1641407134.779 [MissionManager](INFO): DefineArg Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2022-01-05T18:25:34.791Z,1641407134.791 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2022-01-05T18:25:34.795Z,1641407134.795 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2022-01-05T18:25:34.798Z,1641407134.798 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2022-01-05T18:25:34.830Z,1641407134.830 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2022-01-05T18:25:34.833Z,1641407134.833 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2022-01-05T18:25:34.848Z,1641407134.848 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2022-01-05T18:25:34.852Z,1641407134.852 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2022-01-05T18:25:34.885Z,1641407134.885 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2022-01-05T18:25:34.888Z,1641407134.888 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2022-01-05T18:25:34.892Z,1641407134.892 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2022-01-05T18:25:34.895Z,1641407134.895 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2022-01-05T18:25:34.911Z,1641407134.911 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2022-01-05T18:25:34.958Z,1641407134.958 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2022-01-05T18:25:34.961Z,1641407134.961 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2022-01-05T18:25:34.974Z,1641407134.974 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2022-01-05T18:25:34.978Z,1641407134.978 [MissionManager](INFO): DefineOutput Science.PeakFDOMTemperature = nan degC
2022-01-05T18:25:35.034Z,1641407135.034 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2022-01-05T18:25:35.046Z,1641407135.046 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2022-01-05T18:25:35.050Z,1641407135.050 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2022-01-05T18:25:35.062Z,1641407135.062 [MissionManager](INFO): DefineOutput Science.PeakSalt = 0.000000 psu
2022-01-05T18:25:35.066Z,1641407135.066 [MissionManager](INFO): DefineOutput Science.PeakSaltDepth = 0.000000 m
2022-01-05T18:25:35.089Z,1641407135.089 [MissionManager](INFO): DefineOutput Science.PeakSaltTemperature = 0.000000 degC
2022-01-05T18:25:35.112Z,1641407135.112 [MissionManager](INFO): DefineOutput Science.PeakSaltLatitude = nan arcdeg
2022-01-05T18:25:35.121Z,1641407135.121 [MissionManager](INFO): DefineOutput Science.PeakSaltLongitude = nan arcdeg
2022-01-05T18:25:35.129Z,1641407135.129 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 ug/l
2022-01-05T18:25:35.133Z,1641407135.133 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2022-01-05T18:25:35.153Z,1641407135.153 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2022-01-05T18:25:35.161Z,1641407135.161 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2022-01-05T18:25:35.166Z,1641407135.166 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2022-01-05T18:25:35.181Z,1641407135.181 [MissionManager](INFO): DefineOutput Science.PatchSalt = 0.000000 psu
2022-01-05T18:25:35.194Z,1641407135.194 [MissionManager](INFO): DefineOutput Science.PatchSaltDepth = 0.000000 m
2022-01-05T18:25:35.206Z,1641407135.206 [MissionManager](INFO): DefineOutput Science.PatchSaltLatitude = nan arcdeg
2022-01-05T18:25:35.239Z,1641407135.239 [MissionManager](INFO): DefineOutput Science.PatchSaltLongitude = nan arcdeg
2022-01-05T18:25:35.243Z,1641407135.243 [MissionManager](INFO): DefineOutput Science.PatchSaltDistance = nan m
2022-01-05T18:25:35.247Z,1641407135.247 [MissionManager](INFO): DefineOutput Science.PatchOil = 0.000000 psu
2022-01-05T18:25:35.269Z,1641407135.269 [MissionManager](INFO): DefineOutput Science.PatchOilDepth = 0.000000 m
2022-01-05T18:25:35.306Z,1641407135.306 [MissionManager](INFO): DefineOutput Science.PatchOilLatitude = nan arcdeg
2022-01-05T18:25:35.310Z,1641407135.310 [MissionManager](INFO): DefineOutput Science.PatchOilLongitude = nan arcdeg
2022-01-05T18:25:35.314Z,1641407135.314 [MissionManager](INFO): DefineOutput Science.PatchOilDistance = nan m
2022-01-05T18:25:35.441Z,1641407135.441 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-01-05T18:25:35.455Z,1641407135.455 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-01-05T18:25:35.498Z,1641407135.498 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-01-05T18:25:35.549Z,1641407135.549 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-01-05T18:25:35.579Z,1641407135.579 [Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-01-05T18:25:35.618Z,1641407135.618 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-01-05T18:25:35.657Z,1641407135.657 [Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-01-05T18:25:35.691Z,1641407135.691 [Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-01-05T18:25:35.836Z,1641407135.836 [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 of chlorophyll on yo-yo
        profiles in a horizontal sliding window (of length
        numProfilesSlidingwindow)
    </Description><False/></DefineArg>

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

    <DefineArg Name="HighestOilPeakReportActive"><Description>
        Turns on reporting of the highest peak value of oil 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="DetectTrough"><Description>
        Whether to track a trough. If false, track peak; if true, track trough.
        Default is false.
    </Description><False/></DefineArg>

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

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

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

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

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

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

    <DefineArg Name="PeakDetectFDOMActive"><Description>
        Turns on peak detection of
        concentration_of_colored_dissolved_organic_matter_in_sea_water from FDOM
        sensor.
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectSalinityActive"><Description>
        Turns on peak detection of salinity.
    </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>

    <DefineArg Name="DepChangeThreshForAttitudeFlip"><Description>
        Depth change threshold for determining vehicle attitude flip.
    </Description><Units:meter/><Value>2.0</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="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>

    <DefineOutput Name="PeakSalt"><Units:psu/><Value>0</Value></DefineOutput>

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

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

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

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

    <DefineOutput Name="PatchChl"><Units:microgram_per_liter/><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="PatchSalt"><Units:psu/><Value>0</Value></DefineOutput>

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

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

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

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

    <DefineOutput Name="PatchOil"><Units:psu/><Value>0</Value></DefineOutput>

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

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

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

    <DefineOutput Name="PatchOilDistance"><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.average_bioluminescence/>
    </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>
            <Setting><Trigger:PeakDetectVsDepth.depthChangeThresh/><Arg Name="DepChangeThreshForAttitudeFlip"/></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>

<!--debug
        <Syslog Severity="Important">PeakOil, PeakOilDepth, PeakOilTemperature, PeakOilLatitude, PeakOilLongitude = <Arg Name="PeakOil"/><Units:kilogram_per_cubic_meter/>, <Arg Name="PeakOilDepth"/><Units:meter/>, <Arg Name="PeakOilTemperature"/><Units:celsius/>, <Arg Name="PeakOilLatitude"/><Units:degree/>, <Arg Name="PeakOilLongitude"/><Units:degree/></Syslog>
-->

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

    </Aggregate>

    <Aggregate Id="HighestOilPeakReport">

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

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakOil"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakOilDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakOilLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakOilLongitude"/></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="PatchOil"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchOilDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchOilLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchOilLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchOilDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </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 Id="PeakDetectSalinity">

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

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:sea_water_salinity/></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>
            <Setting><Trigger:PeakDetectVsDepth.depthChangeThresh/><Arg Name="DepChangeThreshForAttitudeFlip"/></Setting>
            <OutputArg Name="PeakSalt"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakSaltDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakSaltTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakSaltLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakSaltLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

<!--debug
        <Syslog Severity="Important">PeakSalt, PeakSaltDepth, PeakSaltTemperature, PeakSaltLatitude, PeakSaltLongitude = <Arg Name="PeakSalt"/><Units:psu/>, <Arg Name="PeakSaltDepth"/><Units:meter/>, <Arg Name="PeakSaltTemperature"/><Units:celsius/>, <Arg Name="PeakSaltLatitude"/><Units:degree/>, <Arg Name="PeakSaltLongitude"/><Units:degree/></Syslog>
-->

        <SendData Service="Express"><Arg Name="PeakSalt"/><Arg Name="PeakSaltDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="HighestSaltPeakReport">

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

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakSalt"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakSaltDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakSaltLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakSaltLongitude"/></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="PatchSalt"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchSaltDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchSaltLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchSaltLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchSaltDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </Aggregate>

</Aggregate>


2022-01-05T18:25:35.839Z,1641407135.839 [CommandExec](IMPORTANT): Running ./Missions/Insert/Science.xml
2022-01-05T18:25:36.165Z,1641407136.165 [Default] Stopped
2022-01-05T18:25:36.166Z,1641407136.166 [Default](DEBUG): Aggregate::uninitialize Default
2022-01-05T18:25:36.166Z,1641407136.166 [Default:B.GoToSurface] Stopped
2022-01-05T18:25:36.166Z,1641407136.166 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-01-05T18:25:36.166Z,1641407136.166 [Default:CheckIn] Stopped
2022-01-05T18:25:36.166Z,1641407136.166 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T18:25:36.166Z,1641407136.166 [Default:CheckIn:C.Wait] Stopped
2022-01-05T18:25:36.166Z,1641407136.166 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T18:25:36.166Z,1641407136.166 [MissionManager](IMPORTANT): Started mission Science
2022-01-05T18:25:36.167Z,1641407136.167 [Science] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science](DEBUG): Aggregate::initialize Science
2022-01-05T18:25:36.167Z,1641407136.167 [Science:A] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science:B] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science:C] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science:D] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science:E] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science:F] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science:Read_Oil] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science:PeakDetectChl] Running Loop=1
2022-01-05T18:25:36.167Z,1641407136.167 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2022-01-05T18:25:36.168Z,1641407136.168 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2022-01-05T18:25:36.168Z,1641407136.168 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-01-05T18:25:36.170Z,1641407136.170 [Science:HighestChlPeakReport] Running Loop=1
2022-01-05T18:25:36.170Z,1641407136.170 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2022-01-05T18:25:36.170Z,1641407136.170 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-01-05T18:25:36.171Z,1641407136.171 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-01-05T18:25:36.171Z,1641407136.171 [Science:PeakDetectNO3] Running Loop=1
2022-01-05T18:25:36.171Z,1641407136.171 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2022-01-05T18:25:36.171Z,1641407136.171 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2022-01-05T18:25:36.171Z,1641407136.171 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-01-05T18:25:36.178Z,1641407136.178 [Science:PeakDetectOil] Running Loop=1
2022-01-05T18:25:36.178Z,1641407136.178 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2022-01-05T18:25:36.179Z,1641407136.179 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2022-01-05T18:25:36.179Z,1641407136.179 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-01-05T18:25:36.181Z,1641407136.181 [Science:HighestOilPeakReport] Running Loop=1
2022-01-05T18:25:36.181Z,1641407136.181 [Science:HighestOilPeakReport](DEBUG): Aggregate::initialize Science:HighestOilPeakReport
2022-01-05T18:25:36.181Z,1641407136.181 [Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-01-05T18:25:36.181Z,1641407136.181 [Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-01-05T18:25:36.182Z,1641407136.182 [Science:PeakDetectFDOM] Running Loop=1
2022-01-05T18:25:36.182Z,1641407136.182 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2022-01-05T18:25:36.182Z,1641407136.182 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2022-01-05T18:25:36.182Z,1641407136.182 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-01-05T18:25:36.184Z,1641407136.184 [Science:PeakDetectSalinity] Running Loop=1
2022-01-05T18:25:36.185Z,1641407136.185 [Science:PeakDetectSalinity](DEBUG): Aggregate::initialize Science:PeakDetectSalinity
2022-01-05T18:25:36.185Z,1641407136.185 [Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2022-01-05T18:25:36.185Z,1641407136.185 [Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-01-05T18:25:36.186Z,1641407136.186 [Science:HighestSaltPeakReport] Running Loop=1
2022-01-05T18:25:36.187Z,1641407136.187 [Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize Science:HighestSaltPeakReport
2022-01-05T18:25:36.187Z,1641407136.187 [Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-01-05T18:25:36.187Z,1641407136.187 [Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-01-05T18:25:36.187Z,1641407136.187 [Science:OceanCurrent] Running Loop=1
2022-01-05T18:25:36.187Z,1641407136.187 [Science:OceanCurrent](DEBUG): Aggregate::initialize Science:OceanCurrent
2022-01-05T18:25:36.216Z,1641407136.216 [Science:OceanCurrent:A.] Running Loop=1
2022-01-05T18:25:36.216Z,1641407136.216 [Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2022-01-05T18:25:36.217Z,1641407136.217 [Science:HighestSaltPeakReport] Stopped
2022-01-05T18:25:36.217Z,1641407136.217 [Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize Science:HighestSaltPeakReport
2022-01-05T18:25:36.217Z,1641407136.217 [Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2022-01-05T18:25:36.217Z,1641407136.217 [Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-01-05T18:25:36.218Z,1641407136.218 [Science:PeakDetectSalinity] Stopped
2022-01-05T18:25:36.218Z,1641407136.218 [Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize Science:PeakDetectSalinity
2022-01-05T18:25:36.218Z,1641407136.218 [Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2022-01-05T18:25:36.218Z,1641407136.218 [Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-01-05T18:25:36.218Z,1641407136.218 [Science:PeakDetectFDOM] Stopped
2022-01-05T18:25:36.218Z,1641407136.218 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2022-01-05T18:25:36.218Z,1641407136.218 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2022-01-05T18:25:36.218Z,1641407136.218 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-01-05T18:25:36.218Z,1641407136.218 [Science:HighestOilPeakReport] Stopped
2022-01-05T18:25:36.218Z,1641407136.218 [Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize Science:HighestOilPeakReport
2022-01-05T18:25:36.218Z,1641407136.218 [Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2022-01-05T18:25:36.219Z,1641407136.219 [Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-01-05T18:25:36.219Z,1641407136.219 [Science:PeakDetectOil] Stopped
2022-01-05T18:25:36.219Z,1641407136.219 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2022-01-05T18:25:36.219Z,1641407136.219 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2022-01-05T18:25:36.219Z,1641407136.219 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-01-05T18:25:36.219Z,1641407136.219 [Science:PeakDetectNO3] Stopped
2022-01-05T18:25:36.219Z,1641407136.219 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2022-01-05T18:25:36.219Z,1641407136.219 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2022-01-05T18:25:36.219Z,1641407136.219 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-01-05T18:25:36.219Z,1641407136.219 [Science:HighestChlPeakReport] Stopped
2022-01-05T18:25:36.220Z,1641407136.220 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2022-01-05T18:25:36.220Z,1641407136.220 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2022-01-05T18:25:36.220Z,1641407136.220 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-01-05T18:25:36.220Z,1641407136.220 [Science:PeakDetectChl] Stopped
2022-01-05T18:25:36.220Z,1641407136.220 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2022-01-05T18:25:36.220Z,1641407136.220 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2022-01-05T18:25:36.220Z,1641407136.220 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-01-05T18:25:36.220Z,1641407136.220 [Science:OceanCurrent:A.] Running Loop=1
2022-01-05T18:25:36.220Z,1641407136.220 [Science:Read_Oil] Stopped
2022-01-05T18:25:36.221Z,1641407136.221 [Science:F] Stopped
2022-01-05T18:25:36.221Z,1641407136.221 [Science:E] Running Loop=1
2022-01-05T18:25:36.221Z,1641407136.221 [Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2022-01-05T18:25:36.222Z,1641407136.222 [Science:D] Stopped
2022-01-05T18:25:36.222Z,1641407136.222 [Science:C] Running Loop=1
2022-01-05T18:25:36.222Z,1641407136.222 [Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2022-01-05T18:25:36.223Z,1641407136.223 [Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2022-01-05T18:25:36.223Z,1641407136.223 [Science:B] Running Loop=1
2022-01-05T18:25:36.225Z,1641407136.225 [WetLabsBB2FL](INFO): Powering up
2022-01-05T18:25:36.226Z,1641407136.226 [Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2022-01-05T18:25:36.227Z,1641407136.227 [Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2022-01-05T18:25:36.227Z,1641407136.227 [Science:A] Stopped
2022-01-05T18:25:39.801Z,1641407139.801 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2022-01-05T18:25:41.810Z,1641407141.810 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009055 psu
2022-01-05T18:25:42.814Z,1641407142.814 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009056 psu
2022-01-05T18:25:43.818Z,1641407143.818 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009057 psu
2022-01-05T18:25:44.822Z,1641407144.822 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009002 psu
2022-01-05T18:25:45.826Z,1641407145.826 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009003 psu
2022-01-05T18:25:46.830Z,1641407146.830 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009003 psu
2022-01-05T18:25:47.846Z,1641407147.846 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009004 psu
2022-01-05T18:25:48.838Z,1641407148.838 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009004 psu
2022-01-05T18:25:49.842Z,1641407149.842 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009005 psu
2022-01-05T18:25:50.848Z,1641407150.848 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009006 psu
2022-01-05T18:25:51.850Z,1641407151.850 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009006 psu
2022-01-05T18:25:52.854Z,1641407152.854 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:25:53.858Z,1641407153.858 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:25:54.862Z,1641407154.862 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:25:55.866Z,1641407155.866 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:25:56.870Z,1641407156.870 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:25:57.874Z,1641407157.874 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:25:58.878Z,1641407158.878 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:25:59.882Z,1641407159.882 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:26:00.886Z,1641407160.886 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:26:01.619Z,1641407161.619 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-01-05T18:26:01.619Z,1641407161.619 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:26:01.673Z,1641407161.673 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:26:01.890Z,1641407161.890 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:26:02.048Z,1641407162.048 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:26:02.049Z,1641407162.049 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-01-05T18:26:02.894Z,1641407162.894 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:26:03.898Z,1641407163.898 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009007 psu
2022-01-05T18:26:04.941Z,1641407164.941 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009008 psu
2022-01-05T18:26:05.941Z,1641407165.941 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008953 psu
2022-01-05T18:26:06.937Z,1641407166.937 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009009 psu
2022-01-05T18:26:07.937Z,1641407167.937 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009010 psu
2022-01-05T18:26:08.941Z,1641407168.941 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008956 psu
2022-01-05T18:26:09.945Z,1641407169.945 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008957 psu
2022-01-05T18:26:10.938Z,1641407170.938 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008958 psu
2022-01-05T18:26:11.942Z,1641407171.942 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008958 psu
2022-01-05T18:26:12.946Z,1641407172.946 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008958 psu
2022-01-05T18:26:13.950Z,1641407173.950 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008959 psu
2022-01-05T18:26:14.965Z,1641407174.965 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008959 psu
2022-01-05T18:26:15.958Z,1641407175.958 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008959 psu
2022-01-05T18:26:16.962Z,1641407176.962 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008960 psu
2022-01-05T18:26:17.966Z,1641407177.966 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008960 psu
2022-01-05T18:26:18.970Z,1641407178.970 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008960 psu
2022-01-05T18:26:19.974Z,1641407179.974 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008960 psu
2022-01-05T18:26:20.978Z,1641407180.978 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008961 psu
2022-01-05T18:26:21.982Z,1641407181.982 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008961 psu
2022-01-05T18:26:22.986Z,1641407182.986 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008961 psu
2022-01-05T18:26:23.990Z,1641407183.990 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008961 psu
2022-01-05T18:26:24.994Z,1641407184.994 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008961 psu
2022-01-05T18:26:25.998Z,1641407185.998 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008961 psu
2022-01-05T18:26:27.002Z,1641407187.002 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008961 psu
2022-01-05T18:26:28.006Z,1641407188.006 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008961 psu
2022-01-05T18:26:29.010Z,1641407189.010 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008962 psu
2022-01-05T18:26:30.014Z,1641407190.014 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008962 psu
2022-01-05T18:26:31.020Z,1641407191.020 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008962 psu
2022-01-05T18:26:32.023Z,1641407192.023 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008962 psu
2022-01-05T18:26:33.030Z,1641407193.030 [CTD_Seabird](ERROR): Salinity reading out of range: 0.008962 psu
2022-01-05T18:26:34.030Z,1641407194.030 [CTD_Seabird](ERROR): Salinity reading out of range: 0.010573 psu
2022-01-05T18:26:35.036Z,1641407195.036 [CTD_Seabird](ERROR): Salinity reading out of range: 13.400243 psu
2022-01-05T18:26:36.038Z,1641407196.038 [CTD_Seabird](ERROR): Salinity reading out of range: 23.183609 psu
2022-01-05T18:26:40.378Z,1641407200.378 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-01-05T18:26:40.378Z,1641407200.378 [NAL9602] Data Fault, FailCount= 4
2022-01-05T18:26:40.379Z,1641407200.379 [NAL9602](ERROR): Data Fault
2022-01-05T18:26:40.437Z,1641407200.437 [CBIT](ERROR): Data Fault in component: NAL9602
2022-01-05T18:26:40.781Z,1641407200.781 [NAL9602](INFO): Powering down
2022-01-05T18:26:41.609Z,1641407201.609 [CBIT](INFO): Clearing failed state for component NAL9602
2022-01-05T18:26:41.609Z,1641407201.609 [NAL9602] No Fault, FailCount= 4
2022-01-05T18:27:03.346Z,1641407223.346 [CommandExec](IMPORTANT): got command show variable salinity
2022-01-05T18:27:03.353Z,1641407223.353 [CommandExec](IMPORTANT): sea_water_salinity (practical_salinity_unit)
2022-01-05T18:27:03.540Z,1641407223.540 [CommandExec](IMPORTANT): CTD_NeilBrown.maxSalinityBound (practical_salinity_unit)
2022-01-05T18:27:03.540Z,1641407223.540 [CommandExec](IMPORTANT): CTD_NeilBrown.minSalinityBound (practical_salinity_unit)
2022-01-05T18:27:03.541Z,1641407223.541 [CommandExec](IMPORTANT): CTD_Seabird.maxSalinityBound (practical_salinity_unit)
2022-01-05T18:27:03.542Z,1641407223.542 [CommandExec](IMPORTANT): CTD_Seabird.minSalinityBound (practical_salinity_unit)
2022-01-05T18:27:03.565Z,1641407223.565 [CommandExec](IMPORTANT): StratificationFrontDetector.salinityFront (bool)
2022-01-05T18:27:03.690Z,1641407223.690 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_salinity (practical_salinity_unit)
2022-01-05T18:27:03.696Z,1641407223.696 [CommandExec](IMPORTANT): CTD_Seabird.bin_median_sea_water_salinity (practical_salinity_unit)
2022-01-05T18:27:03.697Z,1641407223.697 [CommandExec](IMPORTANT): CTD_Seabird.bin_mean_sea_water_salinity (practical_salinity_unit)
2022-01-05T18:27:03.697Z,1641407223.697 [CommandExec](IMPORTANT): CTD_Seabird.bin_standard_deviation_sea_water_salinity (practical_salinity_unit)
2022-01-05T18:27:03.714Z,1641407223.714 [CommandExec](IMPORTANT): Science.PeakDetectSalinityActive (bool)
2022-01-05T18:27:11.093Z,1641407231.093 [NAL9602](INFO): Powering up NAL9602
2022-01-05T18:27:14.544Z,1641407234.544 [CommandExec](IMPORTANT): got command report touch CTD_Seabird.sea_water_salinity
2022-01-05T18:27:15.555Z,1641407235.555 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.632710 psu
2022-01-05T18:27:16.370Z,1641407236.370 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.632519 psu
2022-01-05T18:27:17.577Z,1641407237.577 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.632305 psu
2022-01-05T18:27:18.375Z,1641407238.375 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.632210 psu
2022-01-05T18:27:19.601Z,1641407239.601 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.630360 psu
2022-01-05T18:27:20.438Z,1641407240.438 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.632782 psu
2022-01-05T18:27:21.630Z,1641407241.630 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.629971 psu
2022-01-05T18:27:21.990Z,1641407241.990 [NAL9602](INFO): NAL9602 initialized
2022-01-05T18:27:22.422Z,1641407242.422 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.630379 psu
2022-01-05T18:27:23.275Z,1641407243.275 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.627991 psu
2022-01-05T18:27:24.446Z,1641407244.446 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.630466 psu
2022-01-05T18:27:25.269Z,1641407245.269 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.630875 psu
2022-01-05T18:27:26.474Z,1641407246.474 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.631844 psu
2022-01-05T18:27:27.289Z,1641407247.289 [Reporter](INFO): CTD_Seabird.sea_water_salinity 33.631718 psu
2022-01-05T18:27:27.938Z,1641407247.938 [CommandExec](IMPORTANT): got command report clear
2022-01-05T18:27:31.438Z,1641407251.438 [CommandExec](IMPORTANT): got command show variable oxygen
2022-01-05T18:27:31.442Z,1641407251.442 [CommandExec](IMPORTANT): mass_concentration_of_oxygen_in_sea_water (microgram_per_liter)
2022-01-05T18:27:31.534Z,1641407251.534 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffFOffset (none)
2022-01-05T18:27:31.535Z,1641407251.535 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffSoc (none)
2022-01-05T18:27:31.535Z,1641407251.535 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffA (none)
2022-01-05T18:27:31.536Z,1641407251.536 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffB (none)
2022-01-05T18:27:31.536Z,1641407251.536 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffC (none)
2022-01-05T18:27:31.537Z,1641407251.537 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffE (none)
2022-01-05T18:27:31.646Z,1641407251.646 [CommandExec](IMPORTANT): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water (microgram_per_liter)
2022-01-05T18:27:31.647Z,1641407251.647 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_dissolved_oxygen_freq (hertz)
2022-01-05T18:27:48.237Z,1641407268.237 [CommandExec](IMPORTANT): got command report touch CTD_Seabird.mass_concentration_of_oxygen_in_sea_water
2022-01-05T18:27:48.332Z,1641407268.332 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7447.513672 ug/l
2022-01-05T18:27:49.530Z,1641407269.530 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7432.562012 ug/l
2022-01-05T18:27:50.374Z,1641407270.374 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7430.085449 ug/l
2022-01-05T18:27:51.542Z,1641407271.542 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7431.259277 ug/l
2022-01-05T18:27:52.782Z,1641407272.782 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7431.689453 ug/l
2022-01-05T18:27:53.577Z,1641407273.577 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7434.138672 ug/l
2022-01-05T18:27:54.778Z,1641407274.778 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7433.032227 ug/l
2022-01-05T18:27:55.605Z,1641407275.605 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7430.906250 ug/l
2022-01-05T18:27:56.395Z,1641407276.395 [Reporter](INFO): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water 7428.904297 ug/l
2022-01-05T18:27:57.405Z,1641407277.405 [CommandExec](IMPORTANT): got command report clear
2022-01-05T18:27:59.868Z,1641407279.868 [CommandExec](IMPORTANT): got command stop
2022-01-05T18:27:59.868Z,1641407279.868 [CommandExec](IMPORTANT): Scheduling is paused
2022-01-05T18:27:59.868Z,1641407279.868 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2022-01-05T18:28:00.019Z,1641407280.019 [MissionManager](INFO): MissionManager is completed.
2022-01-05T18:28:00.019Z,1641407280.019 [MissionManager](INFO): Uninitializing Mission Science
2022-01-05T18:28:00.019Z,1641407280.019 [Science] Stopped
2022-01-05T18:28:00.024Z,1641407280.024 [Science](DEBUG): Aggregate::uninitialize Science
2022-01-05T18:28:00.024Z,1641407280.024 [Science:B] Stopped
2022-01-05T18:28:00.024Z,1641407280.024 [Science:C] Stopped
2022-01-05T18:28:00.024Z,1641407280.024 [Science:E] Stopped
2022-01-05T18:28:00.024Z,1641407280.024 [Science:OceanCurrent] Stopped
2022-01-05T18:28:00.024Z,1641407280.024 [Science:OceanCurrent](DEBUG): Aggregate::uninitialize Science:OceanCurrent
2022-01-05T18:28:00.024Z,1641407280.024 [Science:OceanCurrent:A.] Stopped
2022-01-05T18:28:00.448Z,1641407280.448 [MissionManager](IMPORTANT): Started mission Default
2022-01-05T18:28:00.449Z,1641407280.449 [Default] Running Loop=1
2022-01-05T18:28:00.449Z,1641407280.449 [Default](DEBUG): Aggregate::initialize Default
2022-01-05T18:28:00.449Z,1641407280.449 [Default:B.GoToSurface] Running Loop=1
2022-01-05T18:28:00.449Z,1641407280.449 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-05T18:28:00.449Z,1641407280.449 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-05T18:28:00.449Z,1641407280.449 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-05T18:28:00.450Z,1641407280.450 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-05T18:28:00.450Z,1641407280.450 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-05T18:28:00.450Z,1641407280.450 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-05T18:28:00.451Z,1641407280.451 [Default:A.Wait] Running Loop=1
2022-01-05T18:28:00.451Z,1641407280.451 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-01-05T18:28:00.568Z,1641407280.568 [WetLabsBB2FL](INFO): Powering down
2022-01-05T18:28:13.760Z,1641407293.760 [Default:A.Wait](INFO): Done Waiting.
2022-01-05T18:28:13.760Z,1641407293.760 [Default:A.Wait] Stopped
2022-01-05T18:28:13.760Z,1641407293.760 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T18:28:14.165Z,1641407294.165 [Default:CheckIn] Running Loop=1
2022-01-05T18:28:14.165Z,1641407294.165 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T18:28:14.165Z,1641407294.165 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T18:29:02.625Z,1641407342.625 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-01-05T18:29:02.625Z,1641407342.625 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:29:02.636Z,1641407342.636 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:29:03.047Z,1641407343.047 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:29:03.047Z,1641407343.047 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-01-05T18:32:03.633Z,1641407523.633 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-01-05T18:32:03.633Z,1641407523.633 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:32:03.681Z,1641407523.681 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:32:04.077Z,1641407524.077 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:32:04.077Z,1641407524.077 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-01-05T18:33:14.344Z,1641407594.344 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-01-05T18:28:14.2Z
2022-01-05T18:33:14.344Z,1641407594.344 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T18:33:14.344Z,1641407594.344 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T18:33:21.191Z,1641407601.191 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220105T174336/Courier0019.lzma
2022-01-05T18:33:22.194Z,1641407602.194 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0019.lzma.bak
2022-01-05T18:33:22.194Z,1641407602.194 [DataOverHttps](INFO): SBD MOMSN=16414078
2022-01-05T18:33:37.809Z,1641407617.809 [DataOverHttps](INFO): Sending 879 bytes from file Logs/20220105T174336/Express0020.lzma
2022-01-05T18:33:38.802Z,1641407618.802 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0020.lzma.bak
2022-01-05T18:33:38.802Z,1641407618.802 [DataOverHttps](INFO): SBD MOMSN=16414080
2022-01-05T18:33:39.820Z,1641407619.820 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T18:33:39.820Z,1641407619.820 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T18:33:39.820Z,1641407619.820 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T18:35:04.654Z,1641407704.654 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-01-05T18:35:04.654Z,1641407704.654 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:35:04.666Z,1641407704.666 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:35:05.076Z,1641407705.076 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:35:05.076Z,1641407705.076 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-01-05T18:37:24.438Z,1641407844.438 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-01-05T18:37:24.438Z,1641407844.438 [NAL9602] Data Fault, FailCount= 5
2022-01-05T18:37:24.438Z,1641407844.438 [NAL9602](ERROR): Data Fault
2022-01-05T18:37:24.457Z,1641407844.457 [CBIT](ERROR): Data Fault in component: NAL9602
2022-01-05T18:37:24.458Z,1641407844.458 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-01-05T18:37:24.846Z,1641407844.846 [NAL9602](INFO): Powering down
2022-01-05T18:37:24.884Z,1641407844.884 [CommandExec](FAULT): Scheduling is paused
2022-01-05T18:37:24.884Z,1641407844.884 [CBIT](INFO): Critical error at 20220105T183724
2022-01-05T18:38:05.675Z,1641407885.675 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2022-01-05T18:38:05.675Z,1641407885.675 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:38:05.687Z,1641407885.687 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:38:06.066Z,1641407886.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:38:06.066Z,1641407886.066 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2022-01-05T18:38:40.430Z,1641407920.430 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T18:38:40.430Z,1641407920.430 [Default:CheckIn:C.Wait] Stopped
2022-01-05T18:38:40.431Z,1641407920.431 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T18:38:40.431Z,1641407920.431 [Default:CheckIn:D] Running Loop=1
2022-01-05T18:38:40.840Z,1641407920.840 [Default:CheckIn:D] Stopped
2022-01-05T18:38:40.840Z,1641407920.840 [Default:CheckIn:E] Running Loop=1
2022-01-05T18:38:41.231Z,1641407921.231 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.673184 min 
2022-01-05T18:38:41.231Z,1641407921.231 [Default:CheckIn:E] Stopped
2022-01-05T18:38:41.231Z,1641407921.231 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T18:38:41.231Z,1641407921.231 [Default:CheckIn] Stopped
2022-01-05T18:38:41.231Z,1641407921.231 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T18:38:41.232Z,1641407921.232 [Default:CheckIn](INFO): Running loop #2
2022-01-05T18:38:41.232Z,1641407921.232 [Default:CheckIn] Running Loop=2
2022-01-05T18:38:41.232Z,1641407921.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T18:38:41.232Z,1641407921.232 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T18:39:24.533Z,1641407964.533 [CBIT](INFO): Clearing failed state for component NAL9602
2022-01-05T18:39:24.533Z,1641407964.533 [NAL9602] No Fault, FailCount= 5
2022-01-05T18:39:24.905Z,1641407964.905 [NAL9602](INFO): Powering up NAL9602
2022-01-05T18:39:35.817Z,1641407975.817 [NAL9602](INFO): NAL9602 initialized
2022-01-05T18:41:06.508Z,1641408066.508 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2022-01-05T18:41:06.509Z,1641408066.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-01-05T18:41:06.522Z,1641408066.522 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-01-05T18:41:06.885Z,1641408066.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-01-05T18:41:06.885Z,1641408066.885 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2022-01-05T18:43:03.715Z,1641408183.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184302.00,A,3648.14127,N,12147.22966,W,0.039,170.93,050122,,,A*72
2022-01-05T18:43:03.719Z,1641408183.719 [NAL9602](INFO): GPS fix at 20220105T184302: (36.802354, -121.787161)
2022-01-05T18:43:03.730Z,1641408183.730 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T18:43:03.731Z,1641408183.731 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T18:43:13.976Z,1641408193.976 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20220105T174336/Courier0022.lzma
2022-01-05T18:43:14.978Z,1641408194.978 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0022.lzma.bak
2022-01-05T18:43:14.978Z,1641408194.978 [DataOverHttps](INFO): SBD MOMSN=16414122
2022-01-05T18:43:32.060Z,1641408212.060 [DataOverHttps](INFO): Sending 316 bytes from file Logs/20220105T174336/Express0024.lzma
2022-01-05T18:43:33.062Z,1641408213.062 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0024.lzma.bak
2022-01-05T18:43:33.062Z,1641408213.062 [DataOverHttps](INFO): SBD MOMSN=16414126
2022-01-05T18:43:34.420Z,1641408214.420 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T18:43:34.420Z,1641408214.420 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T18:43:34.420Z,1641408214.420 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T18:43:36.429Z,1641408216.429 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T18:47:35.484Z,1641408455.484 [CommandExec](IMPORTANT): got command 
2022-01-05T18:47:35.484Z,1641408455.484 [CommandExec](FAULT): Incomplete syntax. Try: help
2022-01-05T18:48:21.657Z,1641408501.657 [CommandExec](IMPORTANT): got command ibit
2022-01-05T18:48:21.729Z,1641408501.729 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-01-05T18:48:21.729Z,1641408501.729 [IBIT](IMPORTANT): Beginning control surface checks.
2022-01-05T18:48:21.745Z,1641408501.745 [CBIT](IMPORTANT): Beginning ground fault scan
2022-01-05T18:48:23.287Z,1641408503.287 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184822.00,A,3648.14127,N,12147.22768,W,0.097,170.93,050122,,,D*7A
2022-01-05T18:48:23.290Z,1641408503.290 [NAL9602](INFO): GPS fix at 20220105T184822: (36.802354, -121.787128)
2022-01-05T18:48:32.626Z,1641408512.626 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002662
CHAN A1 (24V): -0.180099
CHAN A2 (12V): -0.002109
CHAN A3 (5V): -0.001577
CHAN B0 (3.3V): -0.000766
CHAN B1 (3.15aV): -0.000498
CHAN B2 (3.15bV): 0.000017
CHAN B3 (GND): -0.000147
OPEN: -0.000594
Full Scale: +/- 1 mA
2022-01-05T18:49:18.269Z,1641408558.269 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802353 Longitude: -121.787125
2022-01-05T18:49:18.655Z,1641408558.655 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.690001
2022-01-05T18:49:18.676Z,1641408558.676 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-01-05T18:49:18.676Z,1641408558.676 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-01-05T18:49:19.061Z,1641408559.061 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-01-05T18:49:19.061Z,1641408559.061 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-01-05T18:49:19.062Z,1641408559.062 [IBIT](IMPORTANT): Pressure:9.979115 PSI
2022-01-05T18:49:19.062Z,1641408559.062 [IBIT](IMPORTANT): Humidity:8.784031 %
2022-01-05T18:49:19.488Z,1641408559.488 [IBIT](IMPORTANT): Vehicle Pitch:-0.400957 degrees
2022-01-05T18:49:19.488Z,1641408559.488 [IBIT](IMPORTANT): Vehicle  Roll:5.270419 degrees
2022-01-05T18:49:19.489Z,1641408559.489 [IBIT](IMPORTANT): Vehicle  Heading:213.553574 degrees
2022-01-05T18:49:19.862Z,1641408559.862 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-01-05T18:49:19.862Z,1641408559.862 [IBIT](IMPORTANT): buoyancyNeutral: 360.755768 cc
2022-01-05T18:49:19.862Z,1641408559.862 [IBIT](IMPORTANT): massDefault: -1.482178 cm
2022-01-05T18:49:19.863Z,1641408559.863 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2022-01-05T18:49:19.863Z,1641408559.863 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2022-01-05T18:49:19.863Z,1641408559.863 [IBIT](IMPORTANT): IBIT PASSED
2022-01-05T18:49:20.269Z,1641408560.269 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T18:49:20.269Z,1641408560.269 [Default:CheckIn:C.Wait] Stopped
2022-01-05T18:49:20.269Z,1641408560.269 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T18:49:20.269Z,1641408560.269 [Default:CheckIn:D] Running Loop=1
2022-01-05T18:49:20.664Z,1641408560.664 [Default:CheckIn:D] Stopped
2022-01-05T18:49:20.664Z,1641408560.664 [Default:CheckIn:E] Running Loop=1
2022-01-05T18:49:21.073Z,1641408561.073 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.336916 min 
2022-01-05T18:49:21.073Z,1641408561.073 [Default:CheckIn:E] Stopped
2022-01-05T18:49:21.073Z,1641408561.073 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T18:49:21.073Z,1641408561.073 [Default:CheckIn] Stopped
2022-01-05T18:49:21.073Z,1641408561.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T18:49:21.073Z,1641408561.073 [Default:CheckIn](INFO): Running loop #3
2022-01-05T18:49:21.073Z,1641408561.073 [Default:CheckIn] Running Loop=3
2022-01-05T18:49:21.074Z,1641408561.074 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T18:49:21.074Z,1641408561.074 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T18:49:36.819Z,1641408576.819 [NAL9602](INFO): SBD MO Status=2, MOMSN=31076, MT Status=2, MTMSN=0
2022-01-05T18:49:36.819Z,1641408576.819 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-01-05T18:49:46.111Z,1641408586.111 [NAL9602](INFO): SBD MO Status=0, MOMSN=31076, MT Status=0, MTMSN=0
2022-01-05T18:49:46.111Z,1641408586.111 [NAL9602](INFO): No messages in MT queue
2022-01-05T18:49:46.111Z,1641408586.111 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2022-01-05T18:49:47.319Z,1641408587.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184946.00,A,3648.14180,N,12147.22871,W,0.214,170.93,050122,,,A*7F
2022-01-05T18:49:47.322Z,1641408587.322 [NAL9602](INFO): GPS fix at 20220105T184946: (36.802363, -121.787145)
2022-01-05T18:49:47.361Z,1641408587.361 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T18:49:47.361Z,1641408587.361 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T18:49:48.175Z,1641408588.175 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220105T174336/Courier0028.lzma
2022-01-05T18:49:49.174Z,1641408589.174 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0028.lzma.bak
2022-01-05T18:49:49.174Z,1641408589.174 [DataOverHttps](INFO): SBD MOMSN=16414131
2022-01-05T18:50:05.249Z,1641408605.249 [DataOverHttps](INFO): Sending 797 bytes from file Logs/20220105T174336/Express0029.lzma
2022-01-05T18:50:06.250Z,1641408606.250 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0029.lzma.bak
2022-01-05T18:50:06.250Z,1641408606.250 [DataOverHttps](INFO): SBD MOMSN=16414134
2022-01-05T18:50:07.535Z,1641408607.535 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T18:50:07.536Z,1641408607.536 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T18:50:07.536Z,1641408607.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T18:50:19.253Z,1641408619.253 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T18:50:32.604Z,1641408632.604 [CommandLine](INFO): End of History
2022-01-05T18:50:43.312Z,1641408643.312 [CommandLine](INFO): End of History
2022-01-05T18:50:43.316Z,1641408643.316 [CommandLine](INFO): End of History
2022-01-05T18:50:43.320Z,1641408643.320 [CommandLine](INFO): End of History
2022-01-05T18:50:43.323Z,1641408643.323 [CommandLine](INFO): End of History
2022-01-05T18:50:43.331Z,1641408643.331 [CommandLine](INFO): End of History
2022-01-05T18:55:08.239Z,1641408908.239 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T18:55:08.239Z,1641408908.239 [Default:CheckIn:C.Wait] Stopped
2022-01-05T18:55:08.240Z,1641408908.240 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T18:55:08.240Z,1641408908.240 [Default:CheckIn:D] Running Loop=1
2022-01-05T18:55:08.643Z,1641408908.643 [Default:CheckIn:D] Stopped
2022-01-05T18:55:08.643Z,1641408908.643 [Default:CheckIn:E] Running Loop=1
2022-01-05T18:55:09.055Z,1641408909.055 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.136574 min 
2022-01-05T18:55:09.055Z,1641408909.055 [Default:CheckIn:E] Stopped
2022-01-05T18:55:09.055Z,1641408909.055 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T18:55:09.055Z,1641408909.055 [Default:CheckIn] Stopped
2022-01-05T18:55:09.055Z,1641408909.055 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T18:55:09.055Z,1641408909.055 [Default:CheckIn](INFO): Running loop #4
2022-01-05T18:55:09.055Z,1641408909.055 [Default:CheckIn] Running Loop=4
2022-01-05T18:55:09.055Z,1641408909.055 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T18:55:09.056Z,1641408909.056 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T18:55:11.051Z,1641408911.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185510.00,A,3648.14207,N,12147.22973,W,0.156,170.93,050122,,,D*7E
2022-01-05T18:55:11.054Z,1641408911.054 [NAL9602](INFO): GPS fix at 20220105T185510: (36.802368, -121.787162)
2022-01-05T18:55:11.065Z,1641408911.065 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T18:55:11.065Z,1641408911.065 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T18:55:19.067Z,1641408919.067 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220105T174336/Courier0031.lzma
2022-01-05T18:55:20.070Z,1641408920.070 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0031.lzma.bak
2022-01-05T18:55:20.070Z,1641408920.070 [DataOverHttps](INFO): SBD MOMSN=16414170
2022-01-05T18:55:25.723Z,1641408925.723 [NAL9602](INFO): SBD MO Status=0, MOMSN=31077, MT Status=0, MTMSN=0
2022-01-05T18:55:25.723Z,1641408925.723 [NAL9602](INFO): No messages in MT queue
2022-01-05T18:55:36.304Z,1641408936.304 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220105T174336/Express0032.lzma
2022-01-05T18:55:36.704Z,1641408936.704 [CommandExec](IMPORTANT): got command 
2022-01-05T18:55:36.705Z,1641408936.705 [CommandExec](FAULT): Incomplete syntax. Try: help
2022-01-05T18:55:37.306Z,1641408937.306 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0032.lzma.bak
2022-01-05T18:55:37.306Z,1641408937.306 [DataOverHttps](INFO): SBD MOMSN=16414173
2022-01-05T18:55:38.542Z,1641408938.542 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T18:55:38.542Z,1641408938.542 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T18:55:38.542Z,1641408938.542 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T18:55:39.205Z,1641408939.205 [CommandExec](IMPORTANT): got command failComponent
2022-01-05T18:55:39.205Z,1641408939.205 [CommandExec](IMPORTANT): Failed components:
2022-01-05T18:55:39.205Z,1641408939.205 [CommandExec](IMPORTANT): No failed Components.
2022-01-05T18:55:56.297Z,1641408956.297 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T18:59:42.150Z,1641409182.150 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-01-05T18:59:42.152Z,1641409182.152 [BPC1](INFO): Received data from all battery sticks.
2022-01-05T19:00:39.163Z,1641409239.163 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:00:39.163Z,1641409239.163 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:00:39.164Z,1641409239.164 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:00:39.165Z,1641409239.165 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:00:39.571Z,1641409239.571 [Default:CheckIn:D] Stopped
2022-01-05T19:00:39.571Z,1641409239.571 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:00:39.979Z,1641409239.979 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.652045 min 
2022-01-05T19:00:39.979Z,1641409239.979 [Default:CheckIn:E] Stopped
2022-01-05T19:00:39.979Z,1641409239.979 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:00:39.980Z,1641409239.980 [Default:CheckIn] Stopped
2022-01-05T19:00:39.980Z,1641409239.980 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:00:39.981Z,1641409239.981 [Default:CheckIn](INFO): Running loop #5
2022-01-05T19:00:39.981Z,1641409239.981 [Default:CheckIn] Running Loop=5
2022-01-05T19:00:39.981Z,1641409239.981 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:00:39.981Z,1641409239.981 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:00:41.987Z,1641409241.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190041.00,A,3648.14321,N,12147.23097,W,0.369,170.93,050122,,,D*72
2022-01-05T19:00:41.990Z,1641409241.990 [NAL9602](INFO): GPS fix at 20220105T190041: (36.802387, -121.787183)
2022-01-05T19:00:42.029Z,1641409242.029 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:00:42.029Z,1641409242.029 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:00:50.028Z,1641409250.028 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20220105T174336/Courier0034.lzma
2022-01-05T19:00:51.030Z,1641409251.030 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0034.lzma.bak
2022-01-05T19:00:51.030Z,1641409251.030 [DataOverHttps](INFO): SBD MOMSN=16414177
2022-01-05T19:01:09.496Z,1641409269.496 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20220105T174336/Express0035.lzma
2022-01-05T19:01:10.494Z,1641409270.494 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0035.lzma.bak
2022-01-05T19:01:10.494Z,1641409270.494 [DataOverHttps](INFO): SBD MOMSN=16414180
2022-01-05T19:01:11.543Z,1641409271.543 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:01:11.543Z,1641409271.543 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:01:11.543Z,1641409271.543 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:01:45.887Z,1641409305.887 [NAL9602](INFO): SBD MO Status=0, MOMSN=31078, MT Status=0, MTMSN=0
2022-01-05T19:01:45.887Z,1641409305.887 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:02:16.573Z,1641409336.573 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:06:12.275Z,1641409572.275 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:06:12.275Z,1641409572.275 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:06:12.275Z,1641409572.275 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:06:12.276Z,1641409572.276 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:06:12.682Z,1641409572.682 [Default:CheckIn:D] Stopped
2022-01-05T19:06:12.682Z,1641409572.682 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:06:13.079Z,1641409573.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.203894 min 
2022-01-05T19:06:13.079Z,1641409573.079 [Default:CheckIn:E] Stopped
2022-01-05T19:06:13.079Z,1641409573.079 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:06:13.079Z,1641409573.079 [Default:CheckIn] Stopped
2022-01-05T19:06:13.079Z,1641409573.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:06:13.079Z,1641409573.079 [Default:CheckIn](INFO): Running loop #6
2022-01-05T19:06:13.079Z,1641409573.079 [Default:CheckIn] Running Loop=6
2022-01-05T19:06:13.096Z,1641409573.096 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:06:13.096Z,1641409573.096 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:06:15.095Z,1641409575.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190614.00,A,3648.14005,N,12147.23211,W,0.039,0.00,050122,,,D*77
2022-01-05T19:06:15.098Z,1641409575.098 [NAL9602](INFO): GPS fix at 20220105T190614: (36.802334, -121.787202)
2022-01-05T19:06:15.148Z,1641409575.148 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:06:15.148Z,1641409575.148 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:06:25.251Z,1641409585.251 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220105T174336/Courier0037.lzma
2022-01-05T19:06:26.254Z,1641409586.254 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0037.lzma.bak
2022-01-05T19:06:26.254Z,1641409586.254 [DataOverHttps](INFO): SBD MOMSN=16414196
2022-01-05T19:06:42.207Z,1641409602.207 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220105T174336/Express0038.lzma
2022-01-05T19:06:43.210Z,1641409603.210 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0038.lzma.bak
2022-01-05T19:06:43.210Z,1641409603.210 [DataOverHttps](INFO): SBD MOMSN=16414199
2022-01-05T19:06:44.278Z,1641409604.278 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:06:44.279Z,1641409604.279 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:06:44.279Z,1641409604.279 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:09:21.147Z,1641409761.147 [NAL9602](INFO): SBD MO Status=0, MOMSN=31079, MT Status=0, MTMSN=0
2022-01-05T19:09:21.147Z,1641409761.147 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:09:51.849Z,1641409791.849 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:11:44.706Z,1641409904.706 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:11:44.706Z,1641409904.706 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:11:44.706Z,1641409904.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:11:44.706Z,1641409904.706 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:11:45.109Z,1641409905.109 [Default:CheckIn:D] Stopped
2022-01-05T19:11:45.109Z,1641409905.109 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:11:45.507Z,1641409905.507 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.744336 min 
2022-01-05T19:11:45.507Z,1641409905.507 [Default:CheckIn:E] Stopped
2022-01-05T19:11:45.507Z,1641409905.507 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:11:45.536Z,1641409905.536 [Default:CheckIn] Stopped
2022-01-05T19:11:45.536Z,1641409905.536 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:11:45.537Z,1641409905.537 [Default:CheckIn](INFO): Running loop #7
2022-01-05T19:11:45.537Z,1641409905.537 [Default:CheckIn] Running Loop=7
2022-01-05T19:11:45.537Z,1641409905.537 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:11:45.537Z,1641409905.537 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:11:47.512Z,1641409907.512 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191146.00,A,3648.13648,N,12147.23062,W,0.000,0.00,050122,,,D*72
2022-01-05T19:11:47.515Z,1641409907.515 [NAL9602](INFO): GPS fix at 20220105T191146: (36.802275, -121.787177)
2022-01-05T19:11:47.526Z,1641409907.526 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:11:47.526Z,1641409907.526 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:11:55.083Z,1641409915.083 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220105T174336/Courier0040.lzma
2022-01-05T19:11:56.086Z,1641409916.086 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0040.lzma.bak
2022-01-05T19:11:56.086Z,1641409916.086 [DataOverHttps](INFO): SBD MOMSN=16414203
2022-01-05T19:12:01.247Z,1641409921.247 [NAL9602](INFO): SBD MO Status=0, MOMSN=31080, MT Status=0, MTMSN=0
2022-01-05T19:12:01.247Z,1641409921.247 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:12:12.071Z,1641409932.071 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220105T174336/Express0041.lzma
2022-01-05T19:12:13.074Z,1641409933.074 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0041.lzma.bak
2022-01-05T19:12:13.074Z,1641409933.074 [DataOverHttps](INFO): SBD MOMSN=16414206
2022-01-05T19:12:14.183Z,1641409934.183 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:12:14.184Z,1641409934.184 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:12:14.184Z,1641409934.184 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:12:32.013Z,1641409952.013 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:17:14.705Z,1641410234.705 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:17:14.705Z,1641410234.705 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:17:14.706Z,1641410234.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:17:14.706Z,1641410234.706 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:17:15.125Z,1641410235.125 [Default:CheckIn:D] Stopped
2022-01-05T19:17:15.125Z,1641410235.125 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:17:15.507Z,1641410235.507 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.244604 min 
2022-01-05T19:17:15.507Z,1641410235.507 [Default:CheckIn:E] Stopped
2022-01-05T19:17:15.507Z,1641410235.507 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:17:15.508Z,1641410235.508 [Default:CheckIn] Stopped
2022-01-05T19:17:15.508Z,1641410235.508 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:17:15.508Z,1641410235.508 [Default:CheckIn](INFO): Running loop #8
2022-01-05T19:17:15.508Z,1641410235.508 [Default:CheckIn] Running Loop=8
2022-01-05T19:17:15.508Z,1641410235.508 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:17:15.508Z,1641410235.508 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:17:17.516Z,1641410237.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191716.00,A,3648.16727,N,12147.21555,W,0.194,351.43,050122,,,D*73
2022-01-05T19:17:17.519Z,1641410237.519 [NAL9602](INFO): GPS fix at 20220105T191716: (36.802788, -121.786926)
2022-01-05T19:17:17.530Z,1641410237.530 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:17:17.530Z,1641410237.530 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:17:25.003Z,1641410245.003 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220105T174336/Courier0043.lzma
2022-01-05T19:17:26.014Z,1641410246.014 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0043.lzma.bak
2022-01-05T19:17:26.014Z,1641410246.014 [DataOverHttps](INFO): SBD MOMSN=16414216
2022-01-05T19:17:29.635Z,1641410249.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=31081, MT Status=0, MTMSN=0
2022-01-05T19:17:29.635Z,1641410249.635 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:17:41.911Z,1641410261.911 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20220105T174336/Express0044.lzma
2022-01-05T19:17:42.914Z,1641410262.914 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0044.lzma.bak
2022-01-05T19:17:42.914Z,1641410262.914 [DataOverHttps](INFO): SBD MOMSN=16414219
2022-01-05T19:17:44.187Z,1641410264.187 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:17:44.187Z,1641410264.187 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:17:44.187Z,1641410264.187 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:18:00.340Z,1641410280.340 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:22:44.870Z,1641410564.870 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:22:44.870Z,1641410564.870 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:22:44.870Z,1641410564.870 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:22:44.871Z,1641410564.871 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:22:45.267Z,1641410565.267 [Default:CheckIn:D] Stopped
2022-01-05T19:22:45.267Z,1641410565.267 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:22:45.676Z,1641410565.676 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.746973 min 
2022-01-05T19:22:45.676Z,1641410565.676 [Default:CheckIn:E] Stopped
2022-01-05T19:22:45.676Z,1641410565.676 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:22:45.676Z,1641410565.676 [Default:CheckIn] Stopped
2022-01-05T19:22:45.676Z,1641410565.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:22:45.676Z,1641410565.676 [Default:CheckIn](INFO): Running loop #9
2022-01-05T19:22:45.677Z,1641410565.677 [Default:CheckIn] Running Loop=9
2022-01-05T19:22:45.677Z,1641410565.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:22:45.677Z,1641410565.677 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:22:47.691Z,1641410567.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192246.00,A,3648.16550,N,12147.21392,W,0.136,38.22,050122,,,D*4C
2022-01-05T19:22:47.694Z,1641410567.694 [NAL9602](INFO): GPS fix at 20220105T192246: (36.802758, -121.786899)
2022-01-05T19:22:47.705Z,1641410567.705 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:22:47.705Z,1641410567.705 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:22:55.687Z,1641410575.687 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220105T174336/Courier0046.lzma
2022-01-05T19:22:55.771Z,1641410575.771 [NAL9602](INFO): SBD MO Status=0, MOMSN=31082, MT Status=0, MTMSN=0
2022-01-05T19:22:55.771Z,1641410575.771 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:22:56.690Z,1641410576.690 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0046.lzma.bak
2022-01-05T19:22:56.690Z,1641410576.690 [DataOverHttps](INFO): SBD MOMSN=16414225
2022-01-05T19:23:11.611Z,1641410591.611 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220105T174336/Express0047.lzma
2022-01-05T19:23:12.614Z,1641410592.614 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0047.lzma.bak
2022-01-05T19:23:12.614Z,1641410592.614 [DataOverHttps](INFO): SBD MOMSN=16414228
2022-01-05T19:23:13.955Z,1641410593.955 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:23:13.956Z,1641410593.956 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:23:13.956Z,1641410593.956 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:23:26.464Z,1641410606.464 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:28:14.534Z,1641410894.534 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:28:14.534Z,1641410894.534 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:28:14.534Z,1641410894.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:28:14.535Z,1641410894.535 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:28:14.931Z,1641410894.931 [Default:CheckIn:D] Stopped
2022-01-05T19:28:14.931Z,1641410894.931 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:28:15.340Z,1641410895.340 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.241366 min 
2022-01-05T19:28:15.341Z,1641410895.341 [Default:CheckIn:E] Stopped
2022-01-05T19:28:15.341Z,1641410895.341 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:28:15.341Z,1641410895.341 [Default:CheckIn] Stopped
2022-01-05T19:28:15.341Z,1641410895.341 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:28:15.341Z,1641410895.341 [Default:CheckIn](INFO): Running loop #10
2022-01-05T19:28:15.341Z,1641410895.341 [Default:CheckIn] Running Loop=10
2022-01-05T19:28:15.341Z,1641410895.341 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:28:15.341Z,1641410895.341 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:28:17.351Z,1641410897.351 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192816.00,A,3648.33421,N,12147.11729,W,3.577,3.73,050122,,,D*7A
2022-01-05T19:28:17.353Z,1641410897.353 [NAL9602](INFO): GPS fix at 20220105T192816: (36.805570, -121.785288)
2022-01-05T19:28:17.364Z,1641410897.364 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:28:17.364Z,1641410897.364 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:28:25.011Z,1641410905.011 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220105T174336/Courier0049.lzma
2022-01-05T19:28:26.014Z,1641410906.014 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0049.lzma.bak
2022-01-05T19:28:26.014Z,1641410906.014 [DataOverHttps](INFO): SBD MOMSN=16414238
2022-01-05T19:28:30.691Z,1641410910.691 [NAL9602](INFO): SBD MO Status=0, MOMSN=31083, MT Status=0, MTMSN=0
2022-01-05T19:28:30.691Z,1641410910.691 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:28:42.072Z,1641410922.072 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220105T174336/Express0050.lzma
2022-01-05T19:28:43.074Z,1641410923.074 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0050.lzma.bak
2022-01-05T19:28:43.074Z,1641410923.074 [DataOverHttps](INFO): SBD MOMSN=16414241
2022-01-05T19:28:44.432Z,1641410924.432 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:28:44.432Z,1641410924.432 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:28:44.432Z,1641410924.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:29:01.389Z,1641410941.389 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:33:45.007Z,1641411225.007 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:33:45.007Z,1641411225.007 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:33:45.024Z,1641411225.024 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:33:45.024Z,1641411225.024 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:33:45.416Z,1641411225.416 [Default:CheckIn:D] Stopped
2022-01-05T19:33:45.416Z,1641411225.416 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:33:45.815Z,1641411225.815 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.749459 min 
2022-01-05T19:33:45.815Z,1641411225.815 [Default:CheckIn:E] Stopped
2022-01-05T19:33:45.815Z,1641411225.815 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:33:45.815Z,1641411225.815 [Default:CheckIn] Stopped
2022-01-05T19:33:45.816Z,1641411225.816 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:33:45.816Z,1641411225.816 [Default:CheckIn](INFO): Running loop #11
2022-01-05T19:33:45.816Z,1641411225.816 [Default:CheckIn] Running Loop=11
2022-01-05T19:33:45.816Z,1641411225.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:33:45.816Z,1641411225.816 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:33:47.834Z,1641411227.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193347.00,A,3648.31708,N,12147.95120,W,20.508,277.27,050122,,,A*41
2022-01-05T19:33:47.837Z,1641411227.837 [NAL9602](INFO): GPS fix at 20220105T193347: (36.805285, -121.799187)
2022-01-05T19:33:47.847Z,1641411227.847 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:33:47.847Z,1641411227.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:33:55.411Z,1641411235.411 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220105T174336/Courier0052.lzma
2022-01-05T19:33:56.414Z,1641411236.414 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0052.lzma.bak
2022-01-05T19:33:56.414Z,1641411236.414 [DataOverHttps](INFO): SBD MOMSN=16414250
2022-01-05T19:34:01.967Z,1641411241.967 [NAL9602](INFO): SBD MO Status=0, MOMSN=31084, MT Status=0, MTMSN=0
2022-01-05T19:34:01.967Z,1641411241.967 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:34:12.483Z,1641411252.483 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220105T174336/Express0053.lzma
2022-01-05T19:34:13.486Z,1641411253.486 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0053.lzma.bak
2022-01-05T19:34:13.486Z,1641411253.486 [DataOverHttps](INFO): SBD MOMSN=16414257
2022-01-05T19:34:14.527Z,1641411254.527 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:34:14.527Z,1641411254.527 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:34:14.527Z,1641411254.527 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:34:32.677Z,1641411272.677 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:37:18.152Z,1641411438.152 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-01-05T19:39:15.182Z,1641411555.182 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:39:15.182Z,1641411555.182 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:39:15.182Z,1641411555.182 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:39:15.182Z,1641411555.182 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:39:15.575Z,1641411555.575 [Default:CheckIn:D] Stopped
2022-01-05T19:39:15.575Z,1641411555.575 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:39:15.988Z,1641411555.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.252100 min 
2022-01-05T19:39:15.988Z,1641411555.988 [Default:CheckIn:E] Stopped
2022-01-05T19:39:15.988Z,1641411555.988 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:39:15.988Z,1641411555.988 [Default:CheckIn] Stopped
2022-01-05T19:39:15.988Z,1641411555.988 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:39:15.988Z,1641411555.988 [Default:CheckIn](INFO): Running loop #12
2022-01-05T19:39:15.989Z,1641411555.989 [Default:CheckIn] Running Loop=12
2022-01-05T19:39:15.989Z,1641411555.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:39:15.989Z,1641411555.989 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:39:17.995Z,1641411557.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193917.00,A,3648.23443,N,12150.22662,W,14.870,231.50,050122,,,A*4D
2022-01-05T19:39:18.010Z,1641411558.010 [NAL9602](INFO): GPS fix at 20220105T193917: (36.803907, -121.837110)
2022-01-05T19:39:18.020Z,1641411558.020 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:39:18.020Z,1641411558.020 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:39:26.035Z,1641411566.035 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220105T174336/Courier0055.lzma
2022-01-05T19:39:27.038Z,1641411567.038 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0055.lzma.bak
2022-01-05T19:39:27.038Z,1641411567.038 [DataOverHttps](INFO): SBD MOMSN=16414261
2022-01-05T19:39:43.279Z,1641411583.279 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220105T174336/Express0056.lzma
2022-01-05T19:39:44.282Z,1641411584.282 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0056.lzma.bak
2022-01-05T19:39:44.282Z,1641411584.282 [DataOverHttps](INFO): SBD MOMSN=16414264
2022-01-05T19:39:45.473Z,1641411585.473 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:39:45.473Z,1641411585.473 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:39:45.473Z,1641411585.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:39:48.291Z,1641411588.291 [NAL9602](INFO): SBD MO Status=2, MOMSN=31085, MT Status=2, MTMSN=0
2022-01-05T19:39:48.291Z,1641411588.291 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-01-05T19:39:58.795Z,1641411598.795 [NAL9602](INFO): SBD MO Status=0, MOMSN=31085, MT Status=0, MTMSN=0
2022-01-05T19:39:58.795Z,1641411598.795 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:40:29.577Z,1641411629.577 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:44:45.935Z,1641411885.935 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-05T19:44:45.935Z,1641411885.935 [Default:CheckIn:C.Wait] Stopped
2022-01-05T19:44:45.935Z,1641411885.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-05T19:44:45.936Z,1641411885.936 [Default:CheckIn:D] Running Loop=1
2022-01-05T19:44:46.332Z,1641411886.332 [Default:CheckIn:D] Stopped
2022-01-05T19:44:46.332Z,1641411886.332 [Default:CheckIn:E] Running Loop=1
2022-01-05T19:44:46.746Z,1641411886.746 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.764714 min 
2022-01-05T19:44:46.746Z,1641411886.746 [Default:CheckIn:E] Stopped
2022-01-05T19:44:46.747Z,1641411886.747 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-05T19:44:46.747Z,1641411886.747 [Default:CheckIn] Stopped
2022-01-05T19:44:46.747Z,1641411886.747 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-05T19:44:46.747Z,1641411886.747 [Default:CheckIn](INFO): Running loop #13
2022-01-05T19:44:46.747Z,1641411886.747 [Default:CheckIn] Running Loop=13
2022-01-05T19:44:46.747Z,1641411886.747 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-05T19:44:46.747Z,1641411886.747 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-05T19:44:48.747Z,1641411888.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194448.00,A,3647.76140,N,12150.61175,W,0.758,221.04,050122,,,A*72
2022-01-05T19:44:48.750Z,1641411888.750 [NAL9602](INFO): GPS fix at 20220105T194448: (36.796023, -121.843529)
2022-01-05T19:44:48.769Z,1641411888.769 [Default:CheckIn:Read_GPS] Stopped
2022-01-05T19:44:48.770Z,1641411888.770 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-05T19:44:59.967Z,1641411899.967 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220105T174336/Courier0058.lzma
2022-01-05T19:45:00.970Z,1641411900.970 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Courier0058.lzma.bak
2022-01-05T19:45:00.970Z,1641411900.970 [DataOverHttps](INFO): SBD MOMSN=16414274
2022-01-05T19:45:16.915Z,1641411916.915 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220105T174336/Express0059.lzma
2022-01-05T19:45:17.918Z,1641411917.918 [DataOverHttps](INFO): Moved sent file to Logs/20220105T174336/Express0059.lzma.bak
2022-01-05T19:45:17.918Z,1641411917.918 [DataOverHttps](INFO): SBD MOMSN=16414277
2022-01-05T19:45:19.213Z,1641411919.213 [Default:CheckIn:Read_Iridium] Stopped
2022-01-05T19:45:19.214Z,1641411919.214 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-05T19:45:19.214Z,1641411919.214 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-05T19:45:23.235Z,1641411923.235 [NAL9602](INFO): SBD MO Status=0, MOMSN=31086, MT Status=0, MTMSN=0
2022-01-05T19:45:23.235Z,1641411923.235 [NAL9602](INFO): No messages in MT queue
2022-01-05T19:45:53.937Z,1641411953.937 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-05T19:47:27.909Z,1641412047.909 [DataOverHttps](IMPORTANT): SBD MTMSN=20220105T194726