2020-02-05T15:14:53.359Z,1580915693.359 [Supervisor](DEBUG): Initializing supervisor.
2020-02-05T15:14:53.361Z,1580915693.361 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-02-05T15:14:53.362Z,1580915693.362 [SyncHandler](INFO): Protected caller Thread ID is 2579
2020-02-05T15:14:53.362Z,1580915693.362 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-02-05T15:14:53.363Z,1580915693.363 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-02-05T15:14:53.364Z,1580915693.364 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2580
2020-02-05T15:14:53.366Z,1580915693.366 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-02-05T15:14:53.379Z,1580915693.379 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-02-05T15:14:53.380Z,1580915693.380 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-02-05T15:14:53.380Z,1580915693.380 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2581
2020-02-05T15:14:53.381Z,1580915693.381 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-02-05T15:14:53.382Z,1580915693.382 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-02-05T15:14:53.382Z,1580915693.382 [logger ThreadHandler](INFO): Protected caller Thread ID is 2582
2020-02-05T15:14:53.384Z,1580915693.384 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-02-05T15:14:53.384Z,1580915693.384 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-02-05T15:14:53.386Z,1580915693.386 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-02-05T15:14:53.833Z,1580915693.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-02-05T15:14:53.834Z,1580915693.834 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-02-05T15:14:53.933Z,1580915693.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-02-05T15:14:53.933Z,1580915693.933 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-02-05T15:14:54.257Z,1580915694.257 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-02-05T15:14:54.257Z,1580915694.257 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-02-05T15:14:54.397Z,1580915694.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-02-05T15:14:54.398Z,1580915694.398 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-02-05T15:14:54.594Z,1580915694.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-02-05T15:14:54.594Z,1580915694.594 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-02-05T15:14:55.046Z,1580915695.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-02-05T15:14:55.047Z,1580915695.047 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-02-05T15:14:55.266Z,1580915695.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-02-05T15:14:55.266Z,1580915695.266 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-02-05T15:14:55.415Z,1580915695.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-02-05T15:14:55.416Z,1580915695.416 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-02-05T15:14:55.611Z,1580915695.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-02-05T15:14:55.612Z,1580915695.612 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-02-05T15:14:55.709Z,1580915695.709 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-02-05T15:14:55.710Z,1580915695.710 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-02-05T15:14:56.016Z,1580915696.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-02-05T15:14:56.017Z,1580915696.017 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-02-05T15:14:56.098Z,1580915696.098 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-02-05T15:14:56.201Z,1580915696.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-02-05T15:14:56.202Z,1580915696.202 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-02-05T15:14:56.857Z,1580915696.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-02-05T15:14:56.858Z,1580915696.858 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-02-05T15:14:57.282Z,1580915697.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-02-05T15:14:57.284Z,1580915697.284 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-02-05T15:14:57.285Z,1580915697.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-02-05T15:14:58.066Z,1580915698.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-02-05T15:14:58.228Z,1580915698.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-02-05T15:14:58.335Z,1580915698.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-02-05T15:14:58.563Z,1580915698.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-02-05T15:14:58.564Z,1580915698.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-02-05T15:14:58.649Z,1580915698.649 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-02-05T15:14:58.746Z,1580915698.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-02-05T15:14:58.845Z,1580915698.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-02-05T15:14:58.928Z,1580915698.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-02-05T15:14:59.038Z,1580915699.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-02-05T15:14:59.132Z,1580915699.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-02-05T15:14:59.321Z,1580915699.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-02-05T15:14:59.454Z,1580915699.454 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-02-05T15:14:59.459Z,1580915699.459 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-02-05T15:14:59.775Z,1580915699.776 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-02-05T15:14:59.781Z,1580915699.781 [AHRS_M2](INFO): created writer for : platform_orientation
2020-02-05T15:14:59.782Z,1580915699.782 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-02-05T15:14:59.787Z,1580915699.787 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-02-05T15:14:59.788Z,1580915699.788 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-02-05T15:14:59.793Z,1580915699.793 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-02-05T15:14:59.793Z,1580915699.793 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-02-05T15:14:59.798Z,1580915699.798 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-02-05T15:14:59.866Z,1580915699.866 [AHRS_M2] Loaded
2020-02-05T15:14:59.867Z,1580915699.867 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-02-05T15:14:59.945Z,1580915699.945 [DataOverHttps] Loaded
2020-02-05T15:14:59.946Z,1580915699.946 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-02-05T15:14:59.947Z,1580915699.947 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-02-05T15:14:59.947Z,1580915699.947 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2664
2020-02-05T15:14:59.961Z,1580915699.961 [Depth_Keller] Loaded
2020-02-05T15:14:59.961Z,1580915699.961 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-02-05T15:14:59.965Z,1580915699.965 [DropWeight] Loaded
2020-02-05T15:14:59.966Z,1580915699.966 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-02-05T15:15:00.025Z,1580915700.025 [DUSBL_Hydroid] Loaded
2020-02-05T15:15:00.026Z,1580915700.026 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-02-05T15:15:00.071Z,1580915700.071 [Micromodem] Loaded
2020-02-05T15:15:00.071Z,1580915700.071 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-02-05T15:15:00.165Z,1580915700.165 [NAL9602] Loaded
2020-02-05T15:15:00.165Z,1580915700.165 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-02-05T15:15:00.203Z,1580915700.203 [Onboard] Loaded
2020-02-05T15:15:00.203Z,1580915700.203 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-02-05T15:15:00.209Z,1580915700.209 [PowerOnly] Loaded
2020-02-05T15:15:00.209Z,1580915700.209 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-02-05T15:15:00.216Z,1580915700.216 [Radio_Surface] Loaded
2020-02-05T15:15:00.216Z,1580915700.216 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-02-05T15:15:00.217Z,1580915700.217 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-02-05T15:15:00.217Z,1580915700.217 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2665
2020-02-05T15:15:00.259Z,1580915700.259 [RDI_Pathfinder] Loaded
2020-02-05T15:15:00.259Z,1580915700.259 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-02-05T15:15:01.682Z,1580915701.682 [BPC1] Loaded
2020-02-05T15:15:01.683Z,1580915701.683 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-02-05T15:15:01.683Z,1580915701.683 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-02-05T15:15:01.684Z,1580915701.684 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-02-05T15:15:01.696Z,1580915701.696 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-02-05T15:15:01.697Z,1580915701.697 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-02-05T15:15:01.812Z,1580915701.812 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-02-05T15:15:01.813Z,1580915701.813 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-02-05T15:15:01.833Z,1580915701.833 [NavChart] Loaded
2020-02-05T15:15:01.833Z,1580915701.833 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-02-05T15:15:01.837Z,1580915701.837 [UniversalFixResidualReporter] Loaded
2020-02-05T15:15:01.837Z,1580915701.837 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-02-05T15:15:01.851Z,1580915701.851 [WorkSite] Loaded
2020-02-05T15:15:01.851Z,1580915701.851 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2020-02-05T15:15:01.852Z,1580915701.852 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-02-05T15:15:01.852Z,1580915701.852 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-02-05T15:15:01.957Z,1580915701.957 [BuoyancyServo] Loaded
2020-02-05T15:15:01.958Z,1580915701.958 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-02-05T15:15:01.973Z,1580915701.973 [ElevatorServo] Loaded
2020-02-05T15:15:01.973Z,1580915701.973 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-02-05T15:15:01.987Z,1580915701.987 [MassServo] Loaded
2020-02-05T15:15:01.987Z,1580915701.987 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-02-05T15:15:02.002Z,1580915702.002 [RudderServo] Loaded
2020-02-05T15:15:02.003Z,1580915702.003 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-02-05T15:15:02.017Z,1580915702.017 [ThrusterServo] Loaded
2020-02-05T15:15:02.017Z,1580915702.017 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-02-05T15:15:02.018Z,1580915702.018 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-02-05T15:15:02.018Z,1580915702.018 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-02-05T15:15:02.199Z,1580915702.199 [PAR_Licor] Loaded
2020-02-05T15:15:02.199Z,1580915702.199 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-02-05T15:15:02.242Z,1580915702.242 [WetLabsSeaOWL_UV_A] Loaded
2020-02-05T15:15:02.243Z,1580915702.243 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-02-05T15:15:02.244Z,1580915702.244 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408EF4E0
2020-02-05T15:15:02.244Z,1580915702.244 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 2666
2020-02-05T15:15:02.244Z,1580915702.244 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-02-05T15:15:02.245Z,1580915702.245 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-02-05T15:15:03.036Z,1580915703.036 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-02-05T15:15:03.037Z,1580915703.037 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-02-05T15:15:03.087Z,1580915703.087 [DepthRateCalculator] Loaded
2020-02-05T15:15:03.088Z,1580915703.088 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-02-05T15:15:03.093Z,1580915703.093 [PitchRateCalculator] Loaded
2020-02-05T15:15:03.093Z,1580915703.093 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-02-05T15:15:03.105Z,1580915703.105 [SpeedCalculator] Loaded
2020-02-05T15:15:03.106Z,1580915703.106 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-02-05T15:15:03.126Z,1580915703.126 [TempGradientCalculator] Loaded
2020-02-05T15:15:03.126Z,1580915703.126 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-02-05T15:15:03.132Z,1580915703.132 [YawRateCalculator] Loaded
2020-02-05T15:15:03.132Z,1580915703.132 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-02-05T15:15:03.175Z,1580915703.175 [ElevatorOffsetCalculator] Loaded
2020-02-05T15:15:03.176Z,1580915703.176 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-02-05T15:15:03.176Z,1580915703.176 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-02-05T15:15:03.176Z,1580915703.176 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-02-05T15:15:03.312Z,1580915703.312 [SBIT](DEBUG): Construct Startup Built In Test.
2020-02-05T15:15:03.333Z,1580915703.333 [SBIT] Loaded
2020-02-05T15:15:03.334Z,1580915703.334 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-02-05T15:15:03.334Z,1580915703.334 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-02-05T15:15:03.347Z,1580915703.347 [IBIT] Loaded
2020-02-05T15:15:03.347Z,1580915703.347 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-02-05T15:15:03.350Z,1580915703.350 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-02-05T15:15:03.486Z,1580915703.486 [CBIT] Loaded
2020-02-05T15:15:03.487Z,1580915703.487 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-02-05T15:15:03.487Z,1580915703.487 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-02-05T15:15:03.488Z,1580915703.488 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-02-05T15:15:03.567Z,1580915703.567 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-02-05T15:15:03.568Z,1580915703.568 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-02-05T15:15:03.663Z,1580915703.663 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-02-05T15:15:03.664Z,1580915703.664 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-02-05T15:15:03.710Z,1580915703.710 [VerticalControl](DEBUG): Construct VerticalControl.
2020-02-05T15:15:03.790Z,1580915703.790 [VerticalControl] Loaded
2020-02-05T15:15:03.790Z,1580915703.790 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-02-05T15:15:03.791Z,1580915703.791 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-02-05T15:15:03.859Z,1580915703.859 [HorizontalControl] Loaded
2020-02-05T15:15:03.859Z,1580915703.859 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-02-05T15:15:03.860Z,1580915703.860 [SpeedControl](DEBUG): Construct SpeedControl.
2020-02-05T15:15:03.862Z,1580915703.862 [SpeedControl] Loaded
2020-02-05T15:15:03.862Z,1580915703.862 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-02-05T15:15:03.863Z,1580915703.863 [LoopControl](DEBUG): Construct LoopControl.
2020-02-05T15:15:03.863Z,1580915703.863 [LoopControl] Loaded
2020-02-05T15:15:03.864Z,1580915703.864 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-02-05T15:15:03.864Z,1580915703.864 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-02-05T15:15:03.865Z,1580915703.865 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-02-05T15:15:03.896Z,1580915703.896 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-02-05T15:15:03.900Z,1580915703.900 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-02-05T15:15:03.901Z,1580915703.901 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-02-05T15:15:03.907Z,1580915703.907 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-02-05T15:15:03.908Z,1580915703.908 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACF4E0
2020-02-05T15:15:03.908Z,1580915703.908 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2667
2020-02-05T15:15:03.913Z,1580915703.913 [Supervisor](INFO): Main Thread ID is 815
2020-02-05T15:15:03.913Z,1580915703.913 [Supervisor](DEBUG): Running supervisor.
2020-02-05T15:15:03.914Z,1580915703.914 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2668
2020-02-05T15:15:03.916Z,1580915703.916 [controlThread ThreadHandler](INFO): Handler Thread ID is 2669
2020-02-05T15:15:03.917Z,1580915703.917 [controlThread](DEBUG): Initializing ControlThread
2020-02-05T15:15:03.924Z,1580915703.924 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-02-05T15:15:03.925Z,1580915703.925 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-02-05T15:15:03.925Z,1580915703.925 [WorkSite](DEBUG): Initializing WorkSite component.
2020-02-05T15:15:03.929Z,1580915703.929 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-02-05T15:15:03.929Z,1580915703.929 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-02-05T15:15:03.929Z,1580915703.929 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-02-05T15:15:03.930Z,1580915703.930 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-02-05T15:15:03.930Z,1580915703.930 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-02-05T15:15:03.930Z,1580915703.930 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-02-05T15:15:03.931Z,1580915703.931 [SBIT](INFO): Initialize SBIT Component.
2020-02-05T15:15:03.931Z,1580915703.931 [SBIT](IMPORTANT): git: 2020-02-04
2020-02-05T15:15:03.932Z,1580915703.932 [SBIT](INFO): git hash: 9b20438532c20a3476375e83a15ad70cdaf0bc6e
2020-02-05T15:15:03.932Z,1580915703.932 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-02-05T15:15:03.933Z,1580915703.933 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-02-05T15:15:03.934Z,1580915703.934 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2020-02-05T15:15:03.935Z,1580915703.935 [IBIT](INFO): Initialize IBIT Component.
2020-02-05T15:15:03.936Z,1580915703.936 [CBIT](DEBUG): Initialize CBIT Component.
2020-02-05T15:15:03.937Z,1580915703.937 [logger ThreadHandler](INFO): Handler Thread ID is 2670
2020-02-05T15:15:03.948Z,1580915703.948 [CBIT](DEBUG): Initialized mux pins.
2020-02-05T15:15:03.949Z,1580915703.949 [CBIT](DEBUG): Initializing the watchdog timer.
2020-02-05T15:15:03.957Z,1580915703.957 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2671
2020-02-05T15:15:03.958Z,1580915703.958 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-02-05T15:15:03.969Z,1580915703.969 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2672
2020-02-05T15:15:03.973Z,1580915703.973 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-02-05T15:15:03.973Z,1580915703.973 [CBIT](DEBUG): Initializing heartbeat.
2020-02-05T15:15:03.981Z,1580915703.981 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 2673
2020-02-05T15:15:03.982Z,1580915703.982 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-05T15:15:04.009Z,1580915704.009 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2674
2020-02-05T15:15:04.015Z,1580915704.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-02-05T15:15:04.015Z,1580915704.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-02-05T15:15:04.015Z,1580915704.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-02-05T15:15:04.016Z,1580915704.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-02-05T15:15:04.016Z,1580915704.016 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-02-05T15:15:04.016Z,1580915704.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-02-05T15:15:04.016Z,1580915704.016 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-02-05T15:15:04.016Z,1580915704.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-02-05T15:15:04.017Z,1580915704.017 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-02-05T15:15:04.017Z,1580915704.017 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-02-05T15:15:04.017Z,1580915704.017 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-02-05T15:15:04.017Z,1580915704.017 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-02-05T15:15:04.017Z,1580915704.017 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-02-05T15:15:04.018Z,1580915704.018 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-02-05T15:15:04.018Z,1580915704.018 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-02-05T15:15:04.018Z,1580915704.018 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-02-05T15:15:04.044Z,1580915704.044 [CBIT](DEBUG): Deactivating GF circuits.
2020-02-05T15:15:04.045Z,1580915704.045 [CBIT](DEBUG): Deactivating emergency mode.
2020-02-05T15:15:04.080Z,1580915704.080 [CBIT](DEBUG): Backplane powered.
2020-02-05T15:15:04.081Z,1580915704.081 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-02-05T15:15:04.082Z,1580915704.082 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-02-05T15:15:04.083Z,1580915704.083 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-02-05T15:15:04.084Z,1580915704.084 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-02-05T15:15:04.085Z,1580915704.085 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-02-05T15:15:04.094Z,1580915704.094 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-05T15:15:04.120Z,1580915704.120 [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-02-05T15:15:04.121Z,1580915704.121 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2020-02-05T15:15:04.206Z,1580915704.206 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2020-02-05T15:15:04.210Z,1580915704.210 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2020-02-05T15:15:04.224Z,1580915704.224 [MissionManager](INFO): DefineArg DefaultWithUndock.MicromodemCommsInterval = 2.000000 min
2020-02-05T15:15:04.226Z,1580915704.226 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2020-02-05T15:15:04.227Z,1580915704.227 [DefaultWithUndock:B.Undock](DEBUG): Construct.
2020-02-05T15:15:04.297Z,1580915704.297 [DefaultWithUndock:C.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-05T15:15:04.345Z,1580915704.345 [DefaultWithUndock:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-02-05T15:15:04.347Z,1580915704.347 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-02-05T15:15:04.366Z,1580915704.366 [DefaultWithUndock:F.Execute](DEBUG): Construct Execute.
2020-02-05T15:15:04.373Z,1580915704.373 [Radio_Surface](INFO): Powering up
2020-02-05T15:15:04.382Z,1580915704.382 [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="DefaultWithUndock">

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

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to reach the approach depth after detaching form the dock.
        The vehicle will switch over to midcourse guidance after the timeout is expired.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="MicromodemCommsInterval"><Description>
        How often to trigger Umodem communications
    </Description><Units:minute/><Value>2</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>30</Value></Setting>
    </Guidance:Wait>

    <!-- Leave the dock if necessary --> 
    <Guidance:Undock>
        <Sequence/>
        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>
    </Guidance:Undock>

    <!-- This mission assumes open water so from here on it's the same old default that's always used -->
    <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-02-05T15:15:04.387Z,1580915704.387 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-02-05T15:15:04.413Z,1580915704.413 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-05T15:15:04.446Z,1580915704.446 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2020-02-05T15:15:04.447Z,1580915704.447 [DUSBL_Hydroid](INFO): Powering up
2020-02-05T15:15:04.447Z,1580915704.447 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-02-05T15:15:04.630Z,1580915704.630 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-05T15:15:04.642Z,1580915704.642 [WorkSite](ERROR): Failed to open Data/workSite.cfg
2020-02-05T15:15:04.650Z,1580915704.650 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-05T15:15:04.657Z,1580915704.657 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-05T15:15:04.658Z,1580915704.658 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-05T15:15:04.665Z,1580915704.665 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-05T15:15:04.666Z,1580915704.666 [MassServo](DEBUG): Initializing EZServoServo.
2020-02-05T15:15:04.673Z,1580915704.673 [MassServo](DEBUG): Initializing MassServo.
2020-02-05T15:15:04.674Z,1580915704.674 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-05T15:15:04.681Z,1580915704.681 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-05T15:15:04.682Z,1580915704.682 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-02-05T15:15:04.689Z,1580915704.689 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-02-05T15:15:04.904Z,1580915704.904 [Micromodem](INFO): Powering up
2020-02-05T15:15:04.913Z,1580915704.913 [Micromodem](DEBUG): Initializing Micromodem.
2020-02-05T15:15:05.577Z,1580915705.577 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-02-05T15:15:05.577Z,1580915705.577 [RudderServo](FAULT): Rudder failed to initialize
2020-02-05T15:15:05.577Z,1580915705.577 [RudderServo] Communications Fault, FailCount= 1
2020-02-05T15:15:05.577Z,1580915705.577 [RudderServo](ERROR): Communications Fault
2020-02-05T15:15:05.688Z,1580915705.688 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-02-05T15:15:05.882Z,1580915705.882 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-05T15:15:05.882Z,1580915705.882 [RudderServo](INFO): Powering down
2020-02-05T15:15:06.563Z,1580915706.563 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-05T15:15:06.681Z,1580915706.681 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-05T15:15:06.685Z,1580915706.685 [CBIT](INFO): Clearing failed state for component RudderServo
2020-02-05T15:15:06.685Z,1580915706.685 [RudderServo] No Fault, FailCount= 1
2020-02-05T15:15:09.777Z,1580915709.777 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-02-05T15:15:10.179Z,1580915710.179 [Micromodem](INFO): Nmea in: $CATMG,2020-02-05T15:15:10.117963Z,RTC,RTC*5D

2020-02-05T15:15:10.180Z,1580915710.180 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-02-05T15:15:10.117963Z,RTC,RTC*5D

2020-02-05T15:15:10.583Z,1580915710.583 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-02-05T15:15:10.583Z,1580915710.583 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-02-05T15:15:10.975Z,1580915710.975 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-02-05T15:15:10.975Z,1580915710.975 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-02-05T15:15:11.391Z,1580915711.391 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-02-05T15:15:11.391Z,1580915711.391 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-02-05T15:15:11.792Z,1580915711.792 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-02-05T15:15:11.792Z,1580915711.792 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-02-05T15:15:12.199Z,1580915712.199 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-02-05T15:15:12.199Z,1580915712.199 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-02-05T15:15:12.591Z,1580915712.591 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-02-05T15:15:12.591Z,1580915712.591 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-02-05T15:15:13.008Z,1580915713.008 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-02-05T15:15:13.008Z,1580915713.008 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-02-05T15:15:13.387Z,1580915713.387 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-02-05T15:15:13.388Z,1580915713.388 [Micromodem](INFO): Nmea out: $CCCLK,2020,02,05,15,15,14*46

2020-02-05T15:15:13.799Z,1580915713.799 [Micromodem](INFO): Nmea in: $CACLK,2020,2,5,15,15,14*44

2020-02-05T15:15:13.801Z,1580915713.801 [Micromodem](INFO): Nmea in: $CATMS,0,2020-02-05T15:15:15Z*75

2020-02-05T15:15:13.802Z,1580915713.802 [Micromodem](INFO): Nmea in: $CATMG,2020-02-05T15:15:15.026608Z,USER_CMD,RTC*18

2020-02-05T15:15:18.243Z,1580915718.243 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-05T15:15:22.274Z,1580915722.274 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-02-05T15:15:27.567Z,1580915727.567 [SBIT](IMPORTANT): Beginning Startup BIT
2020-02-05T15:15:27.591Z,1580915727.591 [CBIT](IMPORTANT): Beginning ground fault scan
2020-02-05T15:15:30.471Z,1580915730.471 [NAL9602](INFO): Powering up NAL9602
2020-02-05T15:15:38.644Z,1580915738.644 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008324
CHAN A1 (24V): -0.028067
CHAN A2 (12V): -0.007247
CHAN A3 (5V): -0.002051
CHAN B0 (3.3V): 0.000381
CHAN B1 (3.15aV): 0.000236
CHAN B2 (3.15bV): 0.000166
CHAN B3 (GND): 0.002259
OPEN: 0.006433
Full Scale Calc: 4.765 mA, -1.589 mA
2020-02-05T15:15:41.375Z,1580915741.375 [NAL9602](INFO): NAL9602 initialized
2020-02-05T15:15:56.917Z,1580915756.917 [NAL9602](INFO): SBD MO Status=0, MOMSN=10401, MT Status=0, MTMSN=0
2020-02-05T15:15:56.917Z,1580915756.917 [NAL9602](INFO): No messages in MT queue
2020-02-05T15:16:05.072Z,1580915765.072 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-05T15:16:05.073Z,1580915765.073 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,  -101,  -183,
2020-02-05T15:16:20.908Z,1580915780.908 [SBIT](IMPORTANT): SBIT PASSED
2020-02-05T15:16:20.909Z,1580915780.909 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-02-05T15:16:20.909Z,1580915780.909 [SBIT](IMPORTANT): DDM.loadAtStartup=0 bool;

2020-02-05T15:16:20.909Z,1580915780.909 [SBIT](IMPORTANT): DUSBL_Hydroid.verbosity=3 enum;

2020-02-05T15:16:20.910Z,1580915780.910 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=366 cubic_centimeter;

2020-02-05T15:16:20.910Z,1580915780.910 [SBIT](IMPORTANT): VerticalControl.massDefault=0.77 millimeter;

2020-02-05T15:16:21.321Z,1580915781.321 [MissionManager](IMPORTANT): Started mission Startup
2020-02-05T15:16:21.321Z,1580915781.321 [Startup] Running Loop=1
2020-02-05T15:16:21.322Z,1580915781.322 [Startup](DEBUG): Aggregate::initialize Startup
2020-02-05T15:16:21.322Z,1580915781.322 [Startup:A.GoToSurface] Running Loop=1
2020-02-05T15:16:21.322Z,1580915781.322 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-05T15:16:21.322Z,1580915781.322 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-05T15:16:21.323Z,1580915781.323 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-05T15:16:21.323Z,1580915781.323 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-05T15:16:21.323Z,1580915781.323 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-05T15:16:21.324Z,1580915781.324 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-05T15:16:21.325Z,1580915781.325 [Startup:StartupSatComms] Running Loop=1
2020-02-05T15:16:21.325Z,1580915781.325 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-02-05T15:16:21.326Z,1580915781.326 [Startup:StartupSatComms:A] Running Loop=1
2020-02-05T15:16:21.733Z,1580915781.733 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-02-05T15:16:57.625Z,1580915817.625 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151656.00,A,4132.98696,N,07042.73304,W,0.253,0.00,050220,,,A*73
2020-02-05T15:16:57.628Z,1580915817.628 [NAL9602](INFO): GPS fix at 20200205T151656: (41.549783, -70.712217)
2020-02-05T15:16:57.672Z,1580915817.672 [Startup:StartupSatComms:A] Stopped
2020-02-05T15:16:57.672Z,1580915817.672 [Startup:StartupSatComms:B] Running Loop=1
2020-02-05T15:16:58.120Z,1580915818.120 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-05T15:17:29.951Z,1580915849.951 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-05T15:17:57.875Z,1580915877.875 [Startup:StartupSatComms:B](INFO): Timed out from 2020-02-05T15:16:57.7Z
2020-02-05T15:17:57.875Z,1580915877.875 [Startup:StartupSatComms:B] Stopped
2020-02-05T15:17:57.876Z,1580915877.876 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-02-05T15:17:57.876Z,1580915877.876 [Startup:StartupSatComms] Stopped
2020-02-05T15:17:57.876Z,1580915877.876 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-02-05T15:17:57.877Z,1580915877.877 [Startup](INFO): Completed Startup
2020-02-05T15:17:57.877Z,1580915877.877 [MissionManager](INFO): Startup is completed.
2020-02-05T15:17:57.877Z,1580915877.877 [MissionManager](INFO): Uninitializing Mission Startup
2020-02-05T15:17:57.877Z,1580915877.877 [Startup] Stopped
2020-02-05T15:17:57.877Z,1580915877.877 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-02-05T15:17:57.877Z,1580915877.877 [Startup:A.GoToSurface] Stopped
2020-02-05T15:17:57.877Z,1580915877.877 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-05T15:17:58.289Z,1580915878.289 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-02-05T15:17:58.289Z,1580915878.289 [DefaultWithUndock] Running Loop=1
2020-02-05T15:17:58.289Z,1580915878.289 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-02-05T15:17:58.289Z,1580915878.289 [DefaultWithUndock:C.GoToSurface] Running Loop=1
2020-02-05T15:17:58.289Z,1580915878.289 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-05T15:17:58.289Z,1580915878.289 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-05T15:17:58.290Z,1580915878.290 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-05T15:17:58.290Z,1580915878.290 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-05T15:17:58.290Z,1580915878.290 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-05T15:17:58.290Z,1580915878.290 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-05T15:17:58.291Z,1580915878.291 [DefaultWithUndock:A.Wait] Running Loop=1
2020-02-05T15:17:58.291Z,1580915878.291 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-02-05T15:18:28.595Z,1580915908.595 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-02-05T15:18:28.595Z,1580915908.595 [DefaultWithUndock:A.Wait] Stopped
2020-02-05T15:18:28.595Z,1580915908.595 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-05T15:18:28.595Z,1580915908.595 [DefaultWithUndock:B.Undock] Running Loop=1
2020-02-05T15:18:28.595Z,1580915908.595 [DefaultWithUndock:B.Undock](DEBUG): Initialize.
2020-02-05T15:18:29.374Z,1580915909.374 [PowerOnly](INFO): Powering up
2020-02-05T15:18:29.478Z,1580915909.478 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004304
2020-02-05T15:18:30.188Z,1580915910.188 [DefaultWithUndock:B.Undock](INFO): Detaching from dock.
2020-02-05T15:18:30.594Z,1580915910.594 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer.
2020-02-05T15:18:50.788Z,1580915930.788 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-02-05T15:18:51.207Z,1580915931.207 [DefaultWithUndock:B.Undock](INFO): DDM at standby.
2020-02-05T15:18:51.603Z,1580915931.603 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-02-05T15:18:51.603Z,1580915931.603 [DefaultWithUndock:B.Undock] Stopped
2020-02-05T15:18:52.015Z,1580915932.015 [DefaultWithUndock:CheckIn] Running Loop=1
2020-02-05T15:18:52.015Z,1580915932.015 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-05T15:18:52.015Z,1580915932.015 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-05T15:18:52.410Z,1580915932.410 [DefaultWithUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-02-05T15:18:53.992Z,1580915933.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151852.00,A,4132.98034,N,07042.73908,W,0.292,177.59,050220,,,A*71
2020-02-05T15:18:53.994Z,1580915933.994 [NAL9602](INFO): GPS fix at 20200205T151852: (41.549672, -70.712318)
2020-02-05T15:18:54.130Z,1580915934.130 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-05T15:18:54.130Z,1580915934.130 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-05T15:18:54.470Z,1580915934.470 [DefaultWithUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-05T15:19:01.240Z,1580915941.240 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200205T071023/Courier0085.lzma
2020-02-05T15:19:03.248Z,1580915943.248 [DataOverHttps](INFO): Moved sent file to Logs/20200205T071023/Courier0085.lzma.bak
2020-02-05T15:19:03.248Z,1580915943.248 [DataOverHttps](IMPORTANT): SBD MOMSN=12225911, MTMSN=20200205T151901
2020-02-05T15:19:11.013Z,1580915951.013 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 3 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 41.55716 degree;set profile_station.Lon -70.7082 degree" 50t7o 1 2
2020-02-05T15:19:11.102Z,1580915951.102 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 3 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 41.55716 degree;set profile_station.Lon -70.7082 degree" 50t7o 1 2.000000
2020-02-05T15:19:11.102Z,1580915951.102 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=50t7o
2020-02-05T15:19:11.103Z,1580915951.103 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='50t7o'): "load Science/profile_station.xml;set profile_station.MissionTimeout 3 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 41.55716 degree;set profile_station.Lon -70.7082 degree" ASAP
2020-02-05T15:19:13.023Z,1580915953.023 [DataOverHttps](IMPORTANT): SBD MTMSN=20200205T151911
2020-02-05T15:19:21.220Z,1580915961.220 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20200205T151453/Courier0000.lzma
2020-02-05T15:19:21.223Z,1580915961.223 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 6 meter;set profile_station.MaxDepth 10 meter;set profile_station.MinOffshore 500 meter;run " 50t7o 2 2
2020-02-05T15:19:21.306Z,1580915961.306 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 6 meter;set profile_station.MaxDepth 10 meter;set profile_station.MinOffshore 500 meter;run " 50t7o 2 2.000000
2020-02-05T15:19:21.306Z,1580915961.306 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=50t7o
2020-02-05T15:19:21.307Z,1580915961.307 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='50t7o'): "set profile_station.YoYoMaxDepth 7 meter;set profile_station.YoYoMinAltitude 6 meter;set profile_station.MaxDepth 10 meter;set profile_station.MinOffshore 500 meter;run " ASAP
2020-02-05T15:19:21.417Z,1580915961.417 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2020-02-05T15:19:21.417Z,1580915961.417 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2020-02-05T15:19:21.594Z,1580915961.594 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2020-02-05T15:19:21.597Z,1580915961.597 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2020-02-05T15:19:21.600Z,1580915961.600 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2020-02-05T15:19:21.603Z,1580915961.603 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2020-02-05T15:19:21.606Z,1580915961.606 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2020-02-05T15:19:21.609Z,1580915961.609 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2020-02-05T15:19:21.612Z,1580915961.612 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2020-02-05T15:19:21.615Z,1580915961.615 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2020-02-05T15:19:21.620Z,1580915961.620 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2020-02-05T15:19:21.639Z,1580915961.639 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2020-02-05T15:19:21.646Z,1580915961.646 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2020-02-05T15:19:21.657Z,1580915961.657 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2020-02-05T15:19:21.660Z,1580915961.660 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2020-02-05T15:19:21.667Z,1580915961.667 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2020-02-05T15:19:21.671Z,1580915961.671 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2020-02-05T15:19:21.682Z,1580915961.682 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2020-02-05T15:19:21.682Z,1580915961.682 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-02-05T15:19:22.071Z,1580915962.071 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2020-02-05T15:19:22.075Z,1580915962.075 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2020-02-05T15:19:22.078Z,1580915962.078 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2020-02-05T15:19:22.080Z,1580915962.080 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2020-02-05T15:19:22.083Z,1580915962.083 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2020-02-05T15:19:22.086Z,1580915962.086 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2020-02-05T15:19:22.089Z,1580915962.089 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2020-02-05T15:19:22.091Z,1580915962.091 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2020-02-05T15:19:22.094Z,1580915962.094 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2020-02-05T15:19:22.097Z,1580915962.097 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2020-02-05T15:19:22.100Z,1580915962.100 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2020-02-05T15:19:22.127Z,1580915962.127 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2020-02-05T15:19:22.141Z,1580915962.141 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2020-02-05T15:19:22.144Z,1580915962.144 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-02-05T15:19:22.151Z,1580915962.151 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2020-02-05T15:19:22.159Z,1580915962.159 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2020-02-05T15:19:22.181Z,1580915962.181 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 0.000000 bool
2020-02-05T15:19:22.189Z,1580915962.189 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2020-02-05T15:19:22.192Z,1580915962.192 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 0.000000 bool
2020-02-05T15:19:22.200Z,1580915962.200 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2020-02-05T15:19:22.204Z,1580915962.204 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-02-05T15:19:22.216Z,1580915962.216 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2020-02-05T15:19:22.233Z,1580915962.233 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2020-02-05T15:19:22.236Z,1580915962.236 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2020-02-05T15:19:22.243Z,1580915962.243 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2020-02-05T15:19:22.250Z,1580915962.250 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2020-02-05T15:19:22.318Z,1580915962.318 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2020-02-05T15:19:22.323Z,1580915962.323 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2020-02-05T15:19:22.338Z,1580915962.338 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2020-02-05T15:19:22.353Z,1580915962.353 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2020-02-05T15:19:22.356Z,1580915962.356 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2020-02-05T15:19:22.371Z,1580915962.371 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2020-02-05T15:19:22.390Z,1580915962.390 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2020-02-05T15:19:22.406Z,1580915962.406 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2020-02-05T15:19:22.424Z,1580915962.424 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2020-02-05T15:19:22.435Z,1580915962.435 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2020-02-05T15:19:22.446Z,1580915962.446 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2020-02-05T15:19:22.461Z,1580915962.461 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2020-02-05T15:19:22.464Z,1580915962.464 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2020-02-05T15:19:22.475Z,1580915962.475 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2020-02-05T15:19:22.482Z,1580915962.482 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2020-02-05T15:19:22.501Z,1580915962.501 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2020-02-05T15:19:22.514Z,1580915962.514 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2020-02-05T15:19:22.517Z,1580915962.517 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2020-02-05T15:19:22.520Z,1580915962.520 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2020-02-05T15:19:22.545Z,1580915962.545 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2020-02-05T15:19:22.548Z,1580915962.548 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2020-02-05T15:19:22.559Z,1580915962.559 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2020-02-05T15:19:22.571Z,1580915962.571 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2020-02-05T15:19:22.582Z,1580915962.582 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2020-02-05T15:19:22.593Z,1580915962.593 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2020-02-05T15:19:22.646Z,1580915962.646 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-02-05T15:19:22.716Z,1580915962.716 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-02-05T15:19:22.781Z,1580915962.781 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-02-05T15:19:22.807Z,1580915962.807 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-02-05T15:19:22.850Z,1580915962.850 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-02-05T15:19:22.880Z,1580915962.880 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2020-02-05T15:19:22.894Z,1580915962.894 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2020-02-05T15:19:22.895Z,1580915962.895 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-02-05T15:19:22.989Z,1580915962.989 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2020-02-05T15:19:22.992Z,1580915962.992 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2020-02-05T15:19:23.004Z,1580915963.004 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-02-05T15:19:23.015Z,1580915963.015 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2020-02-05T15:19:23.031Z,1580915963.031 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-02-05T15:19:23.053Z,1580915963.053 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2020-02-05T15:19:23.059Z,1580915963.059 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2020-02-05T15:19:23.125Z,1580915963.125 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-05T15:19:23.171Z,1580915963.171 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-02-05T15:19:23.207Z,1580915963.207 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2020-02-05T15:19:23.210Z,1580915963.210 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2020-02-05T15:19:23.221Z,1580915963.221 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-02-05T15:19:23.222Z,1580915963.222 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-02-05T15:19:23.226Z,1580915963.226 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Courier0000.lzma.bak
2020-02-05T15:19:23.226Z,1580915963.226 [DataOverHttps](INFO): SBD MOMSN=12225914
2020-02-05T15:19:23.240Z,1580915963.240 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-02-05T15:19:23.257Z,1580915963.257 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-02-05T15:19:23.282Z,1580915963.282 [profile_station:I.Pitch](DEBUG): Construct.
2020-02-05T15:19:23.299Z,1580915963.299 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-02-05T15:19:23.313Z,1580915963.313 [profile_station:K.SetSpeed](DEBUG): Construct.
2020-02-05T15:19:23.316Z,1580915963.316 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-02-05T15:19:23.351Z,1580915963.351 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-02-05T15:19:23.381Z,1580915963.381 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2020-02-05T15:19:23.399Z,1580915963.399 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2020-02-05T15:19:23.514Z,1580915963.514 [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="SampleISUS"><True/></RedefineArg>
        <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-02-05T15:19:23.514Z,1580915963.514 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2020-02-05T15:19:26.329Z,1580915966.329 [NAL9602](INFO): SBD MO Status=0, MOMSN=10402, MT Status=0, MTMSN=0
2020-02-05T15:19:26.329Z,1580915966.329 [NAL9602](INFO): No messages in MT queue
2020-02-05T15:19:37.864Z,1580915977.864 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 3.000000 hour
2020-02-05T15:19:37.865Z,1580915977.865 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 20.000000 minute
2020-02-05T15:19:37.866Z,1580915977.866 [CommandLine](IMPORTANT): got command set profile_station.Lat 41.557159 degree
2020-02-05T15:19:37.867Z,1580915977.867 [CommandLine](IMPORTANT): got command set profile_station.Lon -70.708199 degree
2020-02-05T15:19:50.294Z,1580915990.294 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 7.000000 meter
2020-02-05T15:19:50.295Z,1580915990.295 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinAltitude 6.000000 meter
2020-02-05T15:19:50.295Z,1580915990.295 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 10.000000 meter
2020-02-05T15:19:50.296Z,1580915990.296 [CommandLine](IMPORTANT): got command set profile_station.MinOffshore 500.000000 meter
2020-02-05T15:19:50.297Z,1580915990.297 [CommandLine](IMPORTANT): got command run 
2020-02-05T15:19:50.300Z,1580915990.300 [CommandLine](IMPORTANT): Running 
2020-02-05T15:19:50.540Z,1580915990.540 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200205T151453/Courier0004.lzma
2020-02-05T15:19:50.607Z,1580915990.607 [DefaultWithUndock] Stopped
2020-02-05T15:19:50.607Z,1580915990.607 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-02-05T15:19:50.607Z,1580915990.607 [DefaultWithUndock:C.GoToSurface] Stopped
2020-02-05T15:19:50.608Z,1580915990.608 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-05T15:19:50.608Z,1580915990.608 [DefaultWithUndock:CheckIn] Stopped
2020-02-05T15:19:50.608Z,1580915990.608 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-05T15:19:50.608Z,1580915990.608 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-05T15:19:50.608Z,1580915990.608 [MissionManager](IMPORTANT): Started mission profile_station
2020-02-05T15:19:50.608Z,1580915990.608 [profile_station] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station](DEBUG): Aggregate::initialize profile_station
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:A] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:B] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:C] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:D] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:E] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:F] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:G] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:H] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:Read_Oil] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-02-05T15:19:50.613Z,1580915990.613 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2020-02-05T15:19:50.614Z,1580915990.614 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-02-05T15:19:50.614Z,1580915990.614 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-02-05T15:19:50.615Z,1580915990.615 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2020-02-05T15:19:50.616Z,1580915990.616 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2020-02-05T15:19:50.616Z,1580915990.616 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-02-05T15:19:50.616Z,1580915990.616 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-02-05T15:19:50.616Z,1580915990.616 [profile_station:Science:PeakDetectNO3] Running Loop=1
2020-02-05T15:19:50.621Z,1580915990.621 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2020-02-05T15:19:50.621Z,1580915990.621 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-02-05T15:19:50.621Z,1580915990.621 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-02-05T15:19:50.623Z,1580915990.623 [profile_station:Science:PeakDetectOil] Running Loop=1
2020-02-05T15:19:50.623Z,1580915990.623 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2020-02-05T15:19:50.623Z,1580915990.623 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-02-05T15:19:50.623Z,1580915990.623 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-02-05T15:19:50.629Z,1580915990.629 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2020-02-05T15:19:50.629Z,1580915990.629 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2020-02-05T15:19:50.629Z,1580915990.629 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-02-05T15:19:50.630Z,1580915990.630 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-02-05T15:19:50.631Z,1580915990.631 [profile_station:StandardEnvelopes] Running Loop=1
2020-02-05T15:19:50.631Z,1580915990.631 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2020-02-05T15:19:50.631Z,1580915990.631 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-02-05T15:19:50.631Z,1580915990.631 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-02-05T15:19:50.631Z,1580915990.631 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-02-05T15:19:50.632Z,1580915990.632 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-02-05T15:19:50.632Z,1580915990.632 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-02-05T15:19:50.632Z,1580915990.632 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-02-05T15:19:50.632Z,1580915990.632 [profile_station:I.Pitch] Running Loop=1
2020-02-05T15:19:50.632Z,1580915990.632 [profile_station:I.Pitch](DEBUG): Initialize.
2020-02-05T15:19:50.636Z,1580915990.636 [profile_station:J.Buoyancy] Running Loop=1
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:K.SetSpeed] Running Loop=1
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:K.SetSpeed](DEBUG): Initialize.
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:L.DepthEnvelope] Running Loop=1
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:N.YoYo] Running Loop=1
2020-02-05T15:19:50.637Z,1580915990.637 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2020-02-05T15:19:50.638Z,1580915990.638 [profile_station:C] Running Loop=1
2020-02-05T15:19:50.638Z,1580915990.638 [profile_station:N.YoYo] Running Loop=1
2020-02-05T15:19:50.639Z,1580915990.639 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-02-05T15:19:50.639Z,1580915990.639 [profile_station:L.DepthEnvelope] Running Loop=1
2020-02-05T15:19:50.645Z,1580915990.645 [profile_station:K.SetSpeed] Running Loop=1
2020-02-05T15:19:50.645Z,1580915990.645 [profile_station:J.Buoyancy] Running Loop=1
2020-02-05T15:19:50.646Z,1580915990.646 [profile_station:I.Pitch] Running Loop=1
2020-02-05T15:19:50.646Z,1580915990.646 [profile_station:StandardEnvelopes] Running Loop=1
2020-02-05T15:19:50.646Z,1580915990.646 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-02-05T15:19:50.646Z,1580915990.646 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-02-05T15:19:50.647Z,1580915990.647 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-02-05T15:19:50.647Z,1580915990.647 [profile_station:C] Stopped
2020-02-05T15:19:50.647Z,1580915990.647 [profile_station:E] Running Loop=1
2020-02-05T15:19:50.647Z,1580915990.647 [profile_station:Science] Running Loop=1
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectFDOM] Stopped
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectOil] Stopped
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-02-05T15:19:50.653Z,1580915990.653 [profile_station:Science:PeakDetectNO3] Stopped
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:HighestChlPeakReport] Stopped
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-02-05T15:19:50.654Z,1580915990.654 [profile_station:Science:Read_Oil] Running Loop=1
2020-02-05T15:19:50.655Z,1580915990.655 [profile_station:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T15:19:50.655Z,1580915990.655 [profile_station:Science:H] Stopped
2020-02-05T15:19:50.655Z,1580915990.655 [profile_station:Science:G] Running Loop=1
2020-02-05T15:19:50.656Z,1580915990.656 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T15:19:50.656Z,1580915990.656 [profile_station:Science:F] Stopped
2020-02-05T15:19:50.656Z,1580915990.656 [profile_station:Science:E] Stopped
2020-02-05T15:19:50.656Z,1580915990.656 [profile_station:Science:D] Stopped
2020-02-05T15:19:50.656Z,1580915990.656 [profile_station:Science:C] Stopped
2020-02-05T15:19:50.656Z,1580915990.656 [profile_station:Science:B] Stopped
2020-02-05T15:19:50.656Z,1580915990.656 [profile_station:Science:A] Stopped
2020-02-05T15:19:50.857Z,1580915990.857 [WetLabsSeaOWL_UV_A](INFO): Powering up
2020-02-05T15:19:50.990Z,1580915990.990 [profile_station:E] Stopped
2020-02-05T15:19:50.990Z,1580915990.990 [profile_station:F] Running Loop=1
2020-02-05T15:19:51.410Z,1580915991.410 [profile_station:F] Stopped
2020-02-05T15:19:51.410Z,1580915991.410 [profile_station:G] Running Loop=1
2020-02-05T15:19:51.457Z,1580915991.457 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000

2020-02-05T15:19:51.457Z,1580915991.457 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2020-02-05T15:19:51.457Z,1580915991.457 [BuoyancyServo] Hardware Fault, FailCount= 1
2020-02-05T15:19:51.457Z,1580915991.457 [BuoyancyServo](ERROR): Hardware Fault
2020-02-05T15:19:51.573Z,1580915991.573 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2020-02-05T15:19:51.811Z,1580915991.811 [profile_station:G] Stopped
2020-02-05T15:19:51.811Z,1580915991.811 [profile_station:StartingMission] Running Loop=1
2020-02-05T15:19:51.811Z,1580915991.811 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2020-02-05T15:19:52.219Z,1580915992.219 [profile_station:NeedComms] Running Loop=1
2020-02-05T15:19:52.219Z,1580915992.219 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-02-05T15:19:52.219Z,1580915992.219 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-02-05T15:19:52.219Z,1580915992.219 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-05T15:19:52.219Z,1580915992.219 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-02-05T15:19:52.220Z,1580915992.220 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-02-05T15:19:52.220Z,1580915992.220 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-02-05T15:19:52.221Z,1580915992.221 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-05T15:19:52.221Z,1580915992.221 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-05T15:19:52.221Z,1580915992.221 [profile_station:NeedComms:A] Running Loop=1
2020-02-05T15:19:52.222Z,1580915992.222 [profile_station:NeedComms:A](INFO): last time_fix was: 1580915932.000000 second since 1970/01/01T00:00:00Z 
2020-02-05T15:19:52.222Z,1580915992.222 [profile_station:NeedComms:A] Stopped
2020-02-05T15:19:52.546Z,1580915992.546 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Courier0004.lzma.bak
2020-02-05T15:19:52.546Z,1580915992.546 [DataOverHttps](INFO): SBD MOMSN=12225919
2020-02-05T15:19:52.628Z,1580915992.628 [profile_station:NeedComms:C] Running Loop=1
2020-02-05T15:19:53.226Z,1580915993.226 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-02-05T15:19:53.435Z,1580915993.435 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-05T15:19:53.435Z,1580915993.435 [BuoyancyServo](INFO): Powering down
2020-02-05T15:19:54.297Z,1580915994.297 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-05T15:19:54.297Z,1580915994.297 [BuoyancyServo] No Fault, FailCount= 1
2020-02-05T15:19:54.607Z,1580915994.607 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151953.00,A,4132.97766,N,07042.74113,W,0.194,131.66,050220,,,A*70
2020-02-05T15:19:54.609Z,1580915994.609 [NAL9602](INFO): GPS fix at 20200205T151953: (41.549628, -70.712352)
2020-02-05T15:19:54.637Z,1580915994.637 [profile_station:NeedComms:C] Stopped
2020-02-05T15:19:54.637Z,1580915994.637 [profile_station:NeedComms:D] Running Loop=1
2020-02-05T15:19:54.643Z,1580915994.643 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-05T15:19:54.644Z,1580915994.644 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-05T15:19:55.089Z,1580915995.089 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-05T15:20:00.708Z,1580916000.708 [PowerOnly](INFO): Powering down
2020-02-05T15:20:11.428Z,1580916011.428 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200205T151453/Courier0007.lzma
2020-02-05T15:20:13.442Z,1580916013.442 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Courier0007.lzma.bak
2020-02-05T15:20:13.442Z,1580916013.442 [DataOverHttps](INFO): SBD MOMSN=12225922
2020-02-05T15:20:27.350Z,1580916027.350 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-05T15:20:31.740Z,1580916031.740 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200205T151453/Courier0010.lzma
2020-02-05T15:20:33.746Z,1580916033.746 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Courier0010.lzma.bak
2020-02-05T15:20:33.746Z,1580916033.746 [DataOverHttps](INFO): SBD MOMSN=12225924
2020-02-05T15:20:52.076Z,1580916052.076 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200205T151453/Courier0013.lzma
2020-02-05T15:20:54.082Z,1580916054.082 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Courier0013.lzma.bak
2020-02-05T15:20:54.082Z,1580916054.082 [DataOverHttps](INFO): SBD MOMSN=12225926
2020-02-05T15:21:12.136Z,1580916072.136 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20200205T071023/Express0086.lzma
2020-02-05T15:21:14.143Z,1580916074.143 [DataOverHttps](INFO): Moved sent file to Logs/20200205T071023/Express0086.lzma.bak
2020-02-05T15:21:14.143Z,1580916074.143 [DataOverHttps](INFO): SBD MOMSN=12225930
2020-02-05T15:21:31.817Z,1580916091.817 [DataOverHttps](INFO): Sending 760 bytes from file Logs/20200205T151453/Express0001.lzma
2020-02-05T15:21:33.822Z,1580916093.822 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Express0001.lzma.bak
2020-02-05T15:21:33.822Z,1580916093.822 [DataOverHttps](INFO): SBD MOMSN=12225934
2020-02-05T15:21:51.801Z,1580916111.801 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20200205T151453/Express0005.lzma
2020-02-05T15:21:53.477Z,1580916113.477 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:21:53.477Z,1580916113.477 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020508350700,35.0, -00,1448.9,  0
2020-02-05T15:21:53.806Z,1580916113.806 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Express0005.lzma.bak
2020-02-05T15:21:53.806Z,1580916113.806 [DataOverHttps](INFO): SBD MOMSN=12225951
2020-02-05T15:22:11.729Z,1580916131.729 [DataOverHttps](INFO): Sending 353 bytes from file Logs/20200205T151453/Express0008.lzma
2020-02-05T15:22:13.734Z,1580916133.734 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Express0008.lzma.bak
2020-02-05T15:22:13.734Z,1580916133.734 [DataOverHttps](INFO): SBD MOMSN=12225955
2020-02-05T15:22:28.583Z,1580916148.583 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-05T15:22:28.591Z,1580916148.591 [BPC1](INFO): Received data from all battery sticks.
2020-02-05T15:22:31.660Z,1580916151.660 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20200205T151453/Express0011.lzma
2020-02-05T15:22:33.666Z,1580916153.666 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Express0011.lzma.bak
2020-02-05T15:22:33.666Z,1580916153.666 [DataOverHttps](INFO): SBD MOMSN=12225961
2020-02-05T15:22:51.305Z,1580916171.305 [DataOverHttps](INFO): Sending 553 bytes from file Logs/20200205T151453/Express0014.lzma
2020-02-05T15:22:53.310Z,1580916173.310 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Express0014.lzma.bak
2020-02-05T15:22:53.310Z,1580916173.310 [DataOverHttps](INFO): SBD MOMSN=12225968
2020-02-05T15:22:54.466Z,1580916174.466 [profile_station:NeedComms:D] Stopped
2020-02-05T15:22:54.467Z,1580916174.467 [profile_station:NeedComms:E] Running Loop=1
2020-02-05T15:22:54.870Z,1580916174.870 [profile_station:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-02-05T15:22:56.448Z,1580916176.448 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152255.00,A,4132.96995,N,07042.75025,W,0.214,244.39,050220,,,A*78
2020-02-05T15:22:56.454Z,1580916176.454 [NAL9602](INFO): GPS fix at 20200205T152255: (41.549499, -70.712504)
2020-02-05T15:22:56.527Z,1580916176.527 [profile_station:NeedComms:E] Stopped
2020-02-05T15:22:56.528Z,1580916176.528 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2020-02-05T15:22:56.528Z,1580916176.528 [profile_station:NeedComms] Stopped
2020-02-05T15:22:56.528Z,1580916176.528 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2020-02-05T15:22:56.528Z,1580916176.528 [profile_station:NeedComms:B.GoToSurface] Stopped
2020-02-05T15:22:56.532Z,1580916176.532 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-05T15:22:56.914Z,1580916176.914 [profile_station:StartingMission](INFO): Completed profile_station:StartingMission
2020-02-05T15:22:56.914Z,1580916176.914 [profile_station:StartingMission] Stopped
2020-02-05T15:22:56.914Z,1580916176.914 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2020-02-05T15:22:56.914Z,1580916176.914 [profile_station:CircleWrapper] Running Loop=1
2020-02-05T15:22:56.914Z,1580916176.914 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper
2020-02-05T15:22:56.914Z,1580916176.914 [profile_station:CircleWrapper:A] Running Loop=1
2020-02-05T15:22:56.914Z,1580916176.914 [profile_station:CircleWrapper:B.Circle] Running Loop=1
2020-02-05T15:22:56.914Z,1580916176.914 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2020-02-05T15:22:57.326Z,1580916177.326 [profile_station:CircleWrapper:A] Running Loop=1
2020-02-05T15:23:11.418Z,1580916191.418 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:23:11.419Z,1580916191.419 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020508362500,35.0, -0.1,   0.0,1448.R,  0
2020-02-05T15:23:13.430Z,1580916193.430 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-05T15:23:13.431Z,1580916193.431 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.15, 129.19, 130.52, 149.82
2020-02-05T15:23:31.307Z,1580916211.307 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 0.77 mm (1 active estimators).
2020-02-05T15:23:37.330Z,1580916217.330 [NAL9602](INFO): SBD MO Status=2, MOMSN=10403, MT Status=2, MTMSN=0
2020-02-05T15:23:37.330Z,1580916217.330 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-05T15:23:45.101Z,1580916225.101 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-05T15:23:45.102Z,1580916225.102 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,      -43.60,       -4.42,.00
2020-02-05T15:23:47.878Z,1580916227.878 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 0.77 mm (2 active estimators).
2020-02-05T15:23:59.982Z,1580916239.982 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:24:03.999Z,1580916243.999 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.240749
2020-02-05T15:24:31.629Z,1580916271.629 [Radio_Surface](INFO): Powering down
2020-02-05T15:24:35.131Z,1580916275.131 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:24:36.133Z,1580916276.133 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-02-05T15:25:24.042Z,1580916324.042 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:25:24.043Z,1580916324.043 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020508383800,35.0, -0.1, 48.9,  0
2020-02-05T15:25:26.056Z,1580916326.056 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-05T15:25:26.056Z,1580916326.056 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -81,  +840,  :BE,   -81,  +840,    +2,A
2020-02-05T15:27:26.036Z,1580916446.036 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-05T15:27:26.037Z,1580916446.037 [RDI_Pathfinder](ERROR): Failed to parse:
:BD55.00,      +167.49,       -3.96,   8.26,  0.00
2020-02-05T15:27:59.122Z,1580916479.122 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-05T15:28:04.398Z,1580916484.398 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 113.89, 112.89,  97.42, 138.34
2020-02-05T15:28:06.434Z,1580916486.434 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   -83,  +788,    -2,    +0,A
2020-02-05T15:28:29.830Z,1580916509.830 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-05T15:28:49.258Z,1580916529.258 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-05T15:30:48.422Z,1580916648.422 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:30:48.422Z,1580916648.422 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020508440200,35.0, -0.1,   0.0,1448.RA,  0.00, 121.87, 117.88, 145.33, 103.91
2020-02-05T15:31:09.116Z,1580916669.116 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-02-05T15:31:09.117Z,1580916669.117 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-05T15:31:09.117Z,1580916669.117 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-05T15:31:09.117Z,1580916669.117 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-05T15:31:09.173Z,1580916669.173 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-05T15:31:09.501Z,1580916669.501 [RDI_Pathfinder](INFO): Powering down
2020-02-05T15:31:10.310Z,1580916670.310 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-05T15:31:10.310Z,1580916670.310 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-05T15:31:23.561Z,1580916683.561 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-05T15:31:32.858Z,1580916692.858 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:31:32.858Z,1580916692.858 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,2002050844464-0.1,   0.0,1448.9,  0
2020-02-05T15:31:34.511Z,1580916694.511 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-05T15:31:34.511Z,1580916694.511 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.95,  83.28, 100.25,  71.63
2020-02-05T15:32:56.519Z,1580916776.519 [RDI_Pathfinder](ERROR): Failed to parse:
00, 118.88, 116.34, 102.41
2020-02-05T15:33:34.866Z,1580916814.866 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-05T15:33:34.866Z,1580916814.866 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  9.97,  68.31,  72.63,  66.31
2020-02-05T15:34:35.867Z,1580916875.867 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:34:35.867Z,1580916875.867 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020508474947,35.0, -0.1,   0.0,1448.9,,  0.00,  71.97,  70.30,  71.63,  69.97
2020-02-05T15:36:18.893Z,1580916978.893 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:36:18.893Z,1580916978.893 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020508493247,35.0, -.0,1448.9A,  0.00, 112.89, 110.40,  94.92, 137.35
2020-02-05T15:36:37.514Z,1580916997.514 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:36:37.514Z,1580916997.514 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020508495147,35.0, -0.1, 48.9,  0
2020-02-05T15:37:36.865Z,1580917056.865 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  78.62,  75.29,  66.64,  93.59
2020-02-05T15:37:54.637Z,1580917074.637 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  89.93,  90.93, 108.90,  78.95
2020-02-05T15:38:20.897Z,1580917100.897 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:38:20.897Z,1580917100.897 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020508513447,35.0, 0.0,1448.9,  0
2020-02-05T15:39:08.551Z,1580917148.551 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-05T15:39:08.551Z,1580917148.551 [Micromodem](INFO): Have incoming data
2020-02-05T15:39:08.553Z,1580917148.553 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,1*20

2020-02-05T15:39:08.956Z,1580917148.956 [Micromodem](INFO): Nmea in: $CACST,6,1,20200205153907.698751,06,589,27,0280,0150,89,02,02,00,04,1,010,001,3,3,1,1,150,14.9,7.00,00,-4.00,-01,-1.09,34,10000,4000*45

2020-02-05T15:40:14.411Z,1580917214.411 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2020-02-05T15:40:14.808Z,1580917214.808 [Micromodem](INFO): Nmea in: $CACST,6,1,20200205154013.812491,06,143,15,0230,0150,119,01,01,01,03,1,-01,-01,2,3,1,0,150,16.3,2.00,00,1.00,-01,-1.47,39,10000,4000*59

2020-02-05T15:40:22.533Z,1580917222.533 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-05T15:40:22.533Z,1580917222.533 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 121.87, 126.53, 121.21
2020-02-05T15:41:40.519Z,1580917300.519 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-05T15:41:40.519Z,1580917300.519 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,  -108,  -13,A
2020-02-05T15:43:02.097Z,1580917382.097 [profile_station:NeedComms] Running Loop=1
2020-02-05T15:43:02.097Z,1580917382.097 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-02-05T15:43:02.097Z,1580917382.097 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-02-05T15:43:02.097Z,1580917382.097 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-05T15:43:02.098Z,1580917382.098 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-02-05T15:43:02.098Z,1580917382.098 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-02-05T15:43:02.098Z,1580917382.098 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-02-05T15:43:02.099Z,1580917382.099 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-05T15:43:02.099Z,1580917382.099 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-05T15:43:02.099Z,1580917382.099 [profile_station:NeedComms:A] Running Loop=1
2020-02-05T15:43:02.101Z,1580917382.101 [profile_station:NeedComms:A](INFO): last time_fix was: 1580916175.000000 second since 1970/01/01T00:00:00Z 
2020-02-05T15:43:02.101Z,1580917382.101 [profile_station:NeedComms:A] Stopped
2020-02-05T15:43:35.941Z,1580917415.941 [profile_station:NeedComms:C] Running Loop=1
2020-02-05T15:43:36.569Z,1580917416.569 [Radio_Surface](INFO): Powering up
2020-02-05T15:43:41.857Z,1580917421.857 [DataOverHttps](INFO): Radio surface powered ON.
2020-02-05T15:44:08.814Z,1580917448.814 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-05T15:44:08.892Z,1580917448.892 [NAL9602](FAULT): received: 
+CSQ:0

OK403, 2, 0, 0, 0

OK
2020-02-05T15:44:08.893Z,1580917448.893 [NAL9602] Data Fault, FailCount= 1
2020-02-05T15:44:08.893Z,1580917448.893 [NAL9602](ERROR): Data Fault
2020-02-05T15:44:09.010Z,1580917449.010 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-05T15:44:09.216Z,1580917449.216 [NAL9602](INFO): Powering down
2020-02-05T15:44:10.118Z,1580917450.118 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-05T15:44:10.118Z,1580917450.118 [NAL9602] No Fault, FailCount= 1
2020-02-05T15:44:11.977Z,1580917451.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:44:14.905Z,1580917454.905 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,  -103,   +88,   +29,A
2020-02-05T15:44:27.002Z,1580917467.002 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2020-02-05T15:44:27.006Z,1580917467.006 [Micromodem](INFO): Nmea in: $CACST,6,1,20200205154426.237504,06,194,10,0148,0150,119,02,02,02,02,1,-01,-01,2,3,1,0,150,-28.6,1.00,00,2.00,-01,4.43,51,10000,4000*5D

2020-02-05T15:44:35.531Z,1580917475.531 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-05T15:44:35.531Z,1580917475.531 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +5,  -264,A
2020-02-05T15:44:39.533Z,1580917479.533 [NAL9602](INFO): Powering up NAL9602
2020-02-05T15:44:47.129Z,1580917487.129 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:44:50.431Z,1580917490.431 [NAL9602](INFO): NAL9602 initialized
2020-02-05T15:45:22.269Z,1580917522.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:45:28.409Z,1580917528.409 [NAL9602](INFO): SBD MO Status=0, MOMSN=10403, MT Status=0, MTMSN=0
2020-02-05T15:45:28.409Z,1580917528.409 [NAL9602](INFO): No messages in MT queue
2020-02-05T15:45:32.047Z,1580917532.047 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2020-02-05T15:45:32.051Z,1580917532.051 [Micromodem](INFO): Nmea in: $CACST,6,1,20200205154532.413057,06,668,16,0181,0150,149,02,02,02,04,4,-01,-01,1,3,3,0,150,13.0,0.00,00,3.00,-01,8.54,41,10000,4000*72

2020-02-05T15:45:50.628Z,1580917550.628 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154549.00,A,4133.45013,N,07042.70969,W,0.292,0.00,050220,,,A*7E
2020-02-05T15:45:50.630Z,1580917550.630 [NAL9602](INFO): GPS fix at 20200205T154549: (41.557502, -70.711828)
2020-02-05T15:45:50.683Z,1580917550.683 [UniversalFixResidualReporter](INFO): Fix residual: 1.1 %DT, over the last 1006.6 m. Residual distance 11.1 m at bearing -98.6 degrees. Fix at (41.5575, -70.7118) with 891.7 m made good.
2020-02-05T15:45:50.688Z,1580917550.688 [profile_station:NeedComms:C] Stopped
2020-02-05T15:45:50.689Z,1580917550.689 [profile_station:NeedComms:D] Running Loop=1
2020-02-05T15:45:57.421Z,1580917557.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:46:23.350Z,1580917583.350 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-05T15:46:32.561Z,1580917592.561 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:47:07.636Z,1580917627.636 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002762
2020-02-05T15:47:25.712Z,1580917645.712 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20200205T151453/Courier0016.lzma
2020-02-05T15:47:27.718Z,1580917647.718 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Courier0016.lzma.bak
2020-02-05T15:47:27.718Z,1580917647.718 [DataOverHttps](INFO): SBD MOMSN=12226113
2020-02-05T15:47:39.734Z,1580917659.734 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-02-05T15:47:39.735Z,1580917659.735 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00, 147.16   -5.19,  13.72,  0.00
2020-02-05T15:47:49.381Z,1580917669.381 [DataOverHttps](INFO): Sending 479 bytes from file Logs/20200205T151453/Express0017.lzma
2020-02-05T15:47:51.386Z,1580917671.386 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Express0017.lzma.bak
2020-02-05T15:47:51.387Z,1580917671.387 [DataOverHttps](INFO): SBD MOMSN=12226123
2020-02-05T15:47:52.691Z,1580917672.691 [profile_station:NeedComms:D] Stopped
2020-02-05T15:47:52.691Z,1580917672.691 [profile_station:NeedComms:E] Running Loop=1
2020-02-05T15:47:54.669Z,1580917674.669 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154753.00,A,4133.44811,N,07042.71351,W,0.097,245.10,050220,,,A*79
2020-02-05T15:47:54.671Z,1580917674.671 [NAL9602](INFO): GPS fix at 20200205T154753: (41.557468, -70.711892)
2020-02-05T15:47:54.768Z,1580917674.768 [profile_station:NeedComms:E] Stopped
2020-02-05T15:47:54.773Z,1580917674.773 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2020-02-05T15:47:54.773Z,1580917674.773 [profile_station:NeedComms] Stopped
2020-02-05T15:47:54.773Z,1580917674.773 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2020-02-05T15:47:54.773Z,1580917674.773 [profile_station:NeedComms:B.GoToSurface] Stopped
2020-02-05T15:47:54.773Z,1580917674.773 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-05T15:48:36.716Z,1580917716.716 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-05T15:48:58.273Z,1580917738.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:49:02.289Z,1580917742.289 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247429
2020-02-05T15:49:23.689Z,1580917763.689 [Radio_Surface](INFO): Powering down
2020-02-05T15:49:33.421Z,1580917773.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:49:34.425Z,1580917774.425 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-02-05T15:51:17.584Z,1580917877.584 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-02-05T15:51:17.585Z,1580917877.585 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-05T15:51:17.585Z,1580917877.585 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-05T15:51:17.585Z,1580917877.585 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-05T15:51:17.668Z,1580917877.668 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-05T15:51:17.969Z,1580917877.969 [RDI_Pathfinder](INFO): Powering down
2020-02-05T15:51:18.776Z,1580917878.776 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-05T15:51:18.776Z,1580917878.776 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-05T15:51:32.059Z,1580917892.059 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-05T15:52:01.978Z,1580917921.978 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,  -106,  +778,   -25,A
2020-02-05T15:52:03.980Z,1580917923.980 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-05T15:52:03.980Z,1580917923.980 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,  -129,  +85,A
2020-02-05T15:52:25.366Z,1580917945.366 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-02-05T15:52:25.366Z,1580917945.366 [RDI_Pathfinder](ERROR): Failed to parse:
:RA 112.39, 104.41, 130.86,  93.93
2020-02-05T15:52:57.279Z,1580917977.279 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-05T15:53:04.986Z,1580917984.986 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-05T15:53:04.987Z,1580917984.987 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,       -8.9  15,   7.25,  0.00
2020-02-05T15:53:27.986Z,1580918007.986 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-05T15:53:47.006Z,1580918027.006 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-05T15:53:47.006Z,1580918027.006 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,      -10.08,      +100.95,       12.0
2020-02-05T15:54:27.001Z,1580918067.001 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-05T15:54:27.001Z,1580918067.001 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,      -11.30,      +1     -0.51,   9.72,  0.00
2020-02-05T15:55:05.359Z,1580918105.359 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-05T15:55:05.359Z,1580918105.359 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020509081894,35.0, -0.1, 48.9,  0
2020-02-05T15:55:46.139Z,1580918146.139 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-05T15:55:46.139Z,1580918146.139 [Micromodem](INFO): Have incoming data
2020-02-05T15:55:46.142Z,1580918146.142 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*53

2020-02-05T15:55:46.142Z,1580918146.142 [Micromodem](ERROR): Could not determine end of data in CARXD:$CARXD,10,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*53

2020-02-05T15:55:46.542Z,1580918146.542 [Micromodem](INFO): Nmea in: $CACST,6,0,20200205155545.372900,06,1182,30,0380,0150,59,02,02,00,04,1,010,001,0,3,1,0,150,21.1,10.00,00,-7.00,-01,0.45,30,10000,4000*6E

2020-02-05T15:55:46.997Z,1580918146.997 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-05T15:55:46.997Z,1580918146.997 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,      -14.03,             -0.59,  1200
2020-02-05T15:55:52.195Z,1580918152.195 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-05T15:55:52.195Z,1580918152.195 [Micromodem](INFO): Have incoming data
2020-02-05T15:55:52.198Z,1580918152.198 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*53

2020-02-05T15:55:52.199Z,1580918152.199 [Micromodem](INFO): Received command:stop
2020-02-05T15:55:52.214Z,1580918152.214 [CommandLine](IMPORTANT): got command stop
2020-02-05T15:55:52.214Z,1580918152.214 [CommandLine](IMPORTANT): Scheduling is paused
2020-02-05T15:55:52.215Z,1580918152.215 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-02-05T15:55:52.298Z,1580918152.298 [MissionManager](INFO): MissionManager is completed.
2020-02-05T15:55:52.298Z,1580918152.298 [MissionManager](INFO): Uninitializing Mission profile_station
2020-02-05T15:55:52.298Z,1580918152.298 [profile_station] Stopped
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:Science] Stopped
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:Science:G] Stopped
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:Science:Read_Oil] Stopped
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:Science:PeakDetectChl] Stopped
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:StandardEnvelopes] Stopped
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-02-05T15:55:52.299Z,1580918152.299 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:I.Pitch] Stopped
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:J.Buoyancy] Stopped
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:K.SetSpeed] Stopped
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:L.DepthEnvelope] Stopped
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:M.AltitudeEnvelope] Stopped
2020-02-05T15:55:52.300Z,1580918152.300 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-02-05T15:55:52.301Z,1580918152.301 [profile_station:N.YoYo] Stopped
2020-02-05T15:55:52.301Z,1580918152.301 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2020-02-05T15:55:52.301Z,1580918152.301 [profile_station:CircleWrapper] Stopped
2020-02-05T15:55:52.301Z,1580918152.301 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2020-02-05T15:55:52.301Z,1580918152.301 [profile_station:CircleWrapper:A] Stopped
2020-02-05T15:55:52.301Z,1580918152.301 [profile_station:CircleWrapper:B.Circle] Stopped
2020-02-05T15:55:52.301Z,1580918152.301 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2020-02-05T15:55:52.505Z,1580918152.505 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-05T15:55:52.606Z,1580918152.606 [Micromodem](INFO): Nmea in: $CACST,6,0,20200205155551.360284,06,859,29,0378,0150,59,02,02,00,04,1,010,001,0,3,1,0,150,17.8,11.00,00,-8.00,-01,0.44,29,10000,4000*55

