2018-09-08T01:15:40.331Z,1536369340.331 [Supervisor](DEBUG): Initializing supervisor.
2018-09-08T01:15:40.334Z,1536369340.334 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-08T01:15:40.334Z,1536369340.334 [SyncHandler](INFO): Protected caller Thread ID is 800
2018-09-08T01:15:40.335Z,1536369340.335 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-08T01:15:40.336Z,1536369340.336 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-08T01:15:40.336Z,1536369340.336 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801
2018-09-08T01:15:40.339Z,1536369340.339 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-08T01:15:40.350Z,1536369340.350 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-08T01:15:40.351Z,1536369340.351 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-08T01:15:40.351Z,1536369340.351 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802
2018-09-08T01:15:40.352Z,1536369340.352 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-08T01:15:40.353Z,1536369340.353 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-08T01:15:40.354Z,1536369340.354 [logger ThreadHandler](INFO): Protected caller Thread ID is 803
2018-09-08T01:15:40.356Z,1536369340.356 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-08T01:15:40.356Z,1536369340.356 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-08T01:15:40.362Z,1536369340.362 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-08T01:15:40.707Z,1536369340.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-08T01:15:40.709Z,1536369340.709 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-08T01:15:40.909Z,1536369340.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-08T01:15:40.910Z,1536369340.910 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-08T01:15:41.377Z,1536369341.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-08T01:15:41.377Z,1536369341.377 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-08T01:15:41.478Z,1536369341.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-08T01:15:41.479Z,1536369341.479 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-08T01:15:41.685Z,1536369341.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-08T01:15:41.686Z,1536369341.686 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-08T01:15:41.823Z,1536369341.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-08T01:15:41.824Z,1536369341.824 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-08T01:15:42.192Z,1536369342.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-08T01:15:42.192Z,1536369342.192 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-08T01:15:42.274Z,1536369342.274 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-08T01:15:42.374Z,1536369342.374 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-08T01:15:42.374Z,1536369342.374 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-08T01:15:42.481Z,1536369342.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-08T01:15:42.482Z,1536369342.482 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-08T01:15:42.801Z,1536369342.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-08T01:15:42.801Z,1536369342.801 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-08T01:15:43.013Z,1536369343.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-08T01:15:43.014Z,1536369343.014 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-08T01:15:43.466Z,1536369343.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-08T01:15:43.467Z,1536369343.467 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-08T01:15:43.614Z,1536369343.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-08T01:15:43.615Z,1536369343.615 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-08T01:15:44.135Z,1536369344.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-08T01:15:44.137Z,1536369344.137 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-tethys/
2018-09-08T01:15:45.287Z,1536369345.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Control.cfg
2018-09-08T01:15:45.389Z,1536369345.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Navigation.cfg
2018-09-08T01:15:45.482Z,1536369345.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Simulator.cfg
2018-09-08T01:15:45.569Z,1536369345.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/logger.cfg
2018-09-08T01:15:45.650Z,1536369345.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Battery.cfg
2018-09-08T01:15:45.883Z,1536369345.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-08T01:15:45.884Z,1536369345.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Science.cfg
2018-09-08T01:15:46.025Z,1536369346.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/secure.cfg
2018-09-08T01:15:46.109Z,1536369346.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/workSite.cfg
2018-09-08T01:15:46.202Z,1536369346.202 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Servo.cfg
2018-09-08T01:15:46.300Z,1536369346.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/BIT.cfg
2018-09-08T01:15:46.397Z,1536369346.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Sensor.cfg
2018-09-08T01:15:46.542Z,1536369346.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/vehicle.cfg
2018-09-08T01:15:46.739Z,1536369346.739 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-08T01:15:46.749Z,1536369346.749 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-08T01:15:46.903Z,1536369346.903 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-08T01:15:46.932Z,1536369346.932 [SBIT] Loaded
2018-09-08T01:15:46.932Z,1536369346.932 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-08T01:15:46.933Z,1536369346.933 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-08T01:15:46.961Z,1536369346.961 [IBIT] Loaded
2018-09-08T01:15:46.961Z,1536369346.961 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-08T01:15:46.964Z,1536369346.964 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-08T01:15:47.120Z,1536369347.120 [CBIT] Loaded
2018-09-08T01:15:47.121Z,1536369347.121 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-08T01:15:47.121Z,1536369347.121 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-08T01:15:47.123Z,1536369347.123 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-08T01:15:47.160Z,1536369347.160 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-08T01:15:47.161Z,1536369347.161 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-08T01:15:47.396Z,1536369347.396 [CTD_Seabird] Loaded
2018-09-08T01:15:47.397Z,1536369347.397 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-09-08T01:15:47.398Z,1536369347.398 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 405B74E0
2018-09-08T01:15:47.398Z,1536369347.398 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 885
2018-09-08T01:15:47.416Z,1536369347.416 [ESPComponent] Loaded
2018-09-08T01:15:47.417Z,1536369347.417 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-09-08T01:15:47.431Z,1536369347.431 [PAR_Licor] Loaded
2018-09-08T01:15:47.431Z,1536369347.431 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-09-08T01:15:47.480Z,1536369347.480 [WetLabsBB2FL] Loaded
2018-09-08T01:15:47.480Z,1536369347.480 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-09-08T01:15:47.481Z,1536369347.481 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405E74E0
2018-09-08T01:15:47.482Z,1536369347.482 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 886
2018-09-08T01:15:47.482Z,1536369347.482 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-08T01:15:47.483Z,1536369347.483 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-08T01:15:47.606Z,1536369347.606 [BuoyancyServo] Loaded
2018-09-08T01:15:47.607Z,1536369347.607 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-08T01:15:47.621Z,1536369347.621 [ElevatorServo] Loaded
2018-09-08T01:15:47.622Z,1536369347.622 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-09-08T01:15:47.636Z,1536369347.636 [MassServo] Loaded
2018-09-08T01:15:47.636Z,1536369347.636 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-09-08T01:15:47.651Z,1536369347.651 [RudderServo] Loaded
2018-09-08T01:15:47.652Z,1536369347.652 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-09-08T01:15:47.666Z,1536369347.666 [ThrusterServo] Loaded
2018-09-08T01:15:47.667Z,1536369347.667 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-09-08T01:15:47.667Z,1536369347.667 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-08T01:15:47.668Z,1536369347.668 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-08T01:15:47.946Z,1536369347.946 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-08T01:15:47.947Z,1536369347.947 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-08T01:15:48.559Z,1536369348.559 [AHRS_M2] Loaded
2018-09-08T01:15:48.560Z,1536369348.560 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-09-08T01:15:48.695Z,1536369348.695 [DataOverHttps] Loaded
2018-09-08T01:15:48.696Z,1536369348.696 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-08T01:15:48.708Z,1536369348.708 [Depth_Keller] Loaded
2018-09-08T01:15:48.709Z,1536369348.709 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-08T01:15:48.714Z,1536369348.714 [DropWeight] Loaded
2018-09-08T01:15:48.714Z,1536369348.714 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-08T01:15:48.804Z,1536369348.804 [NAL9602] Loaded
2018-09-08T01:15:48.804Z,1536369348.804 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-08T01:15:48.809Z,1536369348.809 [Onboard] Loaded
2018-09-08T01:15:48.810Z,1536369348.810 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-08T01:15:48.816Z,1536369348.816 [Radio_Surface] Loaded
2018-09-08T01:15:48.817Z,1536369348.817 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-08T01:15:48.818Z,1536369348.818 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-09-08T01:15:48.818Z,1536369348.818 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 887
2018-09-08T01:15:48.847Z,1536369348.847 [RDI_Pathfinder] Loaded
2018-09-08T01:15:48.847Z,1536369348.847 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-09-08T01:15:48.977Z,1536369348.977 [DAT] Loaded
2018-09-08T01:15:48.977Z,1536369348.977 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2018-09-08T01:15:50.803Z,1536369350.803 [BPC1] Loaded
2018-09-08T01:15:50.803Z,1536369350.803 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-08T01:15:50.803Z,1536369350.803 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-08T01:15:50.804Z,1536369350.804 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-08T01:15:50.832Z,1536369350.832 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-08T01:15:50.834Z,1536369350.834 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-08T01:15:50.940Z,1536369350.940 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-08T01:15:50.940Z,1536369350.940 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-08T01:15:51.058Z,1536369351.058 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-09-08T01:15:51.058Z,1536369351.058 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-09-08T01:15:51.113Z,1536369351.113 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-08T01:15:51.114Z,1536369351.114 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-08T01:15:51.129Z,1536369351.129 [NavChart] Loaded
2018-09-08T01:15:51.130Z,1536369351.130 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-08T01:15:51.134Z,1536369351.134 [UniversalFixResidualReporter] Loaded
2018-09-08T01:15:51.134Z,1536369351.134 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-08T01:15:51.134Z,1536369351.134 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-08T01:15:51.135Z,1536369351.135 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-08T01:15:51.263Z,1536369351.263 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-08T01:15:51.264Z,1536369351.264 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-08T01:15:51.353Z,1536369351.353 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-08T01:15:51.442Z,1536369351.442 [VerticalControl] Loaded
2018-09-08T01:15:51.443Z,1536369351.443 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-08T01:15:51.443Z,1536369351.443 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-08T01:15:51.501Z,1536369351.501 [HorizontalControl] Loaded
2018-09-08T01:15:51.501Z,1536369351.501 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-08T01:15:51.502Z,1536369351.502 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-08T01:15:51.504Z,1536369351.504 [SpeedControl] Loaded
2018-09-08T01:15:51.504Z,1536369351.504 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-08T01:15:51.505Z,1536369351.505 [LoopControl](DEBUG): Construct LoopControl.
2018-09-08T01:15:51.506Z,1536369351.506 [LoopControl] Loaded
2018-09-08T01:15:51.506Z,1536369351.506 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-08T01:15:51.506Z,1536369351.506 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-08T01:15:51.507Z,1536369351.507 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-08T01:15:51.562Z,1536369351.562 [DepthRateCalculator] Loaded
2018-09-08T01:15:51.563Z,1536369351.563 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-08T01:15:51.568Z,1536369351.568 [PitchRateCalculator] Loaded
2018-09-08T01:15:51.569Z,1536369351.569 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-08T01:15:51.581Z,1536369351.581 [SpeedCalculator] Loaded
2018-09-08T01:15:51.581Z,1536369351.581 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-08T01:15:51.603Z,1536369351.603 [TempGradientCalculator] Loaded
2018-09-08T01:15:51.603Z,1536369351.603 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-08T01:15:51.619Z,1536369351.619 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-09-08T01:15:51.619Z,1536369351.619 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-09-08T01:15:51.624Z,1536369351.624 [YawRateCalculator] Loaded
2018-09-08T01:15:51.624Z,1536369351.624 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-08T01:15:51.654Z,1536369351.654 [ElevatorOffsetCalculator] Loaded
2018-09-08T01:15:51.654Z,1536369351.654 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-08T01:15:51.654Z,1536369351.654 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-08T01:15:51.658Z,1536369351.658 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-08T01:15:51.659Z,1536369351.659 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-08T01:15:51.666Z,1536369351.666 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-08T01:15:51.667Z,1536369351.667 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A064E0
2018-09-08T01:15:51.667Z,1536369351.667 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 888
2018-09-08T01:15:51.672Z,1536369351.672 [Supervisor](INFO): Main Thread ID is 796
2018-09-08T01:15:51.672Z,1536369351.672 [Supervisor](DEBUG): Running supervisor.
2018-09-08T01:15:51.673Z,1536369351.673 [CommandLine ThreadHandler](INFO): Handler Thread ID is 889
2018-09-08T01:15:51.675Z,1536369351.675 [controlThread ThreadHandler](INFO): Handler Thread ID is 890
2018-09-08T01:15:51.675Z,1536369351.675 [controlThread](DEBUG): Initializing ControlThread
2018-09-08T01:15:51.676Z,1536369351.676 [SBIT](INFO): Initialize SBIT Component.
2018-09-08T01:15:51.677Z,1536369351.677 [SBIT](IMPORTANT): git: 2018-09-06
2018-09-08T01:15:51.677Z,1536369351.677 [SBIT](INFO): git hash: 66a1d3cbc6e0267838eeaa8d8b2ab3dc44228fef
2018-09-08T01:15:51.677Z,1536369351.677 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-08T01:15:51.679Z,1536369351.679 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-08T01:15:51.680Z,1536369351.680 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-09-08T01:15:51.681Z,1536369351.681 [IBIT](INFO): Initialize IBIT Component.
2018-09-08T01:15:51.682Z,1536369351.682 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-08T01:15:51.682Z,1536369351.682 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-09-08T01:15:51.683Z,1536369351.683 [logger ThreadHandler](INFO): Handler Thread ID is 891
2018-09-08T01:15:51.710Z,1536369351.710 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 892
2018-09-08T01:15:51.711Z,1536369351.711 [CTD_Seabird](INFO): Initializing
2018-09-08T01:15:51.711Z,1536369351.711 [CTD_Seabird](INFO): Checking LCM
2018-09-08T01:15:51.801Z,1536369351.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T01:15:51.802Z,1536369351.802 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T01:15:51.802Z,1536369351.802 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T01:15:51.805Z,1536369351.805 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T01:15:51.805Z,1536369351.805 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T01:15:51.807Z,1536369351.807 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T01:15:51.810Z,1536369351.810 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-08T01:15:51.811Z,1536369351.811 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-08T01:15:51.813Z,1536369351.813 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-08T01:15:51.819Z,1536369351.819 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-08T01:15:51.822Z,1536369351.822 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-08T01:15:51.832Z,1536369351.832 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-08T01:15:51.833Z,1536369351.833 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-08T01:15:51.833Z,1536369351.833 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-08T01:15:51.834Z,1536369351.834 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 893
2018-09-08T01:15:51.835Z,1536369351.835 [WetLabsBB2FL](INFO): Powering down
2018-09-08T01:15:51.858Z,1536369351.858 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-08T01:15:51.859Z,1536369351.859 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-08T01:15:51.867Z,1536369351.867 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T01:15:51.886Z,1536369351.886 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-08T01:15:51.886Z,1536369351.886 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-08T01:15:51.887Z,1536369351.887 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-08T01:15:51.979Z,1536369351.979 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 894
2018-09-08T01:15:51.985Z,1536369351.985 [Radio_Surface](INFO): Powering up
2018-09-08T01:15:51.987Z,1536369351.987 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 895
2018-09-08T01:15:52.134Z,1536369352.134 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-08T01:15:52.138Z,1536369352.138 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-08T01:15:52.138Z,1536369352.138 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-08T01:15:52.139Z,1536369352.139 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-08T01:15:52.139Z,1536369352.139 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-08T01:15:52.139Z,1536369352.139 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-08T01:15:52.139Z,1536369352.139 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-08T01:15:52.139Z,1536369352.139 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-08T01:15:52.139Z,1536369352.139 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-08T01:15:52.140Z,1536369352.140 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-08T01:15:52.140Z,1536369352.140 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-08T01:15:52.140Z,1536369352.140 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-08T01:15:52.140Z,1536369352.140 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-08T01:15:52.140Z,1536369352.140 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-08T01:15:52.141Z,1536369352.141 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-08T01:15:52.141Z,1536369352.141 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-08T01:15:52.141Z,1536369352.141 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-08T01:15:52.156Z,1536369352.156 [CTD_Seabird](INFO): LCM OK
2018-09-08T01:15:52.156Z,1536369352.156 [CTD_Seabird](INFO): Powering up
2018-09-08T01:15:52.180Z,1536369352.180 [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>


2018-09-08T01:15:52.181Z,1536369352.181 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-08T01:15:52.265Z,1536369352.265 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-08T01:15:52.282Z,1536369352.282 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-08T01:15:52.284Z,1536369352.284 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-08T01:15:52.316Z,1536369352.316 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-08T01:15:52.334Z,1536369352.334 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-08T01:15:52.340Z,1536369352.340 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-08T01:15:52.365Z,1536369352.365 [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>


2018-09-08T01:15:52.378Z,1536369352.378 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-08T01:15:52.404Z,1536369352.404 [ESPComponent](INFO): powering down ESP
2018-09-08T01:15:52.818Z,1536369352.818 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-09-08T01:15:52.936Z,1536369352.936 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-08T01:15:52.961Z,1536369352.961 [Depth_Keller](ERROR): Pressure reading out of range: 2102.507080 decibar
2018-09-08T01:15:53.066Z,1536369353.066 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2018-09-08T01:15:53.067Z,1536369353.067 [DAT](INFO): Powering up
2018-09-08T01:15:53.067Z,1536369353.067 [DAT](DEBUG): Initializing DAT.
2018-09-08T01:15:53.074Z,1536369353.074 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2018-09-08T01:15:53.233Z,1536369353.233 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-08T01:15:53.326Z,1536369353.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:53.394Z,1536369353.394 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:53.499Z,1536369353.499 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-08T01:15:53.506Z,1536369353.506 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-08T01:15:53.529Z,1536369353.529 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-09-08T01:15:53.530Z,1536369353.530 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-09-08T01:15:53.561Z,1536369353.561 [MassServo](DEBUG): Initializing EZServoServo.
2018-09-08T01:15:53.566Z,1536369353.566 [MassServo](DEBUG): Initializing MassServo.
2018-09-08T01:15:53.585Z,1536369353.585 [RudderServo](DEBUG): Initializing EZServoServo.
2018-09-08T01:15:53.590Z,1536369353.590 [RudderServo](DEBUG): Initializing RudderServo.
2018-09-08T01:15:53.604Z,1536369353.604 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-08T01:15:53.610Z,1536369353.610 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-08T01:15:53.910Z,1536369353.910 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:53.911Z,1536369353.911 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:54.150Z,1536369354.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:54.151Z,1536369354.151 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:54.482Z,1536369354.482 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-08T01:15:54.482Z,1536369354.482 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-08T01:15:54.482Z,1536369354.482 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-08T01:15:54.482Z,1536369354.482 [BuoyancyServo](ERROR): Communications Fault
2018-09-08T01:15:54.694Z,1536369354.694 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-08T01:15:54.785Z,1536369354.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:54.787Z,1536369354.787 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:54.803Z,1536369354.803 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-08T01:15:54.805Z,1536369354.805 [BuoyancyServo](INFO): Powering down
2018-09-08T01:15:55.137Z,1536369355.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:55.154Z,1536369355.154 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:55.537Z,1536369355.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:55.554Z,1536369355.554 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:55.937Z,1536369355.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:55.954Z,1536369355.954 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:56.396Z,1536369356.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:56.397Z,1536369356.397 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T01:15:56.843Z,1536369356.843 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-08T01:15:56.843Z,1536369356.843 [BuoyancyServo] No Fault, FailCount= 1
2018-09-08T01:15:57.188Z,1536369357.188 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-08T01:15:57.189Z,1536369357.189 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-08T01:15:57.292Z,1536369357.292 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-08T01:15:57.437Z,1536369357.437 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-09-08T01:15:57.595Z,1536369357.595 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-09-08T01:15:58.046Z,1536369358.046 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-08T01:15:58.046Z,1536369358.046 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-08T01:15:58.046Z,1536369358.046 [BuoyancyServo] Communications Fault, FailCount= 2
2018-09-08T01:15:58.046Z,1536369358.046 [BuoyancyServo](ERROR): Communications Fault
2018-09-08T01:15:58.221Z,1536369358.221 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-08T01:15:58.338Z,1536369358.338 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-08T01:15:58.339Z,1536369358.339 [BuoyancyServo](INFO): Powering down
2018-09-08T01:15:58.350Z,1536369358.350 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-08T01:15:58.350Z,1536369358.350 [CTD_Seabird](INFO): Powering down
2018-09-08T01:16:00.379Z,1536369360.379 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-08T01:16:00.379Z,1536369360.379 [BuoyancyServo] No Fault, FailCount= 2
2018-09-08T01:16:00.703Z,1536369360.703 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-08T01:16:00.823Z,1536369360.823 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-08T01:16:06.662Z,1536369366.662 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-08T01:16:19.043Z,1536369379.043 [NAL9602](INFO): Powering up NAL9602
2018-09-08T01:16:20.329Z,1536369380.329 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-08T01:16:20.362Z,1536369380.362 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-08T01:16:29.975Z,1536369389.975 [NAL9602](INFO): NAL9602 initialized
2018-09-08T01:16:31.281Z,1536369391.281 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.010550
CHAN A1 (24V): 0.180967
CHAN A2 (12V): -0.004708
CHAN A3 (5V): -0.002549
CHAN B0 (3.3V): -0.000836
CHAN B1 (3.15aV): -0.001372
CHAN B2 (3.15bV): -0.000941
CHAN B3 (GND): -0.000559
OPEN: 0.005292
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-08T01:16:44.226Z,1536369404.226 [DAT](INFO): setting local address to 6
2018-09-08T01:16:44.627Z,1536369404.627 [DAT](INFO): set local address to 6
2018-09-08T01:17:04.338Z,1536369424.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=48632, MT Status=2, MTMSN=0
2018-09-08T01:17:04.338Z,1536369424.338 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T01:17:14.381Z,1536369434.381 [SBIT](IMPORTANT): SBIT PASSED
2018-09-08T01:17:14.434Z,1536369434.434 [CommandLine](IMPORTANT): got command configSet list
2018-09-08T01:17:14.434Z,1536369434.434 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-08T01:17:14.435Z,1536369434.435 [CommandLine](IMPORTANT): Express linearApproximation sea_water_temperature 0.250000 kelvin;

2018-09-08T01:17:14.775Z,1536369434.775 [MissionManager](IMPORTANT): Started mission Startup
2018-09-08T01:17:14.776Z,1536369434.776 [Startup] Running Loop=1
2018-09-08T01:17:14.776Z,1536369434.776 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-08T01:17:14.776Z,1536369434.776 [Startup:A.GoToSurface] Running Loop=1
2018-09-08T01:17:14.776Z,1536369434.776 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-08T01:17:14.777Z,1536369434.777 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-08T01:17:14.777Z,1536369434.777 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-08T01:17:14.778Z,1536369434.778 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-08T01:17:14.778Z,1536369434.778 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-08T01:17:14.779Z,1536369434.779 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-08T01:17:14.785Z,1536369434.785 [Startup:StartupSatComms] Running Loop=1
2018-09-08T01:17:14.785Z,1536369434.785 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-08T01:17:14.785Z,1536369434.785 [Startup:StartupSatComms:A] Running Loop=1
2018-09-08T01:17:15.183Z,1536369435.183 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-08T01:17:34.741Z,1536369454.741 [NAL9602](INFO): SBD MO Status=2, MOMSN=48632, MT Status=2, MTMSN=0
2018-09-08T01:17:34.741Z,1536369454.741 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T01:17:45.541Z,1536369465.541 [NAL9602](INFO): SBD MO Status=0, MOMSN=48632, MT Status=0, MTMSN=0
2018-09-08T01:17:45.541Z,1536369465.541 [NAL9602](INFO): No messages in MT queue
2018-09-08T01:18:14.809Z,1536369494.809 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-08T01:17:14.8Z
2018-09-08T01:18:14.810Z,1536369494.810 [Startup:StartupSatComms:A] Stopped
2018-09-08T01:18:14.810Z,1536369494.810 [Startup:StartupSatComms:B] Running Loop=1
2018-09-08T01:18:15.211Z,1536369495.211 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-08T01:18:36.893Z,1536369516.893 [NAL9602](INFO): SBD MO Status=1, MOMSN=48633, MT Status=0, MTMSN=0
2018-09-08T01:18:36.946Z,1536369516.946 [NAL9602](INFO): Sent 25 bytes from file Logs/20180908T010234/Courier0007.lzma
2018-09-08T01:18:36.946Z,1536369516.946 [NAL9602](INFO): Packets left to send: 0
2018-09-08T01:18:36.948Z,1536369516.948 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T010234/Courier0007.lzma.parts/0000.sbd
2018-09-08T01:18:36.948Z,1536369516.948 [NAL9602](DEBUG): Completed sending Logs/20180908T010234/Courier0007.lzma
2018-09-08T01:18:47.849Z,1536369527.849 [NAL9602](INFO): SBD MO Status=1, MOMSN=48634, MT Status=0, MTMSN=0
2018-09-08T01:18:47.902Z,1536369527.902 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T010234/Express0001.lzma
2018-09-08T01:18:47.902Z,1536369527.902 [NAL9602](INFO): Packets left to send: 2
2018-09-08T01:18:47.904Z,1536369527.904 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T010234/Express0001.lzma.parts/0002.sbd
2018-09-08T01:18:52.024Z,1536369532.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T01:18:52.024Z,1536369532.024 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-09-08T01:18:52.024Z,1536369532.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T01:18:52.026Z,1536369532.026 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T01:18:52.026Z,1536369532.026 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-08T01:18:52.026Z,1536369532.026 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T01:18:52.072Z,1536369532.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T01:18:52.072Z,1536369532.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T01:18:52.433Z,1536369532.433 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T01:18:52.433Z,1536369532.433 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-09-08T01:18:52.450Z,1536369532.450 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T01:18:52.450Z,1536369532.450 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-08T01:18:52.871Z,1536369532.871 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T01:18:52.872Z,1536369532.872 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T01:18:52.872Z,1536369532.872 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T01:18:52.873Z,1536369532.873 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T01:18:52.873Z,1536369532.873 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T01:18:52.878Z,1536369532.878 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T01:18:53.236Z,1536369533.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-09-08T01:18:53.606Z,1536369533.606 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-09-08T01:18:54.025Z,1536369534.025 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-09-08T01:18:54.427Z,1536369534.427 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-09-08T01:18:54.816Z,1536369534.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-09-08T01:18:55.232Z,1536369535.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-09-08T01:18:55.601Z,1536369535.601 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-09-08T01:18:56.024Z,1536369536.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-09-08T01:18:56.426Z,1536369536.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-09-08T01:18:56.826Z,1536369536.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-09-08T01:18:57.210Z,1536369537.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-09-08T01:18:57.610Z,1536369537.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-09-08T01:18:57.988Z,1536369537.988 [NAL9602](INFO): SBD MO Status=1, MOMSN=48635, MT Status=0, MTMSN=0
2018-09-08T01:18:58.038Z,1536369538.038 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T010234/Express0001.lzma
2018-09-08T01:18:58.038Z,1536369538.038 [NAL9602](INFO): Packets left to send: 1
2018-09-08T01:18:58.040Z,1536369538.040 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T010234/Express0001.lzma.parts/0001.sbd
2018-09-08T01:18:58.088Z,1536369538.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2018-09-08T01:18:58.744Z,1536369538.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2018-09-08T01:18:58.864Z,1536369538.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-09-08T01:19:01.258Z,1536369541.258 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-09-08T01:19:01.371Z,1536369541.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2018-09-08T01:19:01.763Z,1536369541.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2018-09-08T01:19:02.152Z,1536369542.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2018-09-08T01:19:02.556Z,1536369542.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2018-09-08T01:19:02.965Z,1536369542.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2018-09-08T01:19:03.358Z,1536369543.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2018-09-08T01:19:03.768Z,1536369543.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-09-08T01:19:04.157Z,1536369544.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2018-09-08T01:19:04.564Z,1536369544.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2018-09-08T01:19:04.996Z,1536369544.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2018-09-08T01:19:05.366Z,1536369545.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2018-09-08T01:19:05.776Z,1536369545.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s.
2018-09-08T01:19:06.165Z,1536369546.165 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2018-09-08T01:19:06.593Z,1536369546.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.6 s old, using for 20.0 s.
2018-09-08T01:19:06.962Z,1536369546.962 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-09-08T01:19:07.317Z,1536369547.317 [NAL9602](INFO): SBD MO Status=1, MOMSN=48636, MT Status=0, MTMSN=0
2018-09-08T01:19:07.366Z,1536369547.366 [NAL9602](INFO): Sent 152 bytes from file Logs/20180908T010234/Express0001.lzma
2018-09-08T01:19:07.366Z,1536369547.366 [NAL9602](INFO): Packets left to send: 0
2018-09-08T01:19:07.367Z,1536369547.367 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T010234/Express0001.lzma.parts/0000.sbd
2018-09-08T01:19:07.368Z,1536369547.368 [NAL9602](DEBUG): Completed sending Logs/20180908T010234/Express0001.lzma
2018-09-08T01:19:07.416Z,1536369547.416 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2018-09-08T01:19:08.034Z,1536369548.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-09-08T01:19:08.161Z,1536369548.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-09-08T01:19:10.619Z,1536369550.619 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.6 s old, using for 20.0 s.
2018-09-08T01:19:10.731Z,1536369550.731 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.7 s old, using for 20.0 s.
2018-09-08T01:19:11.085Z,1536369551.085 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.1 s old, using for 20.0 s.
2018-09-08T01:19:11.486Z,1536369551.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2018-09-08T01:19:11.914Z,1536369551.914 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2018-09-08T01:19:15.130Z,1536369555.130 [Startup:StartupSatComms:B](INFO): Timed out from 2018-09-08T01:18:14.8Z
2018-09-08T01:19:15.130Z,1536369555.130 [Startup:StartupSatComms:B] Stopped
2018-09-08T01:19:15.130Z,1536369555.130 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-08T01:19:15.130Z,1536369555.130 [Startup:StartupSatComms] Stopped
2018-09-08T01:19:15.131Z,1536369555.131 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-08T01:19:15.132Z,1536369555.132 [Startup](INFO): Completed Startup
2018-09-08T01:19:15.132Z,1536369555.132 [MissionManager](INFO): Startup is completed.
2018-09-08T01:19:15.132Z,1536369555.132 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-08T01:19:15.132Z,1536369555.132 [Startup] Stopped
2018-09-08T01:19:15.132Z,1536369555.132 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-08T01:19:15.132Z,1536369555.132 [Startup:A.GoToSurface] Stopped
2018-09-08T01:19:15.132Z,1536369555.132 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-08T01:19:15.499Z,1536369555.499 [MissionManager](IMPORTANT): Started mission Default
2018-09-08T01:19:15.499Z,1536369555.499 [Default] Running Loop=1
2018-09-08T01:19:15.499Z,1536369555.499 [Default](DEBUG): Aggregate::initialize Default
2018-09-08T01:19:15.500Z,1536369555.500 [Default:B.GoToSurface] Running Loop=1
2018-09-08T01:19:15.500Z,1536369555.500 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-08T01:19:15.500Z,1536369555.500 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-08T01:19:15.500Z,1536369555.500 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-08T01:19:15.501Z,1536369555.501 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-08T01:19:15.501Z,1536369555.501 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-08T01:19:15.502Z,1536369555.502 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-08T01:19:15.502Z,1536369555.502 [Default:A.Wait] Running Loop=1
2018-09-08T01:19:15.502Z,1536369555.502 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-08T01:19:20.657Z,1536369560.657 [NAL9602](INFO): SBD MO Status=1, MOMSN=48637, MT Status=0, MTMSN=0
2018-09-08T01:19:20.710Z,1536369560.710 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T010234/Express0005.lzma
2018-09-08T01:19:20.710Z,1536369560.710 [NAL9602](INFO): Packets left to send: 3
2018-09-08T01:19:20.712Z,1536369560.712 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T010234/Express0005.lzma.parts/0003.sbd
2018-09-08T01:19:28.719Z,1536369568.719 [Default:A.Wait](INFO): Done Waiting.
2018-09-08T01:19:28.720Z,1536369568.720 [Default:A.Wait] Stopped
2018-09-08T01:19:28.720Z,1536369568.720 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-08T01:19:29.098Z,1536369569.098 [Default:CheckIn] Running Loop=1
2018-09-08T01:19:29.099Z,1536369569.099 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-08T01:19:29.099Z,1536369569.099 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-08T01:19:29.540Z,1536369569.540 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-08T01:21:28.758Z,1536369688.758 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004299
2018-09-08T01:21:53.209Z,1536369713.209 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T01:21:53.209Z,1536369713.209 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-09-08T01:21:53.210Z,1536369713.210 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T01:21:53.211Z,1536369713.211 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T01:21:53.212Z,1536369713.212 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-09-08T01:21:53.212Z,1536369713.212 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T01:21:53.251Z,1536369713.251 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T01:21:53.251Z,1536369713.251 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T01:21:53.615Z,1536369713.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T01:21:53.615Z,1536369713.615 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-09-08T01:21:53.615Z,1536369713.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T01:21:53.616Z,1536369713.616 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-09-08T01:21:54.016Z,1536369714.016 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T01:21:54.017Z,1536369714.017 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T01:21:54.017Z,1536369714.017 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T01:21:54.018Z,1536369714.018 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T01:21:54.018Z,1536369714.018 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T01:21:54.018Z,1536369714.018 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T01:21:54.398Z,1536369714.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-09-08T01:21:54.781Z,1536369714.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-09-08T01:21:55.210Z,1536369715.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-09-08T01:21:55.582Z,1536369715.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-09-08T01:21:56.020Z,1536369716.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-09-08T01:21:56.408Z,1536369716.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-09-08T01:21:56.817Z,1536369716.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-09-08T01:21:57.185Z,1536369717.185 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-09-08T01:21:57.607Z,1536369717.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-09-08T01:21:57.993Z,1536369717.993 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-09-08T01:21:58.411Z,1536369718.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-09-08T01:21:58.817Z,1536369718.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-09-08T01:21:59.236Z,1536369719.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-09-08T01:21:59.633Z,1536369719.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-09-08T01:22:00.018Z,1536369720.018 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-09-08T01:22:00.394Z,1536369720.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-09-08T01:22:00.797Z,1536369720.797 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-09-08T01:22:01.203Z,1536369721.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-09-08T01:22:01.602Z,1536369721.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-09-08T01:22:02.000Z,1536369722.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-09-08T01:22:02.400Z,1536369722.400 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-09-08T01:22:02.807Z,1536369722.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-09-08T01:22:03.189Z,1536369723.189 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-09-08T01:22:03.595Z,1536369723.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-09-08T01:22:04.002Z,1536369724.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-09-08T01:22:04.396Z,1536369724.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-09-08T01:22:04.792Z,1536369724.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-09-08T01:22:05.210Z,1536369725.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-09-08T01:22:05.611Z,1536369725.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-09-08T01:22:06.012Z,1536369726.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-09-08T01:22:06.396Z,1536369726.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-09-08T01:22:06.804Z,1536369726.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-09-08T01:22:07.204Z,1536369727.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-09-08T01:22:07.604Z,1536369727.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-09-08T01:22:08.012Z,1536369728.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-09-08T01:22:08.412Z,1536369728.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-09-08T01:22:08.855Z,1536369728.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-09-08T01:22:09.197Z,1536369729.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-09-08T01:22:09.605Z,1536369729.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-09-08T01:22:09.998Z,1536369729.998 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-09-08T01:22:10.408Z,1536369730.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-09-08T01:22:10.813Z,1536369730.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-09-08T01:22:11.224Z,1536369731.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-09-08T01:22:11.652Z,1536369731.652 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-09-08T01:22:12.008Z,1536369732.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-09-08T01:22:12.397Z,1536369732.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-09-08T01:22:12.807Z,1536369732.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-09-08T01:22:13.202Z,1536369733.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-09-08T01:22:26.860Z,1536369746.860 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T012218
2018-09-08T01:22:41.759Z,1536369761.759 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 30 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.983 degree;set profile_station.Lon -122.52 degree" 47yfr 1 2
2018-09-08T01:22:41.768Z,1536369761.768 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 30 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.983 degree;set profile_station.Lon -122.52 degree" 47yfr 1 2.000000
2018-09-08T01:22:41.769Z,1536369761.769 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=47yfr
2018-09-08T01:22:41.771Z,1536369761.771 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='47yfr'): "load Science/profile_station.xml;set profile_station.MissionTimeout 30 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.983 degree;set profile_station.Lon -122.52 degree" ASAP
2018-09-08T01:22:43.355Z,1536369763.355 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T012234
2018-09-08T01:22:52.282Z,1536369772.282 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMaxDepth 100 meter;run " 47yfr 2 2
2018-09-08T01:22:52.378Z,1536369772.378 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMaxDepth 100 meter;run " 47yfr 2 2.000000
2018-09-08T01:22:52.379Z,1536369772.379 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=47yfr
2018-09-08T01:22:52.380Z,1536369772.380 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='47yfr'): "set profile_station.YoYoMaxDepth 100 meter;run " ASAP
2018-09-08T01:22:52.616Z,1536369772.616 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2018-09-08T01:22:52.616Z,1536369772.616 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2018-09-08T01:22:52.813Z,1536369772.813 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2018-09-08T01:22:52.824Z,1536369772.824 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2018-09-08T01:22:52.828Z,1536369772.828 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2018-09-08T01:22:52.840Z,1536369772.840 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2018-09-08T01:22:52.847Z,1536369772.847 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2018-09-08T01:22:52.854Z,1536369772.854 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2018-09-08T01:22:52.857Z,1536369772.857 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2018-09-08T01:22:52.864Z,1536369772.864 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2018-09-08T01:22:52.878Z,1536369772.878 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2018-09-08T01:22:52.881Z,1536369772.881 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2018-09-08T01:22:52.888Z,1536369772.888 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2018-09-08T01:22:52.891Z,1536369772.891 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2018-09-08T01:22:52.894Z,1536369772.894 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2018-09-08T01:22:52.897Z,1536369772.897 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2018-09-08T01:22:52.910Z,1536369772.910 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2018-09-08T01:22:52.913Z,1536369772.913 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2018-09-08T01:22:52.914Z,1536369772.914 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-09-08T01:22:53.215Z,1536369773.215 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2018-09-08T01:22:53.226Z,1536369773.226 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-09-08T01:22:53.234Z,1536369773.234 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-09-08T01:22:53.240Z,1536369773.240 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2018-09-08T01:22:53.243Z,1536369773.243 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2018-09-08T01:22:53.250Z,1536369773.250 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2018-09-08T01:22:53.253Z,1536369773.253 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2018-09-08T01:22:53.260Z,1536369773.260 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2018-09-08T01:22:53.264Z,1536369773.264 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2018-09-08T01:22:53.275Z,1536369773.275 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2018-09-08T01:22:53.282Z,1536369773.282 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-09-08T01:22:53.290Z,1536369773.290 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2018-09-08T01:22:53.297Z,1536369773.297 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2018-09-08T01:22:53.301Z,1536369773.301 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2018-09-08T01:22:53.309Z,1536369773.309 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-09-08T01:22:53.324Z,1536369773.324 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2018-09-08T01:22:53.338Z,1536369773.338 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2018-09-08T01:22:53.346Z,1536369773.346 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2018-09-08T01:22:53.349Z,1536369773.349 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2018-09-08T01:22:53.356Z,1536369773.356 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2018-09-08T01:22:53.361Z,1536369773.361 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2018-09-08T01:22:53.377Z,1536369773.377 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2018-09-08T01:22:53.388Z,1536369773.388 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2018-09-08T01:22:53.392Z,1536369773.392 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2018-09-08T01:22:53.403Z,1536369773.403 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2018-09-08T01:22:53.410Z,1536369773.410 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2018-09-08T01:22:53.418Z,1536369773.418 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2018-09-08T01:22:53.421Z,1536369773.421 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2018-09-08T01:22:53.428Z,1536369773.428 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2018-09-08T01:22:53.442Z,1536369773.442 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2018-09-08T01:22:53.445Z,1536369773.445 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2018-09-08T01:22:53.452Z,1536369773.452 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2018-09-08T01:22:53.460Z,1536369773.460 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2018-09-08T01:22:53.511Z,1536369773.511 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2018-09-08T01:22:53.515Z,1536369773.515 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2018-09-08T01:22:53.531Z,1536369773.531 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2018-09-08T01:22:53.546Z,1536369773.546 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2018-09-08T01:22:53.558Z,1536369773.558 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2018-09-08T01:22:53.561Z,1536369773.561 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2018-09-08T01:22:53.604Z,1536369773.604 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-08T01:22:53.626Z,1536369773.626 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-09-08T01:22:53.679Z,1536369773.679 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-08T01:22:53.727Z,1536369773.727 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-08T01:22:53.740Z,1536369773.740 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2018-09-08T01:22:53.746Z,1536369773.746 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2018-09-08T01:22:53.747Z,1536369773.747 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-09-08T01:22:53.829Z,1536369773.829 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2018-09-08T01:22:53.836Z,1536369773.836 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2018-09-08T01:22:53.851Z,1536369773.851 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-09-08T01:22:53.858Z,1536369773.858 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2018-09-08T01:22:53.882Z,1536369773.882 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-09-08T01:22:53.912Z,1536369773.912 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2018-09-08T01:22:53.923Z,1536369773.923 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2018-09-08T01:22:53.942Z,1536369773.942 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-08T01:22:53.996Z,1536369773.996 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-09-08T01:22:54.031Z,1536369774.031 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2018-09-08T01:22:54.042Z,1536369774.042 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2018-09-08T01:22:54.050Z,1536369774.050 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-09-08T01:22:54.052Z,1536369774.052 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-09-08T01:22:54.059Z,1536369774.059 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-09-08T01:22:54.085Z,1536369774.085 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-09-08T01:22:54.112Z,1536369774.112 [profile_station:I.Pitch](DEBUG): Construct.
2018-09-08T01:22:54.130Z,1536369774.130 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2018-09-08T01:22:54.133Z,1536369774.133 [profile_station:K.SetSpeed](DEBUG): Construct.
2018-09-08T01:22:54.136Z,1536369774.136 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-09-08T01:22:54.158Z,1536369774.158 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-09-08T01:22:54.178Z,1536369774.178 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2018-09-08T01:22:54.204Z,1536369774.204 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2018-09-08T01:22:54.310Z,1536369774.310 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

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

