2020-09-08T17:56:06.924Z,1599587766.924 [Supervisor](DEBUG): Initializing supervisor.
2020-09-08T17:56:06.927Z,1599587766.927 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-09-08T17:56:06.927Z,1599587766.927 [SyncHandler](INFO): Protected caller Thread ID is 826
2020-09-08T17:56:06.928Z,1599587766.928 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-09-08T17:56:06.929Z,1599587766.929 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-09-08T17:56:06.929Z,1599587766.929 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 827
2020-09-08T17:56:06.932Z,1599587766.932 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-09-08T17:56:06.944Z,1599587766.944 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-09-08T17:56:06.945Z,1599587766.945 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-09-08T17:56:06.946Z,1599587766.946 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 828
2020-09-08T17:56:06.946Z,1599587766.946 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-09-08T17:56:06.947Z,1599587766.947 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-09-08T17:56:06.948Z,1599587766.948 [logger ThreadHandler](INFO): Protected caller Thread ID is 829
2020-09-08T17:56:06.950Z,1599587766.950 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-09-08T17:56:06.950Z,1599587766.950 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-09-08T17:56:06.954Z,1599587766.954 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-09-08T17:56:07.048Z,1599587767.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-09-08T17:56:07.050Z,1599587767.050 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-09-08T17:56:07.645Z,1599587767.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-09-08T17:56:07.646Z,1599587767.646 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-09-08T17:56:07.837Z,1599587767.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-09-08T17:56:07.839Z,1599587767.839 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-09-08T17:56:07.938Z,1599587767.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-09-08T17:56:07.939Z,1599587767.939 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-09-08T17:56:08.042Z,1599587768.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-09-08T17:56:08.044Z,1599587768.044 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-09-08T17:56:08.125Z,1599587768.125 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-09-08T17:56:08.262Z,1599587768.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-09-08T17:56:08.264Z,1599587768.264 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-09-08T17:56:08.550Z,1599587768.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-09-08T17:56:08.552Z,1599587768.552 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-09-08T17:56:09.030Z,1599587769.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-09-08T17:56:09.031Z,1599587769.031 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-09-08T17:56:09.206Z,1599587769.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-09-08T17:56:09.207Z,1599587769.207 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-09-08T17:56:09.398Z,1599587769.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-09-08T17:56:09.400Z,1599587769.400 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-09-08T17:56:09.867Z,1599587769.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-09-08T17:56:09.869Z,1599587769.869 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-09-08T17:56:10.095Z,1599587770.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-09-08T17:56:10.097Z,1599587770.097 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-09-08T17:56:10.305Z,1599587770.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-09-08T17:56:10.939Z,1599587770.939 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-09-08T17:56:11.460Z,1599587771.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-09-08T17:56:11.460Z,1599587771.460 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-09-08T17:56:11.790Z,1599587771.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-09-08T17:56:11.792Z,1599587771.792 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-09-08T17:56:11.794Z,1599587771.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-09-08T17:56:11.877Z,1599587771.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-09-08T17:56:12.035Z,1599587772.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-09-08T17:56:12.141Z,1599587772.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-09-08T17:56:12.227Z,1599587772.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-09-08T17:56:12.321Z,1599587772.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-09-08T17:56:12.521Z,1599587772.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-09-08T17:56:12.754Z,1599587772.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-09-08T17:56:12.755Z,1599587772.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-09-08T17:56:12.848Z,1599587772.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-09-08T17:56:12.946Z,1599587772.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-09-08T17:56:13.076Z,1599587773.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-09-08T17:56:13.175Z,1599587773.175 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2020-09-08T17:56:13.179Z,1599587773.179 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-09-08T17:56:13.280Z,1599587773.280 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-09-08T17:56:13.280Z,1599587773.280 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-09-08T17:56:13.346Z,1599587773.346 [VerticalControl](DEBUG): Construct VerticalControl.
2020-09-08T17:56:13.460Z,1599587773.460 [VerticalControl] Loaded
2020-09-08T17:56:13.461Z,1599587773.461 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-09-08T17:56:13.461Z,1599587773.461 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-09-08T17:56:13.543Z,1599587773.543 [HorizontalControl] Loaded
2020-09-08T17:56:13.543Z,1599587773.543 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-09-08T17:56:13.543Z,1599587773.543 [SpeedControl](DEBUG): Construct SpeedControl.
2020-09-08T17:56:13.549Z,1599587773.549 [SpeedControl] Loaded
2020-09-08T17:56:13.549Z,1599587773.549 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-09-08T17:56:13.550Z,1599587773.550 [LoopControl](DEBUG): Construct LoopControl.
2020-09-08T17:56:13.550Z,1599587773.550 [LoopControl] Loaded
2020-09-08T17:56:13.551Z,1599587773.551 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-09-08T17:56:13.551Z,1599587773.551 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-09-08T17:56:13.552Z,1599587773.552 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-09-08T17:56:13.608Z,1599587773.608 [DepthRateCalculator] Loaded
2020-09-08T17:56:13.609Z,1599587773.609 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-09-08T17:56:13.614Z,1599587773.614 [PitchRateCalculator] Loaded
2020-09-08T17:56:13.614Z,1599587773.614 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-09-08T17:56:13.630Z,1599587773.630 [SpeedCalculator] Loaded
2020-09-08T17:56:13.630Z,1599587773.630 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-09-08T17:56:13.652Z,1599587773.652 [TempGradientCalculator] Loaded
2020-09-08T17:56:13.652Z,1599587773.652 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-09-08T17:56:13.657Z,1599587773.657 [YawRateCalculator] Loaded
2020-09-08T17:56:13.657Z,1599587773.657 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-09-08T17:56:13.687Z,1599587773.687 [ElevatorOffsetCalculator] Loaded
2020-09-08T17:56:13.687Z,1599587773.687 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-09-08T17:56:13.688Z,1599587773.688 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-09-08T17:56:13.688Z,1599587773.688 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-09-08T17:56:13.730Z,1599587773.730 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-09-08T17:56:13.730Z,1599587773.730 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-09-08T17:56:13.863Z,1599587773.863 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-09-08T17:56:13.863Z,1599587773.863 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-09-08T17:56:14.213Z,1599587774.213 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-09-08T17:56:14.214Z,1599587774.214 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-09-08T17:56:14.343Z,1599587774.343 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-09-08T17:56:14.344Z,1599587774.344 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-09-08T17:56:14.929Z,1599587774.929 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-09-08T17:56:14.934Z,1599587774.934 [AHRS_M2](INFO): created writer for : platform_orientation
2020-09-08T17:56:14.935Z,1599587774.935 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-09-08T17:56:14.941Z,1599587774.941 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-09-08T17:56:14.941Z,1599587774.941 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-09-08T17:56:14.946Z,1599587774.946 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-09-08T17:56:14.947Z,1599587774.947 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-09-08T17:56:14.953Z,1599587774.953 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-09-08T17:56:15.131Z,1599587775.131 [AHRS_M2] Loaded
2020-09-08T17:56:15.131Z,1599587775.131 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-09-08T17:56:15.276Z,1599587775.276 [DataOverHttps] Loaded
2020-09-08T17:56:15.276Z,1599587775.276 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-09-08T17:56:15.277Z,1599587775.277 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2020-09-08T17:56:15.278Z,1599587775.278 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 911
2020-09-08T17:56:15.309Z,1599587775.309 [Depth_Keller] Loaded
2020-09-08T17:56:15.309Z,1599587775.309 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-09-08T17:56:15.314Z,1599587775.314 [DropWeight] Loaded
2020-09-08T17:56:15.315Z,1599587775.315 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-09-08T17:56:15.431Z,1599587775.431 [NAL9602] Loaded
2020-09-08T17:56:15.431Z,1599587775.431 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-09-08T17:56:15.481Z,1599587775.481 [Onboard] Loaded
2020-09-08T17:56:15.482Z,1599587775.482 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-09-08T17:56:15.483Z,1599587775.483 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0
2020-09-08T17:56:15.483Z,1599587775.483 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 912
2020-09-08T17:56:15.503Z,1599587775.503 [Radio_Surface] Loaded
2020-09-08T17:56:15.503Z,1599587775.503 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-09-08T17:56:15.504Z,1599587775.504 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0
2020-09-08T17:56:15.504Z,1599587775.504 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 913
2020-09-08T17:56:15.566Z,1599587775.566 [RDI_Pathfinder] Loaded
2020-09-08T17:56:15.566Z,1599587775.566 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-09-08T17:56:15.722Z,1599587775.722 [DAT] Loaded
2020-09-08T17:56:15.722Z,1599587775.722 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-09-08T17:56:17.270Z,1599587777.270 [BPC1] Loaded
2020-09-08T17:56:17.270Z,1599587777.270 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-09-08T17:56:17.303Z,1599587777.303 [AMEcho] Loaded
2020-09-08T17:56:17.303Z,1599587777.303 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2020-09-08T17:56:17.303Z,1599587777.303 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-09-08T17:56:17.304Z,1599587777.304 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-09-08T17:56:17.854Z,1599587777.854 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-09-08T17:56:17.854Z,1599587777.854 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-09-08T17:56:17.875Z,1599587777.875 [NavChart] Loaded
2020-09-08T17:56:17.875Z,1599587777.875 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-09-08T17:56:17.879Z,1599587777.879 [UniversalFixResidualReporter] Loaded
2020-09-08T17:56:17.879Z,1599587777.879 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-09-08T17:56:17.880Z,1599587777.880 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-09-08T17:56:18.039Z,1599587778.039 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-09-08T17:56:18.209Z,1599587778.209 [SBIT](DEBUG): Construct Startup Built In Test.
2020-09-08T17:56:18.220Z,1599587778.220 [SBIT] Loaded
2020-09-08T17:56:18.221Z,1599587778.221 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-09-08T17:56:18.221Z,1599587778.221 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-09-08T17:56:18.234Z,1599587778.234 [IBIT] Loaded
2020-09-08T17:56:18.234Z,1599587778.234 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-09-08T17:56:18.237Z,1599587778.237 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-09-08T17:56:18.380Z,1599587778.380 [CBIT] Loaded
2020-09-08T17:56:18.380Z,1599587778.380 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-09-08T17:56:18.381Z,1599587778.381 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-09-08T17:56:18.381Z,1599587778.381 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-09-08T17:56:18.531Z,1599587778.531 [BuoyancyServo] Loaded
2020-09-08T17:56:18.531Z,1599587778.531 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-09-08T17:56:18.560Z,1599587778.560 [ElevatorServo] Loaded
2020-09-08T17:56:18.561Z,1599587778.561 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-09-08T17:56:18.589Z,1599587778.589 [MassServo] Loaded
2020-09-08T17:56:18.590Z,1599587778.590 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-09-08T17:56:18.618Z,1599587778.618 [RudderServo] Loaded
2020-09-08T17:56:18.618Z,1599587778.618 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-09-08T17:56:18.646Z,1599587778.646 [ThrusterServo] Loaded
2020-09-08T17:56:18.646Z,1599587778.646 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-09-08T17:56:18.647Z,1599587778.647 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-09-08T17:56:18.647Z,1599587778.647 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-09-08T17:56:18.678Z,1599587778.678 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-09-08T17:56:18.678Z,1599587778.678 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-09-08T17:56:19.007Z,1599587779.007 [BackSeatDriver] Loaded
2020-09-08T17:56:19.007Z,1599587779.007 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-09-08T17:56:19.008Z,1599587779.008 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0
2020-09-08T17:56:19.009Z,1599587779.009 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 914
2020-09-08T17:56:19.038Z,1599587779.038 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-09-08T17:56:19.044Z,1599587779.044 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-09-08T17:56:19.044Z,1599587779.044 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-09-08T17:56:19.049Z,1599587779.049 [CTD_Seabird](INFO): created writer for : depth
2020-09-08T17:56:19.050Z,1599587779.050 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-09-08T17:56:19.055Z,1599587779.055 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-09-08T17:56:19.055Z,1599587779.055 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-09-08T17:56:19.061Z,1599587779.061 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-09-08T17:56:19.061Z,1599587779.061 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-09-08T17:56:19.099Z,1599587779.099 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-09-08T17:56:19.100Z,1599587779.100 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-09-08T17:56:19.113Z,1599587779.113 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-09-08T17:56:19.114Z,1599587779.114 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-09-08T17:56:19.119Z,1599587779.119 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-09-08T17:56:19.151Z,1599587779.151 [CTD_Seabird] Loaded
2020-09-08T17:56:19.152Z,1599587779.152 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-09-08T17:56:19.153Z,1599587779.153 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B334E0
2020-09-08T17:56:19.153Z,1599587779.153 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 916
2020-09-08T17:56:19.176Z,1599587779.176 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-09-08T17:56:19.176Z,1599587779.176 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-09-08T17:56:19.180Z,1599587779.180 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-09-08T17:56:19.180Z,1599587779.180 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-09-08T17:56:19.185Z,1599587779.185 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-09-08T17:56:19.185Z,1599587779.185 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-09-08T17:56:19.190Z,1599587779.190 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-09-08T17:56:19.190Z,1599587779.190 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-09-08T17:56:19.194Z,1599587779.194 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-09-08T17:56:19.195Z,1599587779.195 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-09-08T17:56:19.199Z,1599587779.199 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-09-08T17:56:19.199Z,1599587779.199 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-09-08T17:56:19.204Z,1599587779.204 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-09-08T17:56:19.204Z,1599587779.204 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-09-08T17:56:19.208Z,1599587779.208 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T17:56:19.213Z,1599587779.213 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T17:56:19.213Z,1599587779.213 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T17:56:19.213Z,1599587779.213 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T17:56:19.218Z,1599587779.218 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T17:56:19.218Z,1599587779.218 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T17:56:19.222Z,1599587779.222 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T17:56:19.223Z,1599587779.223 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T17:56:19.227Z,1599587779.227 [WetLabsBB2FL] Loaded
2020-09-08T17:56:19.227Z,1599587779.227 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-09-08T17:56:19.228Z,1599587779.228 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2020-09-08T17:56:19.229Z,1599587779.229 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 917
2020-09-08T17:56:19.230Z,1599587779.230 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-09-08T17:56:19.233Z,1599587779.233 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-09-08T17:56:19.234Z,1599587779.234 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-09-08T17:56:19.241Z,1599587779.241 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-09-08T17:56:19.242Z,1599587779.242 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0
2020-09-08T17:56:19.242Z,1599587779.242 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 918
2020-09-08T17:56:19.247Z,1599587779.247 [Supervisor](INFO): Main Thread ID is 822
2020-09-08T17:56:19.247Z,1599587779.247 [Supervisor](DEBUG): Running supervisor.
2020-09-08T17:56:19.247Z,1599587779.247 [CommandLine ThreadHandler](INFO): Handler Thread ID is 919
2020-09-08T17:56:19.249Z,1599587779.249 [controlThread ThreadHandler](INFO): Handler Thread ID is 920
2020-09-08T17:56:19.250Z,1599587779.250 [controlThread](DEBUG): Initializing ControlThread
2020-09-08T17:56:19.251Z,1599587779.251 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-09-08T17:56:19.253Z,1599587779.253 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-09-08T17:56:19.253Z,1599587779.253 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-09-08T17:56:19.254Z,1599587779.254 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-09-08T17:56:19.254Z,1599587779.254 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-09-08T17:56:19.254Z,1599587779.254 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-09-08T17:56:19.255Z,1599587779.255 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-09-08T17:56:19.255Z,1599587779.255 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-09-08T17:56:19.256Z,1599587779.256 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-09-08T17:56:19.256Z,1599587779.256 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-09-08T17:56:19.262Z,1599587779.262 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-09-08T17:56:19.262Z,1599587779.262 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-09-08T17:56:19.262Z,1599587779.262 [SBIT](INFO): Initialize SBIT Component.
2020-09-08T17:56:19.263Z,1599587779.263 [SBIT](IMPORTANT): git: 2020-08-31-2-g7c05f98
2020-09-08T17:56:19.263Z,1599587779.263 [SBIT](INFO): git hash: 7c05f98c887f9b002c46c79a8a0d63cabec404dc
2020-09-08T17:56:19.263Z,1599587779.263 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-09-08T17:56:19.264Z,1599587779.264 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-09-08T17:56:19.265Z,1599587779.265 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-09-08T17:56:19.266Z,1599587779.266 [IBIT](INFO): Initialize IBIT Component.
2020-09-08T17:56:19.267Z,1599587779.267 [CBIT](DEBUG): Initialize CBIT Component.
2020-09-08T17:56:19.268Z,1599587779.268 [logger ThreadHandler](INFO): Handler Thread ID is 921
2020-09-08T17:56:19.279Z,1599587779.279 [CBIT](DEBUG): Initialized mux pins.
2020-09-08T17:56:19.279Z,1599587779.279 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2020-09-08T17:56:19.279Z,1599587779.279 [CBIT](DEBUG): Initializing the watchdog timer.
2020-09-08T17:56:19.287Z,1599587779.287 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 922
2020-09-08T17:56:19.288Z,1599587779.288 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-09-08T17:56:19.299Z,1599587779.299 [Onboard ThreadHandler](INFO): Handler Thread ID is 923
2020-09-08T17:56:19.303Z,1599587779.303 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-09-08T17:56:19.303Z,1599587779.303 [CBIT](DEBUG): Initializing heartbeat.
2020-09-08T17:56:19.317Z,1599587779.317 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 924
2020-09-08T17:56:19.339Z,1599587779.339 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 925
2020-09-08T17:56:19.359Z,1599587779.359 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 926
2020-09-08T17:56:19.360Z,1599587779.360 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-09-08T17:56:19.364Z,1599587779.364 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 928
2020-09-08T17:56:19.365Z,1599587779.365 [WetLabsBB2FL](INFO): Powering down
2020-09-08T17:56:19.375Z,1599587779.375 [CBIT](DEBUG): Deactivating GF circuits.
2020-09-08T17:56:19.375Z,1599587779.375 [CBIT](DEBUG): Deactivating emergency mode.
2020-09-08T17:56:19.411Z,1599587779.411 [CBIT](DEBUG): Backplane powered.
2020-09-08T17:56:19.413Z,1599587779.413 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-09-08T17:56:19.478Z,1599587779.478 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 929
2020-09-08T17:56:19.581Z,1599587779.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-09-08T17:56:19.581Z,1599587779.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-09-08T17:56:19.581Z,1599587779.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-09-08T17:56:19.581Z,1599587779.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-09-08T17:56:19.582Z,1599587779.582 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-09-08T17:56:19.582Z,1599587779.582 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-09-08T17:56:19.582Z,1599587779.582 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-09-08T17:56:19.582Z,1599587779.582 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-09-08T17:56:19.582Z,1599587779.582 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-09-08T17:56:19.582Z,1599587779.582 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-09-08T17:56:19.583Z,1599587779.583 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-09-08T17:56:19.583Z,1599587779.583 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-09-08T17:56:19.583Z,1599587779.583 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-09-08T17:56:19.583Z,1599587779.583 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-09-08T17:56:19.583Z,1599587779.583 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-09-08T17:56:19.583Z,1599587779.583 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-09-08T17:56:19.586Z,1599587779.586 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-08T17:56:19.688Z,1599587779.688 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2020-09-08T17:56:19.689Z,1599587779.689 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-09-08T17:56:19.723Z,1599587779.723 [Radio_Surface](INFO): Powering up
2020-09-08T17:56:19.797Z,1599587779.797 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-09-08T17:56:19.798Z,1599587779.798 [Default:A.Wait](DEBUG): Construct Wait.
2020-09-08T17:56:19.799Z,1599587779.799 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-08T17:56:19.848Z,1599587779.848 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-09-08T17:56:19.851Z,1599587779.851 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-09-08T17:56:19.869Z,1599587779.869 [Default:E.Execute](DEBUG): Construct Execute.
2020-09-08T17:56:19.872Z,1599587779.872 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2020-09-08T17:56:19.891Z,1599587779.891 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,AMEcho,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-09-08T17:56:19.896Z,1599587779.896 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-09-08T17:56:20.066Z,1599587780.066 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-09-08T17:56:20.066Z,1599587780.066 [DAT](INFO): Powering up
2020-09-08T17:56:20.066Z,1599587780.066 [DAT](DEBUG): Initializing DAT.
2020-09-08T17:56:20.119Z,1599587780.119 [AMEcho](INFO): Powering up
2020-09-08T17:56:20.146Z,1599587780.146 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-09-08T17:56:20.148Z,1599587780.148 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-09-08T17:56:20.183Z,1599587780.183 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-09-08T17:56:20.185Z,1599587780.185 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-09-08T17:56:20.199Z,1599587780.199 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-09-08T17:56:20.201Z,1599587780.201 [MassServo](DEBUG): Initializing EZServoServo.
2020-09-08T17:56:20.211Z,1599587780.211 [MassServo](DEBUG): Initializing MassServo.
2020-09-08T17:56:20.212Z,1599587780.212 [RudderServo](DEBUG): Initializing EZServoServo.
2020-09-08T17:56:20.223Z,1599587780.223 [RudderServo](DEBUG): Initializing RudderServo.
2020-09-08T17:56:20.224Z,1599587780.224 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-09-08T17:56:20.231Z,1599587780.231 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-09-08T17:56:20.324Z,1599587780.324 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-09-08T17:56:20.499Z,1599587780.499 [AMEcho](INFO): Powering down
2020-09-08T17:56:26.391Z,1599587786.391 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-09-08T17:56:32.844Z,1599587792.844 [DAT](INFO): commRate: 800
2020-09-08T17:56:32.844Z,1599587792.844 [DAT](INFO): commRate: 800
2020-09-08T17:56:33.237Z,1599587793.237 [DAT](INFO): entering command mode
2020-09-08T17:56:33.641Z,1599587793.641 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:34.056Z,1599587794.056 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:34.449Z,1599587794.449 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:34.853Z,1599587794.853 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:35.267Z,1599587795.267 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:35.661Z,1599587795.661 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:36.074Z,1599587796.074 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:36.469Z,1599587796.469 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:36.874Z,1599587796.874 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:37.286Z,1599587797.286 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:37.681Z,1599587797.681 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:38.094Z,1599587798.094 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:38.489Z,1599587798.489 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:38.906Z,1599587798.906 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:39.306Z,1599587799.306 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:39.701Z,1599587799.701 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:40.114Z,1599587800.114 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:40.509Z,1599587800.509 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:40.959Z,1599587800.959 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:41.325Z,1599587801.325 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:41.721Z,1599587801.721 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:42.130Z,1599587802.130 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:42.537Z,1599587802.537 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:42.959Z,1599587802.959 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:43.333Z,1599587803.333 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:43.749Z,1599587803.749 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:44.155Z,1599587804.155 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:44.545Z,1599587804.545 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:44.974Z,1599587804.974 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:45.353Z,1599587805.353 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:45.764Z,1599587805.764 [NAL9602](INFO): Powering up NAL9602
2020-09-08T17:56:45.766Z,1599587805.766 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:46.174Z,1599587806.174 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:46.569Z,1599587806.569 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:46.982Z,1599587806.982 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:47.385Z,1599587807.385 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:47.781Z,1599587807.781 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:48.219Z,1599587808.219 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:48.589Z,1599587808.589 [DAT](DEBUG): checking for command mode acknowledgment
2020-09-08T17:56:48.589Z,1599587808.589 [DAT](FAULT): failed to enter command mode
2020-09-08T17:56:49.007Z,1599587809.007 [DAT](INFO): entering command mode
2020-09-08T17:56:49.398Z,1599587809.398 [DAT](INFO): setting verbose to 3
2020-09-08T17:56:49.814Z,1599587809.814 [DAT](INFO): set verbose to 3
2020-09-08T17:56:49.815Z,1599587809.815 [DAT](INFO): setting DatVerbose to 27440
2020-09-08T17:56:50.224Z,1599587810.224 [DAT](INFO): set DatVerbose to 27440
2020-09-08T17:56:50.224Z,1599587810.224 [DAT](INFO): setting transmit power to 8
2020-09-08T17:56:50.606Z,1599587810.606 [DAT](INFO): set transmit power to 8
2020-09-08T17:56:50.606Z,1599587810.606 [DAT](INFO): setting local address to 9
2020-09-08T17:56:51.060Z,1599587811.060 [DAT](INFO): set local address to 9
2020-09-08T17:56:56.670Z,1599587816.670 [NAL9602](INFO): NAL9602 initialized
2020-09-08T17:56:58.713Z,1599587818.713 [SBIT](IMPORTANT): Beginning Startup BIT
2020-09-08T17:56:58.717Z,1599587818.717 [CBIT](IMPORTANT): Beginning ground fault scan
2020-09-08T17:57:09.714Z,1599587829.714 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.008871
CHAN A1 (24V): 0.036424
CHAN A2 (12V): -0.007483
CHAN A3 (5V): -0.002951
CHAN B0 (3.3V): -0.000386
CHAN B1 (3.15aV): -0.000225
CHAN B2 (3.15bV): -0.000011
CHAN B3 (GND): 0.001594
OPEN: -0.003104
Full Scale Calc: 4.765 mA, -1.589 mA
2020-09-08T17:57:46.770Z,1599587866.770 [NAL9602](INFO): SBD MO Status=2, MOMSN=9547, MT Status=2, MTMSN=0
2020-09-08T17:57:46.770Z,1599587866.770 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-08T17:57:52.449Z,1599587872.449 [SBIT](IMPORTANT): SBIT PASSED
2020-09-08T17:57:52.449Z,1599587872.449 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2020-09-08T17:57:52.833Z,1599587872.833 [MissionManager](IMPORTANT): Started mission Startup
2020-09-08T17:57:52.833Z,1599587872.833 [Startup] Running Loop=1
2020-09-08T17:57:52.833Z,1599587872.833 [Startup](DEBUG): Aggregate::initialize Startup
2020-09-08T17:57:52.833Z,1599587872.833 [Startup:A.GoToSurface] Running Loop=1
2020-09-08T17:57:52.833Z,1599587872.833 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-08T17:57:52.834Z,1599587872.834 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-08T17:57:52.835Z,1599587872.835 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-08T17:57:52.835Z,1599587872.835 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-08T17:57:52.836Z,1599587872.836 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-08T17:57:52.836Z,1599587872.836 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-08T17:57:52.837Z,1599587872.837 [Startup:StartupSatComms] Running Loop=1
2020-09-08T17:57:52.837Z,1599587872.837 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-09-08T17:57:52.837Z,1599587872.837 [Startup:StartupSatComms:A] Running Loop=1
2020-09-08T17:57:53.247Z,1599587873.247 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-09-08T17:58:03.743Z,1599587883.743 [NAL9602](INFO): SBD MO Status=2, MOMSN=9547, MT Status=2, MTMSN=0
2020-09-08T17:58:03.744Z,1599587883.744 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-09-08T17:58:53.045Z,1599587933.045 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-08T17:57:52.8Z
2020-09-08T17:58:53.045Z,1599587933.045 [Startup:StartupSatComms:A] Stopped
2020-09-08T17:58:53.046Z,1599587933.046 [Startup:StartupSatComms:B] Running Loop=1
2020-09-08T17:58:53.453Z,1599587933.453 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-08T17:59:19.320Z,1599587959.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-09-08T17:59:19.320Z,1599587959.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-09-08T17:59:19.359Z,1599587959.359 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-09-08T17:59:19.710Z,1599587959.710 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-09-08T17:59:19.710Z,1599587959.710 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-09-08T17:59:44.995Z,1599587984.995 [CommandLine](IMPORTANT): got command get 
2020-09-08T17:59:44.995Z,1599587984.995 [CommandLine](FAULT): Incomplete syntax. Try: help get
2020-09-08T17:59:51.834Z,1599587991.834 [CommandLine](IMPORTANT): got command get AMEcho.enabled
2020-09-08T17:59:51.834Z,1599587991.834 [CommandLine](IMPORTANT): AMEcho.enabled 1 bool
2020-09-08T17:59:53.238Z,1599587993.238 [Startup:StartupSatComms:B](INFO): Timed out from 2020-09-08T17:58:53.0Z
2020-09-08T17:59:53.239Z,1599587993.239 [Startup:StartupSatComms:B] Stopped
2020-09-08T17:59:53.239Z,1599587993.239 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-09-08T17:59:53.239Z,1599587993.239 [Startup:StartupSatComms] Stopped
2020-09-08T17:59:53.240Z,1599587993.240 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-09-08T17:59:53.240Z,1599587993.240 [Startup](INFO): Completed Startup
2020-09-08T17:59:53.240Z,1599587993.240 [MissionManager](INFO): Startup is completed.
2020-09-08T17:59:53.241Z,1599587993.241 [MissionManager](INFO): Uninitializing Mission Startup
2020-09-08T17:59:53.241Z,1599587993.241 [Startup] Stopped
2020-09-08T17:59:53.241Z,1599587993.241 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-09-08T17:59:53.241Z,1599587993.241 [Startup:A.GoToSurface] Stopped
2020-09-08T17:59:53.241Z,1599587993.241 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-08T17:59:53.644Z,1599587993.644 [MissionManager](IMPORTANT): Started mission Default
2020-09-08T17:59:53.644Z,1599587993.644 [Default] Running Loop=1
2020-09-08T17:59:53.644Z,1599587993.644 [Default](DEBUG): Aggregate::initialize Default
2020-09-08T17:59:53.644Z,1599587993.644 [Default:B.GoToSurface] Running Loop=1
2020-09-08T17:59:53.644Z,1599587993.644 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-08T17:59:53.644Z,1599587993.644 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-08T17:59:53.645Z,1599587993.645 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-08T17:59:53.645Z,1599587993.645 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-08T17:59:53.645Z,1599587993.645 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-08T17:59:53.645Z,1599587993.645 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-08T17:59:53.646Z,1599587993.646 [Default:A.Wait] Running Loop=1
2020-09-08T17:59:53.646Z,1599587993.646 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-09-08T17:59:53.994Z,1599587993.994 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005069
2020-09-08T18:00:06.975Z,1599588006.975 [Default:A.Wait](INFO): Done Waiting.
2020-09-08T18:00:06.976Z,1599588006.976 [Default:A.Wait] Stopped
2020-09-08T18:00:06.976Z,1599588006.976 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-09-08T18:00:07.376Z,1599588007.376 [Default:CheckIn] Running Loop=1
2020-09-08T18:00:07.376Z,1599588007.376 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-08T18:00:07.377Z,1599588007.377 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-08T18:00:07.778Z,1599588007.778 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-09-08T18:02:00.488Z,1599588120.488 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-09-08T18:02:06.146Z,1599588126.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180200.00,A,3648.65575,N,12149.46539,W,0.272,8.51,080920,,,D*75
2020-09-08T18:02:06.150Z,1599588126.150 [NAL9602](INFO): GPS fix at 20200908T180200: (36.810929, -121.824423)
2020-09-08T18:02:06.170Z,1599588126.170 [Default:CheckIn:Read_GPS] Stopped
2020-09-08T18:02:06.170Z,1599588126.170 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-08T18:02:06.582Z,1599588126.582 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-08T18:02:17.624Z,1599588137.624 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20200908T175103/Courier0000.lzma
2020-09-08T18:02:18.625Z,1599588138.625 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175103/Courier0000.lzma.bak
2020-09-08T18:02:18.625Z,1599588138.625 [DataOverHttps](INFO): SBD MOMSN=12646445
2020-09-08T18:02:36.698Z,1599588156.698 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T175103/Courier0004.lzma
2020-09-08T18:02:37.696Z,1599588157.696 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175103/Courier0004.lzma.bak
2020-09-08T18:02:37.697Z,1599588157.697 [DataOverHttps](INFO): SBD MOMSN=12646449
2020-09-08T18:02:38.870Z,1599588158.870 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-08T18:02:56.346Z,1599588176.346 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200908T175103/Courier0007.lzma
2020-09-08T18:02:57.350Z,1599588177.350 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175103/Courier0007.lzma.bak
2020-09-08T18:02:57.350Z,1599588177.350 [DataOverHttps](IMPORTANT): SBD MOMSN=12646453, MTMSN=20200908T180251
2020-09-08T18:03:05.139Z,1599588185.139 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.NeedCommsTime 40 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 30 meter" pgsq 1 2
2020-09-08T18:03:05.200Z,1599588185.200 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.NeedCommsTime 40 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 30 meter" pgsq 1 2.000000
2020-09-08T18:03:05.200Z,1599588185.200 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=pgsq
2020-09-08T18:03:05.201Z,1599588185.201 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='pgsq'): "load Science/profile_station.xml;set profile_station.NeedCommsTime 40 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 30 meter" ASAP
2020-09-08T18:03:06.331Z,1599588186.331 [DataOverHttps](IMPORTANT): SBD MTMSN=20200908T180259
2020-09-08T18:03:16.727Z,1599588196.727 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200908T175606/Courier0000.lzma
2020-09-08T18:03:16.729Z,1599588196.729 [DataOverHttps](INFO): Received command:sched asap "set profile_station.MaxDepth 35 meter;run " pgsq 2 2
2020-09-08T18:03:16.816Z,1599588196.816 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.MaxDepth 35 meter;run " pgsq 2 2.000000
2020-09-08T18:03:16.817Z,1599588196.817 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=pgsq
2020-09-08T18:03:16.818Z,1599588196.818 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='pgsq'): "set profile_station.MaxDepth 35 meter;run " ASAP
2020-09-08T18:03:16.997Z,1599588196.997 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2020-09-08T18:03:16.998Z,1599588196.998 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2020-09-08T18:03:17.092Z,1599588197.092 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2020-09-08T18:03:17.095Z,1599588197.095 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2020-09-08T18:03:17.098Z,1599588197.098 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2020-09-08T18:03:17.102Z,1599588197.102 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2020-09-08T18:03:17.105Z,1599588197.105 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2020-09-08T18:03:17.108Z,1599588197.108 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2020-09-08T18:03:17.111Z,1599588197.111 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2020-09-08T18:03:17.114Z,1599588197.114 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2020-09-08T18:03:17.117Z,1599588197.117 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2020-09-08T18:03:17.120Z,1599588197.120 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2020-09-08T18:03:17.159Z,1599588197.159 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2020-09-08T18:03:17.162Z,1599588197.162 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2020-09-08T18:03:17.165Z,1599588197.165 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2020-09-08T18:03:17.181Z,1599588197.181 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2020-09-08T18:03:17.184Z,1599588197.184 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2020-09-08T18:03:17.187Z,1599588197.187 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2020-09-08T18:03:17.187Z,1599588197.187 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-09-08T18:03:17.623Z,1599588197.623 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2020-09-08T18:03:17.626Z,1599588197.626 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2020-09-08T18:03:17.633Z,1599588197.633 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2020-09-08T18:03:17.637Z,1599588197.637 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2020-09-08T18:03:17.695Z,1599588197.695 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2020-09-08T18:03:17.698Z,1599588197.698 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2020-09-08T18:03:17.715Z,1599588197.715 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2020-09-08T18:03:17.721Z,1599588197.721 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2020-09-08T18:03:17.724Z,1599588197.724 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2020-09-08T18:03:17.751Z,1599588197.751 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2020-09-08T18:03:17.759Z,1599588197.759 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-09-08T18:03:17.779Z,1599588197.779 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2020-09-08T18:03:17.791Z,1599588197.791 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2020-09-08T18:03:17.794Z,1599588197.794 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2020-09-08T18:03:17.819Z,1599588197.819 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-09-08T18:03:17.835Z,1599588197.835 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-09-08T18:03:17.847Z,1599588197.847 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-09-08T18:03:17.850Z,1599588197.850 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2020-09-08T18:03:17.868Z,1599588197.868 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2020-09-08T18:03:17.883Z,1599588197.883 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2020-09-08T18:03:17.909Z,1599588197.909 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2020-09-08T18:03:17.922Z,1599588197.922 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2020-09-08T18:03:17.933Z,1599588197.933 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2020-09-08T18:03:17.941Z,1599588197.941 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2020-09-08T18:03:17.966Z,1599588197.966 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2020-09-08T18:03:17.969Z,1599588197.969 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2020-09-08T18:03:17.989Z,1599588197.989 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2020-09-08T18:03:17.000Z,1599588198.000 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2020-09-08T18:03:18.015Z,1599588198.015 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2020-09-08T18:03:18.036Z,1599588198.036 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2020-09-08T18:03:18.044Z,1599588198.044 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2020-09-08T18:03:18.091Z,1599588198.091 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2020-09-08T18:03:18.094Z,1599588198.094 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2020-09-08T18:03:18.113Z,1599588198.113 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2020-09-08T18:03:18.125Z,1599588198.125 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2020-09-08T18:03:18.136Z,1599588198.136 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2020-09-08T18:03:18.168Z,1599588198.168 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2020-09-08T18:03:18.180Z,1599588198.180 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2020-09-08T18:03:18.191Z,1599588198.191 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2020-09-08T18:03:18.194Z,1599588198.194 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2020-09-08T18:03:18.214Z,1599588198.214 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2020-09-08T18:03:18.225Z,1599588198.225 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2020-09-08T18:03:18.233Z,1599588198.233 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2020-09-08T18:03:18.248Z,1599588198.248 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2020-09-08T18:03:18.259Z,1599588198.259 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2020-09-08T18:03:18.262Z,1599588198.262 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2020-09-08T18:03:18.345Z,1599588198.345 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175606/Courier0000.lzma.bak
2020-09-08T18:03:18.345Z,1599588198.345 [DataOverHttps](INFO): SBD MOMSN=12646459
2020-09-08T18:03:18.453Z,1599588198.453 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-08T18:03:18.541Z,1599588198.541 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-09-08T18:03:18.602Z,1599588198.602 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-08T18:03:18.625Z,1599588198.625 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-08T18:03:18.679Z,1599588198.679 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-09-08T18:03:18.701Z,1599588198.701 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2020-09-08T18:03:18.702Z,1599588198.702 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-09-08T18:03:18.949Z,1599588198.949 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2020-09-08T18:03:18.972Z,1599588198.972 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2020-09-08T18:03:18.995Z,1599588198.995 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-09-08T18:03:18.998Z,1599588198.998 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2020-09-08T18:03:19.035Z,1599588199.035 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-09-08T18:03:19.038Z,1599588199.038 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2020-09-08T18:03:19.054Z,1599588199.054 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2020-09-08T18:03:19.092Z,1599588199.092 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-09-08T18:03:19.191Z,1599588199.191 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-09-08T18:03:19.260Z,1599588199.260 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2020-09-08T18:03:19.303Z,1599588199.303 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2020-09-08T18:03:19.319Z,1599588199.319 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-09-08T18:03:19.320Z,1599588199.320 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-09-08T18:03:19.360Z,1599588199.360 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-09-08T18:03:19.393Z,1599588199.393 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-09-08T18:03:19.455Z,1599588199.455 [profile_station:I.Pitch](DEBUG): Construct.
2020-09-08T18:03:19.466Z,1599588199.466 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2020-09-08T18:03:19.474Z,1599588199.474 [profile_station:K.SetSpeed](DEBUG): Construct.
2020-09-08T18:03:19.520Z,1599588199.520 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-09-08T18:03:19.533Z,1599588199.533 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-09-08T18:03:19.570Z,1599588199.570 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2020-09-08T18:03:19.585Z,1599588199.585 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2020-09-08T18:03:19.749Z,1599588199.749 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

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