2020-02-05T15:55:52.636Z,1580918152.636 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-02-05T15:55:52.637Z,1580918152.637 [DefaultWithUndock] Running Loop=1
2020-02-05T15:55:52.637Z,1580918152.637 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-02-05T15:55:52.637Z,1580918152.637 [DefaultWithUndock:C.GoToSurface] Running Loop=1
2020-02-05T15:55:52.637Z,1580918152.637 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-05T15:55:52.637Z,1580918152.637 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-05T15:55:52.637Z,1580918152.637 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-05T15:55:52.638Z,1580918152.638 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-05T15:55:52.638Z,1580918152.638 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-05T15:55:52.638Z,1580918152.638 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-05T15:55:52.639Z,1580918152.639 [DefaultWithUndock:A.Wait] Running Loop=1
2020-02-05T15:55:52.639Z,1580918152.639 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-02-05T15:55:53.046Z,1580918153.046 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -25.00 mm (3 active estimators).
2020-02-05T15:55:53.145Z,1580918153.145 [Radio_Surface](INFO): Powering up
2020-02-05T15:55:53.360Z,1580918153.360 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2020-02-05T15:55:53.361Z,1580918153.361 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2020-02-05T15:55:53.361Z,1580918153.361 [BuoyancyServo] Communications Fault, FailCount= 1
2020-02-05T15:55:53.361Z,1580918153.361 [BuoyancyServo](ERROR): Communications Fault
2020-02-05T15:55:53.397Z,1580918153.397 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2020-02-05T15:55:55.229Z,1580918155.229 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-05T15:55:55.229Z,1580918155.229 [BuoyancyServo](INFO): Powering down
2020-02-05T15:55:56.188Z,1580918156.188 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2020-02-05T15:55:56.188Z,1580918156.188 [BuoyancyServo] No Fault, FailCount= 1
2020-02-05T15:55:56.432Z,1580918156.432 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-05T15:55:56.433Z,1580918156.433 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-05T15:55:58.031Z,1580918158.031 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-05T15:55:58.031Z,1580918158.031 [Micromodem](INFO): Have incoming data
2020-02-05T15:55:58.035Z,1580918158.035 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*53