<!-- Mission-wide settings -->

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

    <Timeout Duration="MissionTimeout"/>

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

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

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

    </Aggregate>

</Mission>


2018-09-08T01:22:54.311Z,1536369774.311 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2018-09-08T01:23:03.379Z,1536369783.379 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 30.000000 hour
2018-09-08T01:23:03.380Z,1536369783.380 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 20.000000 minute
2018-09-08T01:23:03.381Z,1536369783.381 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.983002 degree
2018-09-08T01:23:03.402Z,1536369783.402 [CommandLine](IMPORTANT): got command set profile_station.Lon -122.519997 degree
2018-09-08T01:23:05.945Z,1536369785.945 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 100.000000 meter
2018-09-08T01:23:05.966Z,1536369785.966 [CommandLine](IMPORTANT): got command run 
2018-09-08T01:23:05.969Z,1536369785.969 [CommandLine](IMPORTANT): Running 
2018-09-08T01:23:05.988Z,1536369785.988 [Default] Stopped
2018-09-08T01:23:05.989Z,1536369785.989 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-08T01:23:05.989Z,1536369785.989 [Default:B.GoToSurface] Stopped
2018-09-08T01:23:05.989Z,1536369785.989 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-08T01:23:05.989Z,1536369785.989 [Default:CheckIn] Stopped
2018-09-08T01:23:05.989Z,1536369785.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-08T01:23:05.989Z,1536369785.989 [Default:CheckIn:Read_GPS] Stopped
2018-09-08T01:23:05.990Z,1536369785.990 [MissionManager](IMPORTANT): Started mission profile_station
2018-09-08T01:23:05.990Z,1536369785.990 [profile_station] Running Loop=1
2018-09-08T01:23:05.990Z,1536369785.990 [profile_station](DEBUG): Aggregate::initialize profile_station
2018-09-08T01:23:05.990Z,1536369785.990 [profile_station:Science] Running Loop=1
2018-09-08T01:23:05.990Z,1536369785.990 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2018-09-08T01:23:05.990Z,1536369785.990 [profile_station:Science:A] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:B] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:C] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:D] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:E] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:F] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:G] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:Read_Oil] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:PeakDetectChl] Running Loop=1
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2018-09-08T01:23:05.991Z,1536369785.991 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-09-08T01:23:05.992Z,1536369785.992 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-08T01:23:06.006Z,1536369786.006 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2018-09-08T01:23:06.006Z,1536369786.006 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2018-09-08T01:23:06.006Z,1536369786.006 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-09-08T01:23:06.006Z,1536369786.006 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-09-08T01:23:06.006Z,1536369786.006 [profile_station:Science:PeakDetectNO3] Running Loop=1
2018-09-08T01:23:06.007Z,1536369786.007 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2018-09-08T01:23:06.007Z,1536369786.007 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-09-08T01:23:06.007Z,1536369786.007 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-08T01:23:06.009Z,1536369786.009 [profile_station:Science:PeakDetectOil] Running Loop=1
2018-09-08T01:23:06.009Z,1536369786.009 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2018-09-08T01:23:06.009Z,1536369786.009 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-09-08T01:23:06.009Z,1536369786.009 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-08T01:23:06.015Z,1536369786.015 [profile_station:StandardEnvelopes] Running Loop=1
2018-09-08T01:23:06.015Z,1536369786.015 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2018-09-08T01:23:06.015Z,1536369786.015 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-09-08T01:23:06.016Z,1536369786.016 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-09-08T01:23:06.016Z,1536369786.016 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-09-08T01:23:06.016Z,1536369786.016 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-09-08T01:23:06.016Z,1536369786.016 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-09-08T01:23:06.016Z,1536369786.016 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-09-08T01:23:06.016Z,1536369786.016 [profile_station:I.Pitch] Running Loop=1
2018-09-08T01:23:06.016Z,1536369786.016 [profile_station:I.Pitch](DEBUG): Initialize.
2018-09-08T01:23:06.017Z,1536369786.017 [profile_station:J.Buoyancy] Running Loop=1
2018-09-08T01:23:06.017Z,1536369786.017 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-09-08T01:23:06.017Z,1536369786.017 [profile_station:K.SetSpeed] Running Loop=1
2018-09-08T01:23:06.017Z,1536369786.017 [profile_station:K.SetSpeed](DEBUG): Initialize.
2018-09-08T01:23:06.017Z,1536369786.017 [profile_station:L.DepthEnvelope] Running Loop=1
2018-09-08T01:23:06.017Z,1536369786.017 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-09-08T01:23:06.026Z,1536369786.026 [profile_station:M.AltitudeEnvelope] Running Loop=1
2018-09-08T01:23:06.026Z,1536369786.026 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-09-08T01:23:06.026Z,1536369786.026 [profile_station:N.YoYo] Running Loop=1
2018-09-08T01:23:06.026Z,1536369786.026 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2018-09-08T01:23:06.026Z,1536369786.026 [profile_station:C] Running Loop=1
2018-09-08T01:23:06.027Z,1536369786.027 [profile_station:N.YoYo] Running Loop=1
2018-09-08T01:23:06.032Z,1536369786.032 [profile_station:M.AltitudeEnvelope] Running Loop=1
2018-09-08T01:23:06.051Z,1536369786.051 [profile_station:L.DepthEnvelope] Running Loop=1
2018-09-08T01:23:06.061Z,1536369786.061 [profile_station:K.SetSpeed] Running Loop=1
2018-09-08T01:23:06.074Z,1536369786.074 [profile_station:J.Buoyancy] Running Loop=1
2018-09-08T01:23:06.079Z,1536369786.079 [profile_station:I.Pitch] Running Loop=1
2018-09-08T01:23:06.093Z,1536369786.093 [profile_station:StandardEnvelopes] Running Loop=1
2018-09-08T01:23:06.093Z,1536369786.093 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-09-08T01:23:06.093Z,1536369786.093 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:06.110Z,1536369786.110 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-09-08T01:23:06.115Z,1536369786.115 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-09-08T01:23:06.126Z,1536369786.126 [profile_station:C] Stopped
2018-09-08T01:23:06.126Z,1536369786.126 [profile_station:E] Running Loop=1
2018-09-08T01:23:06.126Z,1536369786.126 [profile_station:NeedComms] Running Loop=1
2018-09-08T01:23:06.126Z,1536369786.126 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-09-08T01:23:06.127Z,1536369786.127 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-09-08T01:23:06.127Z,1536369786.127 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-08T01:23:06.127Z,1536369786.127 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-08T01:23:06.128Z,1536369786.128 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-08T01:23:06.128Z,1536369786.128 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-08T01:23:06.128Z,1536369786.128 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-08T01:23:06.129Z,1536369786.129 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-08T01:23:06.129Z,1536369786.129 [profile_station:NeedComms:A] Running Loop=1
2018-09-08T01:23:06.147Z,1536369786.147 [profile_station:NeedComms:A](INFO): last time_fix was: nan second since 1970/01/01T00:00:00Z 
2018-09-08T01:23:06.149Z,1536369786.149 [profile_station:NeedComms:A] Stopped
2018-09-08T01:23:06.149Z,1536369786.149 [profile_station:Science] Running Loop=1
2018-09-08T01:23:06.153Z,1536369786.153 [profile_station:Science:PeakDetectOil] Stopped
2018-09-08T01:23:06.153Z,1536369786.153 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2018-09-08T01:23:06.153Z,1536369786.153 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-09-08T01:23:06.153Z,1536369786.153 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-08T01:23:06.153Z,1536369786.153 [profile_station:Science:PeakDetectNO3] Stopped
2018-09-08T01:23:06.153Z,1536369786.153 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2018-09-08T01:23:06.154Z,1536369786.154 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-09-08T01:23:06.154Z,1536369786.154 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-08T01:23:06.154Z,1536369786.154 [profile_station:Science:HighestChlPeakReport] Stopped
2018-09-08T01:23:06.154Z,1536369786.154 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2018-09-08T01:23:06.154Z,1536369786.154 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-09-08T01:23:06.154Z,1536369786.154 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-09-08T01:23:06.154Z,1536369786.154 [profile_station:Science:PeakDetectChl] Running Loop=1
2018-09-08T01:23:06.171Z,1536369786.171 [profile_station:Science:Read_Oil] Stopped
2018-09-08T01:23:06.171Z,1536369786.171 [profile_station:Science:G] Running Loop=1
2018-09-08T01:23:06.172Z,1536369786.172 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-09-08T01:23:06.175Z,1536369786.175 [profile_station:Science:F] Stopped
2018-09-08T01:23:06.175Z,1536369786.175 [profile_station:Science:E] Stopped
2018-09-08T01:23:06.175Z,1536369786.175 [profile_station:Science:D] Running Loop=1
2018-09-08T01:23:06.176Z,1536369786.176 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2018-09-08T01:23:06.177Z,1536369786.177 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2018-09-08T01:23:06.179Z,1536369786.179 [profile_station:Science:C] Running Loop=1
2018-09-08T01:23:06.179Z,1536369786.179 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2018-09-08T01:23:06.180Z,1536369786.180 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2018-09-08T01:23:06.182Z,1536369786.182 [profile_station:Science:B] Stopped
2018-09-08T01:23:06.182Z,1536369786.182 [profile_station:Science:A] Stopped
2018-09-08T01:23:06.323Z,1536369786.323 [profile_station:E] Stopped
2018-09-08T01:23:06.323Z,1536369786.323 [profile_station:F] Running Loop=1
2018-09-08T01:23:06.324Z,1536369786.324 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:06.334Z,1536369786.334 [profile_station:NeedComms:C] Running Loop=1
2018-09-08T01:23:06.379Z,1536369786.379 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2018-09-08T01:23:06.380Z,1536369786.380 [WetLabsBB2FL](INFO): Powering up
2018-09-08T01:23:06.439Z,1536369786.439 [CTD_Seabird](INFO): Initializing
2018-09-08T01:23:06.439Z,1536369786.439 [CTD_Seabird](INFO): Checking LCM
2018-09-08T01:23:06.439Z,1536369786.439 [CTD_Seabird](INFO): LCM OK
2018-09-08T01:23:06.439Z,1536369786.439 [CTD_Seabird](INFO): Powering up
2018-09-08T01:23:06.745Z,1536369786.745 [profile_station:F] Stopped
2018-09-08T01:23:06.745Z,1536369786.745 [profile_station:G] Running Loop=1
2018-09-08T01:23:06.745Z,1536369786.745 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:06.747Z,1536369786.747 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-08T01:23:07.144Z,1536369787.144 [profile_station:G] Stopped
2018-09-08T01:23:07.144Z,1536369787.144 [profile_station:StartingMission] Running Loop=1
2018-09-08T01:23:07.144Z,1536369787.144 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2018-09-08T01:23:07.144Z,1536369787.144 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:07.573Z,1536369787.573 [profile_station:M.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2018-09-08T01:23:07.584Z,1536369787.584 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:07.585Z,1536369787.585 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2018-09-08T01:23:07.950Z,1536369787.950 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:08.342Z,1536369788.342 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:08.753Z,1536369788.753 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:09.134Z,1536369789.134 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:09.555Z,1536369789.555 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:09.964Z,1536369789.964 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:10.341Z,1536369790.341 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:10.767Z,1536369790.767 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:11.137Z,1536369791.137 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:11.565Z,1536369791.565 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:11.771Z,1536369791.771 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-08T01:23:11.837Z,1536369791.837 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-09-08T01:23:11.838Z,1536369791.838 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-09-08T01:23:12.011Z,1536369792.011 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:12.339Z,1536369792.339 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:12.819Z,1536369792.819 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:13.146Z,1536369793.146 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:13.555Z,1536369793.555 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:13.953Z,1536369793.953 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:14.349Z,1536369794.349 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:14.759Z,1536369794.759 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:15.138Z,1536369795.138 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:15.535Z,1536369795.535 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:15.976Z,1536369795.976 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:16.341Z,1536369796.341 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:16.758Z,1536369796.758 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:17.137Z,1536369797.137 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:17.583Z,1536369797.583 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:17.954Z,1536369797.954 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:18.342Z,1536369798.342 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:18.755Z,1536369798.755 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:19.158Z,1536369799.158 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:19.562Z,1536369799.562 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:19.945Z,1536369799.945 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:20.328Z,1536369800.328 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:20.751Z,1536369800.751 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:21.144Z,1536369801.144 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:21.571Z,1536369801.571 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:21.950Z,1536369801.950 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:22.346Z,1536369802.346 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:22.756Z,1536369802.756 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:23.141Z,1536369803.141 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:23.547Z,1536369803.547 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:23.956Z,1536369803.956 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:24.329Z,1536369804.329 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:24.737Z,1536369804.737 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:25.141Z,1536369805.141 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:25.544Z,1536369805.544 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:25.966Z,1536369805.966 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:26.328Z,1536369806.328 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:26.772Z,1536369806.772 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:27.155Z,1536369807.155 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:27.569Z,1536369807.569 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:27.955Z,1536369807.955 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:28.349Z,1536369808.349 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:28.771Z,1536369808.771 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:29.141Z,1536369809.141 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:29.562Z,1536369809.562 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:29.947Z,1536369809.947 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:30.354Z,1536369810.354 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:30.767Z,1536369810.767 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:31.159Z,1536369811.159 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:31.578Z,1536369811.578 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:31.949Z,1536369811.949 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:32.336Z,1536369812.336 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:32.763Z,1536369812.763 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:33.147Z,1536369813.147 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:33.541Z,1536369813.541 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:33.959Z,1536369813.959 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:34.344Z,1536369814.344 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:34.743Z,1536369814.743 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:35.142Z,1536369815.142 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:35.534Z,1536369815.534 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:35.967Z,1536369815.967 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:36.339Z,1536369816.339 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:36.789Z,1536369816.789 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:37.165Z,1536369817.165 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:37.568Z,1536369817.568 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:37.933Z,1536369817.933 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:38.328Z,1536369818.328 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:38.781Z,1536369818.781 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:39.138Z,1536369819.138 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:39.566Z,1536369819.566 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:39.980Z,1536369819.980 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:40.343Z,1536369820.343 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:40.787Z,1536369820.787 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:41.138Z,1536369821.138 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:41.563Z,1536369821.563 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:41.953Z,1536369821.953 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:42.338Z,1536369822.338 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:42.776Z,1536369822.776 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:43.150Z,1536369823.150 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:43.550Z,1536369823.550 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:43.975Z,1536369823.975 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:44.340Z,1536369824.340 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:44.762Z,1536369824.762 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:45.156Z,1536369825.156 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:45.546Z,1536369825.546 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:45.983Z,1536369825.983 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:46.349Z,1536369826.349 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:46.795Z,1536369826.795 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:47.129Z,1536369827.129 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:47.560Z,1536369827.560 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:47.944Z,1536369827.944 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:48.337Z,1536369828.337 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:51.777Z,1536369831.777 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:51.966Z,1536369831.966 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:52.294Z,1536369832.294 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:52.741Z,1536369832.741 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:53.086Z,1536369833.086 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:53.480Z,1536369833.480 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:53.884Z,1536369833.884 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:54.294Z,1536369834.294 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:54.716Z,1536369834.716 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:55.107Z,1536369835.107 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:55.474Z,1536369835.474 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:55.905Z,1536369835.905 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:56.283Z,1536369836.283 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:56.692Z,1536369836.692 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:57.090Z,1536369837.090 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:57.492Z,1536369837.492 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:57.908Z,1536369837.908 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:58.278Z,1536369838.278 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:58.692Z,1536369838.692 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:59.076Z,1536369839.076 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:59.479Z,1536369839.479 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:23:59.883Z,1536369839.883 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:00.294Z,1536369840.294 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:00.755Z,1536369840.755 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:01.097Z,1536369841.097 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:01.482Z,1536369841.482 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:01.896Z,1536369841.896 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:02.282Z,1536369842.282 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:02.731Z,1536369842.731 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:03.085Z,1536369843.085 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:03.494Z,1536369843.494 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:03.910Z,1536369843.910 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:04.282Z,1536369844.282 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:04.663Z,1536369844.663 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:05.073Z,1536369845.073 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:05.494Z,1536369845.494 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:05.911Z,1536369845.911 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:06.299Z,1536369846.299 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:06.726Z,1536369846.726 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:07.080Z,1536369847.080 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:07.482Z,1536369847.482 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:07.887Z,1536369847.887 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:08.281Z,1536369848.281 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:08.705Z,1536369848.705 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:09.090Z,1536369849.090 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:09.495Z,1536369849.495 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:09.914Z,1536369849.914 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:10.284Z,1536369850.284 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:10.721Z,1536369850.721 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:11.073Z,1536369851.073 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:11.486Z,1536369851.486 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:11.883Z,1536369851.883 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:12.292Z,1536369852.292 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:12.744Z,1536369852.744 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:13.094Z,1536369853.094 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:13.487Z,1536369853.487 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:13.895Z,1536369853.895 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:14.275Z,1536369854.275 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:14.711Z,1536369854.711 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:15.086Z,1536369855.086 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:15.509Z,1536369855.509 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:15.918Z,1536369855.918 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:16.271Z,1536369856.271 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:16.729Z,1536369856.729 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:17.069Z,1536369857.069 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:17.480Z,1536369857.480 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:17.884Z,1536369857.884 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:18.287Z,1536369858.287 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:18.735Z,1536369858.735 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:19.097Z,1536369859.097 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:19.483Z,1536369859.483 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:19.894Z,1536369859.894 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:20.282Z,1536369860.282 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:20.717Z,1536369860.717 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:21.085Z,1536369861.085 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:21.487Z,1536369861.487 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:21.893Z,1536369861.893 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:22.279Z,1536369862.279 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:22.735Z,1536369862.735 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:23.095Z,1536369863.095 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:23.471Z,1536369863.471 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:23.881Z,1536369863.881 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:24.286Z,1536369864.286 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:24.715Z,1536369864.715 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:25.118Z,1536369865.118 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:25.478Z,1536369865.478 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:25.895Z,1536369865.895 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:26.300Z,1536369866.300 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:26.732Z,1536369866.732 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:27.094Z,1536369867.094 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:27.473Z,1536369867.473 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:27.900Z,1536369867.900 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:28.276Z,1536369868.276 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:28.705Z,1536369868.705 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:29.084Z,1536369869.084 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:29.484Z,1536369869.484 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:29.881Z,1536369869.881 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:30.290Z,1536369870.290 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:30.744Z,1536369870.744 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:31.092Z,1536369871.092 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:31.478Z,1536369871.478 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:31.889Z,1536369871.889 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:32.286Z,1536369872.286 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:32.704Z,1536369872.704 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:33.076Z,1536369873.076 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:33.486Z,1536369873.486 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:33.893Z,1536369873.893 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:34.286Z,1536369874.286 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:34.690Z,1536369874.690 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:35.094Z,1536369875.094 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:35.484Z,1536369875.484 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:35.925Z,1536369875.925 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:36.291Z,1536369876.291 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:36.732Z,1536369876.732 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:37.081Z,1536369877.081 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:37.478Z,1536369877.478 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:37.889Z,1536369877.889 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:38.273Z,1536369878.273 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:38.735Z,1536369878.735 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:39.085Z,1536369879.085 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:39.522Z,1536369879.522 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:39.890Z,1536369879.890 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:40.275Z,1536369880.275 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:40.736Z,1536369880.736 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:41.082Z,1536369881.082 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:41.469Z,1536369881.469 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:41.906Z,1536369881.906 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:42.286Z,1536369882.286 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:42.731Z,1536369882.731 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:43.094Z,1536369883.094 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:43.478Z,1536369883.478 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:43.893Z,1536369883.893 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:44.292Z,1536369884.292 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:44.696Z,1536369884.696 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:45.103Z,1536369885.103 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:45.494Z,1536369885.494 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:45.903Z,1536369885.903 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:46.295Z,1536369886.295 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:46.703Z,1536369886.703 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:47.078Z,1536369887.078 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:47.472Z,1536369887.472 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:47.898Z,1536369887.898 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:48.316Z,1536369888.316 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:48.749Z,1536369888.749 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:49.089Z,1536369889.089 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:49.459Z,1536369889.459 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:52.917Z,1536369892.917 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:53.053Z,1536369893.053 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:53.486Z,1536369893.486 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:53.884Z,1536369893.884 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:54.272Z,1536369894.272 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T01:24:54.272Z,1536369894.272 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-09-08T01:24:54.272Z,1536369894.272 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T01:24:54.298Z,1536369894.298 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T01:24:54.299Z,1536369894.299 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-09-08T01:24:54.299Z,1536369894.299 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T01:24:54.308Z,1536369894.308 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:54.347Z,1536369894.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T01:24:54.347Z,1536369894.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T01:24:54.659Z,1536369894.659 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:54.681Z,1536369894.681 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T01:24:54.681Z,1536369894.681 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-09-08T01:24:54.691Z,1536369894.691 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T01:24:54.691Z,1536369894.691 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-09-08T01:24:55.055Z,1536369895.055 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T01:24:55.056Z,1536369895.056 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T01:24:55.056Z,1536369895.056 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T01:24:55.057Z,1536369895.057 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T01:24:55.057Z,1536369895.057 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T01:24:55.058Z,1536369895.058 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T01:24:55.062Z,1536369895.062 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:55.458Z,1536369895.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-09-08T01:24:55.464Z,1536369895.464 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:55.871Z,1536369895.871 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-09-08T01:24:55.896Z,1536369895.896 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:56.259Z,1536369896.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-09-08T01:24:56.264Z,1536369896.264 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:56.679Z,1536369896.679 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-09-08T01:24:56.697Z,1536369896.697 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:57.004Z,1536369897.004 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T012448
2018-09-08T01:24:57.063Z,1536369897.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-09-08T01:24:57.073Z,1536369897.073 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:57.437Z,1536369897.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-09-08T01:24:57.451Z,1536369897.451 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:57.867Z,1536369897.867 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-09-08T01:24:57.877Z,1536369897.877 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:58.256Z,1536369898.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-09-08T01:24:58.262Z,1536369898.262 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:58.657Z,1536369898.657 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-09-08T01:24:58.663Z,1536369898.663 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:59.060Z,1536369899.060 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-09-08T01:24:59.066Z,1536369899.066 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:59.460Z,1536369899.460 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-09-08T01:24:59.466Z,1536369899.466 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:24:59.863Z,1536369899.863 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-09-08T01:24:59.869Z,1536369899.869 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:00.248Z,1536369900.248 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-09-08T01:25:00.258Z,1536369900.258 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:00.649Z,1536369900.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-09-08T01:25:00.675Z,1536369900.675 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:01.041Z,1536369901.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-09-08T01:25:01.055Z,1536369901.055 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:01.453Z,1536369901.453 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-09-08T01:25:01.463Z,1536369901.463 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:01.881Z,1536369901.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-09-08T01:25:01.891Z,1536369901.891 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:02.264Z,1536369902.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-09-08T01:25:02.270Z,1536369902.270 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:02.725Z,1536369902.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2018-09-08T01:25:02.747Z,1536369902.747 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:03.059Z,1536369903.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-09-08T01:25:03.065Z,1536369903.065 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:03.454Z,1536369903.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-09-08T01:25:03.465Z,1536369903.465 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:03.877Z,1536369903.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-09-08T01:25:03.893Z,1536369903.893 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:04.247Z,1536369904.247 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-09-08T01:25:04.253Z,1536369904.253 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:04.641Z,1536369904.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-09-08T01:25:04.647Z,1536369904.647 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:05.062Z,1536369905.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-09-08T01:25:05.072Z,1536369905.072 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:05.455Z,1536369905.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-09-08T01:25:05.461Z,1536369905.461 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:05.906Z,1536369905.906 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-09-08T01:25:05.916Z,1536369905.916 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:06.256Z,1536369906.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-09-08T01:25:06.262Z,1536369906.262 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:06.665Z,1536369906.665 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-09-08T01:25:06.671Z,1536369906.671 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:07.046Z,1536369907.046 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-09-08T01:25:07.055Z,1536369907.055 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:07.454Z,1536369907.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-09-08T01:25:07.459Z,1536369907.459 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:07.848Z,1536369907.848 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-09-08T01:25:07.862Z,1536369907.862 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:08.273Z,1536369908.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-09-08T01:25:08.279Z,1536369908.279 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:08.720Z,1536369908.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-09-08T01:25:08.726Z,1536369908.726 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:09.044Z,1536369909.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-09-08T01:25:09.050Z,1536369909.050 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:09.456Z,1536369909.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-09-08T01:25:09.467Z,1536369909.467 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:12.889Z,1536369912.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.6 s old, using for 20.0 s.
2018-09-08T01:25:12.895Z,1536369912.895 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:13.036Z,1536369913.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-09-08T01:25:13.047Z,1536369913.047 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:13.395Z,1536369913.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.1 s old, using for 20.0 s.
2018-09-08T01:25:13.401Z,1536369913.401 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:13.805Z,1536369913.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2018-09-08T01:25:13.811Z,1536369913.811 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:14.196Z,1536369914.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2018-09-08T01:25:14.202Z,1536369914.202 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:14.594Z,1536369914.594 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:15.008Z,1536369915.008 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:15.406Z,1536369915.406 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:15.780Z,1536369915.780 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:16.201Z,1536369916.201 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:16.562Z,1536369916.562 [DataOverHttps](INFO): Received command:maintain sensor NAL9602.latitude_fix 36.983 degree;maintain sensor NAL9602.longitude_fix -122.52 degree
2018-09-08T01:25:18.144Z,1536369918.144 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.983002 degree
2018-09-08T01:25:18.145Z,1536369918.145 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2018-09-08T01:25:18.160Z,1536369918.160 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -122.519997 degree
2018-09-08T01:25:18.162Z,1536369918.162 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2018-09-08T01:25:18.238Z,1536369918.238 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:18.283Z,1536369918.283 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-08T01:25:18.393Z,1536369918.393 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:18.790Z,1536369918.790 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:19.163Z,1536369919.163 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:19.588Z,1536369919.588 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:20.006Z,1536369920.006 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:20.375Z,1536369920.375 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:20.786Z,1536369920.786 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:21.144Z,1536369921.144 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:21.592Z,1536369921.592 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:21.899Z,1536369921.899 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T012513
2018-09-08T01:25:21.980Z,1536369921.980 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:22.367Z,1536369922.367 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:22.785Z,1536369922.785 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:23.190Z,1536369923.190 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:23.583Z,1536369923.583 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:23.983Z,1536369923.983 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:24.368Z,1536369924.368 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:24.770Z,1536369924.770 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:25.172Z,1536369925.172 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:25.573Z,1536369925.573 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:29.029Z,1536369929.029 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:29.179Z,1536369929.179 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:29.599Z,1536369929.599 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:29.955Z,1536369929.955 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2018-09-08T01:25:30.294Z,1536369930.294 [DataOverHttps](INFO): Received command:set NAL9602.time_Fix 1536369859 second
2018-09-08T01:25:31.517Z,1536369931.517 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1536369920.000000 second
2018-09-08T01:25:31.629Z,1536369931.629 [profile_station:NeedComms:C] Stopped
2018-09-08T01:25:31.630Z,1536369931.630 [profile_station:NeedComms:D] Running Loop=1
2018-09-08T01:25:31.801Z,1536369931.801 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-08T01:25:40.616Z,1536369940.616 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20180908T011540/Courier0004.lzma
2018-09-08T01:25:43.014Z,1536369943.014 [DataOverHttps](INFO): Moved sent file to Logs/20180908T011540/Courier0004.lzma.bak
2018-09-08T01:25:43.014Z,1536369943.014 [DataOverHttps](INFO): SBD MOMSN=8492932
2018-09-08T01:26:01.851Z,1536369961.851 [NAL9602](INFO): Powering down
2018-09-08T01:26:13.828Z,1536369973.828 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-09-08T01:26:43.783Z,1536370003.783 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180908T011540/Courier0007.lzma
2018-09-08T01:26:45.388Z,1536370005.388 [DataOverHttps](INFO): Moved sent file to Logs/20180908T011540/Courier0007.lzma.bak
2018-09-08T01:26:45.388Z,1536370005.388 [DataOverHttps](INFO): SBD MOMSN=8492935
2018-09-08T01:27:05.326Z,1536370025.326 [DataOverHttps](INFO): Sending 1138 bytes from file Logs/20180908T010234/Express0005.lzma
2018-09-08T01:27:10.913Z,1536370030.913 [DataOverHttps](INFO): Moved sent file to Logs/20180908T010234/Express0005.lzma.bak
2018-09-08T01:27:10.913Z,1536370030.913 [DataOverHttps](INFO): SBD MOMSN=8492937
2018-09-08T01:27:24.479Z,1536370044.479 [DAT](FAULT): unknown deviceResponse_: l SNR:04.9 CCERR:003

2018-09-08T01:27:24.479Z,1536370044.479 [DAT] Communications Fault, FailCount= 1
2018-09-08T01:27:24.479Z,1536370044.479 [DAT](ERROR): Communications Fault
2018-09-08T01:27:24.573Z,1536370044.573 [CBIT](ERROR): Communications Fault in component: DAT
2018-09-08T01:27:24.919Z,1536370044.919 [DAT](INFO): Powering down
2018-09-08T01:27:25.772Z,1536370045.772 [CBIT](INFO): Clearing failed state for component DAT
2018-09-08T01:27:25.772Z,1536370045.772 [DAT] No Fault, FailCount= 0
2018-09-08T01:27:28.073Z,1536370048.073 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2018-09-08T01:27:28.073Z,1536370048.073 [DAT](INFO): Powering up
2018-09-08T01:27:28.073Z,1536370048.073 [DAT](DEBUG): Initializing DAT.
2018-09-08T01:27:28.091Z,1536370048.091 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2018-09-08T01:27:32.865Z,1536370052.865 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20180908T010234/Express0008.lzma
2018-09-08T01:27:32.913Z,1536370052.913 [RDI_Pathfinder](ERROR): Failed to parse:

2018-09-08T01:27:36.457Z,1536370056.457 [DataOverHttps](INFO): Moved sent file to Logs/20180908T010234/Express0008.lzma.bak
2018-09-08T01:27:36.457Z,1536370056.457 [DataOverHttps](INFO): SBD MOMSN=8492961
2018-09-08T01:27:40.890Z,1536370060.890 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T01:27:40.890Z,1536370060.890 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090801284341,35.0, -0.11448.9,  0
2018-09-08T01:27:43.284Z,1536370063.284 [DAT](INFO): setting local address to 6
2018-09-08T01:27:43.748Z,1536370063.748 [DAT](INFO): set local address to 6
2018-09-08T01:28:34.889Z,1536370114.889 [DataOverHttps](INFO): Sending 701 bytes from file Logs/20180908T011540/Express0001.lzma
2018-09-08T01:28:38.452Z,1536370118.452 [DataOverHttps](INFO): Moved sent file to Logs/20180908T011540/Express0001.lzma.bak
2018-09-08T01:28:38.452Z,1536370118.452 [DataOverHttps](INFO): SBD MOMSN=8492969
2018-09-08T01:29:05.658Z,1536370145.658 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T012857
2018-09-08T01:29:12.475Z,1536370152.475 [DataOverHttps](INFO): Sending 1057 bytes from file Logs/20180908T011540/Express0005.lzma
2018-09-08T01:29:12.492Z,1536370152.492 [DataOverHttps](INFO): Received command:set NAL9602.time_Fix 1536370075 second
2018-09-08T01:29:13.781Z,1536370153.781 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1536370048.000000 second
2018-09-08T01:29:13.808Z,1536370153.808 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e88, 15.114, -0.086, 0.000
2018-09-08T01:29:32.736Z,1536370172.736 [DataOverHttps](INFO): Moved sent file to Logs/20180908T011540/Express0005.lzma.bak
2018-09-08T01:29:32.736Z,1536370172.736 [DataOverHttps](INFO): SBD MOMSN=8492986
2018-09-08T01:29:48.765Z,1536370188.765 [DataOverHttps](INFO): Sending 43 bytes from file Logs/20180908T011540/Courier0010.lzma
2018-09-08T01:29:53.558Z,1536370193.558 [DataOverHttps](INFO): Moved sent file to Logs/20180908T011540/Courier0010.lzma.bak
2018-09-08T01:29:53.558Z,1536370193.558 [DataOverHttps](INFO): SBD MOMSN=8493013
2018-09-08T01:30:20.677Z,1536370220.677 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180908T011540/Express0008.lzma
2018-09-08T01:30:26.273Z,1536370226.273 [DataOverHttps](INFO): Moved sent file to Logs/20180908T011540/Express0008.lzma.bak
2018-09-08T01:30:26.274Z,1536370226.274 [DataOverHttps](INFO): SBD MOMSN=8493015
2018-09-08T01:30:47.829Z,1536370247.829 [DataOverHttps](INFO): Sending 380 bytes from file Logs/20180908T011540/Express0011.lzma
2018-09-08T01:31:00.219Z,1536370260.219 [DataOverHttps](INFO): Moved sent file to Logs/20180908T011540/Express0011.lzma.bak
2018-09-08T01:31:00.219Z,1536370260.219 [DataOverHttps](INFO): SBD MOMSN=8493017
2018-09-08T01:31:00.824Z,1536370260.824 [profile_station:NeedComms:D] Stopped
2018-09-08T01:31:00.825Z,1536370260.825 [profile_station:NeedComms:E] Running Loop=1
2018-09-08T01:31:01.070Z,1536370261.070 [profile_station:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-08T01:31:01.424Z,1536370261.424 [NAL9602](INFO): Powering up
2018-09-08T01:31:12.614Z,1536370272.614 [NAL9602](INFO): NAL9602 initialized
2018-09-08T01:31:13.398Z,1536370273.398 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-08T01:31:13.398Z,1536370273.398 [NAL9602] Data Fault, FailCount= 1
2018-09-08T01:31:13.399Z,1536370273.399 [NAL9602](ERROR): Data Fault
2018-09-08T01:31:13.503Z,1536370273.503 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-08T01:31:13.801Z,1536370273.801 [NAL9602](INFO): Powering down
2018-09-08T01:31:14.703Z,1536370274.703 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-08T01:31:14.703Z,1536370274.703 [NAL9602] No Fault, FailCount= 1
2018-09-08T01:31:43.805Z,1536370303.805 [NAL9602](INFO): Powering up NAL9602
2018-09-08T01:31:54.998Z,1536370314.998 [NAL9602](INFO): NAL9602 initialized
2018-09-08T01:32:14.213Z,1536370334.213 [NAL9602](INFO): SBD MO Status=0, MOMSN=48638, MT Status=0, MTMSN=0
2018-09-08T01:32:14.213Z,1536370334.213 [NAL9602](INFO): No messages in MT queue
2018-09-08T01:32:49.849Z,1536370369.849 [DAT](FAULT): unknown deviceResponse_: s CRC:Fail SNR:02.7 CCERR:004

2018-09-08T01:32:49.849Z,1536370369.849 [DAT] Communications Fault, FailCount= 1
2018-09-08T01:32:49.849Z,1536370369.849 [DAT](ERROR): Communications Fault
2018-09-08T01:32:49.922Z,1536370369.922 [CBIT](ERROR): Communications Fault in component: DAT
2018-09-08T01:32:50.226Z,1536370370.226 [DAT](INFO): Powering down
2018-09-08T01:32:51.105Z,1536370371.105 [CBIT](INFO): Clearing failed state for component DAT
2018-09-08T01:32:51.110Z,1536370371.110 [DAT] No Fault, FailCount= 1
2018-09-08T01:32:53.424Z,1536370373.424 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2018-09-08T01:32:53.424Z,1536370373.424 [DAT](INFO): Powering up
2018-09-08T01:32:53.424Z,1536370373.424 [DAT](DEBUG): Initializing DAT.
2018-09-08T01:32:53.470Z,1536370373.470 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2018-09-08T01:33:07.891Z,1536370387.891 [DAT](INFO): setting local address to 6
2018-09-08T01:33:08.284Z,1536370388.284 [DAT](INFO): set local address to 6
2018-09-08T01:33:11.837Z,1536370391.837 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T013303
2018-09-08T01:33:19.828Z,1536370399.828 [DataOverHttps](INFO): Received command:set NAL9602.time_Fix 1536370296 second
2018-09-08T01:33:21.062Z,1536370401.062 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1536370304.000000 second
2018-09-08T01:33:21.178Z,1536370401.178 [profile_station:NeedComms:E] Stopped
2018-09-08T01:33:21.180Z,1536370401.180 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2018-09-08T01:33:21.180Z,1536370401.180 [profile_station:NeedComms] Stopped
2018-09-08T01:33:21.180Z,1536370401.180 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-09-08T01:33:21.180Z,1536370401.180 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-09-08T01:33:21.180Z,1536370401.180 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-08T01:33:21.322Z,1536370401.322 [profile_station:StartingMission](INFO): Completed profile_station:StartingMission
2018-09-08T01:33:21.322Z,1536370401.322 [profile_station:StartingMission] Stopped
2018-09-08T01:33:21.322Z,1536370401.322 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2018-09-08T01:33:21.322Z,1536370401.322 [profile_station:CircleWrapper] Running Loop=1
2018-09-08T01:33:21.322Z,1536370401.322 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper
2018-09-08T01:33:21.322Z,1536370401.322 [profile_station:CircleWrapper:A] Running Loop=1
2018-09-08T01:33:21.322Z,1536370401.322 [profile_station:CircleWrapper:B.Circle] Running Loop=1
2018-09-08T01:33:21.322Z,1536370401.322 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2018-09-08T01:33:21.760Z,1536370401.760 [profile_station:CircleWrapper:A] Running Loop=1
2018-09-08T01:33:23.300Z,1536370403.300 [profile_station:CircleWrapper:B.Circle] Stopped
2018-09-08T01:33:23.300Z,1536370403.300 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2018-09-08T01:33:23.300Z,1536370403.300 [profile_station:CircleWrapper](INFO): Completed profile_station:CircleWrapper
2018-09-08T01:33:23.301Z,1536370403.301 [profile_station:CircleWrapper] Stopped
2018-09-08T01:33:23.301Z,1536370403.301 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2018-09-08T01:33:23.301Z,1536370403.301 [profile_station:CircleWrapper:A] Stopped
2018-09-08T01:33:23.301Z,1536370403.301 [profile_station:CircleWrapper](INFO): Running loop #2
2018-09-08T01:33:23.301Z,1536370403.301 [profile_station:CircleWrapper] Running Loop=2
2018-09-08T01:33:23.301Z,1536370403.301 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper
2018-09-08T01:33:23.301Z,1536370403.301 [profile_station:CircleWrapper:A] Running Loop=1
2018-09-08T01:33:23.302Z,1536370403.302 [profile_station:CircleWrapper:B.Circle] Running Loop=1
2018-09-08T01:33:23.302Z,1536370403.302 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2018-09-08T01:33:37.539Z,1536370417.539 [Radio_Surface](INFO): Powering down
2018-09-08T01:33:42.068Z,1536370422.068 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 2.00 mm (1 active estimators).
2018-09-08T01:33:44.071Z,1536370424.071 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-08T01:33:44.072Z,1536370424.072 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-2768,-32768,V
2018-09-08T01:33:45.264Z,1536370425.264 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T01:33:45.264Z,1536370425.264 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090801344741,35.0, -0.1,   0.0,10
2018-09-08T01:33:52.842Z,1536370432.842 [NAL9602](INFO): Powering down
2018-09-08T01:34:08.909Z,1536370448.909 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T01:34:08.910Z,1536370448.910 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090801351141,35.0, -0.11448.9,  0
2018-09-08T01:34:10.915Z,1536370450.915 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-08T01:34:10.915Z,1536370450.915 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-3276,-32768,-32768,V
2018-09-08T01:34:42.515Z,1536370482.515 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:35:42.514Z,1536370542.514 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:35:50.589Z,1536370550.589 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): calculated 0.18 from mean 15.63 degC, variance 0.04 degC^2, standard deviation 0.20 degC, and samples: (5.00 m, 15.75 degC), (10.00 m, 15.75 degC), (20.00 m, 15.72 degC), (30.00 m, 15.27 degC)
2018-09-08T01:35:50.598Z,1536370550.598 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T01:36:21.724Z,1536370581.724 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.087494
2018-09-08T01:36:56.570Z,1536370616.570 [RDI_Pathfinder](ERROR): Unknown system health message
2018-09-08T01:36:56.571Z,1536370616.571 [RDI_Pathfinder] Hardware Fault, FailCount= 1
2018-09-08T01:36:56.571Z,1536370616.571 [RDI_Pathfinder](ERROR): Hardware Fault
2018-09-08T01:36:56.722Z,1536370616.722 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2018-09-08T01:36:56.942Z,1536370616.942 [RDI_Pathfinder](INFO): Powering down
2018-09-08T01:36:57.874Z,1536370617.874 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-09-08T01:36:57.874Z,1536370617.874 [RDI_Pathfinder] No Fault, FailCount= 0
2018-09-08T01:37:11.355Z,1536370631.355 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-08T01:37:42.912Z,1536370662.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:38:43.336Z,1536370723.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:39:43.339Z,1536370783.339 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:40:06.154Z,1536370806.154 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 2.00 mm (2 active estimators).
2018-09-08T01:40:43.346Z,1536370843.346 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:41:43.745Z,1536370903.745 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:42:43.749Z,1536370963.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:43:44.128Z,1536371024.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:44:44.531Z,1536371084.531 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:45:11.411Z,1536371111.411 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): calculated 0.92 from mean 12.22 degC, variance 0.99 degC^2, standard deviation 0.99 degC, and samples: (5.00 m, 13.58 degC), (10.00 m, 12.69 degC), (20.00 m, 11.61 degC), (30.00 m, 10.99 degC)
2018-09-08T01:45:11.412Z,1536371111.412 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T01:45:27.754Z,1536371127.754 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-08T01:45:27.754Z,1536371127.754 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-2768,-32768,V
2018-09-08T01:45:44.525Z,1536371144.525 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:46:37.770Z,1536371197.770 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e8a, 15.101, -0.090, 0.000
2018-09-08T01:46:44.534Z,1536371204.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:47:11.782Z,1536371231.782 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): calculated 0.25 from mean 14.36 degC, variance 0.08 degC^2, standard deviation 0.29 degC, and samples: (5.00 m, 14.09 degC), (10.00 m, 14.41 degC), (20.00 m, 14.81 degC), (30.00 m, 14.11 degC)
2018-09-08T01:47:11.783Z,1536371231.783 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T01:47:44.936Z,1536371264.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:48:45.338Z,1536371325.338 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:49:45.748Z,1536371385.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:50:45.748Z,1536371445.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:51:46.534Z,1536371506.534 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:52:17.766Z,1536371537.766 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T01:52:17.767Z,1536371537.767 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090801532020,35.0, -0.1,   0.0, 0
2018-09-08T01:52:46.527Z,1536371566.527 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:53:21.432Z,1536371601.432 [profile_station:NeedComms] Running Loop=1
2018-09-08T01:53:21.432Z,1536371601.432 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-09-08T01:53:21.432Z,1536371601.432 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-09-08T01:53:21.432Z,1536371601.432 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-08T01:53:21.433Z,1536371601.433 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-08T01:53:21.433Z,1536371601.433 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-08T01:53:21.438Z,1536371601.438 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-08T01:53:21.438Z,1536371601.438 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-08T01:53:21.439Z,1536371601.439 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-08T01:53:21.440Z,1536371601.440 [profile_station:NeedComms:A] Running Loop=1
2018-09-08T01:53:21.446Z,1536371601.446 [profile_station:NeedComms:A](INFO): last time_fix was: 1536370304.000000 second since 1970/01/01T00:00:00Z 
2018-09-08T01:53:21.451Z,1536371601.451 [profile_station:NeedComms:A] Stopped
2018-09-08T01:53:46.525Z,1536371626.525 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:54:46.936Z,1536371686.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:55:47.331Z,1536371747.331 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:55:47.770Z,1536371747.770 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-08T01:56:33.176Z,1536371793.176 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): calculated 0.98 from mean 12.34 degC, variance 1.04 degC^2, standard deviation 1.02 degC, and samples: (5.00 m, 13.52 degC), (10.00 m, 13.12 degC), (20.00 m, 11.71 degC), (30.00 m, 11.02 degC)
2018-09-08T01:56:33.180Z,1536371793.180 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T01:56:36.367Z,1536371796.367 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-08T01:56:36.367Z,1536371796.367 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768V
2018-09-08T01:56:43.173Z,1536371803.173 [profile_station:NeedComms:C] Running Loop=1
2018-09-08T01:56:43.235Z,1536371803.235 [Radio_Surface](INFO): Powering up
2018-09-08T01:56:43.918Z,1536371803.918 [NAL9602](INFO): Powering up
2018-09-08T01:56:47.375Z,1536371807.375 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:56:54.979Z,1536371814.979 [NAL9602](INFO): NAL9602 initialized
2018-09-08T01:56:55.765Z,1536371815.765 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-08T01:56:55.765Z,1536371815.765 [NAL9602] Data Fault, FailCount= 1
2018-09-08T01:56:55.765Z,1536371815.765 [NAL9602](ERROR): Data Fault
2018-09-08T01:56:55.922Z,1536371815.922 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-08T01:56:56.171Z,1536371816.171 [NAL9602](INFO): Powering down
2018-09-08T01:56:57.134Z,1536371817.134 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-08T01:56:57.134Z,1536371817.134 [NAL9602] No Fault, FailCount= 1
2018-09-08T01:57:26.182Z,1536371846.182 [NAL9602](INFO): Powering up NAL9602
2018-09-08T01:57:27.411Z,1536371847.411 [RDI_Pathfinder](ERROR): only read 0 of 5 data items
2018-09-08T01:57:27.411Z,1536371847.411 [RDI_Pathfinder](ERROR): Failed to parse:
:BI-32768,-32768,-32768,V
2018-09-08T01:57:28.591Z,1536371848.591 [RDI_Pathfinder](ERROR): Failed to parse:

