2020-09-23T22:30:12.661Z,1600900212.661 [Supervisor](DEBUG): Initializing supervisor.
2020-09-23T22:30:12.665Z,1600900212.665 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-23T22:30:12.665Z,1600900212.665 [SyncHandler](INFO): Protected caller Thread ID is 1030
2020-09-23T22:30:12.666Z,1600900212.666 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-23T22:30:12.667Z,1600900212.667 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-23T22:30:12.667Z,1600900212.667 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1031
2020-09-23T22:30:12.670Z,1600900212.670 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-23T22:30:12.686Z,1600900212.686 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-23T22:30:12.687Z,1600900212.687 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-23T22:30:12.688Z,1600900212.688 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1032
2020-09-23T22:30:12.689Z,1600900212.689 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-23T22:30:12.690Z,1600900212.690 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-23T22:30:12.691Z,1600900212.691 [logger ThreadHandler](INFO): Protected caller Thread ID is 1033
2020-09-23T22:30:12.693Z,1600900212.693 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-23T22:30:12.694Z,1600900212.694 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-23T22:30:12.695Z,1600900212.695 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-23T22:30:12.791Z,1600900212.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-23T22:30:12.792Z,1600900212.792 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-23T22:30:13.377Z,1600900213.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-23T22:30:13.377Z,1600900213.377 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-23T22:30:13.573Z,1600900213.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-23T22:30:13.573Z,1600900213.573 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-23T22:30:13.652Z,1600900213.652 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-23T22:30:13.757Z,1600900213.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-23T22:30:13.757Z,1600900213.757 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-23T22:30:13.838Z,1600900213.838 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-23T22:30:13.939Z,1600900213.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-23T22:30:13.939Z,1600900213.939 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-23T22:30:14.249Z,1600900214.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-23T22:30:14.249Z,1600900214.249 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-23T22:30:14.727Z,1600900214.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-23T22:30:14.728Z,1600900214.728 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-23T22:30:14.875Z,1600900214.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-23T22:30:14.876Z,1600900214.876 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-23T22:30:15.063Z,1600900215.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-23T22:30:15.063Z,1600900215.063 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-23T22:30:15.605Z,1600900215.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-23T22:30:15.606Z,1600900215.606 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-23T22:30:16.169Z,1600900216.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-23T22:30:16.170Z,1600900216.170 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-23T22:30:16.373Z,1600900216.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-23T22:30:16.374Z,1600900216.374 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-23T22:30:16.717Z,1600900216.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-23T22:30:16.717Z,1600900216.717 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-23T22:30:17.046Z,1600900217.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-23T22:30:17.048Z,1600900217.048 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-09-23T22:30:17.049Z,1600900217.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-09-23T22:30:17.132Z,1600900217.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-09-23T22:30:17.286Z,1600900217.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-09-23T22:30:17.394Z,1600900217.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-09-23T22:30:17.480Z,1600900217.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-09-23T22:30:17.574Z,1600900217.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-09-23T22:30:17.762Z,1600900217.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-09-23T22:30:17.995Z,1600900217.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-23T22:30:17.995Z,1600900217.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-09-23T22:30:18.088Z,1600900218.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-09-23T22:30:18.186Z,1600900218.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-09-23T22:30:18.312Z,1600900218.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-09-23T22:30:18.411Z,1600900218.411 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-09-23T22:30:18.424Z,1600900218.424 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-23T22:30:18.508Z,1600900218.508 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-09-23T22:30:18.508Z,1600900218.508 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-23T22:30:18.566Z,1600900218.566 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-23T22:30:18.682Z,1600900218.682 [VerticalControl] Loaded
2020-09-23T22:30:18.682Z,1600900218.682 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-23T22:30:18.687Z,1600900218.687 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-23T22:30:18.771Z,1600900218.771 [HorizontalControl] Loaded
2020-09-23T22:30:18.771Z,1600900218.771 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-23T22:30:18.776Z,1600900218.776 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-23T22:30:18.782Z,1600900218.782 [SpeedControl] Loaded
2020-09-23T22:30:18.782Z,1600900218.782 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-23T22:30:18.787Z,1600900218.787 [LoopControl](DEBUG): Construct LoopControl.
2020-09-23T22:30:18.788Z,1600900218.788 [LoopControl] Loaded
2020-09-23T22:30:18.788Z,1600900218.788 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-23T22:30:18.788Z,1600900218.788 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-23T22:30:18.789Z,1600900218.789 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-23T22:30:18.836Z,1600900218.836 [DepthRateCalculator] Loaded
2020-09-23T22:30:18.836Z,1600900218.836 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-23T22:30:18.846Z,1600900218.846 [PitchRateCalculator] Loaded
2020-09-23T22:30:18.846Z,1600900218.846 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-23T22:30:18.867Z,1600900218.867 [SpeedCalculator] Loaded
2020-09-23T22:30:18.867Z,1600900218.867 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-23T22:30:18.893Z,1600900218.893 [TempGradientCalculator] Loaded
2020-09-23T22:30:18.893Z,1600900218.893 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-23T22:30:18.903Z,1600900218.903 [YawRateCalculator] Loaded
2020-09-23T22:30:18.903Z,1600900218.903 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-23T22:30:18.937Z,1600900218.937 [ElevatorOffsetCalculator] Loaded
2020-09-23T22:30:18.937Z,1600900218.937 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-23T22:30:18.938Z,1600900218.938 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-23T22:30:18.938Z,1600900218.938 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-23T22:30:18.967Z,1600900218.967 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-23T22:30:18.967Z,1600900218.967 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-23T22:30:19.025Z,1600900219.025 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-09-23T22:30:19.026Z,1600900219.026 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-23T22:30:19.353Z,1600900219.353 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-23T22:30:19.354Z,1600900219.354 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-23T22:30:19.460Z,1600900219.460 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-23T22:30:19.461Z,1600900219.461 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-23T22:30:19.871Z,1600900219.871 [AHRS_M2] Loaded
2020-09-23T22:30:19.871Z,1600900219.871 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-23T22:30:19.918Z,1600900219.918 [BackseatComponent] Loaded
2020-09-23T22:30:19.918Z,1600900219.918 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2020-09-23T22:30:19.919Z,1600900219.919 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408F84E0
2020-09-23T22:30:19.920Z,1600900219.920 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1115
2020-09-23T22:30:21.754Z,1600900221.754 [BPC1] Loaded
2020-09-23T22:30:21.755Z,1600900221.755 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-23T22:30:21.947Z,1600900221.947 [DataOverHttps] Loaded
2020-09-23T22:30:21.947Z,1600900221.947 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-23T22:30:21.948Z,1600900221.948 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409284E0
2020-09-23T22:30:21.948Z,1600900221.948 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1116
2020-09-23T22:30:22.045Z,1600900222.045 [Depth_Keller] Loaded
2020-09-23T22:30:22.045Z,1600900222.045 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-23T22:30:22.055Z,1600900222.055 [DropWeight] Loaded
2020-09-23T22:30:22.055Z,1600900222.055 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-09-23T22:30:22.179Z,1600900222.179 [NAL9602] Loaded
2020-09-23T22:30:22.179Z,1600900222.179 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-23T22:30:22.235Z,1600900222.235 [Onboard] Loaded
2020-09-23T22:30:22.235Z,1600900222.235 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-23T22:30:22.236Z,1600900222.236 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409584E0
2020-09-23T22:30:22.237Z,1600900222.237 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1117
2020-09-23T22:30:22.262Z,1600900222.262 [Radio_Surface] Loaded
2020-09-23T22:30:22.262Z,1600900222.262 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-23T22:30:22.263Z,1600900222.263 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409884E0
2020-09-23T22:30:22.263Z,1600900222.263 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1118
2020-09-23T22:30:22.332Z,1600900222.332 [RDI_Pathfinder] Loaded
2020-09-23T22:30:22.332Z,1600900222.332 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-09-23T22:30:22.496Z,1600900222.496 [DAT] Loaded
2020-09-23T22:30:22.496Z,1600900222.496 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-09-23T22:30:22.496Z,1600900222.496 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-23T22:30:22.497Z,1600900222.497 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-23T22:30:22.620Z,1600900222.620 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-23T22:30:22.621Z,1600900222.621 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-23T22:30:22.646Z,1600900222.646 [NavChart] Loaded
2020-09-23T22:30:22.647Z,1600900222.647 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-23T22:30:22.655Z,1600900222.655 [UniversalFixResidualReporter] Loaded
2020-09-23T22:30:22.655Z,1600900222.655 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-23T22:30:22.656Z,1600900222.656 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-23T22:30:22.656Z,1600900222.656 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-23T22:30:22.808Z,1600900222.808 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-23T22:30:22.820Z,1600900222.820 [SBIT] Loaded
2020-09-23T22:30:22.820Z,1600900222.820 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-23T22:30:22.826Z,1600900222.826 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-23T22:30:22.839Z,1600900222.839 [IBIT] Loaded
2020-09-23T22:30:22.839Z,1600900222.839 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-23T22:30:22.847Z,1600900222.847 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-23T22:30:22.992Z,1600900222.992 [CBIT] Loaded
2020-09-23T22:30:22.992Z,1600900222.992 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-23T22:30:22.992Z,1600900222.992 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-23T22:30:22.993Z,1600900222.993 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-23T22:30:23.128Z,1600900223.128 [BuoyancyServo] Loaded
2020-09-23T22:30:23.129Z,1600900223.129 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-23T22:30:23.163Z,1600900223.163 [ElevatorServo] Loaded
2020-09-23T22:30:23.163Z,1600900223.163 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-23T22:30:23.197Z,1600900223.197 [MassServo] Loaded
2020-09-23T22:30:23.197Z,1600900223.197 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-23T22:30:23.231Z,1600900223.231 [RudderServo] Loaded
2020-09-23T22:30:23.231Z,1600900223.231 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-23T22:30:23.264Z,1600900223.264 [ThrusterServo] Loaded
2020-09-23T22:30:23.264Z,1600900223.264 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-23T22:30:23.265Z,1600900223.265 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-23T22:30:23.265Z,1600900223.265 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-23T22:30:23.276Z,1600900223.276 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-23T22:30:23.277Z,1600900223.277 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-23T22:30:23.503Z,1600900223.503 [CTD_Seabird] Loaded
2020-09-23T22:30:23.503Z,1600900223.503 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-09-23T22:30:23.504Z,1600900223.504 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AB74E0
2020-09-23T22:30:23.505Z,1600900223.505 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1119
2020-09-23T22:30:23.576Z,1600900223.576 [WetLabsBB2FL] Loaded
2020-09-23T22:30:23.576Z,1600900223.576 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-09-23T22:30:23.577Z,1600900223.577 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AE74E0
2020-09-23T22:30:23.578Z,1600900223.578 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1120
2020-09-23T22:30:23.579Z,1600900223.579 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-23T22:30:23.584Z,1600900223.584 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-23T22:30:23.586Z,1600900223.586 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-23T22:30:23.595Z,1600900223.595 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-23T22:30:23.596Z,1600900223.596 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B174E0
2020-09-23T22:30:23.596Z,1600900223.596 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1121
2020-09-23T22:30:23.601Z,1600900223.601 [Supervisor](INFO): Main Thread ID is 829
2020-09-23T22:30:23.601Z,1600900223.601 [Supervisor](DEBUG): Running supervisor.
2020-09-23T22:30:23.601Z,1600900223.601 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1122
2020-09-23T22:30:23.604Z,1600900223.604 [controlThread ThreadHandler](INFO): Handler Thread ID is 1123
2020-09-23T22:30:23.604Z,1600900223.604 [controlThread](DEBUG): Initializing ControlThread
2020-09-23T22:30:23.605Z,1600900223.605 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-23T22:30:23.607Z,1600900223.607 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-23T22:30:23.607Z,1600900223.607 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-23T22:30:23.608Z,1600900223.608 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-23T22:30:23.608Z,1600900223.608 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-23T22:30:23.609Z,1600900223.609 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-23T22:30:23.609Z,1600900223.609 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-23T22:30:23.609Z,1600900223.609 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-23T22:30:23.610Z,1600900223.610 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-23T22:30:23.610Z,1600900223.610 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-23T22:30:23.617Z,1600900223.617 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-23T22:30:23.617Z,1600900223.617 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-23T22:30:23.618Z,1600900223.618 [SBIT](INFO): Initialize SBIT Component.
2020-09-23T22:30:23.618Z,1600900223.618 [SBIT](IMPORTANT): git: 2020-09-23a
2020-09-23T22:30:23.618Z,1600900223.618 [SBIT](INFO): git hash: e308fd45a6ed36fdf930f760c966ab830e167584
2020-09-23T22:30:23.619Z,1600900223.619 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-23T22:30:23.620Z,1600900223.620 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-09-23T22:30:23.621Z,1600900223.621 [SBIT](INFO): Beginning SBIT in 75.000000 seconds.
2020-09-23T22:30:23.621Z,1600900223.621 [IBIT](INFO): Initialize IBIT Component.
2020-09-23T22:30:23.623Z,1600900223.623 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-23T22:30:23.623Z,1600900223.623 [logger ThreadHandler](INFO): Handler Thread ID is 1124
2020-09-23T22:30:23.635Z,1600900223.635 [CBIT](DEBUG): Initialized mux pins.
2020-09-23T22:30:23.635Z,1600900223.635 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-23T22:30:23.643Z,1600900223.643 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1125
2020-09-23T22:30:23.655Z,1600900223.655 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1126
2020-09-23T22:30:23.656Z,1600900223.656 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-09-23T22:30:23.659Z,1600900223.659 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-09-23T22:30:23.659Z,1600900223.659 [CBIT](DEBUG): Initializing heartbeat.
2020-09-23T22:30:23.667Z,1600900223.667 [Onboard ThreadHandler](INFO): Handler Thread ID is 1127
2020-09-23T22:30:23.684Z,1600900223.684 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1128
2020-09-23T22:30:23.715Z,1600900223.715 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1129
2020-09-23T22:30:23.716Z,1600900223.716 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-09-23T22:30:23.723Z,1600900223.723 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1131
2020-09-23T22:30:23.727Z,1600900223.727 [WetLabsBB2FL](INFO): Powering up
2020-09-23T22:30:23.728Z,1600900223.728 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1133
2020-09-23T22:30:23.731Z,1600900223.731 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-23T22:30:23.731Z,1600900223.731 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-23T22:30:23.732Z,1600900223.732 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-23T22:30:23.733Z,1600900223.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-23T22:30:23.733Z,1600900223.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-23T22:30:23.733Z,1600900223.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-23T22:30:23.733Z,1600900223.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-23T22:30:23.733Z,1600900223.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-23T22:30:23.733Z,1600900223.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-23T22:30:23.734Z,1600900223.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-23T22:30:23.734Z,1600900223.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-23T22:30:23.734Z,1600900223.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-23T22:30:23.734Z,1600900223.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-23T22:30:23.734Z,1600900223.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-23T22:30:23.734Z,1600900223.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-23T22:30:23.735Z,1600900223.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-23T22:30:23.735Z,1600900223.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-23T22:30:23.735Z,1600900223.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-23T22:30:23.766Z,1600900223.766 [CBIT](DEBUG): Backplane powered.
2020-09-23T22:30:23.769Z,1600900223.769 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-23T22:30:23.782Z,1600900223.782 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-23T22:30:23.814Z,1600900223.814 [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>


2020-09-23T22:30:23.815Z,1600900223.815 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-09-23T22:30:23.889Z,1600900223.889 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-23T22:30:23.890Z,1600900223.890 [Default:A.Wait](DEBUG): Construct Wait.
2020-09-23T22:30:23.892Z,1600900223.892 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-23T22:30:23.936Z,1600900223.936 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-23T22:30:23.939Z,1600900223.939 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-23T22:30:23.960Z,1600900223.960 [Default:E.Execute](DEBUG): Construct Execute.
2020-09-23T22:30:23.963Z,1600900223.963 [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>


2020-09-23T22:30:23.968Z,1600900223.968 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-23T22:30:23.991Z,1600900223.991 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-23T22:30:24.055Z,1600900224.055 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-09-23T22:30:24.066Z,1600900224.066 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-09-23T22:30:24.066Z,1600900224.066 [DAT](INFO): Powering up
2020-09-23T22:30:24.066Z,1600900224.066 [DAT](DEBUG): Initializing DAT.
2020-09-23T22:30:24.091Z,1600900224.091 [Radio_Surface](INFO): Powering up
2020-09-23T22:30:24.091Z,1600900224.091 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-09-23T22:30:24.103Z,1600900224.103 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-09-23T22:30:24.147Z,1600900224.147 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-23T22:30:24.159Z,1600900224.159 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-23T22:30:24.160Z,1600900224.160 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-23T22:30:24.171Z,1600900224.171 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-23T22:30:24.172Z,1600900224.172 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-23T22:30:24.183Z,1600900224.183 [MassServo](DEBUG): Initializing MassServo.
2020-09-23T22:30:24.184Z,1600900224.184 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-23T22:30:24.199Z,1600900224.199 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-23T22:30:24.200Z,1600900224.200 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-23T22:30:24.211Z,1600900224.211 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-23T22:30:24.396Z,1600900224.396 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-09-23T22:30:24.396Z,1600900224.396 [DropWeight] Hardware Fault, FailCount= 1
2020-09-23T22:30:24.396Z,1600900224.396 [DropWeight](ERROR): Hardware Fault
2020-09-23T22:30:24.424Z,1600900224.424 [CommandLine](FAULT): Scheduling is paused
2020-09-23T22:30:24.425Z,1600900224.425 [CBIT](INFO): Critical error at 20200923T223024
2020-09-23T22:30:24.425Z,1600900224.425 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-09-23T22:30:24.427Z,1600900224.427 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-09-23T22:30:24.428Z,1600900224.428 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-09-23T22:30:25.111Z,1600900225.111 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-09-23T22:30:25.111Z,1600900225.111 [RudderServo](FAULT): Rudder failed to initialize
2020-09-23T22:30:25.111Z,1600900225.111 [RudderServo] Communications Fault, FailCount= 1
2020-09-23T22:30:25.111Z,1600900225.111 [RudderServo](ERROR): Communications Fault
2020-09-23T22:30:25.220Z,1600900225.220 [CBIT](INFO): Critical error at 20200923T223024
2020-09-23T22:30:25.222Z,1600900225.222 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-09-23T22:30:25.400Z,1600900225.400 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-23T22:30:25.401Z,1600900225.401 [RudderServo](INFO): Powering down
2020-09-23T22:30:26.050Z,1600900226.050 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-23T22:30:26.172Z,1600900226.172 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-23T22:30:26.176Z,1600900226.176 [CBIT](INFO): Clearing failed state for component RudderServo
2020-09-23T22:30:26.176Z,1600900226.176 [RudderServo] No Fault, FailCount= 1
2020-09-23T22:30:26.339Z,1600900226.339 [WetLabsBB2FL](INFO): Powering down
2020-09-23T22:30:30.748Z,1600900230.748 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-09-23T22:30:38.167Z,1600900238.167 [DAT](INFO): commRate: 800
2020-09-23T22:30:40.182Z,1600900240.182 [DAT](INFO): entering command mode
2020-09-23T22:30:40.585Z,1600900240.585 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:40.990Z,1600900240.990 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:41.393Z,1600900241.393 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:41.797Z,1600900241.797 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:42.202Z,1600900242.202 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:42.605Z,1600900242.605 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:43.009Z,1600900243.009 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:43.417Z,1600900243.417 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:43.822Z,1600900243.822 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:44.221Z,1600900244.221 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:44.626Z,1600900244.626 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:45.033Z,1600900245.033 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:45.433Z,1600900245.433 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:45.838Z,1600900245.838 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:46.240Z,1600900246.240 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:30:46.240Z,1600900246.240 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-09-23T22:30:46.240Z,1600900246.240 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:30:46.241Z,1600900246.241 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:46.291Z,1600900246.291 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:30:46.727Z,1600900246.727 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:30:46.728Z,1600900246.728 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:47.049Z,1600900247.049 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:47.452Z,1600900247.452 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:47.491Z,1600900247.491 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:30:47.491Z,1600900247.491 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-23T22:30:47.864Z,1600900247.864 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:48.260Z,1600900248.260 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:48.668Z,1600900248.668 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:49.084Z,1600900249.084 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:49.489Z,1600900249.489 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:49.885Z,1600900249.885 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:50.288Z,1600900250.288 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:50.688Z,1600900250.688 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:51.098Z,1600900251.098 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:51.496Z,1600900251.496 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:51.900Z,1600900251.900 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:52.304Z,1600900252.304 [NAL9602](INFO): Powering up NAL9602
2020-09-23T22:30:52.306Z,1600900252.306 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:52.716Z,1600900252.716 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:53.112Z,1600900253.112 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:53.516Z,1600900253.516 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:53.920Z,1600900253.920 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:54.330Z,1600900254.330 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:54.728Z,1600900254.728 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:55.132Z,1600900255.132 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:55.536Z,1600900255.536 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-23T22:30:55.537Z,1600900255.537 [DAT](FAULT): failed to enter command mode
2020-09-23T22:30:55.945Z,1600900255.945 [DAT](INFO): entering command mode
2020-09-23T22:30:56.386Z,1600900256.386 [DAT](INFO): setting verbose to 3
2020-09-23T22:30:56.750Z,1600900256.750 [DAT](INFO): set verbose to 3
2020-09-23T22:30:56.750Z,1600900256.750 [DAT](INFO): setting DatVerbose to 27440
2020-09-23T22:30:57.153Z,1600900257.153 [DAT](INFO): set DatVerbose to 27440
2020-09-23T22:30:57.154Z,1600900257.154 [DAT](INFO): setting transmit power to 8
2020-09-23T22:30:57.565Z,1600900257.565 [DAT](INFO): set transmit power to 8
2020-09-23T22:30:57.566Z,1600900257.566 [DAT](INFO): setting local address to 9
2020-09-23T22:30:57.969Z,1600900257.969 [DAT](INFO): set local address to 9
2020-09-23T22:31:03.212Z,1600900263.212 [NAL9602](INFO): NAL9602 initialized
2020-09-23T22:31:10.081Z,1600900270.081 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:31:10.081Z,1600900270.081 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-09-23T22:31:10.081Z,1600900270.081 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:31:10.095Z,1600900270.095 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:31:10.567Z,1600900270.567 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:31:11.328Z,1600900271.328 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:31:11.328Z,1600900271.328 [RDI_Pathfinder] No Fault, FailCount= 2
2020-09-23T22:31:33.924Z,1600900293.924 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:31:33.924Z,1600900293.924 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-09-23T22:31:33.924Z,1600900293.924 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:31:33.940Z,1600900293.940 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:31:33.940Z,1600900293.940 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:31:34.403Z,1600900294.403 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:31:34.431Z,1600900294.431 [CBIT](INFO): Critical error at 20200923T223133
2020-09-23T22:31:39.192Z,1600900299.192 [SBIT](IMPORTANT): Beginning Startup BIT
2020-09-23T22:31:39.197Z,1600900299.197 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-23T22:31:50.143Z,1600900310.143 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006728
CHAN A1 (24V): 0.002942
CHAN A2 (12V): -0.004784
CHAN A3 (5V): -0.000863
CHAN B0 (3.3V): 0.000385
CHAN B1 (3.15aV): -0.000069
CHAN B2 (3.15bV): 0.000696
CHAN B3 (GND): 0.001959
OPEN: 0.005601
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-23T22:31:56.969Z,1600900316.969 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-09-23T22:32:33.339Z,1600900353.339 [SBIT](IMPORTANT): SBIT PASSED
2020-09-23T22:32:33.339Z,1600900353.339 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-09-23T22:32:33.340Z,1600900353.340 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2020-09-23T22:32:33.340Z,1600900353.340 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-09-23T22:32:33.340Z,1600900353.340 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-09-23T22:32:33.340Z,1600900353.340 [SBIT](IMPORTANT): DAT.surfaceThreshold=5 meter;

2020-09-23T22:32:33.340Z,1600900353.340 [SBIT](IMPORTANT): DAT.verbosity=0 count;

2020-09-23T22:32:33.340Z,1600900353.340 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-09-23T22:32:33.341Z,1600900353.341 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-09-23T22:32:33.341Z,1600900353.341 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-09-23T22:32:33.341Z,1600900353.341 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-09-23T22:32:33.341Z,1600900353.341 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-09-23T22:32:33.341Z,1600900353.341 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2020-09-23T22:32:33.341Z,1600900353.341 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=146.161014 cubic_centimeter;

2020-09-23T22:32:33.341Z,1600900353.341 [SBIT](IMPORTANT): VerticalControl.massDefault=13.952984 millimeter;

2020-09-23T22:32:33.341Z,1600900353.341 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-09-23T22:32:33.716Z,1600900353.716 [MissionManager](IMPORTANT): Started mission Startup
2020-09-23T22:32:33.716Z,1600900353.716 [Startup] Running Loop=1
2020-09-23T22:32:33.716Z,1600900353.716 [Startup](DEBUG): Aggregate::initialize Startup
2020-09-23T22:32:33.716Z,1600900353.716 [Startup:A.GoToSurface] Running Loop=1
2020-09-23T22:32:33.716Z,1600900353.716 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-23T22:32:33.717Z,1600900353.717 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-23T22:32:33.717Z,1600900353.717 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-23T22:32:33.718Z,1600900353.718 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-23T22:32:33.718Z,1600900353.718 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-23T22:32:33.719Z,1600900353.719 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-23T22:32:33.721Z,1600900353.721 [Startup:StartupSatComms] Running Loop=1
2020-09-23T22:32:33.722Z,1600900353.722 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-09-23T22:32:33.722Z,1600900353.722 [Startup:StartupSatComms:A] Running Loop=1
2020-09-23T22:32:34.128Z,1600900354.128 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-09-23T22:33:23.852Z,1600900403.852 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-09-23T22:33:23.852Z,1600900403.852 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:33:23.863Z,1600900403.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:33:24.251Z,1600900404.251 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:33:24.251Z,1600900404.251 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-09-23T22:33:33.919Z,1600900413.919 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-23T22:32:33.7Z
2020-09-23T22:33:33.919Z,1600900413.919 [Startup:StartupSatComms:A] Stopped
2020-09-23T22:33:33.919Z,1600900413.919 [Startup:StartupSatComms:B] Running Loop=1
2020-09-23T22:33:34.379Z,1600900414.379 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-23T22:33:46.641Z,1600900426.641 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004849
2020-09-23T22:33:54.027Z,1600900434.027 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20200923T221441/Courier0007.lzma
2020-09-23T22:33:55.028Z,1600900435.028 [DataOverHttps](INFO): Moved sent file to Logs/20200923T221441/Courier0007.lzma.bak
2020-09-23T22:33:55.029Z,1600900435.029 [DataOverHttps](INFO): SBD MOMSN=12666945
2020-09-23T22:34:10.423Z,1600900450.423 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20200923T223012/Courier0000.lzma
2020-09-23T22:34:11.425Z,1600900451.425 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0000.lzma.bak
2020-09-23T22:34:11.425Z,1600900451.425 [DataOverHttps](INFO): SBD MOMSN=12666949
2020-09-23T22:34:27.736Z,1600900467.736 [DataOverHttps](INFO): Sending 1078 bytes from file Logs/20200923T221441/Express0008.lzma
2020-09-23T22:34:28.736Z,1600900468.736 [DataOverHttps](INFO): Moved sent file to Logs/20200923T221441/Express0008.lzma.bak
2020-09-23T22:34:28.737Z,1600900468.737 [DataOverHttps](INFO): SBD MOMSN=12666955
2020-09-23T22:34:34.136Z,1600900474.136 [Startup:StartupSatComms:B](INFO): Timed out from 2020-09-23T22:33:33.9Z
2020-09-23T22:34:34.137Z,1600900474.137 [Startup:StartupSatComms:B] Stopped
2020-09-23T22:34:34.137Z,1600900474.137 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-09-23T22:34:34.137Z,1600900474.137 [Startup:StartupSatComms] Stopped
2020-09-23T22:34:34.137Z,1600900474.137 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-09-23T22:34:34.138Z,1600900474.138 [Startup](INFO): Completed Startup
2020-09-23T22:34:34.138Z,1600900474.138 [MissionManager](INFO): Startup is completed.
2020-09-23T22:34:34.138Z,1600900474.138 [MissionManager](INFO): Uninitializing Mission Startup
2020-09-23T22:34:34.138Z,1600900474.138 [Startup] Stopped
2020-09-23T22:34:34.138Z,1600900474.138 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-09-23T22:34:34.138Z,1600900474.138 [Startup:A.GoToSurface] Stopped
2020-09-23T22:34:34.138Z,1600900474.138 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-23T22:34:34.158Z,1600900474.158 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:34:34.158Z,1600900474.158 [RDI_Pathfinder] No Fault, FailCount= 3
2020-09-23T22:34:34.528Z,1600900474.528 [MissionManager](IMPORTANT): Started mission Default
2020-09-23T22:34:34.528Z,1600900474.528 [Default] Running Loop=1
2020-09-23T22:34:34.528Z,1600900474.528 [Default](DEBUG): Aggregate::initialize Default
2020-09-23T22:34:34.528Z,1600900474.528 [Default:B.GoToSurface] Running Loop=1
2020-09-23T22:34:34.528Z,1600900474.528 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-23T22:34:34.529Z,1600900474.529 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-23T22:34:34.529Z,1600900474.529 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-23T22:34:34.529Z,1600900474.529 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-23T22:34:34.529Z,1600900474.529 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-23T22:34:34.530Z,1600900474.530 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-23T22:34:34.530Z,1600900474.530 [Default:A.Wait] Running Loop=1
2020-09-23T22:34:34.530Z,1600900474.530 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-09-23T22:34:47.153Z,1600900487.153 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-09-23T22:34:47.153Z,1600900487.153 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-09-23T22:34:47.153Z,1600900487.153 [RDI_Pathfinder](ERROR): Data Fault
2020-09-23T22:34:47.242Z,1600900487.242 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-09-23T22:34:47.627Z,1600900487.627 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:34:47.637Z,1600900487.637 [Default:A.Wait](INFO): Done Waiting.
2020-09-23T22:34:47.637Z,1600900487.637 [Default:A.Wait] Stopped
2020-09-23T22:34:47.637Z,1600900487.637 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T22:34:47.984Z,1600900487.984 [Default:CheckIn] Running Loop=1
2020-09-23T22:34:47.984Z,1600900487.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T22:34:47.985Z,1600900487.985 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T22:34:48.389Z,1600900488.389 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-09-23T22:34:48.399Z,1600900488.399 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:34:48.399Z,1600900488.399 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-23T22:34:48.410Z,1600900488.410 [DataOverHttps](INFO): Sending 1070 bytes from file Logs/20200923T223012/Express0001.lzma
2020-09-23T22:34:49.352Z,1600900489.352 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0001.lzma.bak
2020-09-23T22:34:49.353Z,1600900489.353 [DataOverHttps](INFO): SBD MOMSN=12667013
2020-09-23T22:35:10.994Z,1600900510.994 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:35:10.994Z,1600900510.994 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-09-23T22:35:10.994Z,1600900510.994 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:35:11.011Z,1600900511.011 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:35:11.459Z,1600900511.459 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:35:12.215Z,1600900512.215 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:35:12.216Z,1600900512.216 [RDI_Pathfinder] No Fault, FailCount= 2
2020-09-23T22:35:34.840Z,1600900534.840 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:35:34.841Z,1600900534.841 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-09-23T22:35:34.841Z,1600900534.841 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:35:34.881Z,1600900534.881 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:35:34.882Z,1600900534.882 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:35:35.327Z,1600900535.327 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:35:35.343Z,1600900535.343 [CBIT](INFO): Critical error at 20200923T223534
2020-09-23T22:36:06.336Z,1600900566.336 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-23T22:36:24.944Z,1600900584.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-09-23T22:36:24.944Z,1600900584.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:36:24.954Z,1600900584.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:36:25.358Z,1600900585.358 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:36:25.358Z,1600900585.358 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-09-23T22:38:35.092Z,1600900715.092 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:38:35.092Z,1600900715.092 [RDI_Pathfinder] No Fault, FailCount= 3
2020-09-23T22:38:48.003Z,1600900728.003 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-09-23T22:38:48.003Z,1600900728.003 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-09-23T22:38:48.003Z,1600900728.003 [RDI_Pathfinder](ERROR): Data Fault
2020-09-23T22:38:48.052Z,1600900728.052 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-09-23T22:38:48.459Z,1600900728.459 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:38:49.273Z,1600900729.273 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:38:49.273Z,1600900729.273 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-23T22:39:11.824Z,1600900751.824 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:39:11.825Z,1600900751.825 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-09-23T22:39:11.825Z,1600900751.825 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:39:11.845Z,1600900751.845 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:39:12.323Z,1600900752.323 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:39:13.080Z,1600900753.080 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:39:13.080Z,1600900753.080 [RDI_Pathfinder] No Fault, FailCount= 2
2020-09-23T22:39:26.031Z,1600900766.031 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-09-23T22:39:26.031Z,1600900766.031 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:39:26.082Z,1600900766.082 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:39:26.480Z,1600900766.480 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:39:26.480Z,1600900766.480 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-09-23T22:39:35.729Z,1600900775.729 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:39:35.729Z,1600900775.729 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-09-23T22:39:35.729Z,1600900775.729 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:39:35.746Z,1600900775.746 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:39:35.746Z,1600900775.746 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:39:36.211Z,1600900776.211 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:39:36.232Z,1600900776.232 [CBIT](INFO): Critical error at 20200923T223935
2020-09-23T22:39:38.985Z,1600900778.985 [CommandLine](IMPORTANT): got command failComponent
2020-09-23T22:39:38.985Z,1600900778.985 [CommandLine](IMPORTANT): Failed components:
2020-09-23T22:39:38.986Z,1600900778.986 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-09-23T22:39:38.986Z,1600900778.986 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2020-09-23T22:39:44.563Z,1600900784.563 [CommandLine](IMPORTANT): got command failComponent none DropWeight
2020-09-23T22:39:44.563Z,1600900784.563 [DropWeight] No Fault, FailCount= 1
2020-09-23T22:39:44.563Z,1600900784.563 [CommandLine](IMPORTANT): DropWeight failureMode is No Fault
2020-09-23T22:39:48.272Z,1600900788.272 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-23T22:34:47.0Z
2020-09-23T22:39:48.272Z,1600900788.272 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T22:39:48.272Z,1600900788.272 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T22:39:48.682Z,1600900788.682 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-23T22:39:55.674Z,1600900795.674 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20200923T223012/Courier0004.lzma
2020-09-23T22:39:56.676Z,1600900796.676 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0004.lzma.bak
2020-09-23T22:39:56.676Z,1600900796.676 [DataOverHttps](INFO): SBD MOMSN=12667043
2020-09-23T22:39:58.145Z,1600900798.145 [CommandLine](IMPORTANT): got command strobe off
2020-09-23T22:39:58.145Z,1600900798.145 [CommandLine](IMPORTANT): Deactivating strobe
2020-09-23T22:40:13.003Z,1600900813.003 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20200923T223012/Express0005.lzma
2020-09-23T22:40:14.004Z,1600900814.004 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0005.lzma.bak
2020-09-23T22:40:14.004Z,1600900814.004 [DataOverHttps](INFO): SBD MOMSN=12667047
2020-09-23T22:40:16.159Z,1600900816.159 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T22:40:16.159Z,1600900816.159 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-23T22:40:16.160Z,1600900816.160 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-23T22:41:04.212Z,1600900864.212 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-09-23T22:41:04.212Z,1600900864.212 [NAL9602] Data Fault, FailCount= 1
2020-09-23T22:41:04.213Z,1600900864.213 [NAL9602](ERROR): Data Fault
2020-09-23T22:41:04.238Z,1600900864.238 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-23T22:41:04.600Z,1600900864.600 [NAL9602](INFO): Powering down
2020-09-23T22:41:05.429Z,1600900865.429 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-23T22:41:05.429Z,1600900865.429 [NAL9602] No Fault, FailCount= 1
2020-09-23T22:41:16.533Z,1600900876.533 [CommandLine](IMPORTANT): got command failComponent
2020-09-23T22:41:16.533Z,1600900876.533 [CommandLine](IMPORTANT): Failed components:
2020-09-23T22:41:16.534Z,1600900876.534 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2020-09-23T22:41:22.599Z,1600900882.599 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder
2020-09-23T22:41:22.599Z,1600900882.599 [RDI_Pathfinder] No Fault, FailCount= 3
2020-09-23T22:41:22.600Z,1600900882.600 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault
2020-09-23T22:41:34.910Z,1600900894.910 [NAL9602](INFO): Powering up NAL9602
2020-09-23T22:41:45.004Z,1600900905.004 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:41:45.005Z,1600900905.005 [RDI_Pathfinder] Communications Fault, FailCount= 4
2020-09-23T22:41:45.005Z,1600900905.005 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:41:45.025Z,1600900905.025 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:41:45.025Z,1600900905.025 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:41:45.511Z,1600900905.511 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:41:45.542Z,1600900905.542 [CBIT](INFO): Critical error at 20200923T224145
2020-09-23T22:41:45.808Z,1600900905.808 [NAL9602](INFO): NAL9602 initialized
2020-09-23T22:42:27.031Z,1600900947.031 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-09-23T22:42:27.031Z,1600900947.031 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:42:27.042Z,1600900947.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:42:27.454Z,1600900947.454 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:42:27.454Z,1600900947.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-09-23T22:44:21.812Z,1600901061.812 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-09-23T22:44:45.222Z,1600901085.222 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:44:45.222Z,1600901085.222 [RDI_Pathfinder] No Fault, FailCount= 4
2020-09-23T22:44:58.133Z,1600901098.133 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-09-23T22:44:58.133Z,1600901098.133 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-09-23T22:44:58.133Z,1600901098.133 [RDI_Pathfinder](ERROR): Data Fault
2020-09-23T22:44:58.149Z,1600901098.149 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-09-23T22:44:58.607Z,1600901098.607 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:44:59.369Z,1600901099.369 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:44:59.369Z,1600901099.369 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-23T22:45:16.744Z,1600901116.744 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-23T22:45:16.744Z,1600901116.744 [Default:CheckIn:C.Wait] Stopped
2020-09-23T22:45:16.744Z,1600901116.744 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T22:45:16.744Z,1600901116.744 [Default:CheckIn:D] Running Loop=1
2020-09-23T22:45:17.151Z,1600901117.151 [Default:CheckIn:D] Stopped
2020-09-23T22:45:17.151Z,1600901117.151 [Default:CheckIn:E] Running Loop=1
2020-09-23T22:45:17.516Z,1600901117.516 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.710375 min 
2020-09-23T22:45:17.516Z,1600901117.516 [Default:CheckIn:E] Stopped
2020-09-23T22:45:17.516Z,1600901117.516 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-23T22:45:17.517Z,1600901117.517 [Default:CheckIn] Stopped
2020-09-23T22:45:17.517Z,1600901117.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T22:45:17.517Z,1600901117.517 [Default:CheckIn](INFO): Running loop #2
2020-09-23T22:45:17.517Z,1600901117.517 [Default:CheckIn] Running Loop=2
2020-09-23T22:45:17.517Z,1600901117.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T22:45:17.517Z,1600901117.517 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T22:45:21.949Z,1600901121.949 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:45:21.949Z,1600901121.949 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-09-23T22:45:21.949Z,1600901121.949 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:45:21.985Z,1600901121.985 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:45:22.443Z,1600901122.443 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:45:23.198Z,1600901123.198 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:45:23.199Z,1600901123.199 [RDI_Pathfinder] No Fault, FailCount= 2
2020-09-23T22:45:28.071Z,1600901128.071 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-09-23T22:45:28.071Z,1600901128.071 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:45:28.081Z,1600901128.081 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:45:28.464Z,1600901128.464 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:45:28.464Z,1600901128.464 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-09-23T22:45:45.791Z,1600901145.791 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:45:45.791Z,1600901145.791 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-09-23T22:45:45.791Z,1600901145.791 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:45:45.809Z,1600901145.809 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:45:45.810Z,1600901145.810 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:45:46.263Z,1600901146.263 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:45:46.287Z,1600901146.287 [CBIT](INFO): Critical error at 20200923T224545
2020-09-23T22:47:29.639Z,1600901249.639 [CommandLine](IMPORTANT): got command failComponent
2020-09-23T22:47:29.640Z,1600901249.640 [CommandLine](IMPORTANT): Failed components:
2020-09-23T22:47:29.640Z,1600901249.640 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2020-09-23T22:48:29.039Z,1600901309.039 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-09-23T22:48:29.039Z,1600901309.039 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:48:29.057Z,1600901309.057 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:48:29.468Z,1600901309.468 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:48:29.468Z,1600901309.468 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-09-23T22:48:46.045Z,1600901326.045 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:48:46.045Z,1600901326.045 [RDI_Pathfinder] No Fault, FailCount= 3
2020-09-23T22:48:58.951Z,1600901338.951 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-09-23T22:48:58.951Z,1600901338.951 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-09-23T22:48:58.951Z,1600901338.951 [RDI_Pathfinder](ERROR): Data Fault
2020-09-23T22:48:58.982Z,1600901338.982 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-09-23T22:48:59.052Z,1600901339.052 [CommandLine](IMPORTANT): got command failComponent
2020-09-23T22:48:59.052Z,1600901339.052 [CommandLine](IMPORTANT): Failed components:
2020-09-23T22:48:59.052Z,1600901339.052 [CommandLine](IMPORTANT): RDI_Pathfinder: Data Fault
2020-09-23T22:48:59.407Z,1600901339.407 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:49:00.153Z,1600901340.153 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:49:00.153Z,1600901340.153 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-23T22:49:00.586Z,1600901340.586 [CommandLine](IMPORTANT): got command failComponent
2020-09-23T22:49:00.586Z,1600901340.586 [CommandLine](IMPORTANT): Failed components:
2020-09-23T22:49:00.587Z,1600901340.587 [CommandLine](IMPORTANT): No failed Components.
2020-09-23T22:49:22.771Z,1600901362.771 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:49:22.771Z,1600901362.771 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-09-23T22:49:22.771Z,1600901362.771 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:49:22.821Z,1600901362.821 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:49:23.267Z,1600901363.267 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:49:23.985Z,1600901363.985 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:49:23.985Z,1600901363.985 [RDI_Pathfinder] No Fault, FailCount= 2
2020-09-23T22:49:46.609Z,1600901386.609 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:49:46.609Z,1600901386.609 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-09-23T22:49:46.609Z,1600901386.609 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:49:46.673Z,1600901386.673 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:49:46.673Z,1600901386.673 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:49:47.011Z,1600901387.011 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:49:47.061Z,1600901387.061 [CBIT](INFO): Critical error at 20200923T224946
2020-09-23T22:50:17.712Z,1600901417.712 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-23T22:45:17.5Z
2020-09-23T22:50:17.712Z,1600901417.712 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T22:50:17.712Z,1600901417.712 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T22:50:25.018Z,1600901425.018 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20200923T223012/Courier0007.lzma
2020-09-23T22:50:26.020Z,1600901426.020 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0007.lzma.bak
2020-09-23T22:50:26.020Z,1600901426.020 [DataOverHttps](INFO): SBD MOMSN=12667062
2020-09-23T22:50:42.408Z,1600901442.408 [DataOverHttps](INFO): Sending 412 bytes from file Logs/20200923T223012/Express0008.lzma
2020-09-23T22:50:43.409Z,1600901443.409 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0008.lzma.bak
2020-09-23T22:50:43.409Z,1600901443.409 [DataOverHttps](INFO): SBD MOMSN=12667072
2020-09-23T22:50:47.611Z,1600901447.611 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T22:50:47.611Z,1600901447.611 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-23T22:50:47.611Z,1600901447.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-23T22:51:30.034Z,1600901490.034 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-09-23T22:51:30.034Z,1600901490.034 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:51:30.045Z,1600901490.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:51:30.436Z,1600901490.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:51:30.436Z,1600901490.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-09-23T22:51:46.984Z,1600901506.984 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-09-23T22:51:46.984Z,1600901506.984 [NAL9602] Data Fault, FailCount= 2
2020-09-23T22:51:46.984Z,1600901506.984 [NAL9602](ERROR): Data Fault
2020-09-23T22:51:47.032Z,1600901507.032 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-23T22:51:47.388Z,1600901507.388 [NAL9602](INFO): Powering down
2020-09-23T22:51:48.227Z,1600901508.227 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-23T22:51:48.227Z,1600901508.227 [NAL9602] No Fault, FailCount= 2
2020-09-23T22:52:17.712Z,1600901537.712 [NAL9602](INFO): Powering up NAL9602
2020-09-23T22:52:28.597Z,1600901548.597 [NAL9602](INFO): NAL9602 initialized
2020-09-23T22:52:46.830Z,1600901566.830 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:52:46.830Z,1600901566.830 [RDI_Pathfinder] No Fault, FailCount= 3
2020-09-23T22:52:59.712Z,1600901579.712 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-09-23T22:52:59.712Z,1600901579.712 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-09-23T22:52:59.712Z,1600901579.712 [RDI_Pathfinder](ERROR): Data Fault
2020-09-23T22:52:59.763Z,1600901579.763 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-09-23T22:53:00.195Z,1600901580.195 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:53:00.945Z,1600901580.945 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:53:00.945Z,1600901580.945 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-23T22:53:23.549Z,1600901603.549 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:53:23.549Z,1600901603.549 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-09-23T22:53:23.549Z,1600901603.549 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:53:23.566Z,1600901603.566 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:53:24.095Z,1600901604.095 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:53:24.789Z,1600901604.789 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:53:24.789Z,1600901604.789 [RDI_Pathfinder] No Fault, FailCount= 2
2020-09-23T22:53:47.398Z,1600901627.398 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-09-23T22:53:47.398Z,1600901627.398 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-09-23T22:53:47.398Z,1600901627.398 [RDI_Pathfinder](ERROR): Communications Fault
2020-09-23T22:53:47.465Z,1600901627.465 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:53:47.475Z,1600901627.475 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-09-23T22:53:47.859Z,1600901627.859 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:53:47.880Z,1600901627.880 [CBIT](INFO): Critical error at 20200923T225347
2020-09-23T22:54:31.035Z,1600901671.035 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-09-23T22:54:31.035Z,1600901671.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:54:31.046Z,1600901671.046 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:54:31.440Z,1600901671.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:54:31.440Z,1600901671.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-09-23T22:55:48.227Z,1600901748.227 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-23T22:55:48.227Z,1600901748.227 [Default:CheckIn:C.Wait] Stopped
2020-09-23T22:55:48.227Z,1600901748.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T22:55:48.227Z,1600901748.227 [Default:CheckIn:D] Running Loop=1
2020-09-23T22:55:48.628Z,1600901748.628 [Default:CheckIn:D] Stopped
2020-09-23T22:55:48.628Z,1600901748.628 [Default:CheckIn:E] Running Loop=1
2020-09-23T22:55:49.016Z,1600901749.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.234991 min 
2020-09-23T22:55:49.016Z,1600901749.016 [Default:CheckIn:E] Stopped
2020-09-23T22:55:49.016Z,1600901749.016 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-23T22:55:49.016Z,1600901749.016 [Default:CheckIn] Stopped
2020-09-23T22:55:49.017Z,1600901749.017 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T22:55:49.017Z,1600901749.017 [Default:CheckIn](INFO): Running loop #3
2020-09-23T22:55:49.017Z,1600901749.017 [Default:CheckIn] Running Loop=3
2020-09-23T22:55:49.017Z,1600901749.017 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T22:55:49.017Z,1600901749.017 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T22:56:47.726Z,1600901807.726 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:56:47.726Z,1600901807.726 [RDI_Pathfinder] No Fault, FailCount= 3
2020-09-23T22:56:56.454Z,1600901816.454 [CommandLine](IMPORTANT): got command 
2020-09-23T22:56:56.454Z,1600901816.454 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-09-23T22:57:00.409Z,1600901820.409 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder
2020-09-23T22:57:00.410Z,1600901820.410 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault
2020-09-23T22:57:00.497Z,1600901820.497 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-09-23T22:57:00.497Z,1600901820.497 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-09-23T22:57:00.497Z,1600901820.497 [RDI_Pathfinder](ERROR): Data Fault
2020-09-23T22:57:00.560Z,1600901820.560 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-09-23T22:57:01.035Z,1600901821.035 [RDI_Pathfinder](INFO): Powering down
2020-09-23T22:57:01.777Z,1600901821.777 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-09-23T22:57:01.777Z,1600901821.777 [RDI_Pathfinder] No Fault, FailCount= 1
2020-09-23T22:57:03.873Z,1600901823.873 [CommandLine](IMPORTANT): got command failComponent
2020-09-23T22:57:03.873Z,1600901823.873 [CommandLine](IMPORTANT): Failed components:
2020-09-23T22:57:03.873Z,1600901823.873 [CommandLine](IMPORTANT): No failed Components.
2020-09-23T22:57:17.091Z,1600901837.091 [RDI_Pathfinder](INFO): Failure count cleared after critical for RDI_Pathfinder
2020-09-23T22:57:32.041Z,1600901852.041 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-09-23T22:57:32.041Z,1600901852.041 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T22:57:32.052Z,1600901852.052 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T22:57:32.448Z,1600901852.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T22:57:32.448Z,1600901852.448 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-09-23T22:57:57.817Z,1600901877.817 [CommandLine](IMPORTANT): got command 
2020-09-23T22:57:57.817Z,1600901877.817 [CommandLine](FAULT): Incomplete syntax. Try: help
2020-09-23T22:57:58.767Z,1600901878.767 [CommandLine](IMPORTANT): got command failComponent
2020-09-23T22:57:58.767Z,1600901878.767 [CommandLine](IMPORTANT): Failed components:
2020-09-23T22:57:58.767Z,1600901878.767 [CommandLine](IMPORTANT): No failed Components.
2020-09-23T23:00:33.025Z,1600902033.025 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-09-23T23:00:33.025Z,1600902033.025 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T23:00:33.036Z,1600902033.036 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T23:00:33.416Z,1600902033.416 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T23:00:33.416Z,1600902033.416 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-09-23T23:00:49.176Z,1600902049.176 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-23T22:55:49.0Z
2020-09-23T23:00:49.176Z,1600902049.176 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T23:00:49.176Z,1600902049.176 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T23:00:56.106Z,1600902056.106 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20200923T223012/Courier0010.lzma
2020-09-23T23:00:57.108Z,1600902057.108 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0010.lzma.bak
2020-09-23T23:00:57.108Z,1600902057.108 [DataOverHttps](INFO): SBD MOMSN=12667097
2020-09-23T23:01:15.151Z,1600902075.151 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20200923T223012/Express0011.lzma
2020-09-23T23:01:16.153Z,1600902076.153 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0011.lzma.bak
2020-09-23T23:01:16.153Z,1600902076.153 [DataOverHttps](INFO): SBD MOMSN=12667100
2020-09-23T23:01:18.301Z,1600902078.301 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T23:01:18.301Z,1600902078.301 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-23T23:01:18.301Z,1600902078.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-23T23:01:24.335Z,1600902084.335 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2020-09-23T23:01:24.338Z,1600902084.338 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2020-09-23T23:02:31.401Z,1600902151.401 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-09-23T23:02:31.401Z,1600902151.401 [NAL9602] Data Fault, FailCount= 3
2020-09-23T23:02:31.401Z,1600902151.401 [NAL9602](ERROR): Data Fault
2020-09-23T23:02:31.417Z,1600902151.417 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-23T23:02:31.808Z,1600902151.808 [NAL9602](INFO): Powering down
2020-09-23T23:02:32.646Z,1600902152.646 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-23T23:02:32.646Z,1600902152.646 [NAL9602] No Fault, FailCount= 3
2020-09-23T23:03:02.109Z,1600902182.109 [NAL9602](INFO): Powering up NAL9602
2020-09-23T23:03:13.016Z,1600902193.016 [NAL9602](INFO): NAL9602 initialized
2020-09-23T23:03:34.060Z,1600902214.060 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-09-23T23:03:34.060Z,1600902214.060 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T23:03:34.071Z,1600902214.071 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T23:03:34.468Z,1600902214.468 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T23:03:34.468Z,1600902214.468 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-09-23T23:06:18.872Z,1600902378.872 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-23T23:06:18.872Z,1600902378.872 [Default:CheckIn:C.Wait] Stopped
2020-09-23T23:06:18.872Z,1600902378.872 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T23:06:18.872Z,1600902378.872 [Default:CheckIn:D] Running Loop=1
2020-09-23T23:06:19.287Z,1600902379.287 [Default:CheckIn:D] Stopped
2020-09-23T23:06:19.287Z,1600902379.287 [Default:CheckIn:E] Running Loop=1
2020-09-23T23:06:19.724Z,1600902379.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.745978 min 
2020-09-23T23:06:19.724Z,1600902379.724 [Default:CheckIn:E] Stopped
2020-09-23T23:06:19.724Z,1600902379.724 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-23T23:06:19.724Z,1600902379.724 [Default:CheckIn] Stopped
2020-09-23T23:06:19.724Z,1600902379.724 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T23:06:19.724Z,1600902379.724 [Default:CheckIn](INFO): Running loop #4
2020-09-23T23:06:19.724Z,1600902379.724 [Default:CheckIn] Running Loop=4
2020-09-23T23:06:19.725Z,1600902379.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T23:06:19.725Z,1600902379.725 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T23:06:35.042Z,1600902395.042 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-09-23T23:06:35.042Z,1600902395.042 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-23T23:06:35.076Z,1600902395.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-23T23:06:35.456Z,1600902395.456 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-23T23:06:35.456Z,1600902395.456 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-09-23T23:06:35.834Z,1600902395.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230634.00,A,3648.16703,N,12147.27720,W,1.497,299.26,230920,,,A*70
2020-09-23T23:06:35.837Z,1600902395.837 [NAL9602](INFO): GPS fix at 20200923T230634: (36.802784, -121.787953)
2020-09-23T23:06:35.855Z,1600902395.855 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T23:06:35.855Z,1600902395.855 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T23:06:44.263Z,1600902404.263 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20200923T223012/Courier0013.lzma
2020-09-23T23:06:45.265Z,1600902405.265 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0013.lzma.bak
2020-09-23T23:06:45.265Z,1600902405.265 [DataOverHttps](INFO): SBD MOMSN=12667118
2020-09-23T23:07:04.635Z,1600902424.635 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20200923T223012/Express0014.lzma
2020-09-23T23:07:05.638Z,1600902425.638 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0014.lzma.bak
2020-09-23T23:07:05.639Z,1600902425.639 [DataOverHttps](INFO): SBD MOMSN=12667121
2020-09-23T23:07:08.176Z,1600902428.176 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T23:07:08.176Z,1600902428.176 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-23T23:07:08.177Z,1600902428.177 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-23T23:07:08.559Z,1600902428.559 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-23T23:07:59.328Z,1600902479.328 [DataOverHttps](IMPORTANT): SBD MTMSN=20200923T230758
2020-09-23T23:08:10.823Z,1600902490.823 [DataOverHttps](INFO): Received command:ibit
2020-09-23T23:08:10.835Z,1600902490.835 [CommandLine](IMPORTANT): got command ibit
2020-09-23T23:08:11.208Z,1600902491.208 [IBIT](IMPORTANT): Beginning Initiated BIT
2020-09-23T23:08:11.208Z,1600902491.208 [IBIT](IMPORTANT): Beginning control surface checks.
2020-09-23T23:08:11.211Z,1600902491.211 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-23T23:08:12.806Z,1600902492.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230811.00,A,3648.16441,N,12147.26045,W,2.216,145.85,230920,,,A*7E
2020-09-23T23:08:12.808Z,1600902492.808 [NAL9602](INFO): GPS fix at 20200923T230811: (36.802740, -121.787674)
2020-09-23T23:08:22.152Z,1600902502.152 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009454
CHAN A1 (24V): -0.000369
CHAN A2 (12V): -0.007105
CHAN A3 (5V): -0.002381
CHAN B0 (3.3V): 0.000219
CHAN B1 (3.15aV): 0.000027
CHAN B2 (3.15bV): -0.000572
CHAN B3 (GND): 0.001869
OPEN: 0.005397
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-23T23:08:24.131Z,1600902504.131 [NAL9602](INFO): SBD MO Status=2, MOMSN=9694, MT Status=2, MTMSN=0
2020-09-23T23:08:24.131Z,1600902504.131 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-23T23:08:35.849Z,1600902515.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=9694, MT Status=2, MTMSN=0
2020-09-23T23:08:35.849Z,1600902515.849 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-23T23:09:08.229Z,1600902548.229 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802742 Longitude: -121.787674
2020-09-23T23:09:08.588Z,1600902548.588 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.492001
2020-09-23T23:09:08.589Z,1600902548.589 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2020-09-23T23:09:08.589Z,1600902548.589 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.000000 V
2020-09-23T23:09:09.001Z,1600902549.001 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2020-09-23T23:09:09.001Z,1600902549.001 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2020-09-23T23:09:09.001Z,1600902549.001 [IBIT](IMPORTANT): Pressure:7.597600 PSI
2020-09-23T23:09:09.002Z,1600902549.002 [IBIT](IMPORTANT): Humidity:30.362595 %
2020-09-23T23:09:09.423Z,1600902549.423 [IBIT](IMPORTANT): Vehicle Pitch:-0.028743 degrees
2020-09-23T23:09:09.423Z,1600902549.423 [IBIT](IMPORTANT): Vehicle  Roll:0.523804 degrees
2020-09-23T23:09:09.423Z,1600902549.423 [IBIT](IMPORTANT): Vehicle  Heading:29.294573 degrees
2020-09-23T23:09:09.799Z,1600902549.799 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-09-23T23:09:09.800Z,1600902549.800 [IBIT](IMPORTANT): buoyancyNeutral: 146.161011 cc
2020-09-23T23:09:09.800Z,1600902549.800 [IBIT](IMPORTANT): massDefault: 1.395298 cm
2020-09-23T23:09:09.800Z,1600902549.800 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2020-09-23T23:09:09.801Z,1600902549.801 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2020-09-23T23:09:09.801Z,1600902549.801 [IBIT](IMPORTANT): IBIT PASSED
2020-09-23T23:09:13.825Z,1600902553.825 [NAL9602](INFO): SBD MO Status=0, MOMSN=9694, MT Status=0, MTMSN=0
2020-09-23T23:09:13.825Z,1600902553.825 [NAL9602](INFO): No messages in MT queue
2020-09-23T23:09:44.527Z,1600902584.527 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-23T23:12:08.769Z,1600902728.769 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-23T23:12:08.769Z,1600902728.769 [Default:CheckIn:C.Wait] Stopped
2020-09-23T23:12:08.769Z,1600902728.769 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T23:12:08.769Z,1600902728.769 [Default:CheckIn:D] Running Loop=1
2020-09-23T23:12:09.210Z,1600902729.210 [Default:CheckIn:D] Stopped
2020-09-23T23:12:09.210Z,1600902729.210 [Default:CheckIn:E] Running Loop=1
2020-09-23T23:12:09.573Z,1600902729.573 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.578027 min 
2020-09-23T23:12:09.573Z,1600902729.573 [Default:CheckIn:E] Stopped
2020-09-23T23:12:09.573Z,1600902729.573 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-23T23:12:09.573Z,1600902729.573 [Default:CheckIn] Stopped
2020-09-23T23:12:09.573Z,1600902729.573 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T23:12:09.574Z,1600902729.574 [Default:CheckIn](INFO): Running loop #5
2020-09-23T23:12:09.574Z,1600902729.574 [Default:CheckIn] Running Loop=5
2020-09-23T23:12:09.574Z,1600902729.574 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T23:12:09.574Z,1600902729.574 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T23:12:11.582Z,1600902731.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231210.00,A,3648.15089,N,12147.22326,W,0.136,24.56,230920,,,A*4E
2020-09-23T23:12:11.584Z,1600902731.584 [NAL9602](INFO): GPS fix at 20200923T231210: (36.802515, -121.787054)
2020-09-23T23:12:11.594Z,1600902731.594 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T23:12:11.594Z,1600902731.594 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T23:12:18.118Z,1600902738.118 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200923T223012/Courier0016.lzma
2020-09-23T23:12:19.122Z,1600902739.122 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0016.lzma.bak
2020-09-23T23:12:19.122Z,1600902739.122 [DataOverHttps](IMPORTANT): SBD MOMSN=12667129, MTMSN=20200923T231218
2020-09-23T23:12:27.341Z,1600902747.341 [NAL9602](INFO): SBD MO Status=0, MOMSN=9695, MT Status=0, MTMSN=0
2020-09-23T23:12:27.341Z,1600902747.341 [NAL9602](INFO): No messages in MT queue
2020-09-23T23:12:27.819Z,1600902747.819 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" 1hn42 1 2
2020-09-23T23:12:27.899Z,1600902747.899 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" 1hn42 1 2.000000
2020-09-23T23:12:27.900Z,1600902747.900 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=1hn42
2020-09-23T23:12:27.901Z,1600902747.901 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='1hn42'): "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" ASAP
2020-09-23T23:12:29.085Z,1600902749.085 [DataOverHttps](IMPORTANT): SBD MTMSN=20200923T231227
2020-09-23T23:12:36.399Z,1600902756.399 [DataOverHttps](INFO): Sending 822 bytes from file Logs/20200923T223012/Express0017.lzma
2020-09-23T23:12:36.404Z,1600902756.404 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 30 meter;set profile_station.MaxDepth 42 meter;run" 1hn42 2 2
2020-09-23T23:12:36.504Z,1600902756.504 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 30 meter;set profile_station.MaxDepth 42 meter;run" 1hn42 2 2.000000
2020-09-23T23:12:36.504Z,1600902756.504 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=1hn42
2020-09-23T23:12:36.505Z,1600902756.505 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='1hn42'): "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 30 meter;set profile_station.MaxDepth 42 meter;run" ASAP
2020-09-23T23:12:36.697Z,1600902756.697 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2020-09-23T23:12:36.697Z,1600902756.697 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2020-09-23T23:12:36.792Z,1600902756.792 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2020-09-23T23:12:36.795Z,1600902756.795 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2020-09-23T23:12:36.798Z,1600902756.798 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2020-09-23T23:12:36.801Z,1600902756.801 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2020-09-23T23:12:36.804Z,1600902756.804 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2020-09-23T23:12:36.808Z,1600902756.808 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2020-09-23T23:12:36.811Z,1600902756.811 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2020-09-23T23:12:36.814Z,1600902756.814 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2020-09-23T23:12:36.817Z,1600902756.817 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2020-09-23T23:12:36.820Z,1600902756.820 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2020-09-23T23:12:36.823Z,1600902756.823 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2020-09-23T23:12:36.826Z,1600902756.826 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2020-09-23T23:12:36.829Z,1600902756.829 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2020-09-23T23:12:36.832Z,1600902756.832 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2020-09-23T23:12:36.835Z,1600902756.835 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2020-09-23T23:12:36.868Z,1600902756.868 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2020-09-23T23:12:36.869Z,1600902756.869 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-09-23T23:12:37.226Z,1600902757.226 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2020-09-23T23:12:37.234Z,1600902757.234 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2020-09-23T23:12:37.248Z,1600902757.248 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2020-09-23T23:12:37.255Z,1600902757.255 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2020-09-23T23:12:37.258Z,1600902757.258 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2020-09-23T23:12:37.265Z,1600902757.265 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2020-09-23T23:12:37.279Z,1600902757.279 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2020-09-23T23:12:37.282Z,1600902757.282 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2020-09-23T23:12:37.289Z,1600902757.289 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2020-09-23T23:12:37.292Z,1600902757.292 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2020-09-23T23:12:37.306Z,1600902757.306 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-09-23T23:12:37.310Z,1600902757.310 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2020-09-23T23:12:37.322Z,1600902757.322 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2020-09-23T23:12:37.330Z,1600902757.330 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2020-09-23T23:12:37.338Z,1600902757.338 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-09-23T23:12:37.346Z,1600902757.346 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-09-23T23:12:37.349Z,1600902757.349 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-09-23T23:12:37.364Z,1600902757.364 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2020-09-23T23:12:37.372Z,1600902757.372 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2020-09-23T23:12:37.379Z,1600902757.379 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2020-09-23T23:12:37.382Z,1600902757.382 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2020-09-23T23:12:37.389Z,1600902757.389 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2020-09-23T23:12:37.393Z,1600902757.393 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2020-09-23T23:12:37.472Z,1600902757.472 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2020-09-23T23:12:37.487Z,1600902757.487 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2020-09-23T23:12:37.511Z,1600902757.511 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2020-09-23T23:12:37.514Z,1600902757.514 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2020-09-23T23:12:37.530Z,1600902757.530 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2020-09-23T23:12:37.534Z,1600902757.534 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2020-09-23T23:12:37.545Z,1600902757.545 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2020-09-23T23:12:37.556Z,1600902757.556 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2020-09-23T23:12:37.568Z,1600902757.568 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2020-09-23T23:12:37.581Z,1600902757.581 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2020-09-23T23:12:37.592Z,1600902757.592 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2020-09-23T23:12:37.604Z,1600902757.604 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2020-09-23T23:12:37.615Z,1600902757.615 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2020-09-23T23:12:37.627Z,1600902757.627 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2020-09-23T23:12:37.630Z,1600902757.630 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2020-09-23T23:12:37.648Z,1600902757.648 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2020-09-23T23:12:37.668Z,1600902757.668 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2020-09-23T23:12:37.698Z,1600902757.698 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2020-09-23T23:12:37.724Z,1600902757.724 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2020-09-23T23:12:37.727Z,1600902757.727 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2020-09-23T23:12:37.730Z,1600902757.730 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2020-09-23T23:12:37.749Z,1600902757.749 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2020-09-23T23:12:37.752Z,1600902757.752 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2020-09-23T23:12:37.985Z,1600902757.985 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-23T23:12:38.012Z,1600902758.012 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0017.lzma.bak
2020-09-23T23:12:38.013Z,1600902758.013 [DataOverHttps](IMPORTANT): SBD MOMSN=12667134, MTMSN=20200923T231236
2020-09-23T23:12:38.021Z,1600902758.021 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-09-23T23:12:38.051Z,1600902758.051 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-23T23:12:38.078Z,1600902758.078 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-23T23:12:38.100Z,1600902758.100 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-23T23:12:38.123Z,1600902758.123 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2020-09-23T23:12:38.123Z,1600902758.123 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-09-23T23:12:38.189Z,1600902758.189 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2020-09-23T23:12:38.192Z,1600902758.192 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2020-09-23T23:12:38.196Z,1600902758.196 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-09-23T23:12:38.218Z,1600902758.218 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2020-09-23T23:12:38.221Z,1600902758.221 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-09-23T23:12:38.224Z,1600902758.224 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2020-09-23T23:12:38.272Z,1600902758.272 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2020-09-23T23:12:38.280Z,1600902758.280 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-23T23:12:38.329Z,1600902758.329 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-09-23T23:12:38.359Z,1600902758.359 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2020-09-23T23:12:38.362Z,1600902758.362 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2020-09-23T23:12:38.369Z,1600902758.369 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-09-23T23:12:38.379Z,1600902758.379 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-09-23T23:12:38.397Z,1600902758.397 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-09-23T23:12:38.459Z,1600902758.459 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-09-23T23:12:38.512Z,1600902758.512 [profile_station:I.Pitch](DEBUG): Construct.
2020-09-23T23:12:38.531Z,1600902758.531 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-09-23T23:12:38.544Z,1600902758.544 [profile_station:K.SetSpeed](DEBUG): Construct.
2020-09-23T23:12:38.555Z,1600902758.555 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-09-23T23:12:38.589Z,1600902758.589 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-09-23T23:12:38.624Z,1600902758.624 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2020-09-23T23:12:38.724Z,1600902758.724 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2020-09-23T23:12:38.824Z,1600902758.824 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       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/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>60</Value></DefineArg>