2020-02-05T15:55:58.036Z,1580918158.036 [Micromodem](INFO): Received command:stop
2020-02-05T15:55:58.121Z,1580918158.121 [CommandLine](IMPORTANT): got command stop
2020-02-05T15:55:58.431Z,1580918158.431 [Micromodem](INFO): Nmea in: $CACST,6,0,20200205155557.436078,06,1032,30,0371,0150,89,02,02,00,03,1,010,001,0,3,1,0,150,17.7,13.00,00,-10.00,-01,0.34,34,10000,4000*56

2020-02-05T15:55:58.989Z,1580918158.989 [DataOverHttps](INFO): Radio surface powered ON.
2020-02-05T15:56:04.482Z,1580918164.482 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-05T15:56:04.483Z,1580918164.483 [Micromodem](INFO): Have incoming data
2020-02-05T15:56:04.487Z,1580918164.487 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*53

2020-02-05T15:56:04.488Z,1580918164.488 [Micromodem](INFO): Received command:stop
2020-02-05T15:56:04.569Z,1580918164.569 [CommandLine](IMPORTANT): got command stop
2020-02-05T15:56:04.888Z,1580918164.888 [Micromodem](INFO): Nmea in: $CACST,6,0,20200205155603.669336,06,5054,37,0380,0150,59,01,01,00,03,1,010,001,0,3,1,0,150,18.6,12.00,00,-9.00,-01,0.49,30,10000,4000*6C