2018-09-08T01:57:37.372Z,1536371857.372 [NAL9602](INFO): NAL9602 initialized
2018-09-08T01:57:47.771Z,1536371867.771 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T01:57:54.178Z,1536371874.178 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=48639, MT Status=1, MTMSN=1427
2018-09-08T01:57:54.178Z,1536371874.178 [NAL9602](INFO): Data available in MT queue
2018-09-08T01:57:54.770Z,1536371874.770 [NAL9602](INFO): Received command:maintain clear
2018-09-08T01:57:54.791Z,1536371874.791 [CommandLine](IMPORTANT): got command maintain clear
2018-09-08T01:57:54.888Z,1536371874.888 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-08T01:58:34.535Z,1536371914.535 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003577
2018-09-08T02:00:03.796Z,1536372003.796 [DAT](FAULT): unknown deviceResponse_: Fail SNR:07.9 CCERR:005

2018-09-08T02:00:03.796Z,1536372003.796 [DAT] Communications Fault, FailCount= 1
2018-09-08T02:00:03.796Z,1536372003.796 [DAT](ERROR): Communications Fault
2018-09-08T02:00:03.909Z,1536372003.909 [CBIT](ERROR): Communications Fault in component: DAT
2018-09-08T02:00:04.183Z,1536372004.183 [DAT](INFO): Powering down
2018-09-08T02:00:04.583Z,1536372004.583 [DAT](FAULT): LCB fault:  Software Overcurrent.
2018-09-08T02:00:04.583Z,1536372004.583 [DAT] Hardware Fault, FailCount= 1
2018-09-08T02:00:04.583Z,1536372004.583 [DAT](ERROR): Hardware Fault
2018-09-08T02:00:05.048Z,1536372005.048 [CBIT](INFO): Clearing failed state for component DAT
2018-09-08T02:00:05.048Z,1536372005.048 [DAT] No Fault, FailCount= 1
2018-09-08T02:00:07.401Z,1536372007.401 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2018-09-08T02:00:07.401Z,1536372007.401 [DAT](INFO): Powering up
2018-09-08T02:00:07.406Z,1536372007.406 [DAT](DEBUG): Initializing DAT.
2018-09-08T02:00:07.482Z,1536372007.482 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2018-09-08T02:00:07.795Z,1536372007.795 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:00:07.796Z,1536372007.796 [RDI_Pathfinder](ERROR): Failed to parse:
:TS2011020,35.0, -0.1,   0.0,1448.9,  0
2018-09-08T02:00:25.046Z,1536372025.046 [DAT](INFO): setting local address to 6
2018-09-08T02:00:25.431Z,1536372025.431 [DAT](INFO): set local address to 6
2018-09-08T02:02:48.583Z,1536372168.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:03:43.453Z,1536372223.453 [profile_station:NeedComms:C](INFO): Timed out from 2018-09-08T01:56:43.2Z
2018-09-08T02:03:43.453Z,1536372223.453 [profile_station:NeedComms:C:A_Timeout] Running Loop=1
2018-09-08T02:03:43.453Z,1536372223.453 [profile_station:NeedComms:C:A_Timeout](DEBUG): Aggregate::initialize profile_station:NeedComms:C:A_Timeout
2018-09-08T02:03:43.470Z,1536372223.470 [profile_station:NeedComms:C:A_Timeout:A] Running Loop=1
2018-09-08T02:03:43.470Z,1536372223.470 [profile_station:NeedComms:C:A_Timeout:A](IMPORTANT): first GPS update timeout
2018-09-08T02:03:43.472Z,1536372223.472 [profile_station:NeedComms:C:A_Timeout:A] Stopped
2018-09-08T02:03:43.472Z,1536372223.472 [profile_station:NeedComms:C:A_Timeout](INFO): Completed profile_station:NeedComms:C:A_Timeout
2018-09-08T02:03:43.472Z,1536372223.472 [profile_station:NeedComms:C] Stopped
2018-09-08T02:03:43.472Z,1536372223.472 [profile_station:NeedComms:D] Running Loop=1
2018-09-08T02:03:48.596Z,1536372228.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:04:15.010Z,1536372255.010 [NAL9602](INFO): Powering down
2018-09-08T02:04:18.000Z,1536372259.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:04:37.802Z,1536372277.802 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.024857
2018-09-08T02:04:37.811Z,1536372277.811 [NAL9602](INFO): Powering up
2018-09-08T02:04:49.009Z,1536372289.009 [NAL9602](INFO): NAL9602 initialized
2018-09-08T02:05:08.410Z,1536372308.410 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:05:09.232Z,1536372309.232 [NAL9602](INFO): SBD MO Status=1, MOMSN=48640, MT Status=0, MTMSN=0
2018-09-08T02:05:09.282Z,1536372309.282 [NAL9602](INFO): Sent 43 bytes from file Logs/20180908T011540/Courier0013.lzma
2018-09-08T02:05:09.282Z,1536372309.282 [NAL9602](INFO): Packets left to send: 0
2018-09-08T02:05:09.284Z,1536372309.284 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Courier0013.lzma.parts/0000.sbd
2018-09-08T02:05:09.284Z,1536372309.284 [NAL9602](DEBUG): Completed sending Logs/20180908T011540/Courier0013.lzma
2018-09-08T02:05:20.297Z,1536372320.297 [NAL9602](INFO): SBD MO Status=1, MOMSN=48641, MT Status=0, MTMSN=0
2018-09-08T02:05:20.354Z,1536372320.354 [NAL9602](INFO): Sent 25 bytes from file Logs/20180908T011540/Courier0016.lzma
2018-09-08T02:05:20.354Z,1536372320.354 [NAL9602](INFO): Packets left to send: 0
2018-09-08T02:05:20.356Z,1536372320.356 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Courier0016.lzma.parts/0000.sbd
2018-09-08T02:05:20.356Z,1536372320.356 [NAL9602](DEBUG): Completed sending Logs/20180908T011540/Courier0016.lzma
2018-09-08T02:05:35.354Z,1536372335.354 [NAL9602](INFO): SBD MO Status=1, MOMSN=48642, MT Status=0, MTMSN=0
2018-09-08T02:05:35.404Z,1536372335.404 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0014.lzma
2018-09-08T02:05:35.404Z,1536372335.404 [NAL9602](INFO): Packets left to send: 7
2018-09-08T02:05:35.405Z,1536372335.405 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0014.lzma.parts/0007.sbd
2018-09-08T02:05:47.649Z,1536372347.649 [NAL9602](INFO): SBD MO Status=1, MOMSN=48643, MT Status=0, MTMSN=0
2018-09-08T02:05:47.702Z,1536372347.702 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0014.lzma
2018-09-08T02:05:47.703Z,1536372347.703 [NAL9602](INFO): Packets left to send: 6
2018-09-08T02:05:47.704Z,1536372347.704 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0014.lzma.parts/0006.sbd
2018-09-08T02:05:59.952Z,1536372359.952 [NAL9602](INFO): SBD MO Status=1, MOMSN=48644, MT Status=0, MTMSN=0
2018-09-08T02:06:00.002Z,1536372360.002 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0014.lzma
2018-09-08T02:06:00.002Z,1536372360.002 [NAL9602](INFO): Packets left to send: 5
2018-09-08T02:06:00.003Z,1536372360.003 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0014.lzma.parts/0005.sbd
2018-09-08T02:06:09.067Z,1536372369.067 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:06:19.472Z,1536372379.472 [NAL9602](INFO): SBD MO Status=1, MOMSN=48645, MT Status=0, MTMSN=0
2018-09-08T02:06:19.530Z,1536372379.530 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0014.lzma
2018-09-08T02:06:19.530Z,1536372379.530 [NAL9602](INFO): Packets left to send: 4
2018-09-08T02:06:19.532Z,1536372379.532 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0014.lzma.parts/0004.sbd
2018-09-08T02:06:31.293Z,1536372391.293 [NAL9602](INFO): SBD MO Status=1, MOMSN=48646, MT Status=0, MTMSN=0
2018-09-08T02:06:31.348Z,1536372391.348 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0014.lzma
2018-09-08T02:06:31.348Z,1536372391.348 [NAL9602](INFO): Packets left to send: 3
2018-09-08T02:06:31.351Z,1536372391.351 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0014.lzma.parts/0003.sbd
2018-09-08T02:06:45.188Z,1536372405.188 [NAL9602](INFO): SBD MO Status=1, MOMSN=48647, MT Status=0, MTMSN=0
2018-09-08T02:06:45.246Z,1536372405.246 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0014.lzma
2018-09-08T02:06:45.246Z,1536372405.246 [NAL9602](INFO): Packets left to send: 2
2018-09-08T02:06:45.248Z,1536372405.248 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0014.lzma.parts/0002.sbd
2018-09-08T02:06:49.837Z,1536372409.837 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-08T02:06:58.208Z,1536372418.208 [NAL9602](INFO): SBD MO Status=1, MOMSN=48648, MT Status=0, MTMSN=0
2018-09-08T02:06:58.266Z,1536372418.266 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0014.lzma
2018-09-08T02:06:58.266Z,1536372418.266 [NAL9602](INFO): Packets left to send: 1
2018-09-08T02:06:58.267Z,1536372418.267 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0014.lzma.parts/0001.sbd
2018-09-08T02:07:09.579Z,1536372429.579 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:07:09.583Z,1536372429.583 [NAL9602](INFO): SBD MO Status=1, MOMSN=48649, MT Status=0, MTMSN=0
2018-09-08T02:07:09.639Z,1536372429.639 [NAL9602](INFO): Sent 15 bytes from file Logs/20180908T011540/Express0014.lzma
2018-09-08T02:07:09.640Z,1536372429.640 [NAL9602](INFO): Packets left to send: 0
2018-09-08T02:07:09.642Z,1536372429.642 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0014.lzma.parts/0000.sbd
2018-09-08T02:07:09.643Z,1536372429.643 [NAL9602](DEBUG): Completed sending Logs/20180908T011540/Express0014.lzma
2018-09-08T02:07:37.423Z,1536372457.423 [NAL9602](INFO): SBD MO Status=2, MOMSN=48650, MT Status=2, MTMSN=0
2018-09-08T02:07:37.423Z,1536372457.423 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:07:53.637Z,1536372473.637 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=48650, MT Status=1, MTMSN=1428
2018-09-08T02:07:53.690Z,1536372473.690 [NAL9602](INFO): Sent 38 bytes from file Logs/20180908T011540/Express0017.lzma
2018-09-08T02:07:53.691Z,1536372473.691 [NAL9602](INFO): Packets left to send: 0
2018-09-08T02:07:53.693Z,1536372473.693 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0017.lzma.parts/0000.sbd
2018-09-08T02:07:53.693Z,1536372473.693 [NAL9602](DEBUG): Completed sending Logs/20180908T011540/Express0017.lzma
2018-09-08T02:07:54.402Z,1536372474.402 [NAL9602](INFO): Received command:sched asap "configSet Express linearApproximation latitude 0.005 angular_degree persist;configSet Express linearApproximation longitude 0.005 angular_degree persist;configSet Express linearApproximation depth 5 meter persist" 480cr 1 2
2018-09-08T02:07:54.439Z,1536372474.439 [CommandLine](IMPORTANT): got command schedule asap "configSet Express linearApproximation latitude 0.005 angular_degree persist;configSet Express linearApproximation longitude 0.005 angular_degree persist;configSet Express linearApproximation depth 5 meter persist" 480cr 1 2.000000
2018-09-08T02:07:54.440Z,1536372474.440 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=480cr
2018-09-08T02:07:54.441Z,1536372474.441 [CommandLine](IMPORTANT): Scheduled #3 (#1 of 2 with id='480cr'): "configSet Express linearApproximation latitude 0.005 angular_degree persist;configSet Express linearApproximation longitude 0.005 angular_degree persist;configSet Express linearApproximation depth 5 meter persist" ASAP
2018-09-08T02:08:09.769Z,1536372489.769 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:08:12.179Z,1536372492.179 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=48651, MT Status=1, MTMSN=1429
2018-09-08T02:08:12.982Z,1536372492.982 [NAL9602](INFO): Received command:sched asap "configSet Express none ESPComponent.sampling persist;configSet Express none ESPComponent.sample_number persist;configSet RDI_Pathfinder.loadAtStartup 0 bool persist;configSet CBIT.gf24Offset 180 microampere persist" 480cr 2 2
2018-09-08T02:08:13.039Z,1536372493.039 [CommandLine](IMPORTANT): got command schedule asap "configSet Express none ESPComponent.sampling persist;configSet Express none ESPComponent.sample_number persist;configSet RDI_Pathfinder.loadAtStartup 0 bool persist;configSet CBIT.gf24Offset 180 microampere persist" 480cr 2 2.000000
2018-09-08T02:08:13.039Z,1536372493.039 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=480cr
2018-09-08T02:08:13.040Z,1536372493.040 [CommandLine](IMPORTANT): Scheduled #4 (#2 of 2 with id='480cr'): "configSet Express none ESPComponent.sampling persist;configSet Express none ESPComponent.sample_number persist;configSet RDI_Pathfinder.loadAtStartup 0 bool persist;configSet CBIT.gf24Offset 180 microampere persist" ASAP
2018-09-08T02:08:14.688Z,1536372494.688 [CommandLine](IMPORTANT): got command configSet Express linearApproximation latitude 0.005000 angular_degree persist
2018-09-08T02:08:14.699Z,1536372494.699 [CommandLine](IMPORTANT): got command configSet Express linearApproximation longitude 0.005000 angular_degree persist
2018-09-08T02:08:14.706Z,1536372494.706 [CommandLine](IMPORTANT): got command configSet Express linearApproximation depth 5.000000 meter persist
2018-09-08T02:08:18.388Z,1536372498.388 [CommandLine](IMPORTANT): got command configSet Express none ESPComponent.sampling persist
2018-09-08T02:08:18.391Z,1536372498.391 [CommandLine](IMPORTANT): got command configSet Express none ESPComponent.sample_number persist
2018-09-08T02:08:18.394Z,1536372498.394 [CommandLine](IMPORTANT): got command configSet RDI_Pathfinder.loadAtStartup 0.000000 bool persist
2018-09-08T02:08:18.394Z,1536372498.394 [CommandLine](IMPORTANT): configSet RDI_Pathfinder.loadAtStartup requires a restart to take effect.
2018-09-08T02:08:18.397Z,1536372498.397 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 180.000000 microampere persist
2018-09-08T02:08:19.721Z,1536372499.721 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e88,*15.141,*-0.090,*0.000
2018-09-08T02:08:33.253Z,1536372513.253 [NAL9602](INFO): SBD MO Status=0, MOMSN=48652, MT Status=0, MTMSN=0
2018-09-08T02:08:41.070Z,1536372521.070 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:08:41.071Z,1536372521.071 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802094320,35.0, -0.1,   0.,  0
2018-09-08T02:08:52.228Z,1536372532.228 [NAL9602](INFO): SBD MO Status=1, MOMSN=48653, MT Status=0, MTMSN=0
2018-09-08T02:08:52.278Z,1536372532.278 [NAL9602](INFO): Sent 25 bytes from file Logs/20180908T011540/Courier0019.lzma
2018-09-08T02:08:52.278Z,1536372532.278 [NAL9602](INFO): Packets left to send: 0
2018-09-08T02:08:52.280Z,1536372532.280 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Courier0019.lzma.parts/0000.sbd
2018-09-08T02:08:52.280Z,1536372532.280 [NAL9602](DEBUG): Completed sending Logs/20180908T011540/Courier0019.lzma
2018-09-08T02:09:10.413Z,1536372550.413 [NAL9602](INFO): SBD MO Status=1, MOMSN=48654, MT Status=0, MTMSN=0
2018-09-08T02:09:10.482Z,1536372550.482 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0020.lzma
2018-09-08T02:09:10.482Z,1536372550.482 [NAL9602](INFO): Packets left to send: 2
2018-09-08T02:09:10.484Z,1536372550.484 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0020.lzma.parts/0002.sbd
2018-09-08T02:09:10.800Z,1536372550.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:09:27.804Z,1536372567.804 [NAL9602](INFO): SBD MO Status=2, MOMSN=48655, MT Status=2, MTMSN=0
2018-09-08T02:09:27.804Z,1536372567.804 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:09:56.407Z,1536372596.407 [NAL9602](INFO): SBD MO Status=2, MOMSN=48655, MT Status=2, MTMSN=0
2018-09-08T02:09:56.408Z,1536372596.408 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:10:11.124Z,1536372611.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:10:19.546Z,1536372619.546 [NAL9602](INFO): SBD MO Status=2, MOMSN=48655, MT Status=2, MTMSN=0
2018-09-08T02:10:19.546Z,1536372619.546 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:10:40.252Z,1536372640.252 [NAL9602](INFO): SBD MO Status=2, MOMSN=48655, MT Status=2, MTMSN=0
2018-09-08T02:10:40.252Z,1536372640.252 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:11:07.778Z,1536372667.778 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:11:07.778Z,1536372667.778 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802121020,35.0, -0.1,  8.9,  0
2018-09-08T02:11:11.370Z,1536372671.370 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:11:17.777Z,1536372677.777 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:11:17.777Z,1536372677.777 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802122020,35.0, -0.1,   .9,  0
2018-09-08T02:11:28.969Z,1536372688.969 [NAL9602](INFO): SBD MO Status=2, MOMSN=48655, MT Status=2, MTMSN=0
2018-09-08T02:11:28.969Z,1536372688.969 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:12:02.289Z,1536372722.289 [NAL9602](INFO): SBD MO Status=2, MOMSN=48655, MT Status=2, MTMSN=0
2018-09-08T02:12:02.290Z,1536372722.290 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:12:11.263Z,1536372731.263 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:12:26.869Z,1536372746.869 [NAL9602](INFO): SBD MO Status=2, MOMSN=48655, MT Status=2, MTMSN=0
2018-09-08T02:12:26.869Z,1536372746.869 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:12:32.263Z,1536372752.263 [Radio_Surface](INFO): Powering down
2018-09-08T02:12:38.322Z,1536372758.322 [Radio_Surface](INFO): Powering up
2018-09-08T02:12:50.721Z,1536372770.721 [RDI_Pathfinder](ERROR): only read 4 of 5 data items
2018-09-08T02:12:50.721Z,1536372770.721 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-3:BS,-32768,-32768,-32768,V
2018-09-08T02:13:05.056Z,1536372785.056 [NAL9602](INFO): SBD MO Status=1, MOMSN=48655, MT Status=0, MTMSN=0
2018-09-08T02:13:05.110Z,1536372785.110 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T011540/Express0020.lzma
2018-09-08T02:13:05.110Z,1536372785.110 [NAL9602](INFO): Packets left to send: 1
2018-09-08T02:13:05.111Z,1536372785.111 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0020.lzma.parts/0001.sbd
2018-09-08T02:13:11.324Z,1536372791.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:13:24.520Z,1536372804.520 [NAL9602](INFO): SBD MO Status=2, MOMSN=48656, MT Status=2, MTMSN=0
2018-09-08T02:13:24.520Z,1536372804.520 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:13:37.516Z,1536372817.516 [NAL9602](INFO): SBD MO Status=1, MOMSN=48656, MT Status=0, MTMSN=0
2018-09-08T02:13:37.566Z,1536372817.566 [NAL9602](INFO): Sent 95 bytes from file Logs/20180908T011540/Express0020.lzma
2018-09-08T02:13:37.566Z,1536372817.566 [NAL9602](INFO): Packets left to send: 0
2018-09-08T02:13:37.567Z,1536372817.567 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T011540/Express0020.lzma.parts/0000.sbd
2018-09-08T02:13:37.568Z,1536372817.568 [NAL9602](DEBUG): Completed sending Logs/20180908T011540/Express0020.lzma
2018-09-08T02:14:11.524Z,1536372851.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:14:15.932Z,1536372855.932 [NAL9602](INFO): SBD MO Status=2, MOMSN=48657, MT Status=2, MTMSN=0
2018-09-08T02:14:15.932Z,1536372855.932 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-08T02:14:26.379Z,1536372866.379 [RDI_Pathfinder](ERROR): Failed to parse:     +0.00,        +0.00,        +0.00,   0.00,2234.79
2018-09-08T02:14:38.321Z,1536372878.321 [NAL9602](INFO): SBD MO Status=0, MOMSN=48657, MT Status=0, MTMSN=0
2018-09-08T02:14:38.433Z,1536372878.433 [profile_station:NeedComms:D] Stopped
2018-09-08T02:14:38.433Z,1536372878.433 [profile_station:NeedComms:E] Running Loop=1
2018-09-08T02:14:40.319Z,1536372880.319 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-08T02:14:40.319Z,1536372880.319 [NAL9602] Data Fault, FailCount= 2
2018-09-08T02:14:40.319Z,1536372880.319 [NAL9602](ERROR): Data Fault
2018-09-08T02:14:40.426Z,1536372880.426 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-08T02:14:40.732Z,1536372880.732 [NAL9602](INFO): Powering down
2018-09-08T02:14:41.643Z,1536372881.643 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-08T02:14:41.643Z,1536372881.643 [NAL9602] No Fault, FailCount= 2
2018-09-08T02:15:10.752Z,1536372910.752 [NAL9602](INFO): Powering up NAL9602
2018-09-08T02:15:11.916Z,1536372911.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:15:16.773Z,1536372916.773 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: -32768
2018-09-08T02:15:16.774Z,1536372916.774 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2018-09-08T02:15:21.921Z,1536372921.921 [NAL9602](INFO): NAL9602 initialized
2018-09-08T02:16:11.919Z,1536372971.919 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:16:36.777Z,1536372996.777 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e88, 15.141, -0.086, 0.000
2018-09-08T02:17:12.331Z,1536373032.331 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:18:12.734Z,1536373092.734 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:18:57.052Z,1536373137.052 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002780
2018-09-08T02:19:27.866Z,1536373167.866 [NAL9602](INFO): GPS fix at 20180908T021918: (36.978450, -122.527178)
2018-09-08T02:19:27.918Z,1536373167.918 [UniversalFixResidualReporter](INFO): Fix residual: 53.8 %DT, over the last 1318.9 m. Residual distance 708.9 m at bearing 72.8 degrees. Fix at (36.9785, -122.5272) with 814.3 m made good.
2018-09-08T02:19:27.938Z,1536373167.938 [profile_station:NeedComms:E] Stopped
2018-09-08T02:19:27.939Z,1536373167.939 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2018-09-08T02:19:27.939Z,1536373167.939 [profile_station:NeedComms] Stopped
2018-09-08T02:19:27.939Z,1536373167.939 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-09-08T02:19:27.939Z,1536373167.939 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-09-08T02:19:27.939Z,1536373167.939 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-08T02:19:29.338Z,1536373169.338 [Radio_Surface](INFO): Powering down
2018-09-08T02:19:35.406Z,1536373175.406 [Radio_Surface](INFO): Powering up
2018-09-08T02:19:41.496Z,1536373181.496 [RDI_Pathfinder](ERROR): Failed to parse:
0e88,0e88,*15.040,*-0.086,*0.000
2018-09-08T02:19:58.039Z,1536373198.039 [Radio_Surface](INFO): Powering down
2018-09-08T02:20:00.253Z,1536373200.253 [NAL9602](INFO): Powering down
2018-09-08T02:20:12.662Z,1536373212.662 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:20:42.368Z,1536373242.368 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-08T02:20:42.368Z,1536373242.368 [RDI_Pathfinder](ERROR): Failed to parse:
:HM15.088, -0.087, 0.000
2018-09-08T02:21:13.106Z,1536373273.106 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:21:16.751Z,1536373276.751 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:21:16.751Z,1536373276.751 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802221920,35.0, -0.1,   0.0,144