<!-- You probably need to change these. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>300</Value></DefineArg>

<!-- You probably do not need to change these. -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

<!-- You are even less likely to need to change these. -->

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

<!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

<!-- Check for additional instructions first. -->

    <Call Id="StartingMission" RefId="NeedComms"/>

<!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

        <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

        <Guidance:Circle>
            <Sequence/>
            <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
            <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
            <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
            <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
        </Guidance:Circle>

    </Aggregate>

</Mission>


2020-09-23T23:12:38.831Z,1600902758.831 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2020-09-23T23:12:55.583Z,1600902775.583 [DataOverHttps](INFO): Received command:stop
2020-09-23T23:12:56.723Z,1600902776.723 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 2.000000 hour
2020-09-23T23:12:56.724Z,1600902776.724 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 20.000000 minute
2020-09-23T23:12:56.725Z,1600902776.725 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.797001 degree
2020-09-23T23:12:56.726Z,1600902776.726 [CommandLine](IMPORTANT): got command set profile_station.Lon -121.847000 degree
2020-09-23T23:12:56.827Z,1600902776.827 [CommandLine](IMPORTANT): got command stop
2020-09-23T23:12:56.834Z,1600902776.834 [CommandLine](IMPORTANT): Scheduling is paused for 1 commands
2020-09-23T23:12:56.871Z,1600902776.871 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-09-23T23:12:58.064Z,1600902778.064 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-23T23:13:07.451Z,1600902787.451 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinDepth 5.000000 meter
2020-09-23T23:13:07.451Z,1600902787.451 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 30.000000 meter
2020-09-23T23:13:07.452Z,1600902787.452 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 42.000000 meter
2020-09-23T23:13:07.453Z,1600902787.453 [CommandLine](IMPORTANT): got command run
2020-09-23T23:13:07.460Z,1600902787.460 [CommandLine](IMPORTANT): Running 
2020-09-23T23:13:07.780Z,1600902787.780 [Default] Stopped
2020-09-23T23:13:07.780Z,1600902787.780 [Default](DEBUG): Aggregate::uninitialize Default
2020-09-23T23:13:07.780Z,1600902787.780 [Default:B.GoToSurface] Stopped
2020-09-23T23:13:07.780Z,1600902787.780 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-23T23:13:07.780Z,1600902787.780 [Default:CheckIn] Stopped
2020-09-23T23:13:07.781Z,1600902787.781 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T23:13:07.781Z,1600902787.781 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T23:13:07.781Z,1600902787.781 [MissionManager](IMPORTANT): Started mission profile_station
2020-09-23T23:13:07.781Z,1600902787.781 [profile_station] Running Loop=1
2020-09-23T23:13:07.781Z,1600902787.781 [profile_station](DEBUG): Aggregate::initialize profile_station
2020-09-23T23:13:07.781Z,1600902787.781 [profile_station:Science] Running Loop=1
2020-09-23T23:13:07.781Z,1600902787.781 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2020-09-23T23:13:07.781Z,1600902787.781 [profile_station:Science:A] Running Loop=1
2020-09-23T23:13:07.781Z,1600902787.781 [profile_station:Science:B] Running Loop=1
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:C] Running Loop=1
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:D] Running Loop=1
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:E] Running Loop=1
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:F] Running Loop=1
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:Read_Oil] Running Loop=1
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-09-23T23:13:07.782Z,1600902787.782 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-23T23:13:07.784Z,1600902787.784 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2020-09-23T23:13:07.784Z,1600902787.784 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2020-09-23T23:13:07.785Z,1600902787.785 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-09-23T23:13:07.785Z,1600902787.785 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-09-23T23:13:07.785Z,1600902787.785 [profile_station:Science:PeakDetectNO3] Running Loop=1
2020-09-23T23:13:07.785Z,1600902787.785 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2020-09-23T23:13:07.785Z,1600902787.785 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-09-23T23:13:07.785Z,1600902787.785 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-23T23:13:07.803Z,1600902787.803 [profile_station:Science:PeakDetectOil] Running Loop=1
2020-09-23T23:13:07.804Z,1600902787.804 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2020-09-23T23:13:07.804Z,1600902787.804 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-09-23T23:13:07.804Z,1600902787.804 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-23T23:13:07.806Z,1600902787.806 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2020-09-23T23:13:07.806Z,1600902787.806 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2020-09-23T23:13:07.806Z,1600902787.806 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-09-23T23:13:07.806Z,1600902787.806 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-23T23:13:07.812Z,1600902787.812 [profile_station:Science:OceanCurrent] Running Loop=1
2020-09-23T23:13:07.812Z,1600902787.812 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::initialize profile_station:Science:OceanCurrent
2020-09-23T23:13:07.813Z,1600902787.813 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2020-09-23T23:13:07.813Z,1600902787.813 [profile_station:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2020-09-23T23:13:07.814Z,1600902787.814 [profile_station:StandardEnvelopes] Running Loop=1
2020-09-23T23:13:07.818Z,1600902787.818 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2020-09-23T23:13:07.819Z,1600902787.819 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-09-23T23:13:07.819Z,1600902787.819 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-09-23T23:13:07.819Z,1600902787.819 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-09-23T23:13:07.819Z,1600902787.819 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-09-23T23:13:07.819Z,1600902787.819 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-09-23T23:13:07.819Z,1600902787.819 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-09-23T23:13:07.819Z,1600902787.819 [profile_station:I.Pitch] Running Loop=1
2020-09-23T23:13:07.820Z,1600902787.820 [profile_station:I.Pitch](DEBUG): Initialize.
2020-09-23T23:13:07.820Z,1600902787.820 [profile_station:J.Buoyancy] Running Loop=1
2020-09-23T23:13:07.820Z,1600902787.820 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-09-23T23:13:07.820Z,1600902787.820 [profile_station:K.SetSpeed] Running Loop=1
2020-09-23T23:13:07.820Z,1600902787.820 [profile_station:K.SetSpeed](DEBUG): Initialize.
2020-09-23T23:13:07.820Z,1600902787.820 [profile_station:L.DepthEnvelope] Running Loop=1
2020-09-23T23:13:07.820Z,1600902787.820 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-09-23T23:13:07.820Z,1600902787.820 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-09-23T23:13:07.821Z,1600902787.821 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-09-23T23:13:07.821Z,1600902787.821 [profile_station:N.YoYo] Running Loop=1
2020-09-23T23:13:07.821Z,1600902787.821 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2020-09-23T23:13:07.821Z,1600902787.821 [profile_station:C] Running Loop=1
2020-09-23T23:13:07.822Z,1600902787.822 [profile_station:N.YoYo] Running Loop=1
2020-09-23T23:13:07.822Z,1600902787.822 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-09-23T23:13:07.827Z,1600902787.827 [profile_station:L.DepthEnvelope] Running Loop=1
2020-09-23T23:13:07.828Z,1600902787.828 [profile_station:K.SetSpeed] Running Loop=1
2020-09-23T23:13:07.828Z,1600902787.828 [profile_station:J.Buoyancy] Running Loop=1
2020-09-23T23:13:07.829Z,1600902787.829 [profile_station:I.Pitch] Running Loop=1
2020-09-23T23:13:07.829Z,1600902787.829 [profile_station:StandardEnvelopes] Running Loop=1
2020-09-23T23:13:07.829Z,1600902787.829 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-09-23T23:13:07.846Z,1600902787.846 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-09-23T23:13:07.846Z,1600902787.846 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-09-23T23:13:07.855Z,1600902787.855 [profile_station:C] Stopped
2020-09-23T23:13:07.855Z,1600902787.855 [profile_station:E] Running Loop=1
2020-09-23T23:13:07.856Z,1600902787.856 [profile_station:Science] Running Loop=1
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectFDOM] Stopped
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectOil] Stopped
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectNO3] Stopped
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2020-09-23T23:13:07.857Z,1600902787.857 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-09-23T23:13:07.858Z,1600902787.858 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-23T23:13:07.858Z,1600902787.858 [profile_station:Science:HighestChlPeakReport] Stopped
2020-09-23T23:13:07.858Z,1600902787.858 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2020-09-23T23:13:07.858Z,1600902787.858 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-09-23T23:13:07.858Z,1600902787.858 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-09-23T23:13:07.858Z,1600902787.858 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-09-23T23:13:07.858Z,1600902787.858 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2020-09-23T23:13:07.859Z,1600902787.859 [profile_station:Science:Read_Oil] Stopped
2020-09-23T23:13:07.859Z,1600902787.859 [profile_station:Science:F] Stopped
2020-09-23T23:13:07.859Z,1600902787.859 [profile_station:Science:E] Running Loop=1
2020-09-23T23:13:07.860Z,1600902787.860 [profile_station:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T23:13:07.860Z,1600902787.860 [profile_station:Science:D] Stopped
2020-09-23T23:13:07.860Z,1600902787.860 [profile_station:Science:C] Running Loop=1
2020-09-23T23:13:07.861Z,1600902787.861 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-09-23T23:13:07.861Z,1600902787.861 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-09-23T23:13:07.861Z,1600902787.861 [profile_station:Science:B] Running Loop=1
2020-09-23T23:13:07.862Z,1600902787.862 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-09-23T23:13:07.864Z,1600902787.864 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-09-23T23:13:07.864Z,1600902787.864 [profile_station:Science:A] Stopped
2020-09-23T23:13:07.864Z,1600902787.864 [MissionManager](INFO): MissionManager is completed.
2020-09-23T23:13:07.864Z,1600902787.864 [MissionManager](INFO): Uninitializing Mission profile_station
2020-09-23T23:13:07.864Z,1600902787.864 [profile_station] Stopped
2020-09-23T23:13:07.864Z,1600902787.864 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science] Stopped
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:B] Stopped
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:C] Stopped
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:E] Stopped
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:OceanCurrent] Stopped
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:OceanCurrent:A.] Stopped
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:PeakDetectChl] Stopped
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-23T23:13:07.865Z,1600902787.865 [profile_station:StandardEnvelopes] Stopped
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:E] Stopped
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:I.Pitch] Stopped
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:J.Buoyancy] Stopped
2020-09-23T23:13:07.866Z,1600902787.866 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-09-23T23:13:07.875Z,1600902787.875 [profile_station:K.SetSpeed] Stopped
2020-09-23T23:13:07.875Z,1600902787.875 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2020-09-23T23:13:07.875Z,1600902787.875 [profile_station:L.DepthEnvelope] Stopped
2020-09-23T23:13:07.875Z,1600902787.875 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2020-09-23T23:13:07.875Z,1600902787.875 [profile_station:M.AltitudeEnvelope] Stopped
2020-09-23T23:13:07.875Z,1600902787.875 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-09-23T23:13:07.875Z,1600902787.875 [profile_station:N.YoYo] Stopped
2020-09-23T23:13:07.875Z,1600902787.875 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2020-09-23T23:13:08.186Z,1600902788.186 [MissionManager](IMPORTANT): Started mission Default
2020-09-23T23:13:08.186Z,1600902788.186 [Default] Running Loop=1
2020-09-23T23:13:08.195Z,1600902788.195 [Default](DEBUG): Aggregate::initialize Default
2020-09-23T23:13:08.195Z,1600902788.195 [Default:B.GoToSurface] Running Loop=1
2020-09-23T23:13:08.195Z,1600902788.195 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-23T23:13:08.195Z,1600902788.195 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-23T23:13:08.195Z,1600902788.195 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-23T23:13:08.196Z,1600902788.196 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-23T23:13:08.196Z,1600902788.196 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-23T23:13:08.196Z,1600902788.196 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-23T23:13:08.197Z,1600902788.197 [Default:A.Wait] Running Loop=1
2020-09-23T23:13:08.197Z,1600902788.197 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-09-23T23:13:08.649Z,1600902788.649 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200923T223012/Courier0022.lzma
2020-09-23T23:13:09.645Z,1600902789.645 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0022.lzma.bak
2020-09-23T23:13:09.645Z,1600902789.645 [DataOverHttps](INFO): SBD MOMSN=12667163
2020-09-23T23:13:21.522Z,1600902801.522 [Default:A.Wait](INFO): Done Waiting.
2020-09-23T23:13:21.522Z,1600902801.522 [Default:A.Wait] Stopped
2020-09-23T23:13:21.522Z,1600902801.522 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T23:13:21.914Z,1600902801.914 [Default:CheckIn] Running Loop=1
2020-09-23T23:13:21.930Z,1600902801.930 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T23:13:21.931Z,1600902801.931 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T23:13:23.925Z,1600902803.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231323.00,A,3648.15042,N,12147.22320,W,0.019,24.56,230920,,,A*42
2020-09-23T23:13:23.928Z,1600902803.928 [NAL9602](INFO): GPS fix at 20200923T231323: (36.802507, -121.787053)
2020-09-23T23:13:23.949Z,1600902803.949 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T23:13:23.949Z,1600902803.949 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T23:13:32.134Z,1600902812.134 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200923T223012/Courier0025.lzma
2020-09-23T23:13:33.136Z,1600902813.136 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0025.lzma.bak
2020-09-23T23:13:33.137Z,1600902813.137 [DataOverHttps](INFO): SBD MOMSN=12667165
2020-09-23T23:13:40.234Z,1600902820.234 [NAL9602](INFO): SBD MO Status=0, MOMSN=9696, MT Status=0, MTMSN=0
2020-09-23T23:13:40.234Z,1600902820.234 [NAL9602](INFO): No messages in MT queue
2020-09-23T23:13:49.458Z,1600902829.458 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200923T223012/Courier0028.lzma
2020-09-23T23:13:50.461Z,1600902830.461 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0028.lzma.bak
2020-09-23T23:13:50.461Z,1600902830.461 [DataOverHttps](INFO): SBD MOMSN=12667167
2020-09-23T23:14:06.558Z,1600902846.558 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200923T223012/Courier0031.lzma
2020-09-23T23:14:07.560Z,1600902847.560 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0031.lzma.bak
2020-09-23T23:14:07.560Z,1600902847.560 [DataOverHttps](INFO): SBD MOMSN=12667169
2020-09-23T23:14:10.926Z,1600902850.926 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-23T23:14:24.811Z,1600902864.811 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200923T223012/Express0020.lzma
2020-09-23T23:14:25.813Z,1600902865.813 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0020.lzma.bak
2020-09-23T23:14:25.813Z,1600902865.813 [DataOverHttps](INFO): SBD MOMSN=12667172
2020-09-23T23:14:43.919Z,1600902883.919 [DataOverHttps](INFO): Sending 324 bytes from file Logs/20200923T223012/Express0023.lzma
2020-09-23T23:14:44.921Z,1600902884.921 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0023.lzma.bak
2020-09-23T23:14:44.921Z,1600902884.921 [DataOverHttps](INFO): SBD MOMSN=12667174
2020-09-23T23:15:04.995Z,1600902904.995 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20200923T223012/Express0026.lzma
2020-09-23T23:15:05.996Z,1600902905.996 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0026.lzma.bak
2020-09-23T23:15:05.997Z,1600902905.997 [DataOverHttps](INFO): SBD MOMSN=12667180
2020-09-23T23:15:23.619Z,1600902923.619 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20200923T223012/Express0029.lzma
2020-09-23T23:15:24.620Z,1600902924.620 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0029.lzma.bak
2020-09-23T23:15:24.621Z,1600902924.621 [DataOverHttps](INFO): SBD MOMSN=12667187
2020-09-23T23:15:42.222Z,1600902942.222 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20200923T223012/Express0032.lzma
2020-09-23T23:15:43.224Z,1600902943.224 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0032.lzma.bak
2020-09-23T23:15:43.224Z,1600902943.224 [DataOverHttps](INFO): SBD MOMSN=12667197
2020-09-23T23:15:45.497Z,1600902945.497 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T23:15:45.498Z,1600902945.498 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-23T23:15:45.498Z,1600902945.498 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-23T23:20:45.897Z,1600903245.897 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-23T23:20:45.897Z,1600903245.897 [Default:CheckIn:C.Wait] Stopped
2020-09-23T23:20:45.897Z,1600903245.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T23:20:45.898Z,1600903245.898 [Default:CheckIn:D] Running Loop=1
2020-09-23T23:20:46.114Z,1600903246.114 [Default:CheckIn:D] Stopped
2020-09-23T23:20:46.114Z,1600903246.114 [Default:CheckIn:E] Running Loop=1
2020-09-23T23:20:46.565Z,1600903246.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.632129 min 
2020-09-23T23:20:46.565Z,1600903246.565 [Default:CheckIn:E] Stopped
2020-09-23T23:20:46.566Z,1600903246.566 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-23T23:20:46.566Z,1600903246.566 [Default:CheckIn] Stopped
2020-09-23T23:20:46.566Z,1600903246.566 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T23:20:46.566Z,1600903246.566 [Default:CheckIn](INFO): Running loop #2
2020-09-23T23:20:46.566Z,1600903246.566 [Default:CheckIn] Running Loop=2
2020-09-23T23:20:46.566Z,1600903246.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T23:20:46.579Z,1600903246.579 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T23:20:48.521Z,1600903248.521 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232047.00,A,3648.25081,N,12147.13467,W,5.618,15.11,230920,,,D*4C
2020-09-23T23:20:48.524Z,1600903248.524 [NAL9602](INFO): GPS fix at 20200923T232047: (36.804180, -121.785578)
2020-09-23T23:20:48.534Z,1600903248.534 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T23:20:48.534Z,1600903248.534 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T23:20:52.024Z,1600903252.024 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200923T223012/Courier0034.lzma
2020-09-23T23:20:53.029Z,1600903253.029 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0034.lzma.bak
2020-09-23T23:20:53.029Z,1600903253.029 [DataOverHttps](IMPORTANT): SBD MOMSN=12667209, MTMSN=20200923T232052
2020-09-23T23:21:01.767Z,1600903261.767 [DataOverHttps](INFO): Received command:stop
2020-09-23T23:21:01.835Z,1600903261.835 [CommandLine](IMPORTANT): got command stop
2020-09-23T23:21:05.069Z,1600903265.069 [NAL9602](INFO): SBD MO Status=0, MOMSN=9697, MT Status=0, MTMSN=0
2020-09-23T23:21:05.069Z,1600903265.069 [NAL9602](INFO): No messages in MT queue
2020-09-23T23:21:10.474Z,1600903270.474 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20200923T223012/Express0035.lzma
2020-09-23T23:21:11.477Z,1600903271.477 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0035.lzma.bak
2020-09-23T23:21:11.477Z,1600903271.477 [DataOverHttps](INFO): SBD MOMSN=12667213
2020-09-23T23:21:28.602Z,1600903288.602 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200923T223012/Courier0037.lzma
2020-09-23T23:21:29.604Z,1600903289.604 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0037.lzma.bak
2020-09-23T23:21:29.604Z,1600903289.604 [DataOverHttps](INFO): SBD MOMSN=12667216
2020-09-23T23:21:35.772Z,1600903295.772 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-23T23:21:46.814Z,1600903306.814 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20200923T223012/Express0038.lzma
2020-09-23T23:21:47.816Z,1600903307.816 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0038.lzma.bak
2020-09-23T23:21:47.817Z,1600903307.817 [DataOverHttps](INFO): SBD MOMSN=12667218
2020-09-23T23:21:49.960Z,1600903309.960 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T23:21:49.960Z,1600903309.960 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-23T23:21:49.960Z,1600903309.960 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-23T23:26:50.506Z,1600903610.506 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-23T23:26:50.506Z,1600903610.506 [Default:CheckIn:C.Wait] Stopped
2020-09-23T23:26:50.506Z,1600903610.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T23:26:50.510Z,1600903610.510 [Default:CheckIn:D] Running Loop=1
2020-09-23T23:26:50.910Z,1600903610.910 [Default:CheckIn:D] Stopped
2020-09-23T23:26:50.910Z,1600903610.910 [Default:CheckIn:E] Running Loop=1
2020-09-23T23:26:51.386Z,1600903611.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.712057 min 
2020-09-23T23:26:51.386Z,1600903611.386 [Default:CheckIn:E] Stopped
2020-09-23T23:26:51.386Z,1600903611.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-23T23:26:51.386Z,1600903611.386 [Default:CheckIn] Stopped
2020-09-23T23:26:51.386Z,1600903611.386 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T23:26:51.391Z,1600903611.391 [Default:CheckIn](INFO): Running loop #3
2020-09-23T23:26:51.391Z,1600903611.391 [Default:CheckIn] Running Loop=3
2020-09-23T23:26:51.391Z,1600903611.391 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T23:26:51.391Z,1600903611.391 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T23:26:53.318Z,1600903613.318 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232652.00,A,3648.33395,N,12147.91212,W,10.944,270.84,230920,,,D*4E
2020-09-23T23:26:53.320Z,1600903613.320 [NAL9602](INFO): GPS fix at 20200923T232652: (36.805566, -121.798535)
2020-09-23T23:26:53.330Z,1600903613.330 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T23:26:53.342Z,1600903613.342 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T23:27:01.362Z,1600903621.362 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200923T223012/Courier0040.lzma
2020-09-23T23:27:02.364Z,1600903622.364 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0040.lzma.bak
2020-09-23T23:27:02.365Z,1600903622.365 [DataOverHttps](INFO): SBD MOMSN=12667223
2020-09-23T23:27:09.477Z,1600903629.477 [NAL9602](INFO): SBD MO Status=0, MOMSN=9698, MT Status=0, MTMSN=0
2020-09-23T23:27:09.477Z,1600903629.477 [NAL9602](INFO): No messages in MT queue
2020-09-23T23:27:23.514Z,1600903643.514 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200923T223012/Express0041.lzma
2020-09-23T23:27:28.534Z,1600903648.534 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Express0041.lzma.bak
2020-09-23T23:27:28.535Z,1600903648.535 [DataOverHttps](INFO): SBD MOMSN=12667226
2020-09-23T23:27:30.962Z,1600903650.962 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T23:27:30.962Z,1600903650.962 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-23T23:27:30.962Z,1600903650.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-23T23:27:40.184Z,1600903660.184 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-23T23:31:08.015Z,1600903868.015 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:31:11.027Z,1600903871.027 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267311
2020-09-23T23:31:43.163Z,1600903903.163 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:32:18.311Z,1600903938.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:32:31.481Z,1600903951.481 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-09-23T23:32:31.481Z,1600903951.481 [Default:CheckIn:C.Wait] Stopped
2020-09-23T23:32:31.481Z,1600903951.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T23:32:31.481Z,1600903951.481 [Default:CheckIn:D] Running Loop=1
2020-09-23T23:32:31.897Z,1600903951.897 [Default:CheckIn:D] Stopped
2020-09-23T23:32:31.897Z,1600903951.897 [Default:CheckIn:E] Running Loop=1
2020-09-23T23:32:32.293Z,1600903952.293 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.395172 min 
2020-09-23T23:32:32.293Z,1600903952.293 [Default:CheckIn:E] Stopped
2020-09-23T23:32:32.293Z,1600903952.293 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-09-23T23:32:32.294Z,1600903952.294 [Default:CheckIn] Stopped
2020-09-23T23:32:32.294Z,1600903952.294 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T23:32:32.294Z,1600903952.294 [Default:CheckIn](INFO): Running loop #4
2020-09-23T23:32:32.294Z,1600903952.294 [Default:CheckIn] Running Loop=4
2020-09-23T23:32:32.294Z,1600903952.294 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-23T23:32:32.294Z,1600903952.294 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-23T23:32:34.298Z,1600903954.298 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233233.00,A,3648.41212,N,12148.54785,W,0.389,109.79,230920,,,D*7F
2020-09-23T23:32:34.300Z,1600903954.300 [NAL9602](INFO): GPS fix at 20200923T233233: (36.806869, -121.809131)
2020-09-23T23:32:34.310Z,1600903954.310 [Default:CheckIn:Read_GPS] Stopped
2020-09-23T23:32:34.310Z,1600903954.310 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-23T23:32:51.561Z,1600903971.561 [NAL9602](INFO): SBD MO Status=1, MOMSN=9699, MT Status=0, MTMSN=0
2020-09-23T23:32:51.619Z,1600903971.619 [NAL9602](INFO): Sent 72 bytes from file Logs/20200923T223012/Courier0043.lzma
2020-09-23T23:32:51.619Z,1600903971.619 [NAL9602](INFO): Packets left to send: 0
2020-09-23T23:32:53.463Z,1600903973.463 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:33:02.605Z,1600903982.605 [NAL9602](INFO): SBD MO Status=1, MOMSN=9700, MT Status=0, MTMSN=0
2020-09-23T23:33:02.655Z,1600903982.655 [NAL9602](INFO): Sent 284 bytes from file Logs/20200923T223012/Express0044.lzma
2020-09-23T23:33:02.655Z,1600903982.655 [NAL9602](INFO): Packets left to send: 0
2020-09-23T23:33:12.164Z,1600903992.164 [NAL9602](INFO): SBD MO Status=0, MOMSN=9701, MT Status=0, MTMSN=0
2020-09-23T23:33:12.254Z,1600903992.254 [Default:CheckIn:Read_Iridium] Stopped
2020-09-23T23:33:12.254Z,1600903992.254 [Default:CheckIn:C.Wait] Running Loop=1
2020-09-23T23:33:12.254Z,1600903992.254 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-09-23T23:33:28.603Z,1600904008.603 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:33:42.864Z,1600904022.864 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-23T23:34:03.751Z,1600904043.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:34:38.891Z,1600904078.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:35:14.031Z,1600904114.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:35:49.179Z,1600904149.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-09-23T23:36:22.700Z,1600904182.700 [DataOverHttps](IMPORTANT): SBD MTMSN=20200923T233621
2020-09-23T23:36:22.704Z,1600904182.704 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003539
2020-09-23T23:36:30.426Z,1600904190.426 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 42 meter;run " 1ho7s 2 2
2020-09-23T23:36:30.516Z,1600904190.516 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 42 meter;run " 1ho7s 2 2.000000
2020-09-23T23:36:30.516Z,1600904190.516 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=1ho7s
2020-09-23T23:36:30.517Z,1600904190.517 [CommandLine](IMPORTANT): Scheduled #3 (#2 of 2 with id='1ho7s'): "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 42 meter;run " ASAP
2020-09-23T23:36:31.429Z,1600904191.429 [DataOverHttps](IMPORTANT): SBD MTMSN=20200923T233630
2020-09-23T23:36:38.959Z,1600904198.959 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" 1ho8o 1 2
2020-09-23T23:36:39.040Z,1600904199.040 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" 1ho8o 1 2.000000
2020-09-23T23:36:39.040Z,1600904199.040 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=1ho8o
2020-09-23T23:36:39.041Z,1600904199.041 [CommandLine](IMPORTANT): Scheduled #4 (#1 of 2 with id='1ho8o'): "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" ASAP
2020-09-23T23:36:39.960Z,1600904199.960 [DataOverHttps](IMPORTANT): SBD MTMSN=20200923T233638
2020-09-23T23:36:51.462Z,1600904211.462 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 42 meter;run " 1ho8o 2 2
2020-09-23T23:36:51.480Z,1600904211.480 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 42 meter;run " 1ho8o 2 2.000000
2020-09-23T23:36:51.480Z,1600904211.480 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=1ho8o
2020-09-23T23:36:51.482Z,1600904211.482 [CommandLine](IMPORTANT): Scheduled #5 (#2 of 2 with id='1ho8o'): "set profile_station.YoYoMinDepth 5 meter;set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 42 meter;run " ASAP
2020-09-23T23:36:51.607Z,1600904211.607 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2020-09-23T23:36:51.608Z,1600904211.608 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2020-09-23T23:36:51.708Z,1600904211.708 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2020-09-23T23:36:51.711Z,1600904211.711 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2020-09-23T23:36:51.713Z,1600904211.713 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2020-09-23T23:36:51.715Z,1600904211.715 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2020-09-23T23:36:51.718Z,1600904211.718 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2020-09-23T23:36:51.720Z,1600904211.720 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2020-09-23T23:36:51.722Z,1600904211.722 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2020-09-23T23:36:51.724Z,1600904211.724 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2020-09-23T23:36:51.727Z,1600904211.727 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2020-09-23T23:36:51.729Z,1600904211.729 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2020-09-23T23:36:51.731Z,1600904211.731 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2020-09-23T23:36:51.733Z,1600904211.733 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2020-09-23T23:36:51.735Z,1600904211.735 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2020-09-23T23:36:51.737Z,1600904211.737 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2020-09-23T23:36:51.740Z,1600904211.740 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2020-09-23T23:36:51.742Z,1600904211.742 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2020-09-23T23:36:51.743Z,1600904211.743 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-09-23T23:36:52.105Z,1600904212.105 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2020-09-23T23:36:52.113Z,1600904212.113 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2020-09-23T23:36:52.115Z,1600904212.115 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2020-09-23T23:36:52.117Z,1600904212.117 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2020-09-23T23:36:52.119Z,1600904212.119 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2020-09-23T23:36:52.122Z,1600904212.122 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2020-09-23T23:36:52.124Z,1600904212.124 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2020-09-23T23:36:52.126Z,1600904212.126 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2020-09-23T23:36:52.128Z,1600904212.128 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2020-09-23T23:36:52.130Z,1600904212.130 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2020-09-23T23:36:52.132Z,1600904212.132 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-09-23T23:36:52.135Z,1600904212.135 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2020-09-23T23:36:52.137Z,1600904212.137 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2020-09-23T23:36:52.140Z,1600904212.140 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2020-09-23T23:36:52.143Z,1600904212.143 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-09-23T23:36:52.145Z,1600904212.145 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-09-23T23:36:52.148Z,1600904212.148 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-09-23T23:36:52.150Z,1600904212.150 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2020-09-23T23:36:52.153Z,1600904212.153 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2020-09-23T23:36:52.155Z,1600904212.155 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2020-09-23T23:36:52.157Z,1600904212.157 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2020-09-23T23:36:52.179Z,1600904212.179 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2020-09-23T23:36:52.182Z,1600904212.182 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2020-09-23T23:36:52.188Z,1600904212.188 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2020-09-23T23:36:52.201Z,1600904212.201 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2020-09-23T23:36:52.208Z,1600904212.208 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2020-09-23T23:36:52.210Z,1600904212.210 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2020-09-23T23:36:52.217Z,1600904212.217 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2020-09-23T23:36:52.223Z,1600904212.223 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2020-09-23T23:36:52.226Z,1600904212.226 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2020-09-23T23:36:52.233Z,1600904212.233 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2020-09-23T23:36:52.239Z,1600904212.239 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2020-09-23T23:36:52.242Z,1600904212.242 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2020-09-23T23:36:52.249Z,1600904212.249 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2020-09-23T23:36:52.255Z,1600904212.255 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2020-09-23T23:36:52.257Z,1600904212.257 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2020-09-23T23:36:52.264Z,1600904212.264 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2020-09-23T23:36:52.271Z,1600904212.271 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2020-09-23T23:36:52.273Z,1600904212.273 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2020-09-23T23:36:52.284Z,1600904212.284 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2020-09-23T23:36:52.286Z,1600904212.286 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2020-09-23T23:36:52.293Z,1600904212.293 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2020-09-23T23:36:52.297Z,1600904212.297 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2020-09-23T23:36:52.304Z,1600904212.304 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2020-09-23T23:36:52.316Z,1600904212.316 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2020-09-23T23:36:52.318Z,1600904212.318 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2020-09-23T23:36:52.404Z,1600904212.404 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-23T23:36:52.429Z,1600904212.429 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-09-23T23:36:52.471Z,1600904212.471 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-23T23:36:52.485Z,1600904212.485 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-23T23:36:52.519Z,1600904212.519 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-23T23:36:52.545Z,1600904212.545 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2020-09-23T23:36:52.545Z,1600904212.545 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-09-23T23:36:52.627Z,1600904212.627 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2020-09-23T23:36:52.629Z,1600904212.629 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2020-09-23T23:36:52.635Z,1600904212.635 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-09-23T23:36:52.638Z,1600904212.638 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2020-09-23T23:36:52.644Z,1600904212.644 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-09-23T23:36:52.646Z,1600904212.646 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2020-09-23T23:36:52.649Z,1600904212.649 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2020-09-23T23:36:52.654Z,1600904212.654 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-23T23:36:52.699Z,1600904212.699 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-09-23T23:36:52.723Z,1600904212.723 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2020-09-23T23:36:52.726Z,1600904212.726 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2020-09-23T23:36:52.728Z,1600904212.728 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-09-23T23:36:52.729Z,1600904212.729 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-09-23T23:36:52.819Z,1600904212.819 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-09-23T23:36:52.849Z,1600904212.849 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-09-23T23:36:52.869Z,1600904212.869 [profile_station:I.Pitch](DEBUG): Construct.
2020-09-23T23:36:52.890Z,1600904212.890 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-09-23T23:36:52.893Z,1600904212.893 [profile_station:K.SetSpeed](DEBUG): Construct.
2020-09-23T23:36:52.895Z,1600904212.895 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-09-23T23:36:52.915Z,1600904212.915 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-09-23T23:36:52.921Z,1600904212.921 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2020-09-23T23:36:52.948Z,1600904212.948 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2020-09-23T23:36:52.989Z,1600904212.989 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       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/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>60</Value></DefineArg>