2020-02-05T15:56:10.997Z,1580918170.997 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-05T15:56:10.997Z,1580918170.997 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +7,  +648, 

2020-02-05T15:56:22.697Z,1580918182.697 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-02-05T15:56:22.697Z,1580918182.697 [DefaultWithUndock:A.Wait] Stopped
2020-02-05T15:56:22.697Z,1580918182.697 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-05T15:56:22.697Z,1580918182.697 [DefaultWithUndock:B.Undock] Running Loop=1
2020-02-05T15:56:22.697Z,1580918182.697 [DefaultWithUndock:B.Undock](DEBUG): Initialize.
2020-02-05T15:56:23.485Z,1580918183.485 [PowerOnly](INFO): Powering up
2020-02-05T15:56:24.325Z,1580918184.325 [DefaultWithUndock:B.Undock](INFO): Detaching from dock.
2020-02-05T15:56:24.713Z,1580918184.713 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-02-05T15:56:25.135Z,1580918185.135 [DefaultWithUndock:B.Undock](INFO): DDM at standby.
2020-02-05T15:56:25.521Z,1580918185.521 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-02-05T15:56:25.521Z,1580918185.521 [DefaultWithUndock:B.Undock] Stopped
2020-02-05T15:56:25.926Z,1580918185.926 [DefaultWithUndock:CheckIn] Running Loop=1
2020-02-05T15:56:25.926Z,1580918185.926 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-05T15:56:25.927Z,1580918185.927 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-05T15:56:27.915Z,1580918187.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155626.00,A,4133.57779,N,07042.57343,W,0.719,0.00,050220,,,A*7C
2020-02-05T15:56:27.917Z,1580918187.917 [NAL9602](INFO): GPS fix at 20200205T155626: (41.559630, -70.709557)
2020-02-05T15:56:28.005Z,1580918188.005 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-05T15:56:28.005Z,1580918188.005 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-05T15:56:29.121Z,1580918189.121 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:56:58.623Z,1580918218.623 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-05T15:56:58.704Z,1580918218.704 [NAL9602](FAULT): received: 
+CSQ:0