2018-09-08T02:21:25.546Z,1536373285.546 [RDI_Pathfinder](ERROR): Failed to parse:
:H88,0e87, 15.081, -0.090, 0.000
2018-09-08T02:21:44.357Z,1536373304.357 [RDI_Pathfinder](ERROR): only read 1 of 1 data item for altitude
2018-09-08T02:21:44.362Z,1536373304.362 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,,0e89, 15.081, -0.090, 0.000
2018-09-08T02:21:57.102Z,1536373317.102 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.053177
2018-09-08T02:22:00.341Z,1536373320.341 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): calculated 0.29 from mean 15.48 degC, variance 0.11 degC^2, standard deviation 0.33 degC, and samples: (5.00 m, 15.68 degC), (10.00 m, 15.69 degC), (20.00 m, 15.63 degC), (30.00 m, 14.90 degC)
2018-09-08T02:22:00.341Z,1536373320.341 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T02:22:06.747Z,1536373326.747 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:22:06.747Z,1536373326.747 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802230920,35.0, -0.1,   0.0,1448.BI,-32768,-32768,-32768,-32768,V
2018-09-08T02:23:13.110Z,1536373393.110 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:23:23.553Z,1536373403.553 [RDI_Pathfinder](ERROR): Failed to parse:
0e88,0e88, 15.088, -0.087, 0.000
2018-09-08T02:23:54.360Z,1536373434.360 [RDI_Pathfinder](ERROR): only read 1 of 1 data item for altitude
2018-09-08T02:23:54.360Z,1536373434.360 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G0e87, 15.101, -0.086, 0.000
2018-09-08T02:24:13.896Z,1536373453.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:24:15.551Z,1536373455.551 [RDI_Pathfinder](ERROR): Failed to parse:
0e88,0e89, 15.081, -0.093, 0.000
2018-09-08T02:25:14.300Z,1536373514.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:25:52.354Z,1536373552.354 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-08T02:25:52.354Z,1536373552.354 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-