<!-- You probably need to change these. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>300</Value></DefineArg>

<!-- You probably do not need to change these. -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

<!-- You are even less likely to need to change these. -->

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

<!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

<!-- Check for additional instructions first. -->

    <Call Id="StartingMission" RefId="NeedComms"/>

<!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

        <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

        <Guidance:Circle>
            <Sequence/>
            <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
            <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
            <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
            <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
        </Guidance:Circle>

    </Aggregate>

</Mission>


2020-09-23T23:36:52.989Z,1600904212.989 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2020-09-23T23:37:00.546Z,1600904220.546 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 2.000000 hour
2020-09-23T23:37:00.547Z,1600904220.547 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 20.000000 minute
2020-09-23T23:37:00.548Z,1600904220.548 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.797001 degree
2020-09-23T23:37:00.549Z,1600904220.549 [CommandLine](IMPORTANT): got command set profile_station.Lon -121.847000 degree
2020-09-23T23:37:06.940Z,1600904226.940 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinDepth 5.000000 meter
2020-09-23T23:37:06.942Z,1600904226.942 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 20.000000 meter
2020-09-23T23:37:06.947Z,1600904226.947 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 42.000000 meter
2020-09-23T23:37:06.947Z,1600904226.947 [CommandLine](IMPORTANT): got command run 
2020-09-23T23:37:06.949Z,1600904226.949 [CommandLine](IMPORTANT): Running 
2020-09-23T23:37:07.309Z,1600904227.309 [Default] Stopped
2020-09-23T23:37:07.310Z,1600904227.310 [Default](DEBUG): Aggregate::uninitialize Default
2020-09-23T23:37:07.310Z,1600904227.310 [Default:B.GoToSurface] Stopped
2020-09-23T23:37:07.310Z,1600904227.310 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-23T23:37:07.310Z,1600904227.310 [Default:CheckIn] Stopped
2020-09-23T23:37:07.310Z,1600904227.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-23T23:37:07.310Z,1600904227.310 [Default:CheckIn:C.Wait] Stopped
2020-09-23T23:37:07.310Z,1600904227.310 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-09-23T23:37:07.310Z,1600904227.310 [MissionManager](IMPORTANT): Started mission profile_station
2020-09-23T23:37:07.310Z,1600904227.310 [profile_station] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station](DEBUG): Aggregate::initialize profile_station
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:A] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:B] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:C] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:D] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:E] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:F] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:Read_Oil] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-09-23T23:37:07.311Z,1600904227.311 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2020-09-23T23:37:07.312Z,1600904227.312 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-09-23T23:37:07.312Z,1600904227.312 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-23T23:37:07.313Z,1600904227.313 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2020-09-23T23:37:07.313Z,1600904227.313 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2020-09-23T23:37:07.313Z,1600904227.313 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-09-23T23:37:07.313Z,1600904227.313 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-09-23T23:37:07.314Z,1600904227.314 [profile_station:Science:PeakDetectNO3] Running Loop=1
2020-09-23T23:37:07.314Z,1600904227.314 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2020-09-23T23:37:07.314Z,1600904227.314 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-09-23T23:37:07.314Z,1600904227.314 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-23T23:37:07.315Z,1600904227.315 [profile_station:Science:PeakDetectOil] Running Loop=1
2020-09-23T23:37:07.315Z,1600904227.315 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2020-09-23T23:37:07.315Z,1600904227.315 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-09-23T23:37:07.315Z,1600904227.315 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-23T23:37:07.316Z,1600904227.316 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2020-09-23T23:37:07.317Z,1600904227.317 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2020-09-23T23:37:07.317Z,1600904227.317 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-09-23T23:37:07.317Z,1600904227.317 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-23T23:37:07.318Z,1600904227.318 [profile_station:Science:OceanCurrent] Running Loop=1
2020-09-23T23:37:07.318Z,1600904227.318 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::initialize profile_station:Science:OceanCurrent
2020-09-23T23:37:07.318Z,1600904227.318 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2020-09-23T23:37:07.318Z,1600904227.318 [profile_station:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2020-09-23T23:37:07.318Z,1600904227.318 [profile_station:StandardEnvelopes] Running Loop=1
2020-09-23T23:37:07.318Z,1600904227.318 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2020-09-23T23:37:07.318Z,1600904227.318 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:I.Pitch] Running Loop=1
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:I.Pitch](DEBUG): Initialize.
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:J.Buoyancy] Running Loop=1
2020-09-23T23:37:07.319Z,1600904227.319 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-09-23T23:37:07.320Z,1600904227.320 [profile_station:K.SetSpeed] Running Loop=1
2020-09-23T23:37:07.320Z,1600904227.320 [profile_station:K.SetSpeed](DEBUG): Initialize.
2020-09-23T23:37:07.320Z,1600904227.320 [profile_station:L.DepthEnvelope] Running Loop=1
2020-09-23T23:37:07.320Z,1600904227.320 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-09-23T23:37:07.320Z,1600904227.320 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-09-23T23:37:07.320Z,1600904227.320 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-09-23T23:37:07.320Z,1600904227.320 [profile_station:N.YoYo] Running Loop=1
2020-09-23T23:37:07.320Z,1600904227.320 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2020-09-23T23:37:07.321Z,1600904227.321 [profile_station:C] Running Loop=1
2020-09-23T23:37:07.321Z,1600904227.321 [profile_station:N.YoYo] Running Loop=1
2020-09-23T23:37:07.321Z,1600904227.321 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-09-23T23:37:07.322Z,1600904227.322 [profile_station:L.DepthEnvelope] Running Loop=1
2020-09-23T23:37:07.349Z,1600904227.349 [profile_station:K.SetSpeed] Running Loop=1
2020-09-23T23:37:07.350Z,1600904227.350 [profile_station:J.Buoyancy] Running Loop=1
2020-09-23T23:37:07.351Z,1600904227.351 [profile_station:I.Pitch] Running Loop=1
2020-09-23T23:37:07.352Z,1600904227.352 [profile_station:StandardEnvelopes] Running Loop=1
2020-09-23T23:37:07.352Z,1600904227.352 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-09-23T23:37:07.373Z,1600904227.373 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-09-23T23:37:07.374Z,1600904227.374 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-09-23T23:37:07.374Z,1600904227.374 [profile_station:C] Stopped
2020-09-23T23:37:07.374Z,1600904227.374 [profile_station:E] Running Loop=1
2020-09-23T23:37:07.374Z,1600904227.374 [profile_station:Science] Running Loop=1
2020-09-23T23:37:07.375Z,1600904227.375 [profile_station:Science:PeakDetectFDOM] Stopped
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectOil] Stopped
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectNO3] Stopped
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-23T23:37:07.376Z,1600904227.376 [profile_station:Science:HighestChlPeakReport] Stopped
2020-09-23T23:37:07.377Z,1600904227.377 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2020-09-23T23:37:07.377Z,1600904227.377 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-09-23T23:37:07.377Z,1600904227.377 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-09-23T23:37:07.377Z,1600904227.377 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-09-23T23:37:07.377Z,1600904227.377 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2020-09-23T23:37:07.379Z,1600904227.379 [profile_station:Science:Read_Oil] Stopped
2020-09-23T23:37:07.379Z,1600904227.379 [profile_station:Science:F] Stopped
2020-09-23T23:37:07.379Z,1600904227.379 [profile_station:Science:E] Running Loop=1
2020-09-23T23:37:07.379Z,1600904227.379 [profile_station:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-09-23T23:37:07.380Z,1600904227.380 [profile_station:Science:D] Stopped
2020-09-23T23:37:07.380Z,1600904227.380 [profile_station:Science:C] Running Loop=1
2020-09-23T23:37:07.380Z,1600904227.380 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-09-23T23:37:07.380Z,1600904227.380 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-09-23T23:37:07.381Z,1600904227.381 [profile_station:Science:B] Running Loop=1
2020-09-23T23:37:07.381Z,1600904227.381 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-09-23T23:37:07.381Z,1600904227.381 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-09-23T23:37:07.382Z,1600904227.382 [profile_station:Science:A] Stopped
2020-09-23T23:37:07.616Z,1600904227.616 [WetLabsBB2FL](INFO): Powering up
2020-09-23T23:37:07.720Z,1600904227.720 [profile_station:E] Stopped
2020-09-23T23:37:07.720Z,1600904227.720 [profile_station:F] Running Loop=1
2020-09-23T23:37:08.144Z,1600904228.144 [profile_station:F] Stopped
2020-09-23T23:37:08.144Z,1600904228.144 [profile_station:G] Running Loop=1
2020-09-23T23:37:08.524Z,1600904228.524 [profile_station:G] Stopped
2020-09-23T23:37:08.524Z,1600904228.524 [profile_station:StartingMission] Running Loop=1
2020-09-23T23:37:08.524Z,1600904228.524 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2020-09-23T23:37:08.945Z,1600904228.945 [profile_station:NeedComms] Running Loop=1
2020-09-23T23:37:08.945Z,1600904228.945 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-09-23T23:37:08.946Z,1600904228.946 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-09-23T23:37:08.946Z,1600904228.946 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-23T23:37:08.946Z,1600904228.946 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-09-23T23:37:08.947Z,1600904228.947 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-09-23T23:37:08.947Z,1600904228.947 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-09-23T23:37:08.947Z,1600904228.947 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-23T23:37:08.948Z,1600904228.948 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-23T23:37:08.948Z,1600904228.948 [profile_station:NeedComms:A] Running Loop=1
2020-09-23T23:37:08.949Z,1600904228.949 [profile_station:NeedComms:A](INFO): last time_fix was: 1600903953.000000 second since 1970/01/01T00:00:00Z 
2020-09-23T23:37:08.949Z,1600904228.949 [profile_station:NeedComms:A] Stopped
2020-09-23T23:37:09.356Z,1600904229.356 [profile_station:NeedComms:C] Running Loop=1
2020-09-23T23:37:09.789Z,1600904229.789 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-09-23T23:37:11.335Z,1600904231.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233710.00,A,3648.40863,N,12148.51815,W,0.486,105.87,230920,,,D*70
2020-09-23T23:37:11.337Z,1600904231.337 [NAL9602](INFO): GPS fix at 20200923T233710: (36.806810, -121.808636)
2020-09-23T23:37:11.357Z,1600904231.357 [profile_station:NeedComms:C] Stopped
2020-09-23T23:37:11.358Z,1600904231.358 [profile_station:NeedComms:D] Running Loop=1
2020-09-23T23:37:11.376Z,1600904231.376 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2020-09-23T23:37:11.828Z,1600904231.828 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-23T23:37:20.942Z,1600904240.942 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200923T223012/Courier0046.lzma
2020-09-23T23:37:21.944Z,1600904241.944 [DataOverHttps](INFO): Moved sent file to Logs/20200923T223012/Courier0046.lzma.bak
2020-09-23T23:37:21.944Z,1600904241.944 [DataOverHttps](INFO): SBD MOMSN=12667245
2020-09-23T23:37:36.090Z,1600904256.090 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-09-23T23:37:36.476Z,1600904256.476 [CTD_Seabird](ERROR): Failed to parse device response: 0.21,15.30079,5308.43
2020-09-23T23:37:37.301Z,1600904257.301 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9702, MT Status=1, MTMSN=498
2020-09-23T23:37:37.302Z,1600904257.302 [NAL9602](INFO): Data available in MT queue
2020-09-23T23:37:37.788Z,1600904257.788 [NAL9602](INFO): Received command:restart logs