OK403, 0, 0, 0, 0

OK
2020-02-05T15:56:58.705Z,1580918218.705 [NAL9602] Data Fault, FailCount= 1
2020-02-05T15:56:58.705Z,1580918218.705 [NAL9602](ERROR): Data Fault
2020-02-05T15:56:58.809Z,1580918218.809 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-05T15:56:59.020Z,1580918219.020 [NAL9602](INFO): Powering down
2020-02-05T15:56:59.917Z,1580918219.917 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-05T15:56:59.917Z,1580918219.917 [NAL9602] No Fault, FailCount= 1
2020-02-05T15:57:04.261Z,1580918224.261 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:57:12.003Z,1580918232.003 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-05T15:57:12.003Z,1580918232.003 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,  -115,  -109,  :BE,  -115,  -109,  -154,A
2020-02-05T15:57:29.321Z,1580918249.321 [NAL9602](INFO): Powering up NAL9602
2020-02-05T15:57:39.401Z,1580918259.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:57:40.229Z,1580918260.229 [NAL9602](INFO): NAL9602 initialized
2020-02-05T15:57:54.800Z,1580918274.800 [PowerOnly](INFO): Powering down
2020-02-05T15:58:11.342Z,1580918291.342 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-05T15:58:14.557Z,1580918294.557 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:58:49.709Z,1580918329.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-02-05T15:59:24.448Z,1580918364.448 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006876
2020-02-05T15:59:32.036Z,1580918372.036 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200205T151453/Courier0019.lzma
2020-02-05T15:59:34.042Z,1580918374.042 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Courier0019.lzma.bak
2020-02-05T15:59:34.042Z,1580918374.042 [DataOverHttps](INFO): SBD MOMSN=12226237
2020-02-05T15:59:51.185Z,1580918391.185 [DataOverHttps](INFO): Sending 414 bytes from file Logs/20200205T151453/Express0020.lzma
2020-02-05T15:59:53.190Z,1580918393.190 [DataOverHttps](INFO): Moved sent file to Logs/20200205T151453/Express0020.lzma.bak
2020-02-05T15:59:53.190Z,1580918393.190 [DataOverHttps](INFO): SBD MOMSN=12226241
2020-02-05T15:59:54.402Z,1580918394.402 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-05T15:59:54.402Z,1580918394.402 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-05T15:59:54.402Z,1580918394.402 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-05T16:01:49.594Z,1580918509.594 [DataOverHttps](IMPORTANT): SBD MTMSN=20200205T160147
2020-02-05T16:01:57.236Z,1580918517.236 [DataOverHttps](INFO): Received command:restart app
2020-02-05T16:01:57.245Z,1580918517.245 [CommandLine](IMPORTANT): got command restart application
2020-02-05T16:01:58.249Z,1580918518.249 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T16:01:58.249Z,1580918518.249 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.445Z,1580918518.445 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-02-05T16:01:58.445Z,1580918518.445 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.445Z,1580918518.445 [CommandLine](INFO): Join timeout helper Thread ID is 3194
2020-02-05T16:01:58.459Z,1580918518.459 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-05T16:01:58.459Z,1580918518.459 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.459Z,1580918518.459 [NavChartDb](INFO): Join timeout helper Thread ID is 3195
2020-02-05T16:01:58.521Z,1580918518.521 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T16:01:58.521Z,1580918518.521 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.541Z,1580918518.541 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-02-05T16:01:58.541Z,1580918518.541 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.541Z,1580918518.541 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 3196
2020-02-05T16:01:58.569Z,1580918518.569 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T16:01:58.569Z,1580918518.569 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-05T16:01:58.570Z,1580918518.570 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.580Z,1580918518.580 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-05T16:01:58.581Z,1580918518.581 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.581Z,1580918518.581 [Radio_Surface](INFO): Join timeout helper Thread ID is 3197
2020-02-05T16:01:58.822Z,1580918518.822 [Radio_Surface](INFO): Powering down
2020-02-05T16:01:58.823Z,1580918518.823 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T16:01:58.823Z,1580918518.823 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.824Z,1580918518.824 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-05T16:01:58.824Z,1580918518.824 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:58.825Z,1580918518.825 [DataOverHttps](INFO): Join timeout helper Thread ID is 3198
2020-02-05T16:01:59.241Z,1580918519.241 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T16:01:59.244Z,1580918519.244 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:59.249Z,1580918519.249 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-05T16:01:59.250Z,1580918519.250 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:59.250Z,1580918519.250 [logger](INFO): Join timeout helper Thread ID is 3199
2020-02-05T16:01:59.265Z,1580918519.265 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T16:01:59.265Z,1580918519.265 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:59.269Z,1580918519.269 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-05T16:01:59.269Z,1580918519.269 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:59.270Z,1580918519.270 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-05T16:01:59.270Z,1580918519.270 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:01:59.270Z,1580918519.270 [controlThread](INFO): Join timeout helper Thread ID is 3200
2020-02-05T16:01:59.597Z,1580918519.597 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T16:01:59.597Z,1580918519.597 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-05T16:01:59.597Z,1580918519.597 [AHRS_M2](INFO): Powering down
2020-02-05T16:01:59.669Z,1580918519.669 [DUSBL_Hydroid](INFO): Powering down
2020-02-05T16:01:59.761Z,1580918519.761 [Micromodem](INFO): Powering down
2020-02-05T16:01:59.857Z,1580918519.857 [NAL9602](INFO): Powering down
2020-02-05T16:01:59.929Z,1580918519.929 [RDI_Pathfinder](INFO): Powering down
2020-02-05T16:01:59.930Z,1580918519.930 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-05T16:01:59.931Z,1580918519.931 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-05T16:01:59.931Z,1580918519.931 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-05T16:01:59.932Z,1580918519.932 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2020-02-05T16:01:59.932Z,1580918519.932 [DefaultWithUndock] Stopped
2020-02-05T16:01:59.932Z,1580918519.932 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-02-05T16:01:59.932Z,1580918519.932 [DefaultWithUndock:C.GoToSurface] Stopped
2020-02-05T16:01:59.933Z,1580918519.933 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-05T16:01:59.933Z,1580918519.933 [DefaultWithUndock:CheckIn] Stopped
2020-02-05T16:01:59.933Z,1580918519.933 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-05T16:01:59.933Z,1580918519.933 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-05T16:01:59.933Z,1580918519.933 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-05T16:01:59.936Z,1580918519.936 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-05T16:01:59.936Z,1580918519.936 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-05T16:01:59.936Z,1580918519.936 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-05T16:01:59.937Z,1580918519.937 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-05T16:01:59.937Z,1580918519.937 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-05T16:01:59.937Z,1580918519.937 [BuoyancyServo](INFO): Powering down
2020-02-05T16:01:59.949Z,1580918519.949 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-05T16:01:59.949Z,1580918519.949 [ElevatorServo](INFO): Powering down
2020-02-05T16:01:59.950Z,1580918519.950 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-02-05T16:01:59.950Z,1580918519.950 [MassServo](INFO): Powering down
2020-02-05T16:01:59.950Z,1580918519.950 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-05T16:01:59.951Z,1580918519.951 [RudderServo](INFO): Powering down
2020-02-05T16:01:59.951Z,1580918519.951 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-05T16:01:59.951Z,1580918519.951 [ThrusterServo](INFO): Powering down
2020-02-05T16:01:59.952Z,1580918519.952 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-05T16:01:59.953Z,1580918519.953 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-05T16:01:59.953Z,1580918519.953 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-05T16:01:59.953Z,1580918519.953 [CBIT](DEBUG): Powering off loads.
2020-02-05T16:01:59.964Z,1580918519.964 [CBIT](DEBUG): Disabling WDT.
2020-02-05T16:01:59.976Z,1580918519.976 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-05T16:01:59.977Z,1580918519.977 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:02:00.029Z,1580918520.029 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:02:00.039Z,1580918520.039 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:02:00.081Z,1580918520.081 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:02:00.138Z,1580918520.138 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-05T16:02:00.205Z,1580918520.205 [logger ThreadHandler](INFO): Thread cancelled.