2018-09-08T02:26:14.304Z,1536373574.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:27:14.315Z,1536373634.315 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:27:26.760Z,1536373646.760 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:27:26.760Z,1536373646.760 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802282920,35.0, -0.1,   0.,  0
2018-09-08T02:28:15.106Z,1536373695.106 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:28:32.355Z,1536373712.355 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:28:32.356Z,1536373712.356 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802293520,35.0, -0.1,   0.0,1448.BI,-32768-32768,-32768,V
2018-09-08T02:28:34.787Z,1536373714.787 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e89, 15.074, -0.087, 0.000
2018-09-08T02:29:12.756Z,1536373752.756 [RDI_Pathfinder](ERROR): only read 1 of 5 data items
2018-09-08T02:29:12.756Z,1536373752.756 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768-32768,-32768,V
2018-09-08T02:29:15.112Z,1536373755.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:29:56.751Z,1536373796.751 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:29:56.751Z,1536373796.751 [RDI_Pathfinder](ERROR): Failed to parse::TS,180908023059 -0.1,   0.0,1448.9,  0
2018-09-08T02:30:02.760Z,1536373802.760 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-08T02:30:02.760Z,1536373802.760 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-3768,V
2018-09-08T02:30:15.118Z,1536373815.118 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:30:34.756Z,1536373834.756 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:30:34.756Z,1536373834.756 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802313720,35.0, -0.1,   0.0  0
2018-09-08T02:31:02.364Z,1536373862.364 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e8a, 15.081, -0.083, 0.000
2018-09-08T02:31:12.761Z,1536373872.761 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-08T02:31:12.761Z,1536373872.761 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18090802321520,35.0, 0.0,1448.9,  0
2018-09-08T02:31:15.188Z,1536373875.188 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): calculated 0.64 from mean 11.59 degC, variance 0.46 degC^2, standard deviation 0.68 degC, and samples: (5.00 m, 12.52 degC), (10.00 m, 11.94 degC), (20.00 m, 11.08 degC), (30.00 m, 10.82 degC)
2018-09-08T02:31:15.188Z,1536373875.188 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T02:31:15.511Z,1536373875.511 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:31:16.759Z,1536373876.759 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 32768
2018-09-08T02:31:16.760Z,1536373876.760 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2018-09-08T02:32:15.940Z,1536373935.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:33:16.360Z,1536373996.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:33:19.582Z,1536373999.582 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): calculated 0.42 from mean 13.93 degC, variance 0.20 degC^2, standard deviation 0.44 degC, and samples: (5.00 m, 13.43 degC), (10.00 m, 14.19 degC), (20.00 m, 14.51 degC), (30.00 m, 13.57 degC)
2018-09-08T02:33:19.582Z,1536373999.582 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T02:34:16.356Z,1536374056.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:35:16.737Z,1536374116.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:36:16.751Z,1536374176.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:37:16.749Z,1536374236.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:38:16.746Z,1536374296.746 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:39:10.792Z,1536374350.792 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e87,*15.081,*-0.090,*0.000
2018-09-08T02:39:17.132Z,1536374357.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:39:27.995Z,1536374367.995 [profile_station:NeedComms] Running Loop=1
2018-09-08T02:39:27.996Z,1536374367.996 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-09-08T02:39:27.996Z,1536374367.996 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-09-08T02:39:27.996Z,1536374367.996 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-08T02:39:27.996Z,1536374367.996 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-08T02:39:27.997Z,1536374367.997 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-08T02:39:27.997Z,1536374367.997 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-08T02:39:28.006Z,1536374368.006 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-08T02:39:28.006Z,1536374368.006 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-08T02:39:28.006Z,1536374368.006 [profile_station:NeedComms:A] Running Loop=1
2018-09-08T02:39:28.008Z,1536374368.008 [profile_station:NeedComms:A](INFO): last time_fix was: 1536373158.000000 second since 1970/01/01T00:00:00Z 
2018-09-08T02:39:28.008Z,1536374368.008 [profile_station:NeedComms:A] Stopped
2018-09-08T02:40:15.554Z,1536374415.554 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 768
2018-09-08T02:40:15.555Z,1536374415.555 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2018-09-08T02:40:17.532Z,1536374417.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:41:17.537Z,1536374477.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:41:18.808Z,1536374478.808 [profile_station:CircleWrapper:B.Circle] Stopped
2018-09-08T02:41:18.809Z,1536374478.809 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2018-09-08T02:41:18.810Z,1536374478.810 [profile_station:CircleWrapper](INFO): Completed profile_station:CircleWrapper
2018-09-08T02:41:18.810Z,1536374478.810 [profile_station:CircleWrapper] Stopped
2018-09-08T02:41:18.810Z,1536374478.810 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2018-09-08T02:41:18.810Z,1536374478.810 [profile_station:CircleWrapper:A] Stopped
2018-09-08T02:41:18.810Z,1536374478.810 [profile_station:CircleWrapper](INFO): Running loop #3
2018-09-08T02:41:18.810Z,1536374478.810 [profile_station:CircleWrapper] Running Loop=3
2018-09-08T02:41:18.810Z,1536374478.810 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper
2018-09-08T02:41:18.810Z,1536374478.810 [profile_station:CircleWrapper:A] Running Loop=1
2018-09-08T02:41:18.811Z,1536374478.811 [profile_station:CircleWrapper:B.Circle] Running Loop=1
2018-09-08T02:41:18.811Z,1536374478.811 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2018-09-08T02:42:18.340Z,1536374538.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:42:41.996Z,1536374561.996 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): calculated 0.98 from mean 11.92 degC, variance 1.09 degC^2, standard deviation 1.04 degC, and samples: (5.00 m, 13.37 degC), (10.00 m, 12.43 degC), (20.00 m, 11.11 degC), (30.00 m, 10.77 degC)
2018-09-08T02:42:41.997Z,1536374561.997 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T02:42:54.444Z,1536374574.444 [profile_station:NeedComms:C] Running Loop=1
2018-09-08T02:42:54.695Z,1536374574.695 [Radio_Surface](INFO): Powering up
2018-09-08T02:42:55.178Z,1536374575.178 [NAL9602](INFO): Powering up
2018-09-08T02:43:05.981Z,1536374585.981 [NAL9602](INFO): NAL9602 initialized
2018-09-08T02:43:16.016Z,1536374596.016 [DAT](FAULT): unknown deviceResponse_: NR:06.9 CCERR:005

