2019-10-17T17:41:52.335Z,1571334112.335 [Supervisor](DEBUG): Initializing supervisor.
2019-10-17T17:41:52.338Z,1571334112.338 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-17T17:41:52.338Z,1571334112.338 [SyncHandler](INFO): Protected caller Thread ID is 1270
2019-10-17T17:41:52.339Z,1571334112.339 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-17T17:41:52.340Z,1571334112.340 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-17T17:41:52.340Z,1571334112.340 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1271
2019-10-17T17:41:52.343Z,1571334112.343 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-17T17:41:52.355Z,1571334112.355 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-17T17:41:52.356Z,1571334112.356 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-17T17:41:52.356Z,1571334112.356 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1272
2019-10-17T17:41:52.357Z,1571334112.357 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-17T17:41:52.358Z,1571334112.358 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-17T17:41:52.358Z,1571334112.358 [logger ThreadHandler](INFO): Protected caller Thread ID is 1273
2019-10-17T17:41:52.360Z,1571334112.360 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-17T17:41:52.360Z,1571334112.360 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-17T17:41:52.362Z,1571334112.362 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-17T17:41:52.573Z,1571334112.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-17T17:41:52.574Z,1571334112.574 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-17T17:41:52.652Z,1571334112.652 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-17T17:41:53.081Z,1571334113.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-17T17:41:53.082Z,1571334113.082 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-17T17:41:53.407Z,1571334113.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-17T17:41:53.407Z,1571334113.407 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-17T17:41:53.501Z,1571334113.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-17T17:41:53.502Z,1571334113.502 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-17T17:41:53.796Z,1571334113.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-17T17:41:53.797Z,1571334113.797 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-17T17:41:53.990Z,1571334113.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-17T17:41:53.990Z,1571334113.990 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-17T17:41:54.436Z,1571334114.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-17T17:41:54.437Z,1571334114.437 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-17T17:41:54.540Z,1571334114.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-17T17:41:54.541Z,1571334114.541 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-17T17:41:54.639Z,1571334114.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-17T17:41:54.640Z,1571334114.640 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-17T17:41:55.234Z,1571334115.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-17T17:41:55.235Z,1571334115.235 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-17T17:41:55.626Z,1571334115.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-17T17:41:55.626Z,1571334115.626 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-17T17:41:55.820Z,1571334115.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-17T17:41:55.821Z,1571334115.821 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-17T17:41:55.964Z,1571334115.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-17T17:41:55.965Z,1571334115.965 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-17T17:41:56.111Z,1571334116.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-17T17:41:56.113Z,1571334116.113 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-10-17T17:41:56.114Z,1571334116.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-10-17T17:41:56.204Z,1571334116.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-10-17T17:41:56.288Z,1571334116.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-10-17T17:41:56.391Z,1571334116.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-10-17T17:41:56.473Z,1571334116.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-10-17T17:41:56.567Z,1571334116.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-10-17T17:41:56.666Z,1571334116.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-10-17T17:41:56.876Z,1571334116.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-10-17T17:41:56.951Z,1571334116.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-10-17T17:41:57.118Z,1571334117.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-10-17T17:41:57.251Z,1571334117.251 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-10-17T17:41:57.379Z,1571334117.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-10-17T17:41:58.429Z,1571334118.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-17T17:41:58.430Z,1571334118.430 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-10-17T17:41:58.431Z,1571334118.431 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-17T17:41:58.436Z,1571334118.436 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-17T17:41:58.583Z,1571334118.583 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-17T17:41:58.741Z,1571334118.741 [VerticalControl] Loaded
2019-10-17T17:41:58.741Z,1571334118.741 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-17T17:41:58.742Z,1571334118.742 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-17T17:41:58.810Z,1571334118.810 [HorizontalControl] Loaded
2019-10-17T17:41:58.810Z,1571334118.810 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-17T17:41:58.811Z,1571334118.811 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-17T17:41:58.816Z,1571334118.816 [SpeedControl] Loaded
2019-10-17T17:41:58.817Z,1571334118.817 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-17T17:41:58.818Z,1571334118.818 [LoopControl](DEBUG): Construct LoopControl.
2019-10-17T17:41:58.818Z,1571334118.818 [LoopControl] Loaded
2019-10-17T17:41:58.818Z,1571334118.818 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-17T17:41:58.819Z,1571334118.819 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-17T17:41:58.819Z,1571334118.819 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-17T17:41:58.832Z,1571334118.832 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-17T17:41:58.833Z,1571334118.833 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-17T17:41:58.931Z,1571334118.931 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-17T17:41:58.932Z,1571334118.932 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-17T17:41:59.058Z,1571334119.058 [BuoyancyServo] Loaded
2019-10-17T17:41:59.059Z,1571334119.059 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-17T17:41:59.070Z,1571334119.070 [ElevatorServo] Loaded
2019-10-17T17:41:59.070Z,1571334119.070 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-17T17:41:59.081Z,1571334119.081 [MassServo] Loaded
2019-10-17T17:41:59.081Z,1571334119.081 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-17T17:41:59.092Z,1571334119.092 [RudderServo] Loaded
2019-10-17T17:41:59.092Z,1571334119.092 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-17T17:41:59.103Z,1571334119.103 [ThrusterServo] Loaded
2019-10-17T17:41:59.103Z,1571334119.103 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-17T17:41:59.104Z,1571334119.104 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-17T17:41:59.104Z,1571334119.104 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-17T17:41:59.211Z,1571334119.211 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-17T17:41:59.211Z,1571334119.211 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-17T17:41:59.231Z,1571334119.231 [NavChart] Loaded
2019-10-17T17:41:59.232Z,1571334119.232 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-17T17:41:59.235Z,1571334119.235 [UniversalFixResidualReporter] Loaded
2019-10-17T17:41:59.236Z,1571334119.236 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-17T17:41:59.236Z,1571334119.236 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-17T17:41:59.236Z,1571334119.236 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-17T17:41:59.308Z,1571334119.308 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-17T17:41:59.309Z,1571334119.309 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-17T17:41:59.567Z,1571334119.567 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-17T17:41:59.572Z,1571334119.572 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-17T17:41:59.574Z,1571334119.574 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-17T17:41:59.579Z,1571334119.579 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-17T17:41:59.580Z,1571334119.580 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-17T17:41:59.584Z,1571334119.584 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-17T17:41:59.585Z,1571334119.585 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-17T17:41:59.590Z,1571334119.590 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-17T17:41:59.661Z,1571334119.661 [AHRS_M2] Loaded
2019-10-17T17:41:59.661Z,1571334119.661 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-17T17:41:59.738Z,1571334119.738 [DataOverHttps] Loaded
2019-10-17T17:41:59.738Z,1571334119.738 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-17T17:41:59.739Z,1571334119.739 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0
2019-10-17T17:41:59.740Z,1571334119.740 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1355
2019-10-17T17:41:59.753Z,1571334119.753 [Depth_Keller] Loaded
2019-10-17T17:41:59.753Z,1571334119.753 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-17T17:41:59.758Z,1571334119.758 [DropWeight] Loaded
2019-10-17T17:41:59.758Z,1571334119.758 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-17T17:41:59.855Z,1571334119.855 [NAL9602] Loaded
2019-10-17T17:41:59.855Z,1571334119.855 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-17T17:41:59.870Z,1571334119.870 [Onboard] Loaded
2019-10-17T17:41:59.871Z,1571334119.871 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-17T17:41:59.874Z,1571334119.874 [Radio_Surface] Loaded
2019-10-17T17:41:59.874Z,1571334119.874 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-17T17:41:59.875Z,1571334119.875 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0
2019-10-17T17:41:59.876Z,1571334119.876 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1356
2019-10-17T17:42:01.374Z,1571334121.374 [BPC1] Loaded
2019-10-17T17:42:01.374Z,1571334121.374 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-17T17:42:01.375Z,1571334121.375 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-17T17:42:01.375Z,1571334121.375 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-17T17:42:01.417Z,1571334121.417 [DepthRateCalculator] Loaded
2019-10-17T17:42:01.417Z,1571334121.417 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-17T17:42:01.422Z,1571334121.422 [PitchRateCalculator] Loaded
2019-10-17T17:42:01.423Z,1571334121.423 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-17T17:42:01.434Z,1571334121.434 [SpeedCalculator] Loaded
2019-10-17T17:42:01.434Z,1571334121.434 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-17T17:42:01.456Z,1571334121.456 [TempGradientCalculator] Loaded
2019-10-17T17:42:01.456Z,1571334121.456 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-17T17:42:01.461Z,1571334121.461 [YawRateCalculator] Loaded
2019-10-17T17:42:01.461Z,1571334121.461 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-17T17:42:01.491Z,1571334121.491 [ElevatorOffsetCalculator] Loaded
2019-10-17T17:42:01.491Z,1571334121.491 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-17T17:42:01.491Z,1571334121.491 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-17T17:42:01.492Z,1571334121.492 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-17T17:42:01.676Z,1571334121.676 [Aanderaa_O2] Loaded
2019-10-17T17:42:01.676Z,1571334121.676 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-10-17T17:42:01.686Z,1571334121.686 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-17T17:42:01.691Z,1571334121.691 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-17T17:42:01.691Z,1571334121.691 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-17T17:42:01.696Z,1571334121.696 [CTD_Seabird](INFO): created writer for : depth
2019-10-17T17:42:01.697Z,1571334121.697 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-17T17:42:01.702Z,1571334121.702 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-17T17:42:01.703Z,1571334121.703 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-17T17:42:01.708Z,1571334121.708 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-17T17:42:01.708Z,1571334121.708 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-17T17:42:01.713Z,1571334121.713 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-17T17:42:01.714Z,1571334121.714 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-17T17:42:01.720Z,1571334121.720 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-17T17:42:01.720Z,1571334121.720 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-17T17:42:01.725Z,1571334121.725 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-17T17:42:01.752Z,1571334121.752 [CTD_Seabird] Loaded
2019-10-17T17:42:01.752Z,1571334121.752 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-17T17:42:01.753Z,1571334121.753 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409B04E0
2019-10-17T17:42:01.754Z,1571334121.754 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1357
2019-10-17T17:42:01.768Z,1571334121.768 [PAR_Licor] Loaded
2019-10-17T17:42:01.768Z,1571334121.768 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-17T17:42:01.817Z,1571334121.817 [WetLabsBB2FL] Loaded
2019-10-17T17:42:01.817Z,1571334121.817 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-17T17:42:01.818Z,1571334121.818 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0
2019-10-17T17:42:01.818Z,1571334121.818 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1358
2019-10-17T17:42:01.819Z,1571334121.819 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-17T17:42:01.820Z,1571334121.820 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-17T17:42:01.853Z,1571334121.853 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-17T17:42:01.853Z,1571334121.853 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-17T17:42:02.143Z,1571334122.143 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-17T17:42:02.143Z,1571334122.143 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-17T17:42:02.275Z,1571334122.275 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-17T17:42:02.285Z,1571334122.285 [SBIT] Loaded
2019-10-17T17:42:02.286Z,1571334122.286 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-17T17:42:02.287Z,1571334122.287 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-17T17:42:02.298Z,1571334122.298 [IBIT] Loaded
2019-10-17T17:42:02.298Z,1571334122.298 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-17T17:42:02.301Z,1571334122.301 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-17T17:42:02.438Z,1571334122.438 [CBIT] Loaded
2019-10-17T17:42:02.439Z,1571334122.439 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-17T17:42:02.439Z,1571334122.439 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-17T17:42:02.448Z,1571334122.448 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-17T17:42:02.448Z,1571334122.448 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-17T17:42:02.491Z,1571334122.491 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-17T17:42:02.492Z,1571334122.492 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2019-10-17T17:42:02.492Z,1571334122.492 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1359
2019-10-17T17:42:02.498Z,1571334122.498 [Supervisor](INFO): Main Thread ID is 995
2019-10-17T17:42:02.498Z,1571334122.498 [Supervisor](DEBUG): Running supervisor.
2019-10-17T17:42:02.498Z,1571334122.498 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1360
2019-10-17T17:42:02.521Z,1571334122.521 [controlThread ThreadHandler](INFO): Handler Thread ID is 1361
2019-10-17T17:42:02.521Z,1571334122.521 [controlThread](DEBUG): Initializing ControlThread
2019-10-17T17:42:02.522Z,1571334122.522 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-17T17:42:02.523Z,1571334122.523 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-17T17:42:02.524Z,1571334122.524 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-17T17:42:02.524Z,1571334122.524 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-17T17:42:02.526Z,1571334122.526 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-17T17:42:02.527Z,1571334122.527 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-17T17:42:02.531Z,1571334122.531 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-17T17:42:02.532Z,1571334122.532 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-17T17:42:02.532Z,1571334122.532 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-17T17:42:02.532Z,1571334122.532 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-17T17:42:02.533Z,1571334122.533 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-17T17:42:02.533Z,1571334122.533 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-17T17:42:02.537Z,1571334122.537 [SBIT](INFO): Initialize SBIT Component.
2019-10-17T17:42:02.538Z,1571334122.538 [SBIT](IMPORTANT): git: 2019-09-10
2019-10-17T17:42:02.538Z,1571334122.538 [SBIT](INFO): git hash: e950883795b57abbf97d89589ea0268241928c93
2019-10-17T17:42:02.538Z,1571334122.538 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-17T17:42:02.539Z,1571334122.539 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-10-17T17:42:02.540Z,1571334122.540 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-10-17T17:42:02.541Z,1571334122.541 [IBIT](INFO): Initialize IBIT Component.
2019-10-17T17:42:02.542Z,1571334122.542 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-17T17:42:02.543Z,1571334122.543 [logger ThreadHandler](INFO): Handler Thread ID is 1362
2019-10-17T17:42:02.544Z,1571334122.544 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1363
2019-10-17T17:42:02.547Z,1571334122.547 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-17T17:42:02.553Z,1571334122.553 [CBIT](DEBUG): Initialized mux pins.
2019-10-17T17:42:02.553Z,1571334122.553 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-17T17:42:02.573Z,1571334122.573 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1364
2019-10-17T17:42:02.575Z,1571334122.575 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1365
2019-10-17T17:42:02.576Z,1571334122.576 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1366
2019-10-17T17:42:02.580Z,1571334122.580 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-17T17:42:02.582Z,1571334122.582 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-17T17:42:02.583Z,1571334122.583 [WetLabsBB2FL](INFO): Powering down
2019-10-17T17:42:02.593Z,1571334122.593 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-17T17:42:02.593Z,1571334122.593 [CBIT](DEBUG): Initializing heartbeat.
2019-10-17T17:42:02.609Z,1571334122.609 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1367
2019-10-17T17:42:02.612Z,1571334122.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-17T17:42:02.612Z,1571334122.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-17T17:42:02.612Z,1571334122.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-17T17:42:02.612Z,1571334122.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-17T17:42:02.613Z,1571334122.613 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-17T17:42:02.613Z,1571334122.613 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-17T17:42:02.613Z,1571334122.613 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-17T17:42:02.613Z,1571334122.613 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-17T17:42:02.613Z,1571334122.613 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-17T17:42:02.614Z,1571334122.614 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-17T17:42:02.614Z,1571334122.614 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-17T17:42:02.614Z,1571334122.614 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-17T17:42:02.614Z,1571334122.614 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-17T17:42:02.614Z,1571334122.614 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-17T17:42:02.614Z,1571334122.614 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-17T17:42:02.615Z,1571334122.615 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-17T17:42:02.665Z,1571334122.665 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-17T17:42:02.665Z,1571334122.665 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-17T17:42:02.701Z,1571334122.701 [CBIT](DEBUG): Backplane powered.
2019-10-17T17:42:02.702Z,1571334122.702 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-17T17:42:02.712Z,1571334122.712 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-17T17:42:02.723Z,1571334122.723 [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>


2019-10-17T17:42:02.723Z,1571334122.723 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-17T17:42:02.803Z,1571334122.803 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-17T17:42:02.804Z,1571334122.804 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-17T17:42:02.806Z,1571334122.806 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-17T17:42:02.836Z,1571334122.836 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-17T17:42:02.855Z,1571334122.855 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-17T17:42:02.860Z,1571334122.860 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-17T17:42:02.880Z,1571334122.880 [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>


2019-10-17T17:42:02.884Z,1571334122.884 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-17T17:42:02.897Z,1571334122.897 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-17T17:42:02.944Z,1571334122.944 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-10-17T17:42:02.977Z,1571334122.977 [Radio_Surface](INFO): Powering up
2019-10-17T17:42:03.019Z,1571334123.019 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-17T17:42:03.065Z,1571334123.065 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-17T17:42:03.077Z,1571334123.077 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-17T17:42:03.078Z,1571334123.078 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-17T17:42:03.085Z,1571334123.085 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-17T17:42:03.086Z,1571334123.086 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-17T17:42:03.093Z,1571334123.093 [MassServo](DEBUG): Initializing MassServo.
2019-10-17T17:42:03.094Z,1571334123.094 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-17T17:42:03.101Z,1571334123.101 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-17T17:42:03.102Z,1571334123.102 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-17T17:42:03.113Z,1571334123.113 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-17T17:42:03.391Z,1571334123.391 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-10-17T17:42:03.391Z,1571334123.391 [DropWeight] Hardware Fault, FailCount= 1
2019-10-17T17:42:03.391Z,1571334123.391 [DropWeight](ERROR): Hardware Fault
2019-10-17T17:42:03.492Z,1571334123.492 [CommandLine](FAULT): Scheduling is paused
2019-10-17T17:42:03.492Z,1571334123.492 [CBIT](INFO): Critical error at 20191017T174203
2019-10-17T17:42:03.493Z,1571334123.493 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-17T17:42:03.495Z,1571334123.495 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-17T17:42:03.496Z,1571334123.496 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-17T17:42:04.077Z,1571334124.077 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-17T17:42:04.077Z,1571334124.077 [RudderServo](FAULT): Rudder failed to initialize
2019-10-17T17:42:04.077Z,1571334124.077 [RudderServo] Communications Fault, FailCount= 1
2019-10-17T17:42:04.077Z,1571334124.077 [RudderServo](ERROR): Communications Fault
2019-10-17T17:42:04.186Z,1571334124.186 [CBIT](INFO): Critical error at 20191017T174203
2019-10-17T17:42:04.188Z,1571334124.188 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-17T17:42:04.378Z,1571334124.378 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-17T17:42:04.378Z,1571334124.378 [RudderServo](INFO): Powering down
2019-10-17T17:42:05.072Z,1571334125.072 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-17T17:42:05.206Z,1571334125.206 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-17T17:42:05.210Z,1571334125.210 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-17T17:42:05.210Z,1571334125.210 [RudderServo] No Fault, FailCount= 1
2019-10-17T17:42:06.425Z,1571334126.425 [Aanderaa_O2](INFO): Powering down
2019-10-17T17:42:26.092Z,1571334146.092 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-17T17:42:26.104Z,1571334146.104 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-17T17:42:29.906Z,1571334149.906 [NAL9602](INFO): Powering up NAL9602
2019-10-17T17:42:36.843Z,1571334156.843 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.012482
CHAN A1 (24V): 0.000456
CHAN A2 (12V): -0.002429
CHAN A3 (5V): -0.001215
CHAN B0 (3.3V): 0.000079
CHAN B1 (3.15aV): -0.000476
CHAN B2 (3.15bV): -0.000210
CHAN B3 (GND): -0.000050
OPEN: -0.000339
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-17T17:42:40.814Z,1571334160.814 [NAL9602](INFO): NAL9602 initialized
2019-10-17T17:42:41.633Z,1571334161.633 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:43:19.871Z,1571334199.871 [SBIT](IMPORTANT): SBIT PASSED
2019-10-17T17:43:19.942Z,1571334199.942 [CommandLine](IMPORTANT): got command configSet list
2019-10-17T17:43:19.942Z,1571334199.942 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-17T17:43:19.943Z,1571334199.943 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere;

2019-10-17T17:43:19.943Z,1571334199.943 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-10-17T17:43:19.943Z,1571334199.943 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-10-17T17:43:19.943Z,1571334199.943 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-10-17T17:43:20.274Z,1571334200.274 [MissionManager](IMPORTANT): Started mission Startup
2019-10-17T17:43:20.274Z,1571334200.274 [Startup] Running Loop=1
2019-10-17T17:43:20.275Z,1571334200.275 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-17T17:43:20.275Z,1571334200.275 [Startup:A.GoToSurface] Running Loop=1
2019-10-17T17:43:20.275Z,1571334200.275 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-17T17:43:20.275Z,1571334200.275 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-17T17:43:20.276Z,1571334200.276 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-17T17:43:20.276Z,1571334200.276 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-17T17:43:20.276Z,1571334200.276 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-17T17:43:20.301Z,1571334200.301 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-17T17:43:20.302Z,1571334200.302 [Startup:StartupSatComms] Running Loop=1
2019-10-17T17:43:20.303Z,1571334200.303 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-17T17:43:20.303Z,1571334200.303 [Startup:StartupSatComms:A] Running Loop=1
2019-10-17T17:43:20.675Z,1571334200.675 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-17T17:43:58.607Z,1571334238.607 [CommandLine](IMPORTANT): got command gfscan
2019-10-17T17:43:58.677Z,1571334238.677 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-17T17:44:09.562Z,1571334249.562 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.013941
CHAN A1 (24V): 0.000599
CHAN A2 (12V): -0.001992
CHAN A3 (5V): -0.001851
CHAN B0 (3.3V): -0.000243
CHAN B1 (3.15aV): 0.000039
CHAN B2 (3.15bV): -0.000154
CHAN B3 (GND): 0.000624
OPEN: -0.000392
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-17T17:44:20.463Z,1571334260.463 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-17T17:43:20.3Z
2019-10-17T17:44:20.463Z,1571334260.463 [Startup:StartupSatComms:A] Stopped
2019-10-17T17:44:20.463Z,1571334260.463 [Startup:StartupSatComms:B] Running Loop=1
2019-10-17T17:44:20.861Z,1571334260.861 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-17T17:44:46.571Z,1571334286.571 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004933
2019-10-17T17:44:53.985Z,1571334293.985 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20191017T170819/Courier0010.lzma
2019-10-17T17:44:55.990Z,1571334295.990 [DataOverHttps](INFO): Moved sent file to Logs/20191017T170819/Courier0010.lzma.bak
2019-10-17T17:44:55.991Z,1571334295.991 [DataOverHttps](INFO): SBD MOMSN=11920743
2019-10-17T17:45:02.527Z,1571334302.527 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-17T17:45:02.527Z,1571334302.527 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-17T17:45:02.537Z,1571334302.537 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-17T17:45:02.898Z,1571334302.898 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-17T17:45:02.898Z,1571334302.898 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-17T17:45:12.621Z,1571334312.621 [DataOverHttps](INFO): Sending 315 bytes from file Logs/20191017T173826/Courier0000.lzma
2019-10-17T17:45:14.626Z,1571334314.626 [DataOverHttps](INFO): Moved sent file to Logs/20191017T173826/Courier0000.lzma.bak
2019-10-17T17:45:14.626Z,1571334314.626 [DataOverHttps](INFO): SBD MOMSN=11920746
2019-10-17T17:45:20.669Z,1571334320.669 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-17T17:44:20.5Z
2019-10-17T17:45:20.669Z,1571334320.669 [Startup:StartupSatComms:B] Stopped
2019-10-17T17:45:20.669Z,1571334320.669 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-17T17:45:20.669Z,1571334320.669 [Startup:StartupSatComms] Stopped
2019-10-17T17:45:20.669Z,1571334320.669 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-17T17:45:20.670Z,1571334320.670 [Startup](INFO): Completed Startup
2019-10-17T17:45:20.670Z,1571334320.670 [MissionManager](INFO): Startup is completed.
2019-10-17T17:45:20.670Z,1571334320.670 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-17T17:45:20.670Z,1571334320.670 [Startup] Stopped
2019-10-17T17:45:20.671Z,1571334320.671 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-17T17:45:20.671Z,1571334320.671 [Startup:A.GoToSurface] Stopped
2019-10-17T17:45:20.671Z,1571334320.671 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-17T17:45:21.093Z,1571334321.093 [MissionManager](IMPORTANT): Started mission Default
2019-10-17T17:45:21.094Z,1571334321.094 [Default] Running Loop=1
2019-10-17T17:45:21.094Z,1571334321.094 [Default](DEBUG): Aggregate::initialize Default
2019-10-17T17:45:21.094Z,1571334321.094 [Default:B.GoToSurface] Running Loop=1
2019-10-17T17:45:21.094Z,1571334321.094 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-17T17:45:21.094Z,1571334321.094 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-17T17:45:21.094Z,1571334321.094 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-17T17:45:21.095Z,1571334321.095 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-17T17:45:21.095Z,1571334321.095 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-17T17:45:21.095Z,1571334321.095 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-17T17:45:21.096Z,1571334321.096 [Default:A.Wait] Running Loop=1
2019-10-17T17:45:21.096Z,1571334321.096 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-17T17:45:31.964Z,1571334331.964 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20191017T173948/Courier0000.lzma
2019-10-17T17:45:33.970Z,1571334333.970 [DataOverHttps](INFO): Moved sent file to Logs/20191017T173948/Courier0000.lzma.bak
2019-10-17T17:45:33.970Z,1571334333.970 [DataOverHttps](INFO): SBD MOMSN=11920755
2019-10-17T17:45:34.387Z,1571334334.387 [Default:A.Wait](INFO): Done Waiting.
2019-10-17T17:45:34.388Z,1571334334.388 [Default:A.Wait] Stopped
2019-10-17T17:45:34.388Z,1571334334.388 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-17T17:45:34.794Z,1571334334.794 [Default:CheckIn] Running Loop=1
2019-10-17T17:45:34.794Z,1571334334.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-17T17:45:34.795Z,1571334334.795 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-17T17:45:35.074Z,1571334335.074 [CommandLine](IMPORTANT): got command stop
2019-10-17T17:45:35.075Z,1571334335.075 [CommandLine](IMPORTANT): Scheduling is paused
2019-10-17T17:45:35.192Z,1571334335.192 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-17T17:46:38.378Z,1571334398.378 [CommandLine](IMPORTANT): got command show variable par
2019-10-17T17:46:38.404Z,1571334398.404 [CommandLine](IMPORTANT): PAR_Licor.loadControl (none)
2019-10-17T17:46:38.404Z,1571334398.404 [CommandLine](IMPORTANT): PAR_Licor.ad (none)
2019-10-17T17:46:38.404Z,1571334398.404 [CommandLine](IMPORTANT): PAR_Licor.adRes (bit)
2019-10-17T17:46:38.405Z,1571334398.405 [CommandLine](IMPORTANT): PAR_Licor.adTimeout (millisecond)
2019-10-17T17:46:38.405Z,1571334398.405 [CommandLine](IMPORTANT): PAR_Licor.adVref (volt)
2019-10-17T17:46:38.456Z,1571334398.456 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2019-10-17T17:46:38.456Z,1571334398.456 [CommandLine](IMPORTANT): PAR_Licor.simulateHardware (bool)
2019-10-17T17:46:38.457Z,1571334398.457 [CommandLine](IMPORTANT): PAR_Licor.adcCal (microampere_per_count)
2019-10-17T17:46:38.457Z,1571334398.457 [CommandLine](IMPORTANT): PAR_Licor.darkCount (count)
2019-10-17T17:46:38.457Z,1571334398.457 [CommandLine](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter)
2019-10-17T17:46:38.458Z,1571334398.458 [CommandLine](IMPORTANT): PAR_Licor.maxValidPitch (degree)
2019-10-17T17:46:38.458Z,1571334398.458 [CommandLine](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter)
2019-10-17T17:46:38.458Z,1571334398.458 [CommandLine](IMPORTANT): PAR_Licor.minValidPitch (degree)
2019-10-17T17:46:38.459Z,1571334398.459 [CommandLine](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere)
2019-10-17T17:46:38.459Z,1571334398.459 [CommandLine](IMPORTANT): PAR_Licor.serial (none)
2019-10-17T17:46:38.524Z,1571334398.524 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter)
2019-10-17T17:46:38.524Z,1571334398.524 [CommandLine](IMPORTANT): PAR_Licor.adcCount (count)
2019-10-17T17:47:10.039Z,1571334430.039 [CommandLine](IMPORTANT): got command report mod PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water
2019-10-17T17:47:10.195Z,1571334430.195 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.270435 umol/s/m2
2019-10-17T17:47:10.576Z,1571334430.576 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.227520 umol/s/m2
2019-10-17T17:47:10.967Z,1571334430.967 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.246593 umol/s/m2
2019-10-17T17:47:11.379Z,1571334431.379 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.249773 umol/s/m2
2019-10-17T17:47:11.769Z,1571334431.769 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.321301 umol/s/m2
2019-10-17T17:47:12.172Z,1571334432.172 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.268847 umol/s/m2
2019-10-17T17:47:12.587Z,1571334432.587 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.308584 umol/s/m2
2019-10-17T17:47:12.981Z,1571334432.981 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.198908 umol/s/m2
2019-10-17T17:47:13.377Z,1571334433.377 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.245005 umol/s/m2
2019-10-17T17:47:13.783Z,1571334433.783 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.327658 umol/s/m2
2019-10-17T17:47:14.205Z,1571334434.205 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.260900 umol/s/m2
2019-10-17T17:47:14.581Z,1571334434.581 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.313353 umol/s/m2
2019-10-17T17:47:14.993Z,1571334434.993 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.184605 umol/s/m2
2019-10-17T17:47:15.398Z,1571334435.398 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.272026 umol/s/m2
2019-10-17T17:47:15.813Z,1571334435.813 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.302227 umol/s/m2
2019-10-17T17:47:16.197Z,1571334436.197 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.286331 umol/s/m2
2019-10-17T17:47:16.666Z,1571334436.666 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.252951 umol/s/m2
2019-10-17T17:47:17.029Z,1571334437.029 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.214804 umol/s/m2
2019-10-17T17:47:17.437Z,1571334437.437 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.257719 umol/s/m2
2019-10-17T17:47:17.845Z,1571334437.845 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.222752 umol/s/m2
2019-10-17T17:47:18.225Z,1571334438.225 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.173477 umol/s/m2
2019-10-17T17:47:18.637Z,1571334438.637 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.438701 umol/s/m2
2019-10-17T17:47:19.045Z,1571334439.045 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.000000 umol/s/m2
2019-10-17T17:47:19.441Z,1571334439.441 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.025432 umol/s/m2
2019-10-17T17:47:19.841Z,1571334439.841 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.004768 umol/s/m2
2019-10-17T17:47:20.246Z,1571334440.246 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.011126 umol/s/m2
2019-10-17T17:47:20.670Z,1571334440.670 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.074706 umol/s/m2
2019-10-17T17:47:21.044Z,1571334441.044 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.096959 umol/s/m2
2019-10-17T17:47:21.461Z,1571334441.461 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.007947 umol/s/m2
2019-10-17T17:47:21.876Z,1571334441.876 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.006358 umol/s/m2
2019-10-17T17:47:22.325Z,1571334442.325 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.054043 umol/s/m2
2019-10-17T17:47:22.710Z,1571334442.710 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.006358 umol/s/m2
2019-10-17T17:47:23.108Z,1571334443.108 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.036558 umol/s/m2
2019-10-17T17:47:23.484Z,1571334443.484 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.352868 umol/s/m2
2019-10-17T17:47:23.900Z,1571334443.900 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.233877 umol/s/m2
2019-10-17T17:47:24.300Z,1571334444.300 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.270435 umol/s/m2
2019-10-17T17:47:24.693Z,1571334444.693 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.246593 umol/s/m2
2019-10-17T17:47:25.094Z,1571334445.094 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.314941 umol/s/m2
2019-10-17T17:47:25.508Z,1571334445.508 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.254541 umol/s/m2
2019-10-17T17:47:25.920Z,1571334445.920 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.249773 umol/s/m2
2019-10-17T17:47:26.304Z,1571334446.304 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.227520 umol/s/m2
2019-10-17T17:47:26.710Z,1571334446.710 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.170298 umol/s/m2
2019-10-17T17:47:27.136Z,1571334447.136 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.237057 umol/s/m2
2019-10-17T17:47:27.541Z,1571334447.541 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.268847 umol/s/m2
2019-10-17T17:47:27.953Z,1571334447.953 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.259308 umol/s/m2
2019-10-17T17:47:28.341Z,1571334448.341 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.198908 umol/s/m2
2019-10-17T17:47:28.779Z,1571334448.779 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.254541 umol/s/m2
2019-10-17T17:47:29.586Z,1571334449.586 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.195728 umol/s/m2
2019-10-17T17:47:29.993Z,1571334449.993 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.241825 umol/s/m2
2019-10-17T17:47:30.372Z,1571334450.372 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.117844 umol/s/m2
2019-10-17T17:47:30.785Z,1571334450.785 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.197319 umol/s/m2
2019-10-17T17:47:31.168Z,1571334451.168 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.189371 umol/s/m2
2019-10-17T17:47:31.576Z,1571334451.576 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.162352 umol/s/m2
2019-10-17T17:47:31.991Z,1571334451.991 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -34.136028 umol/s/m2
2019-10-17T17:47:32.399Z,1571334452.399 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -139.956757 umol/s/m2
2019-10-17T17:47:32.812Z,1571334452.812 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -295.563721 umol/s/m2
2019-10-17T17:47:33.184Z,1571334453.184 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -200.934616 umol/s/m2
2019-10-17T17:47:33.591Z,1571334453.591 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -239.049149 umol/s/m2
2019-10-17T17:47:33.977Z,1571334453.977 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -240.452682 umol/s/m2
2019-10-17T17:47:34.432Z,1571334454.432 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -213.556824 umol/s/m2
2019-10-17T17:47:34.804Z,1571334454.804 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -250.754211 umol/s/m2
2019-10-17T17:47:35.216Z,1571334455.216 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -362.017395 umol/s/m2
2019-10-17T17:47:35.610Z,1571334455.610 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -298.968384 umol/s/m2
2019-10-17T17:47:36.004Z,1571334456.004 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -268.079712 umol/s/m2
2019-10-17T17:47:36.417Z,1571334456.417 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -264.706818 umol/s/m2
2019-10-17T17:47:36.802Z,1571334456.802 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -244.418472 umol/s/m2
2019-10-17T17:47:37.208Z,1571334457.208 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -229.377060 umol/s/m2
2019-10-17T17:47:37.624Z,1571334457.624 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -249.183762 umol/s/m2
2019-10-17T17:47:38.035Z,1571334458.035 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -242.727249 umol/s/m2
2019-10-17T17:47:38.451Z,1571334458.451 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -282.313660 umol/s/m2
2019-10-17T17:47:38.837Z,1571334458.837 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -150.849579 umol/s/m2
2019-10-17T17:47:39.236Z,1571334459.236 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -349.209229 umol/s/m2
2019-10-17T17:47:39.622Z,1571334459.622 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.225931 umol/s/m2
2019-10-17T17:47:40.045Z,1571334460.045 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.233877 umol/s/m2
2019-10-17T17:47:40.441Z,1571334460.441 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.184605 umol/s/m2
2019-10-17T17:47:40.847Z,1571334460.847 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.257719 umol/s/m2
2019-10-17T17:47:41.312Z,1571334461.312 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.264078 umol/s/m2
2019-10-17T17:47:41.657Z,1571334461.657 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.314941 umol/s/m2
2019-10-17T17:47:42.073Z,1571334462.073 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.265669 umol/s/m2
2019-10-17T17:47:42.471Z,1571334462.471 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.308584 umol/s/m2
2019-10-17T17:47:42.891Z,1571334462.891 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.229109 umol/s/m2
2019-10-17T17:47:43.297Z,1571334463.297 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.310173 umol/s/m2
2019-10-17T17:47:43.685Z,1571334463.685 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.245005 umol/s/m2
2019-10-17T17:47:44.088Z,1571334464.088 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.198908 umol/s/m2
2019-10-17T17:47:44.442Z,1571334464.442 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-17T17:47:44.499Z,1571334464.499 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.241825 umol/s/m2
2019-10-17T17:47:44.901Z,1571334464.901 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.322889 umol/s/m2
2019-10-17T17:47:45.257Z,1571334465.257 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:47:45.304Z,1571334465.304 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.245005 umol/s/m2
2019-10-17T17:47:45.711Z,1571334465.711 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.281563 umol/s/m2
2019-10-17T17:47:46.161Z,1571334466.161 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.264078 umol/s/m2
2019-10-17T17:47:46.505Z,1571334466.505 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.213215 umol/s/m2
2019-10-17T17:47:46.942Z,1571334466.942 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.221163 umol/s/m2
2019-10-17T17:47:47.319Z,1571334467.319 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.232288 umol/s/m2
2019-10-17T17:47:47.733Z,1571334467.733 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.187782 umol/s/m2
2019-10-17T17:47:48.147Z,1571334468.147 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.211624 umol/s/m2
2019-10-17T17:47:48.540Z,1571334468.540 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.286331 umol/s/m2
2019-10-17T17:47:48.940Z,1571334468.940 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.229109 umol/s/m2
2019-10-17T17:47:49.339Z,1571334469.339 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.225931 umol/s/m2
2019-10-17T17:47:49.741Z,1571334469.741 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.206856 umol/s/m2
2019-10-17T17:47:50.115Z,1571334470.115 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:47:50.152Z,1571334470.152 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.314941 umol/s/m2
2019-10-17T17:47:50.565Z,1571334470.565 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.222752 umol/s/m2
2019-10-17T17:47:50.969Z,1571334470.969 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.227520 umol/s/m2
2019-10-17T17:47:51.347Z,1571334471.347 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.205267 umol/s/m2
2019-10-17T17:47:51.754Z,1571334471.754 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.240234 umol/s/m2
2019-10-17T17:47:52.167Z,1571334472.167 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.222752 umol/s/m2
2019-10-17T17:47:52.556Z,1571334472.556 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.173477 umol/s/m2
2019-10-17T17:47:52.945Z,1571334472.945 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:47:53.000Z,1571334473.000 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.211624 umol/s/m2
2019-10-17T17:47:53.434Z,1571334473.434 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.249773 umol/s/m2
2019-10-17T17:47:53.808Z,1571334473.808 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.256130 umol/s/m2
2019-10-17T17:47:54.198Z,1571334474.198 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.155993 umol/s/m2
2019-10-17T17:47:54.609Z,1571334474.609 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.213215 umol/s/m2
2019-10-17T17:47:54.989Z,1571334474.989 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.148045 umol/s/m2
2019-10-17T17:47:55.395Z,1571334475.395 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.214804 umol/s/m2
2019-10-17T17:47:55.761Z,1571334475.761 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:47:55.826Z,1571334475.826 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.194139 umol/s/m2
2019-10-17T17:47:56.230Z,1571334476.230 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.163940 umol/s/m2
2019-10-17T17:47:56.613Z,1571334476.613 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.241825 umol/s/m2
2019-10-17T17:47:57.011Z,1571334477.011 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.249773 umol/s/m2
2019-10-17T17:47:57.424Z,1571334477.424 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.176655 umol/s/m2
2019-10-17T17:47:57.785Z,1571334477.785 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:47:57.843Z,1571334477.843 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.221163 umol/s/m2
2019-10-17T17:47:58.232Z,1571334478.232 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.202089 umol/s/m2
2019-10-17T17:47:58.685Z,1571334478.685 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.265669 umol/s/m2
2019-10-17T17:47:59.059Z,1571334479.059 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.227520 umol/s/m2
2019-10-17T17:47:59.456Z,1571334479.456 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.286331 umol/s/m2
2019-10-17T17:47:59.811Z,1571334479.811 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:47:59.874Z,1571334479.874 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.241825 umol/s/m2
2019-10-17T17:48:00.288Z,1571334480.288 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.213215 umol/s/m2
2019-10-17T17:48:00.681Z,1571334480.681 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.216393 umol/s/m2
2019-10-17T17:48:01.098Z,1571334481.098 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.237057 umol/s/m2
2019-10-17T17:48:01.488Z,1571334481.488 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.210035 umol/s/m2
2019-10-17T17:48:01.877Z,1571334481.877 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.245005 umol/s/m2
2019-10-17T17:48:02.298Z,1571334482.298 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.240234 umol/s/m2
2019-10-17T17:48:02.704Z,1571334482.704 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.305405 umol/s/m2
2019-10-17T17:48:03.037Z,1571334483.037 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:48:03.120Z,1571334483.120 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.291100 umol/s/m2
2019-10-17T17:48:03.482Z,1571334483.482 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-17T17:48:03.482Z,1571334483.482 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-17T17:48:03.491Z,1571334483.491 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-17T17:48:03.493Z,1571334483.493 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.273615 umol/s/m2
2019-10-17T17:48:03.887Z,1571334483.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-17T17:48:03.887Z,1571334483.887 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-17T17:48:03.889Z,1571334483.889 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.264078 umol/s/m2
2019-10-17T17:48:04.280Z,1571334484.280 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.243416 umol/s/m2
2019-10-17T17:48:04.687Z,1571334484.687 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.276793 umol/s/m2
2019-10-17T17:48:05.091Z,1571334485.091 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.318121 umol/s/m2
2019-10-17T17:48:05.551Z,1571334485.551 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.187782 umol/s/m2
2019-10-17T17:48:05.869Z,1571334485.869 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:48:05.930Z,1571334485.930 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.305405 umol/s/m2
2019-10-17T17:48:06.336Z,1571334486.336 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.313353 umol/s/m2
2019-10-17T17:48:06.750Z,1571334486.750 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.373753 umol/s/m2
2019-10-17T17:48:07.120Z,1571334487.120 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.292688 umol/s/m2
2019-10-17T17:48:07.521Z,1571334487.521 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.246593 umol/s/m2
2019-10-17T17:48:07.912Z,1571334487.912 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.283154 umol/s/m2
2019-10-17T17:48:08.325Z,1571334488.325 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.278383 umol/s/m2
2019-10-17T17:48:08.745Z,1571334488.745 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.251362 umol/s/m2
2019-10-17T17:48:09.101Z,1571334489.101 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:48:09.156Z,1571334489.156 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.283154 umol/s/m2
2019-10-17T17:48:09.538Z,1571334489.538 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.264078 umol/s/m2
2019-10-17T17:48:09.949Z,1571334489.949 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.245005 umol/s/m2
2019-10-17T17:48:10.353Z,1571334490.353 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.249773 umol/s/m2
2019-10-17T17:48:10.737Z,1571334490.737 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.281563 umol/s/m2
2019-10-17T17:48:11.200Z,1571334491.200 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.256130 umol/s/m2
2019-10-17T17:48:11.577Z,1571334491.577 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.272026 umol/s/m2
2019-10-17T17:48:11.921Z,1571334491.921 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:48:11.981Z,1571334491.981 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.294279 umol/s/m2
2019-10-17T17:48:12.388Z,1571334492.388 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.299046 umol/s/m2
2019-10-17T17:48:12.772Z,1571334492.772 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.284742 umol/s/m2
2019-10-17T17:48:13.172Z,1571334493.172 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.222752 umol/s/m2
2019-10-17T17:48:13.589Z,1571334493.589 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.189371 umol/s/m2
2019-10-17T17:48:13.976Z,1571334493.976 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.187782 umol/s/m2
2019-10-17T17:48:14.380Z,1571334494.380 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.260900 umol/s/m2
2019-10-17T17:48:14.788Z,1571334494.788 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -22.195728 umol/s/m2
2019-10-17T17:48:15.157Z,1571334495.157 [NAL9602](DEBUG): Fix Requested
2019-10-17T17:48:15.208Z,1571334495.208 [Reporter](INFO): PAR_Licor.downwelling_phot