<!-- Mission-wide settings -->

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

    <Timeout Duration="MissionTimeout"/>

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>
    
    <ReadData Strategy="AllDevices">
        <AMEcho.altitude/>
    </ReadData>

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

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

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

    </Aggregate>

</Mission>


2020-09-08T18:03:19.749Z,1599588199.749 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2020-09-08T18:03:31.774Z,1599588211.774 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 40.000000 minute
2020-09-08T18:03:31.775Z,1599588211.775 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.797001 degree
2020-09-08T18:03:31.776Z,1599588211.776 [CommandLine](IMPORTANT): got command set profile_station.Lon -121.847000 degree
2020-09-08T18:03:31.776Z,1599588211.776 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 30.000000 meter
2020-09-08T18:03:34.857Z,1599588214.857 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 35.000000 meter
2020-09-08T18:03:34.857Z,1599588214.857 [CommandLine](IMPORTANT): got command run 
2020-09-08T18:03:35.058Z,1599588215.058 [Default] Stopped
2020-09-08T18:03:35.063Z,1599588215.063 [Default](DEBUG): Aggregate::uninitialize Default
2020-09-08T18:03:35.063Z,1599588215.063 [Default:B.GoToSurface] Stopped
2020-09-08T18:03:35.063Z,1599588215.063 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-08T18:03:35.063Z,1599588215.063 [Default:CheckIn] Stopped
2020-09-08T18:03:35.063Z,1599588215.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-08T18:03:35.063Z,1599588215.063 [Default:CheckIn:Read_Iridium] Stopped
2020-09-08T18:03:35.063Z,1599588215.063 [MissionManager](IMPORTANT): Started mission profile_station
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station](DEBUG): Aggregate::initialize profile_station
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:A] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:B] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:C] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:D] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:E] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:F] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:Read_Oil] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-09-08T18:03:35.064Z,1599588215.064 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2020-09-08T18:03:35.065Z,1599588215.065 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-09-08T18:03:35.065Z,1599588215.065 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-08T18:03:35.071Z,1599588215.071 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2020-09-08T18:03:35.071Z,1599588215.071 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2020-09-08T18:03:35.071Z,1599588215.071 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-09-08T18:03:35.071Z,1599588215.071 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-09-08T18:03:35.072Z,1599588215.072 [profile_station:Science:PeakDetectNO3] Running Loop=1
2020-09-08T18:03:35.072Z,1599588215.072 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2020-09-08T18:03:35.072Z,1599588215.072 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-09-08T18:03:35.072Z,1599588215.072 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-08T18:03:35.074Z,1599588215.074 [profile_station:Science:PeakDetectOil] Running Loop=1
2020-09-08T18:03:35.074Z,1599588215.074 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2020-09-08T18:03:35.074Z,1599588215.074 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-09-08T18:03:35.074Z,1599588215.074 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-08T18:03:35.081Z,1599588215.081 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2020-09-08T18:03:35.081Z,1599588215.081 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2020-09-08T18:03:35.081Z,1599588215.081 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-09-08T18:03:35.081Z,1599588215.081 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-09-08T18:03:35.087Z,1599588215.087 [profile_station:Science:OceanCurrent] Running Loop=1
2020-09-08T18:03:35.087Z,1599588215.087 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::initialize profile_station:Science:OceanCurrent
2020-09-08T18:03:35.087Z,1599588215.087 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2020-09-08T18:03:35.087Z,1599588215.087 [profile_station:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2020-09-08T18:03:35.088Z,1599588215.088 [profile_station:StandardEnvelopes] Running Loop=1
2020-09-08T18:03:35.088Z,1599588215.088 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2020-09-08T18:03:35.088Z,1599588215.088 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-09-08T18:03:35.088Z,1599588215.088 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-09-08T18:03:35.088Z,1599588215.088 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-09-08T18:03:35.088Z,1599588215.088 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-09-08T18:03:35.088Z,1599588215.088 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-09-08T18:03:35.088Z,1599588215.088 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-09-08T18:03:35.089Z,1599588215.089 [profile_station:I.Pitch] Running Loop=1
2020-09-08T18:03:35.089Z,1599588215.089 [profile_station:I.Pitch](DEBUG): Initialize.
2020-09-08T18:03:35.089Z,1599588215.089 [profile_station:J.Buoyancy] Running Loop=1
2020-09-08T18:03:35.089Z,1599588215.089 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-09-08T18:03:35.089Z,1599588215.089 [profile_station:K.SetSpeed] Running Loop=1
2020-09-08T18:03:35.089Z,1599588215.089 [profile_station:K.SetSpeed](DEBUG): Initialize.
2020-09-08T18:03:35.089Z,1599588215.089 [profile_station:L.DepthEnvelope] Running Loop=1
2020-09-08T18:03:35.089Z,1599588215.089 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-09-08T18:03:35.090Z,1599588215.090 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-09-08T18:03:35.090Z,1599588215.090 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-09-08T18:03:35.090Z,1599588215.090 [profile_station:N.YoYo] Running Loop=1
2020-09-08T18:03:35.090Z,1599588215.090 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2020-09-08T18:03:35.090Z,1599588215.090 [profile_station:O] Running Loop=1
2020-09-08T18:03:35.090Z,1599588215.090 [profile_station:C] Running Loop=1
2020-09-08T18:03:35.095Z,1599588215.095 [profile_station:O] Running Loop=1
2020-09-08T18:03:35.096Z,1599588215.096 [profile_station:O](DEBUG): Initialize ReadDataComponent to sense AMEcho.altitude
2020-09-08T18:03:35.096Z,1599588215.096 [profile_station:N.YoYo] Running Loop=1
2020-09-08T18:03:35.096Z,1599588215.096 [profile_station:M.AltitudeEnvelope] Running Loop=1
2020-09-08T18:03:35.097Z,1599588215.097 [profile_station:L.DepthEnvelope] Running Loop=1
2020-09-08T18:03:35.098Z,1599588215.098 [profile_station:K.SetSpeed] Running Loop=1
2020-09-08T18:03:35.103Z,1599588215.103 [profile_station:J.Buoyancy] Running Loop=1
2020-09-08T18:03:35.103Z,1599588215.103 [profile_station:I.Pitch] Running Loop=1
2020-09-08T18:03:35.103Z,1599588215.103 [profile_station:StandardEnvelopes] Running Loop=1
2020-09-08T18:03:35.104Z,1599588215.104 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-09-08T18:03:35.104Z,1599588215.104 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-09-08T18:03:35.104Z,1599588215.104 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-09-08T18:03:35.105Z,1599588215.105 [profile_station:C] Stopped
2020-09-08T18:03:35.105Z,1599588215.105 [profile_station:E] Running Loop=1
2020-09-08T18:03:35.105Z,1599588215.105 [profile_station:Science] Running Loop=1
2020-09-08T18:03:35.106Z,1599588215.106 [profile_station:Science:PeakDetectFDOM] Stopped
2020-09-08T18:03:35.106Z,1599588215.106 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2020-09-08T18:03:35.106Z,1599588215.106 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-09-08T18:03:35.106Z,1599588215.106 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-08T18:03:35.106Z,1599588215.106 [profile_station:Science:PeakDetectOil] Stopped
2020-09-08T18:03:35.106Z,1599588215.106 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:PeakDetectNO3] Stopped
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:HighestChlPeakReport] Stopped
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-09-08T18:03:35.111Z,1599588215.111 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-09-08T18:03:35.112Z,1599588215.112 [profile_station:Science:PeakDetectChl] Running Loop=1
2020-09-08T18:03:35.112Z,1599588215.112 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2020-09-08T18:03:35.120Z,1599588215.120 [profile_station:Science:Read_Oil] Stopped
2020-09-08T18:03:35.120Z,1599588215.120 [profile_station:Science:F] Stopped
2020-09-08T18:03:35.120Z,1599588215.120 [profile_station:Science:E] Running Loop=1
2020-09-08T18:03:35.121Z,1599588215.121 [profile_station:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-09-08T18:03:35.121Z,1599588215.121 [profile_station:Science:D] Stopped
2020-09-08T18:03:35.121Z,1599588215.121 [profile_station:Science:C] Running Loop=1
2020-09-08T18:03:35.122Z,1599588215.122 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-09-08T18:03:35.122Z,1599588215.122 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-09-08T18:03:35.122Z,1599588215.122 [profile_station:Science:B] Running Loop=1
2020-09-08T18:03:35.127Z,1599588215.127 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-09-08T18:03:35.128Z,1599588215.128 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-09-08T18:03:35.128Z,1599588215.128 [profile_station:Science:A] Stopped
2020-09-08T18:03:35.243Z,1599588215.243 [CommandLine](IMPORTANT): Running 
2020-09-08T18:03:35.452Z,1599588215.452 [profile_station:E] Stopped
2020-09-08T18:03:35.453Z,1599588215.453 [profile_station:F] Running Loop=1
2020-09-08T18:03:35.548Z,1599588215.548 [WetLabsBB2FL](INFO): Powering up
2020-09-08T18:03:35.854Z,1599588215.854 [AMEcho](INFO): Powering up
2020-09-08T18:03:35.936Z,1599588215.936 [profile_station:F] Stopped
2020-09-08T18:03:35.936Z,1599588215.936 [profile_station:G] Running Loop=1
2020-09-08T18:03:36.315Z,1599588216.315 [profile_station:G] Stopped
2020-09-08T18:03:36.316Z,1599588216.316 [profile_station:StartingMission] Running Loop=1
2020-09-08T18:03:36.316Z,1599588216.316 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2020-09-08T18:03:36.677Z,1599588216.677 [profile_station:NeedComms] Running Loop=1
2020-09-08T18:03:36.677Z,1599588216.677 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2020-09-08T18:03:36.677Z,1599588216.677 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2020-09-08T18:03:36.677Z,1599588216.677 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-08T18:03:36.677Z,1599588216.677 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2020-09-08T18:03:36.678Z,1599588216.678 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2020-09-08T18:03:36.678Z,1599588216.678 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2020-09-08T18:03:36.678Z,1599588216.678 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-08T18:03:36.692Z,1599588216.692 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-08T18:03:36.693Z,1599588216.693 [profile_station:NeedComms:A] Running Loop=1
2020-09-08T18:03:36.723Z,1599588216.723 [profile_station:NeedComms:A](INFO): last time_fix was: 1599588120.000000 second since 1970/01/01T00:00:00Z 
2020-09-08T18:03:36.724Z,1599588216.724 [profile_station:NeedComms:A] Stopped
2020-09-08T18:03:37.113Z,1599588217.113 [profile_station:NeedComms:C] Running Loop=1
2020-09-08T18:03:37.511Z,1599588217.511 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-09-08T18:03:38.160Z,1599588218.160 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2020-09-08T18:03:39.086Z,1599588219.086 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180333.00,A,3648.65861,N,12149.45858,W,0.505,26.02,080920,,,A*4D
2020-09-08T18:03:39.088Z,1599588219.088 [NAL9602](INFO): GPS fix at 20200908T180333: (36.810977, -121.824310)
2020-09-08T18:03:39.174Z,1599588219.174 [profile_station:NeedComms:C] Stopped
2020-09-08T18:03:39.174Z,1599588219.174 [profile_station:NeedComms:D] Running Loop=1
2020-09-08T18:03:39.518Z,1599588219.518 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-09-08T18:03:40.427Z,1599588220.427 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20200908T175606/Courier0004.lzma
2020-09-08T18:03:41.431Z,1599588221.431 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175606/Courier0004.lzma.bak
2020-09-08T18:03:41.431Z,1599588221.431 [DataOverHttps](INFO): SBD MOMSN=12646466
2020-09-08T18:04:02.702Z,1599588242.702 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200908T175606/Courier0007.lzma
2020-09-08T18:04:03.704Z,1599588243.704 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175606/Courier0007.lzma.bak
2020-09-08T18:04:03.705Z,1599588243.705 [DataOverHttps](INFO): SBD MOMSN=12646469
2020-09-08T18:04:09.848Z,1599588249.848 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-09-08T18:04:09.923Z,1599588249.923 [NAL9602](FAULT): received: 
+CSQ:0

OK47, 2, 0, 0, 0

OK
2020-09-08T18:04:09.923Z,1599588249.923 [NAL9602] Data Fault, FailCount= 1
2020-09-08T18:04:09.923Z,1599588249.923 [NAL9602](ERROR): Data Fault
2020-09-08T18:04:10.071Z,1599588250.071 [CBIT](ERROR): Data Fault in component: NAL9602
2020-09-08T18:04:10.260Z,1599588250.260 [NAL9602](INFO): Powering down
2020-09-08T18:04:11.109Z,1599588251.109 [CBIT](INFO): Clearing failed state for component NAL9602
2020-09-08T18:04:11.109Z,1599588251.109 [NAL9602] No Fault, FailCount= 1
2020-09-08T18:04:21.064Z,1599588261.064 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200908T175606/Courier0010.lzma
2020-09-08T18:04:22.065Z,1599588262.065 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175606/Courier0010.lzma.bak
2020-09-08T18:04:22.065Z,1599588262.065 [DataOverHttps](INFO): SBD MOMSN=12646471
2020-09-08T18:04:39.275Z,1599588279.275 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T175606/Courier0013.lzma
2020-09-08T18:04:40.276Z,1599588280.276 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175606/Courier0013.lzma.bak
2020-09-08T18:04:40.277Z,1599588280.277 [DataOverHttps](INFO): SBD MOMSN=12646473
2020-09-08T18:04:40.560Z,1599588280.560 [NAL9602](INFO): Powering up NAL9602
2020-09-08T18:04:51.420Z,1599588291.420 [NAL9602](INFO): NAL9602 initialized
2020-09-08T18:04:58.456Z,1599588298.456 [DataOverHttps](INFO): Sending 391 bytes from file Logs/20200908T163749/Express0034.lzma
2020-09-08T18:04:59.457Z,1599588299.457 [DataOverHttps](INFO): Moved sent file to Logs/20200908T163749/Express0034.lzma.bak
2020-09-08T18:04:59.457Z,1599588299.457 [DataOverHttps](INFO): SBD MOMSN=12646476
2020-09-08T18:05:16.496Z,1599588316.496 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20200908T163749/Express0037.lzma
2020-09-08T18:05:17.497Z,1599588317.497 [DataOverHttps](INFO): Moved sent file to Logs/20200908T163749/Express0037.lzma.bak
2020-09-08T18:05:17.497Z,1599588317.497 [DataOverHttps](INFO): SBD MOMSN=12646487
2020-09-08T18:05:22.536Z,1599588322.536 [NAL9602](INFO): Not Powering down - fast GPS
2020-09-08T18:05:23.760Z,1599588323.760 [CommandLine](IMPORTANT): got command stop
2020-09-08T18:05:23.760Z,1599588323.760 [CommandLine](IMPORTANT): Scheduling is paused
2020-09-08T18:05:23.760Z,1599588323.760 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-09-08T18:05:23.789Z,1599588323.789 [MissionManager](INFO): MissionManager is completed.
2020-09-08T18:05:23.789Z,1599588323.789 [MissionManager](INFO): Uninitializing Mission profile_station
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station] Stopped
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station:Science] Stopped
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station:Science:B] Stopped
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station:Science:C] Stopped
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station:Science:E] Stopped
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station:Science:OceanCurrent] Stopped
2020-09-08T18:05:23.789Z,1599588323.789 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:Science:OceanCurrent:A.] Stopped
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:Science:PeakDetectChl] Stopped
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:NeedComms] Stopped
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:NeedComms:B.GoToSurface] Stopped
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:NeedComms:D] Stopped
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:StandardEnvelopes] Stopped
2020-09-08T18:05:23.790Z,1599588323.790 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2020-09-08T18:05:23.799Z,1599588323.799 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-09-08T18:05:23.799Z,1599588323.799 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-09-08T18:05:23.799Z,1599588323.799 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-09-08T18:05:23.799Z,1599588323.799 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-09-08T18:05:23.799Z,1599588323.799 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:StartingMission] Stopped
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:I.Pitch] Stopped
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:J.Buoyancy] Stopped
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:K.SetSpeed] Stopped
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2020-09-08T18:05:23.800Z,1599588323.800 [profile_station:L.DepthEnvelope] Stopped
2020-09-08T18:05:23.801Z,1599588323.801 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2020-09-08T18:05:23.801Z,1599588323.801 [profile_station:M.AltitudeEnvelope] Stopped
2020-09-08T18:05:23.801Z,1599588323.801 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-09-08T18:05:23.801Z,1599588323.801 [profile_station:N.YoYo] Stopped
2020-09-08T18:05:23.801Z,1599588323.801 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2020-09-08T18:05:23.801Z,1599588323.801 [profile_station:O] Stopped
2020-09-08T18:05:24.055Z,1599588324.055 [WetLabsBB2FL](INFO): Powering down
2020-09-08T18:05:24.273Z,1599588324.273 [MissionManager](IMPORTANT): Started mission Default
2020-09-08T18:05:24.274Z,1599588324.274 [Default] Running Loop=1
2020-09-08T18:05:24.274Z,1599588324.274 [Default](DEBUG): Aggregate::initialize Default
2020-09-08T18:05:24.274Z,1599588324.274 [Default:B.GoToSurface] Running Loop=1
2020-09-08T18:05:24.274Z,1599588324.274 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-09-08T18:05:24.274Z,1599588324.274 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-09-08T18:05:24.274Z,1599588324.274 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-09-08T18:05:24.287Z,1599588324.287 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-09-08T18:05:24.287Z,1599588324.287 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-09-08T18:05:24.288Z,1599588324.288 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-09-08T18:05:25.235Z,1599588325.235 [Default:A.Wait] Running Loop=1
2020-09-08T18:05:25.235Z,1599588325.235 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-09-08T18:05:25.507Z,1599588325.507 [AMEcho](INFO): Powering down
2020-09-08T18:05:28.675Z,1599588328.675 [CommandLine](IMPORTANT): got command configSet list
2020-09-08T18:05:28.675Z,1599588328.675 [CommandLine](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2020-09-08T18:05:35.255Z,1599588335.255 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20200908T163749/Express0040.lzma
2020-09-08T18:05:36.256Z,1599588336.256 [DataOverHttps](INFO): Moved sent file to Logs/20200908T163749/Express0040.lzma.bak
2020-09-08T18:05:36.257Z,1599588336.257 [DataOverHttps](INFO): SBD MOMSN=12646494
2020-09-08T18:05:38.602Z,1599588338.602 [Default:A.Wait](INFO): Done Waiting.
2020-09-08T18:05:38.602Z,1599588338.602 [Default:A.Wait] Stopped
2020-09-08T18:05:38.602Z,1599588338.602 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-09-08T18:05:39.023Z,1599588339.023 [Default:CheckIn] Running Loop=1
2020-09-08T18:05:39.023Z,1599588339.023 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-09-08T18:05:39.023Z,1599588339.023 [Default:CheckIn:Read_GPS] Running Loop=1
2020-09-08T18:05:41.022Z,1599588341.022 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180534.00,A,3648.66298,N,12149.45249,W,0.952,0.00,080920,,,A*71
2020-09-08T18:05:41.024Z,1599588341.024 [NAL9602](INFO): GPS fix at 20200908T180534: (36.811050, -121.824208)
2020-09-08T18:05:41.079Z,1599588341.079 [Default:CheckIn:Read_GPS] Stopped
2020-09-08T18:05:41.079Z,1599588341.079 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-09-08T18:05:55.561Z,1599588355.561 [NAL9602](INFO): SBD MO Status=0, MOMSN=9547, MT Status=0, MTMSN=0
2020-09-08T18:05:55.561Z,1599588355.561 [NAL9602](INFO): No messages in MT queue
2020-09-08T18:05:58.566Z,1599588358.566 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200908T175606/Courier0016.lzma
2020-09-08T18:05:59.568Z,1599588359.568 [DataOverHttps](INFO): Moved sent file to Logs/20200908T175606/Courier0016.lzma.bak
2020-09-08T18:05:59.569Z,1599588359.569 [DataOverHttps](INFO): SBD MOMSN=12646508
2020-09-08T18:06:04.394Z,1599588364.394 [CommandLine](IMPORTANT): got command restart system
2020-09-08T18:06:06.499Z,1599588366.499 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:06.499Z,1599588366.499 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:06.699Z,1599588366.699 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-09-08T18:06:06.700Z,1599588366.700 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:06.703Z,1599588366.703 [CommandLine](INFO): Join timeout helper Thread ID is 1018
2020-09-08T18:06:06.705Z,1599588366.705 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-09-08T18:06:06.705Z,1599588366.705 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:06.705Z,1599588366.705 [NavChartDb](INFO): Join timeout helper Thread ID is 1019
2020-09-08T18:06:06.807Z,1599588366.807 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:06.807Z,1599588366.807 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:06.824Z,1599588366.824 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-09-08T18:06:06.824Z,1599588366.824 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:06.824Z,1599588366.824 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1020
2020-09-08T18:06:06.827Z,1599588366.827 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:06.828Z,1599588366.828 [WetLabsBB2FL](INFO): Powering down
2020-09-08T18:06:06.828Z,1599588366.828 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:06.830Z,1599588366.830 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-09-08T18:06:06.830Z,1599588366.830 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:06.830Z,1599588366.830 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1021
2020-09-08T18:06:07.795Z,1599588367.795 [CTD_Seabird](INFO): Powering down
2020-09-08T18:06:07.807Z,1599588367.807 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:07.807Z,1599588367.807 [CTD_Seabird](INFO): Powering down
2020-09-08T18:06:07.819Z,1599588367.819 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:07.835Z,1599588367.835 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-09-08T18:06:07.835Z,1599588367.835 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:07.835Z,1599588367.835 [BackSeatDriver](INFO): Join timeout helper Thread ID is 1022
2020-09-08T18:06:08.163Z,1599588368.163 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:08.163Z,1599588368.163 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:08.180Z,1599588368.180 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-09-08T18:06:08.180Z,1599588368.180 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:08.180Z,1599588368.180 [Radio_Surface](INFO): Join timeout helper Thread ID is 1023
2020-09-08T18:06:08.563Z,1599588368.563 [Radio_Surface](INFO): Powering down
2020-09-08T18:06:08.564Z,1599588368.564 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:08.564Z,1599588368.564 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:08.567Z,1599588368.567 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-09-08T18:06:08.568Z,1599588368.568 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:08.568Z,1599588368.568 [Onboard](INFO): Join timeout helper Thread ID is 1024
2020-09-08T18:06:09.815Z,1599588369.815 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:09.815Z,1599588369.815 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:09.827Z,1599588369.827 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-09-08T18:06:09.828Z,1599588369.828 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:09.828Z,1599588369.828 [DataOverHttps](INFO): Join timeout helper Thread ID is 1025
2020-09-08T18:06:10.567Z,1599588370.567 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:10.572Z,1599588370.572 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:10.588Z,1599588370.588 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-09-08T18:06:10.588Z,1599588370.588 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:10.588Z,1599588370.588 [logger](INFO): Join timeout helper Thread ID is 1026
2020-09-08T18:06:10.607Z,1599588370.607 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:10.607Z,1599588370.607 [logger ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:10.627Z,1599588370.627 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-09-08T18:06:10.627Z,1599588370.627 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:10.628Z,1599588370.628 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-09-08T18:06:10.628Z,1599588370.628 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:10.628Z,1599588370.628 [controlThread](INFO): Join timeout helper Thread ID is 1027
2020-09-08T18:06:10.896Z,1599588370.896 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-09-08T18:06:10.896Z,1599588370.896 [controlThread](DEBUG): Uninitializing ControlThread
2020-09-08T18:06:10.898Z,1599588370.898 [AHRS_M2](INFO): Powering down
2020-09-08T18:06:10.971Z,1599588370.971 [NAL9602](INFO): Powering down
2020-09-08T18:06:11.043Z,1599588371.043 [RDI_Pathfinder](INFO): Powering down
2020-09-08T18:06:11.044Z,1599588371.044 [DAT](INFO): Powering down
2020-09-08T18:06:11.164Z,1599588371.164 [AMEcho](INFO): Powering down
2020-09-08T18:06:11.165Z,1599588371.165 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-09-08T18:06:11.166Z,1599588371.166 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-09-08T18:06:11.166Z,1599588371.166 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-09-08T18:06:11.167Z,1599588371.167 [MissionManager](INFO): Uninitializing Mission Default
2020-09-08T18:06:11.167Z,1599588371.167 [Default] Stopped
2020-09-08T18:06:11.167Z,1599588371.167 [Default](DEBUG): Aggregate::uninitialize Default
2020-09-08T18:06:11.167Z,1599588371.167 [Default:B.GoToSurface] Stopped
2020-09-08T18:06:11.167Z,1599588371.167 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-09-08T18:06:11.167Z,1599588371.167 [Default:CheckIn] Stopped
2020-09-08T18:06:11.168Z,1599588371.168 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-09-08T18:06:11.168Z,1599588371.168 [Default:CheckIn:Read_Iridium] Stopped
2020-09-08T18:06:11.170Z,1599588371.170 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-09-08T18:06:11.170Z,1599588371.170 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-09-08T18:06:11.171Z,1599588371.171 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-09-08T18:06:11.171Z,1599588371.171 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-09-08T18:06:11.171Z,1599588371.171 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-09-08T18:06:11.172Z,1599588371.172 [BuoyancyServo](INFO): Powering down
2020-09-08T18:06:11.183Z,1599588371.183 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-09-08T18:06:11.183Z,1599588371.183 [ElevatorServo](INFO): Powering down
2020-09-08T18:06:11.184Z,1599588371.184 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-09-08T18:06:11.184Z,1599588371.184 [MassServo](INFO): Powering down
2020-09-08T18:06:11.185Z,1599588371.185 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-09-08T18:06:11.185Z,1599588371.185 [RudderServo](INFO): Powering down
2020-09-08T18:06:11.186Z,1599588371.186 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-09-08T18:06:11.186Z,1599588371.186 [ThrusterServo](INFO): Powering down
2020-09-08T18:06:11.187Z,1599588371.187 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-09-08T18:06:11.187Z,1599588371.187 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-09-08T18:06:11.187Z,1599588371.187 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-09-08T18:06:11.187Z,1599588371.187 [CBIT](DEBUG): Powering off loads.
2020-09-08T18:06:11.199Z,1599588371.199 [CBIT](DEBUG): Disabling WDT.
2020-09-08T18:06:11.211Z,1599588371.211 [CBIT](DEBUG): Opening all GF detection circuits.
2020-09-08T18:06:11.211Z,1599588371.211 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:11.317Z,1599588371.317 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:11.320Z,1599588371.320 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:11.326Z,1599588371.326 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:11.385Z,1599588371.385 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:11.389Z,1599588371.389 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:11.392Z,1599588371.392 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:11.401Z,1599588371.401 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-09-08T18:06:11.476Z,1599588371.476 [logger ThreadHandler](INFO): Thread cancelled.