2018-09-08T02:43:16.016Z,1536374596.016 [DAT] Communications Fault, FailCount= 1
2018-09-08T02:43:16.016Z,1536374596.016 [DAT](ERROR): Communications Fault
2018-09-08T02:43:16.253Z,1536374596.253 [CBIT](ERROR): Communications Fault in component: DAT
2018-09-08T02:43:16.424Z,1536374596.424 [DAT](INFO): Powering down
2018-09-08T02:43:16.808Z,1536374596.808 [DAT](FAULT): LCB fault:  Software Overcurrent.
2018-09-08T02:43:16.808Z,1536374596.808 [DAT] Hardware Fault, FailCount= 1
2018-09-08T02:43:16.808Z,1536374596.808 [DAT](ERROR): Hardware Fault
2018-09-08T02:43:17.283Z,1536374597.283 [CBIT](INFO): Clearing failed state for component DAT
2018-09-08T02:43:17.284Z,1536374597.284 [DAT] No Fault, FailCount= 1
2018-09-08T02:43:17.980Z,1536374597.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T02:43:19.613Z,1536374599.613 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2018-09-08T02:43:19.613Z,1536374599.613 [DAT](INFO): Powering up
2018-09-08T02:43:19.614Z,1536374599.614 [DAT](DEBUG): Initializing DAT.
2018-09-08T02:43:19.635Z,1536374599.635 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2018-09-08T02:43:22.016Z,1536374602.016 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=48658, MT Status=1, MTMSN=1430
2018-09-08T02:43:22.017Z,1536374602.017 [NAL9602](INFO): Data available in MT queue
2018-09-08T02:43:22.634Z,1536374602.634 [NAL9602](INFO): Received command:restart sys
2018-09-08T02:43:22.639Z,1536374602.639 [CommandLine](IMPORTANT): got command restart system
2018-09-08T02:43:24.552Z,1536374604.552 [RDI_Pathfinder](ERROR): Failed to parse:
:H88,0e8a, 15.114, -0.084, 0.000
2018-09-08T02:43:24.982Z,1536374604.982 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-09-08T02:43:24.986Z,1536374604.986 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T02:43:24.986Z,1536374604.986 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:24.997Z,1536374604.997 [MissionManager](INFO): MissionManager is completed.
2018-09-08T02:43:24.997Z,1536374604.997 [MissionManager](INFO): Uninitializing Mission profile_station
2018-09-08T02:43:24.997Z,1536374604.997 [profile_station] Stopped
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station:Science] Stopped
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station:Science:C] Stopped
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station:Science:D] Stopped
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station:Science:G] Stopped
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station:Science:PeakDetectChl] Stopped
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2018-09-08T02:43:25.002Z,1536374605.002 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:NeedComms] Stopped
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:NeedComms:C] Stopped
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:StandardEnvelopes] Stopped
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2018-09-08T02:43:25.003Z,1536374605.003 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:I.Pitch] Stopped
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:J.Buoyancy] Stopped
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:K.SetSpeed] Stopped
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2018-09-08T02:43:25.004Z,1536374605.004 [profile_station:L.DepthEnvelope] Stopped
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:M.AltitudeEnvelope] Stopped
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:N.YoYo] Stopped
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:CircleWrapper] Stopped
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:CircleWrapper:A] Stopped
2018-09-08T02:43:25.005Z,1536374605.005 [profile_station:CircleWrapper:B.Circle] Stopped
2018-09-08T02:43:25.010Z,1536374605.010 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2018-09-08T02:43:25.062Z,1536374605.062 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-08T02:43:25.062Z,1536374605.062 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:25.063Z,1536374605.063 [CommandLine](INFO): Join timeout helper Thread ID is 1455
2018-09-08T02:43:25.078Z,1536374605.078 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-08T02:43:25.078Z,1536374605.078 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:25.078Z,1536374605.078 [NavChartDb](INFO): Join timeout helper Thread ID is 1456
2018-09-08T02:43:25.098Z,1536374605.098 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T02:43:25.098Z,1536374605.098 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:25.114Z,1536374605.114 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-08T02:43:25.114Z,1536374605.114 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:25.115Z,1536374605.115 [Radio_Surface](INFO): Join timeout helper Thread ID is 1457
2018-09-08T02:43:25.312Z,1536374605.312 [WetLabsBB2FL](INFO): Powering down
2018-09-08T02:43:25.426Z,1536374605.426 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T02:43:25.426Z,1536374605.426 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:25.438Z,1536374605.438 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-09-08T02:43:25.438Z,1536374605.438 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:25.438Z,1536374605.438 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1459
2018-09-08T02:43:25.441Z,1536374605.441 [MissionManager](IMPORTANT): Started mission Default
2018-09-08T02:43:25.441Z,1536374605.441 [Default] Running Loop=1
2018-09-08T02:43:25.441Z,1536374605.441 [Default](DEBUG): Aggregate::initialize Default
2018-09-08T02:43:25.441Z,1536374605.441 [Default:B.GoToSurface] Running Loop=1
2018-09-08T02:43:25.446Z,1536374605.446 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-08T02:43:25.446Z,1536374605.446 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-08T02:43:25.446Z,1536374605.446 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-08T02:43:25.447Z,1536374605.447 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-08T02:43:25.447Z,1536374605.447 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-08T02:43:25.448Z,1536374605.448 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-08T02:43:25.449Z,1536374605.449 [Default:A.Wait] Running Loop=1
2018-09-08T02:43:25.449Z,1536374605.449 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-08T02:43:25.526Z,1536374605.526 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-08T02:43:25.526Z,1536374605.526 [CTD_Seabird](INFO): Powering down
2018-09-08T02:43:25.818Z,1536374605.818 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T02:43:25.818Z,1536374605.818 [WetLabsBB2FL](INFO): Powering down
2018-09-08T02:43:25.819Z,1536374605.819 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:25.826Z,1536374605.826 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-09-08T02:43:25.826Z,1536374605.826 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:25.826Z,1536374605.826 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1460
2018-09-08T02:43:25.930Z,1536374605.930 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T02:43:26.013Z,1536374606.013 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-08T02:43:26.013Z,1536374606.013 [CTD_Seabird](INFO): Powering down
2018-09-08T02:43:26.014Z,1536374606.014 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.027Z,1536374606.027 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-08T02:43:26.027Z,1536374606.027 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.027Z,1536374606.027 [logger](INFO): Join timeout helper Thread ID is 1462
2018-09-08T02:43:26.046Z,1536374606.046 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T02:43:26.046Z,1536374606.046 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.066Z,1536374606.066 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-08T02:43:26.067Z,1536374606.067 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.067Z,1536374606.067 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-08T02:43:26.067Z,1536374606.067 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.067Z,1536374606.067 [controlThread](INFO): Join timeout helper Thread ID is 1463
2018-09-08T02:43:26.070Z,1536374606.070 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T02:43:26.070Z,1536374606.070 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-08T02:43:26.073Z,1536374606.073 [AHRS_M2](INFO): Powering down
2018-09-08T02:43:26.147Z,1536374606.147 [NAL9602](INFO): Powering down
2018-09-08T02:43:26.218Z,1536374606.218 [RDI_Pathfinder](INFO): Powering down
2018-09-08T02:43:26.219Z,1536374606.219 [DAT](INFO): Powering down
2018-09-08T02:43:26.339Z,1536374606.339 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-08T02:43:26.340Z,1536374606.340 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-08T02:43:26.340Z,1536374606.340 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-08T02:43:26.341Z,1536374606.341 [MissionManager](INFO): Uninitializing Mission Default
2018-09-08T02:43:26.341Z,1536374606.341 [Default] Stopped
2018-09-08T02:43:26.341Z,1536374606.341 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-08T02:43:26.342Z,1536374606.342 [Default:A.Wait] Stopped
2018-09-08T02:43:26.342Z,1536374606.342 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-08T02:43:26.342Z,1536374606.342 [Default:B.GoToSurface] Stopped
2018-09-08T02:43:26.342Z,1536374606.342 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-08T02:43:26.346Z,1536374606.346 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-08T02:43:26.347Z,1536374606.347 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-08T02:43:26.347Z,1536374606.347 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-08T02:43:26.347Z,1536374606.347 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-08T02:43:26.348Z,1536374606.348 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-08T02:43:26.349Z,1536374606.349 [BuoyancyServo](INFO): Powering down
2018-09-08T02:43:26.366Z,1536374606.366 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-09-08T02:43:26.366Z,1536374606.366 [ElevatorServo](INFO): Powering down
2018-09-08T02:43:26.367Z,1536374606.367 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-09-08T02:43:26.367Z,1536374606.367 [MassServo](INFO): Powering down
2018-09-08T02:43:26.368Z,1536374606.368 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-09-08T02:43:26.369Z,1536374606.369 [RudderServo](INFO): Powering down
2018-09-08T02:43:26.370Z,1536374606.370 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-08T02:43:26.370Z,1536374606.370 [ThrusterServo](INFO): Powering down
2018-09-08T02:43:26.371Z,1536374606.371 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-08T02:43:26.371Z,1536374606.371 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-08T02:43:26.372Z,1536374606.372 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-08T02:43:26.373Z,1536374606.373 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.416Z,1536374606.416 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.423Z,1536374606.423 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.627Z,1536374606.627 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.692Z,1536374606.692 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-08T02:43:26.762Z,1536374606.762 [logger ThreadHandler](INFO): Thread cancelled.