2018-08-23T16:23:41.374Z,1535041421.374 [Supervisor](DEBUG): Initializing supervisor.
2018-08-23T16:23:41.377Z,1535041421.377 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-08-23T16:23:41.378Z,1535041421.378 [SyncHandler](INFO): Protected caller Thread ID is 1844
2018-08-23T16:23:41.378Z,1535041421.378 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-08-23T16:23:41.379Z,1535041421.379 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-08-23T16:23:41.379Z,1535041421.379 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1845
2018-08-23T16:23:41.383Z,1535041421.383 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-08-23T16:23:41.395Z,1535041421.395 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-08-23T16:23:41.396Z,1535041421.396 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-08-23T16:23:41.396Z,1535041421.396 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1846
2018-08-23T16:23:41.397Z,1535041421.397 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-08-23T16:23:41.398Z,1535041421.398 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-08-23T16:23:41.398Z,1535041421.398 [logger ThreadHandler](INFO): Protected caller Thread ID is 1847
2018-08-23T16:23:41.400Z,1535041421.400 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-08-23T16:23:41.401Z,1535041421.401 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-08-23T16:23:41.402Z,1535041421.402 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-08-23T16:23:41.502Z,1535041421.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-08-23T16:23:41.502Z,1535041421.502 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-08-23T16:23:41.606Z,1535041421.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-08-23T16:23:41.607Z,1535041421.607 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-08-23T16:23:41.824Z,1535041421.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-08-23T16:23:41.824Z,1535041421.824 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-08-23T16:23:42.171Z,1535041422.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-08-23T16:23:42.171Z,1535041422.171 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-08-23T16:23:42.532Z,1535041422.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-08-23T16:23:42.532Z,1535041422.532 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-08-23T16:23:43.023Z,1535041423.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-08-23T16:23:43.024Z,1535041423.024 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-08-23T16:23:43.471Z,1535041423.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-08-23T16:23:43.472Z,1535041423.472 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-08-23T16:23:43.973Z,1535041423.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-08-23T16:23:43.973Z,1535041423.973 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-08-23T16:23:44.055Z,1535041424.055 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-08-23T16:23:44.445Z,1535041424.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-08-23T16:23:44.446Z,1535041424.446 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-08-23T16:23:44.734Z,1535041424.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-08-23T16:23:44.735Z,1535041424.735 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-08-23T16:23:44.970Z,1535041424.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-08-23T16:23:44.970Z,1535041424.970 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-08-23T16:23:45.193Z,1535041425.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-08-23T16:23:45.194Z,1535041425.194 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-08-23T16:23:45.295Z,1535041425.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-08-23T16:23:45.296Z,1535041425.296 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-08-23T16:23:45.443Z,1535041425.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-08-23T16:23:45.445Z,1535041425.445 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-08-23T16:23:45.446Z,1535041425.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-08-23T16:23:45.686Z,1535041425.686 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-08-23T16:23:45.687Z,1535041425.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-08-23T16:23:45.796Z,1535041425.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-08-23T16:23:45.946Z,1535041425.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-08-23T16:23:46.041Z,1535041426.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-08-23T16:23:46.128Z,1535041426.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-08-23T16:23:46.277Z,1535041426.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-08-23T16:23:46.467Z,1535041426.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-08-23T16:23:46.567Z,1535041426.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-08-23T16:23:46.664Z,1535041426.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-08-23T16:23:46.764Z,1535041426.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-08-23T16:23:46.848Z,1535041426.848 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-08-23T16:23:46.857Z,1535041426.857 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-08-23T16:23:46.962Z,1535041426.962 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-08-23T16:23:46.962Z,1535041426.962 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-08-23T16:23:46.976Z,1535041426.976 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-08-23T16:23:46.977Z,1535041426.977 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-08-23T16:23:47.020Z,1535041427.020 [DepthRateCalculator] Loaded
2018-08-23T16:23:47.021Z,1535041427.021 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-08-23T16:23:47.027Z,1535041427.027 [PitchRateCalculator] Loaded
2018-08-23T16:23:47.027Z,1535041427.027 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-08-23T16:23:47.044Z,1535041427.044 [SpeedCalculator] Loaded
2018-08-23T16:23:47.044Z,1535041427.044 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-08-23T16:23:47.067Z,1535041427.067 [TempGradientCalculator] Loaded
2018-08-23T16:23:47.067Z,1535041427.067 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-08-23T16:23:47.083Z,1535041427.083 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-08-23T16:23:47.083Z,1535041427.083 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-08-23T16:23:47.089Z,1535041427.089 [YawRateCalculator] Loaded
2018-08-23T16:23:47.089Z,1535041427.089 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-08-23T16:23:47.131Z,1535041427.131 [ElevatorOffsetCalculator] Loaded
2018-08-23T16:23:47.131Z,1535041427.131 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-08-23T16:23:47.131Z,1535041427.131 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-08-23T16:23:47.132Z,1535041427.132 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-08-23T16:23:47.158Z,1535041427.158 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-08-23T16:23:47.158Z,1535041427.158 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-08-23T16:23:47.461Z,1535041427.461 [DataOverHttps] Loaded
2018-08-23T16:23:47.461Z,1535041427.461 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-08-23T16:23:47.488Z,1535041427.488 [Depth_Keller] Loaded
2018-08-23T16:23:47.489Z,1535041427.489 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-08-23T16:23:47.494Z,1535041427.494 [DropWeight] Loaded
2018-08-23T16:23:47.494Z,1535041427.494 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-08-23T16:23:47.597Z,1535041427.597 [NAL9602] Loaded
2018-08-23T16:23:47.597Z,1535041427.597 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-08-23T16:23:47.613Z,1535041427.613 [Onboard] Loaded
2018-08-23T16:23:47.614Z,1535041427.614 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-08-23T16:23:47.624Z,1535041427.624 [Radio_Surface] Loaded
2018-08-23T16:23:47.625Z,1535041427.625 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-08-23T16:23:47.626Z,1535041427.626 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B84E0
2018-08-23T16:23:47.626Z,1535041427.626 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1926
2018-08-23T16:23:47.676Z,1535041427.676 [PNI_TCM] Loaded
2018-08-23T16:23:47.677Z,1535041427.677 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-08-23T16:23:47.740Z,1535041427.740 [Rowe_600LCM] Loaded
2018-08-23T16:23:47.741Z,1535041427.741 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-08-23T16:23:47.742Z,1535041427.742 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407E84E0
2018-08-23T16:23:47.742Z,1535041427.742 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1927
2018-08-23T16:23:49.582Z,1535041429.582 [BPC1] Loaded
2018-08-23T16:23:49.582Z,1535041429.582 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-08-23T16:23:49.583Z,1535041429.583 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-08-23T16:23:49.583Z,1535041429.583 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-08-23T16:23:49.958Z,1535041429.958 [SBIT](DEBUG): Construct Startup Built In Test.
2018-08-23T16:23:49.986Z,1535041429.986 [SBIT] Loaded
2018-08-23T16:23:49.986Z,1535041429.986 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-08-23T16:23:49.987Z,1535041429.987 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-08-23T16:23:49.999Z,1535041429.999 [IBIT] Loaded
2018-08-23T16:23:49.999Z,1535041429.999 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-08-23T16:23:50.002Z,1535041430.002 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-08-23T16:23:50.233Z,1535041430.233 [CBIT] Loaded
2018-08-23T16:23:50.234Z,1535041430.234 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-08-23T16:23:50.234Z,1535041430.234 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-08-23T16:23:50.235Z,1535041430.235 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-08-23T16:23:50.388Z,1535041430.388 [Aanderaa_O2] Loaded
2018-08-23T16:23:50.388Z,1535041430.388 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-08-23T16:23:50.444Z,1535041430.444 [CTD_Seabird] Loaded
2018-08-23T16:23:50.444Z,1535041430.444 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-08-23T16:23:50.445Z,1535041430.445 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408AC4E0
2018-08-23T16:23:50.445Z,1535041430.445 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1928
2018-08-23T16:23:50.463Z,1535041430.463 [ESPComponent] Loaded
2018-08-23T16:23:50.464Z,1535041430.464 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-08-23T16:23:50.478Z,1535041430.478 [PAR_Licor] Loaded
2018-08-23T16:23:50.479Z,1535041430.479 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-08-23T16:23:50.530Z,1535041430.530 [WetLabsBB2FL] Loaded
2018-08-23T16:23:50.530Z,1535041430.530 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-08-23T16:23:50.531Z,1535041430.531 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408DC4E0
2018-08-23T16:23:50.532Z,1535041430.532 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1929
2018-08-23T16:23:50.532Z,1535041430.532 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-08-23T16:23:50.533Z,1535041430.533 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-08-23T16:23:50.787Z,1535041430.787 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-08-23T16:23:50.787Z,1535041430.787 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-08-23T16:23:50.859Z,1535041430.859 [VerticalControl](DEBUG): Construct VerticalControl.
2018-08-23T16:23:50.957Z,1535041430.957 [VerticalControl] Loaded
2018-08-23T16:23:50.957Z,1535041430.957 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-08-23T16:23:50.958Z,1535041430.958 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-08-23T16:23:51.022Z,1535041431.022 [HorizontalControl] Loaded
2018-08-23T16:23:51.022Z,1535041431.022 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-08-23T16:23:51.023Z,1535041431.023 [SpeedControl](DEBUG): Construct SpeedControl.
2018-08-23T16:23:51.028Z,1535041431.028 [SpeedControl] Loaded
2018-08-23T16:23:51.029Z,1535041431.029 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-08-23T16:23:51.029Z,1535041431.029 [LoopControl](DEBUG): Construct LoopControl.
2018-08-23T16:23:51.030Z,1535041431.030 [LoopControl] Loaded
2018-08-23T16:23:51.030Z,1535041431.030 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-08-23T16:23:51.031Z,1535041431.031 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-08-23T16:23:51.031Z,1535041431.031 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-08-23T16:23:51.116Z,1535041431.116 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-08-23T16:23:51.116Z,1535041431.116 [StratificationFrontDetector](DEBUG): (re)initializing
2018-08-23T16:23:51.116Z,1535041431.116 [StratificationFrontDetector] Loaded
2018-08-23T16:23:51.117Z,1535041431.117 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-08-23T16:23:51.117Z,1535041431.117 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-08-23T16:23:51.118Z,1535041431.118 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-08-23T16:23:51.220Z,1535041431.220 [BuoyancyServo] Loaded
2018-08-23T16:23:51.220Z,1535041431.220 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-08-23T16:23:51.232Z,1535041431.232 [ElevatorServo] Loaded
2018-08-23T16:23:51.232Z,1535041431.232 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-08-23T16:23:51.244Z,1535041431.244 [MassServo] Loaded
2018-08-23T16:23:51.244Z,1535041431.244 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-08-23T16:23:51.256Z,1535041431.256 [RudderServo] Loaded
2018-08-23T16:23:51.256Z,1535041431.256 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-08-23T16:23:51.267Z,1535041431.267 [ThrusterServo] Loaded
2018-08-23T16:23:51.267Z,1535041431.267 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-08-23T16:23:51.268Z,1535041431.268 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-08-23T16:23:51.268Z,1535041431.268 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-08-23T16:23:51.363Z,1535041431.363 [DeadReckonUsingSpeedCalculator] Loaded
2018-08-23T16:23:51.363Z,1535041431.363 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-08-23T16:23:51.380Z,1535041431.380 [NavChart] Loaded
2018-08-23T16:23:51.380Z,1535041431.380 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-08-23T16:23:51.384Z,1535041431.384 [UniversalFixResidualReporter] Loaded
2018-08-23T16:23:51.385Z,1535041431.385 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-08-23T16:23:51.385Z,1535041431.385 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-08-23T16:23:51.389Z,1535041431.389 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-08-23T16:23:51.390Z,1535041431.390 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-08-23T16:23:51.397Z,1535041431.397 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-08-23T16:23:51.398Z,1535041431.398 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A354E0
2018-08-23T16:23:51.398Z,1535041431.398 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1930
2018-08-23T16:23:51.403Z,1535041431.403 [Supervisor](INFO): Main Thread ID is 796
2018-08-23T16:23:51.403Z,1535041431.403 [Supervisor](DEBUG): Running supervisor.
2018-08-23T16:23:51.404Z,1535041431.404 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1931
2018-08-23T16:23:51.406Z,1535041431.406 [controlThread ThreadHandler](INFO): Handler Thread ID is 1932
2018-08-23T16:23:51.406Z,1535041431.406 [controlThread](DEBUG): Initializing ControlThread
2018-08-23T16:23:51.407Z,1535041431.407 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-23T16:23:51.408Z,1535041431.408 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-08-23T16:23:51.408Z,1535041431.408 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-08-23T16:23:51.408Z,1535041431.408 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-08-23T16:23:51.409Z,1535041431.409 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-08-23T16:23:51.410Z,1535041431.410 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-08-23T16:23:51.410Z,1535041431.410 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-08-23T16:23:51.415Z,1535041431.415 [SBIT](INFO): Initialize SBIT Component.
2018-08-23T16:23:51.416Z,1535041431.416 [SBIT](IMPORTANT): git: 2018-08-02-21-g63d70fd
2018-08-23T16:23:51.416Z,1535041431.416 [SBIT](INFO): git hash: 63d70fd1dcf2228c636b95ec153d21aec1fd7d0e
2018-08-23T16:23:51.417Z,1535041431.417 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-08-23T16:23:51.418Z,1535041431.418 [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-08-23T16:23:51.419Z,1535041431.419 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2018-08-23T16:23:51.419Z,1535041431.419 [IBIT](INFO): Initialize IBIT Component.
2018-08-23T16:23:51.420Z,1535041431.420 [CBIT](DEBUG): Initialize CBIT Component.
2018-08-23T16:23:51.421Z,1535041431.421 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-08-23T16:23:51.422Z,1535041431.422 [logger ThreadHandler](INFO): Handler Thread ID is 1933
2018-08-23T16:23:51.441Z,1535041431.441 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1934
2018-08-23T16:23:51.447Z,1535041431.447 [Radio_Surface](INFO): Powering up
2018-08-23T16:23:51.453Z,1535041431.453 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1935
2018-08-23T16:23:51.454Z,1535041431.454 [Rowe_600LCM](INFO): Initializing
2018-08-23T16:23:51.454Z,1535041431.454 [Rowe_600LCM](INFO): Checking LCM
2018-08-23T16:23:51.464Z,1535041431.464 [Rowe_600LCM](INFO): LCM OK
2018-08-23T16:23:51.464Z,1535041431.464 [Rowe_600LCM](INFO): Powering up
2018-08-23T16:23:51.470Z,1535041431.470 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1936
2018-08-23T16:23:51.470Z,1535041431.470 [CTD_Seabird](INFO): Initializing
2018-08-23T16:23:51.471Z,1535041431.471 [CTD_Seabird](INFO): Checking LCM
2018-08-23T16:23:51.471Z,1535041431.471 [CTD_Seabird](INFO): LCM OK
2018-08-23T16:23:51.471Z,1535041431.471 [CTD_Seabird](INFO): Powering up
2018-08-23T16:23:51.481Z,1535041431.481 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1937
2018-08-23T16:23:51.483Z,1535041431.483 [WetLabsBB2FL](INFO): Powering down
2018-08-23T16:23:51.509Z,1535041431.509 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1938
2018-08-23T16:23:51.512Z,1535041431.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000
2018-08-23T16:23:51.512Z,1535041431.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000
2018-08-23T16:23:51.512Z,1535041431.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000
2018-08-23T16:23:51.512Z,1535041431.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000
2018-08-23T16:23:51.513Z,1535041431.513 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000
2018-08-23T16:23:51.513Z,1535041431.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000
2018-08-23T16:23:51.513Z,1535041431.513 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000
2018-08-23T16:23:51.513Z,1535041431.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000
2018-08-23T16:23:51.537Z,1535041431.537 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-08-23T16:23:51.539Z,1535041431.539 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-08-23T16:23:51.540Z,1535041431.540 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-08-23T16:23:51.540Z,1535041431.540 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-08-23T16:23:51.542Z,1535041431.542 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-23T16:23:51.543Z,1535041431.543 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-23T16:23:51.543Z,1535041431.543 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-23T16:23:51.544Z,1535041431.544 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-08-23T16:23:51.544Z,1535041431.544 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-08-23T16:23:51.546Z,1535041431.546 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-08-23T16:23:51.566Z,1535041431.566 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-23T16:23:51.594Z,1535041431.594 [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-08-23T16:23:51.595Z,1535041431.595 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-08-23T16:23:51.684Z,1535041431.684 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-08-23T16:23:51.685Z,1535041431.685 [Default:A.Wait](DEBUG): Construct Wait.
2018-08-23T16:23:51.687Z,1535041431.687 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-23T16:23:51.730Z,1535041431.730 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-08-23T16:23:51.732Z,1535041431.732 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-08-23T16:23:51.758Z,1535041431.758 [Default:E.Execute](DEBUG): Construct Execute.
2018-08-23T16:23:51.768Z,1535041431.768 [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 300</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-08-23T16:23:51.773Z,1535041431.773 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-08-23T16:23:51.802Z,1535041431.802 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-08-23T16:23:52.143Z,1535041432.143 [ESPComponent](INFO): powering down ESP
2018-08-23T16:23:52.693Z,1535041432.693 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-23T16:23:52.811Z,1535041432.811 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-23T16:23:52.817Z,1535041432.817 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-23T16:23:52.840Z,1535041432.840 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-08-23T16:23:52.857Z,1535041432.857 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-08-23T16:23:52.864Z,1535041432.864 [MassServo](DEBUG): Initializing EZServoServo.
2018-08-23T16:23:52.869Z,1535041432.869 [MassServo](DEBUG): Initializing MassServo.
2018-08-23T16:23:52.883Z,1535041432.883 [RudderServo](DEBUG): Initializing EZServoServo.
2018-08-23T16:23:52.885Z,1535041432.885 [RudderServo](DEBUG): Initializing RudderServo.
2018-08-23T16:23:52.892Z,1535041432.892 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-08-23T16:23:52.897Z,1535041432.897 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-08-23T16:23:53.123Z,1535041433.123 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-23T16:23:53.486Z,1535041433.486 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-23T16:23:53.927Z,1535041433.927 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-23T16:23:54.361Z,1535041434.361 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-23T16:23:54.745Z,1535041434.745 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-23T16:23:55.137Z,1535041435.137 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-23T16:23:55.525Z,1535041435.525 [Aanderaa_O2](INFO): Powering down
2018-08-23T16:23:55.645Z,1535041435.645 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-23T16:23:55.897Z,1535041435.897 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-23T16:23:56.032Z,1535041436.032 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-23T16:23:56.067Z,1535041436.067 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-23T16:23:56.068Z,1535041436.068 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-23T16:23:56.081Z,1535041436.081 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-23T16:23:57.203Z,1535041437.203 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-23T16:23:57.389Z,1535041437.389 [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-08-23T16:23:57.391Z,1535041437.391 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-08-23T16:23:58.135Z,1535041438.135 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-23T16:23:58.136Z,1535041438.136 [CTD_Seabird](INFO): Powering down
2018-08-23T16:24:12.074Z,1535041452.074 [SBIT](IMPORTANT): Beginning Startup BIT
2018-08-23T16:24:12.110Z,1535041452.110 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-23T16:24:17.943Z,1535041457.943 [NAL9602](INFO): Powering up NAL9602
2018-08-23T16:24:23.699Z,1535041463.699 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004647
CHAN A1 (24V): -0.012843
CHAN A2 (12V): -0.006273
CHAN A3 (5V): -0.008613
CHAN B0 (3.3V): -0.003901
CHAN B1 (3.15aV): -0.002880
CHAN B2 (3.15bV): -0.000926
CHAN B3 (GND): -0.004609
OPEN: 0.011992
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-23T16:24:29.111Z,1535041469.111 [NAL9602](INFO): NAL9602 initialized
2018-08-23T16:24:41.124Z,1535041481.124 [NAL9602](INFO): SBD MO Status=0, MOMSN=23433, MT Status=0, MTMSN=0
2018-08-23T16:24:41.125Z,1535041481.125 [NAL9602](INFO): No messages in MT queue
2018-08-23T16:24:42.316Z,1535041482.316 [NAL9602](INFO): GPS fix at 20180823T162430: (41.827259, -83.413094)
2018-08-23T16:25:06.142Z,1535041506.142 [SBIT](IMPORTANT): SBIT PASSED
2018-08-23T16:25:06.242Z,1535041506.242 [CommandLine](IMPORTANT): got command configSet list
2018-08-23T16:25:06.243Z,1535041506.243 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-08-23T16:25:06.244Z,1535041506.244 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.loadAtStartup=0 bool;

2018-08-23T16:25:06.244Z,1535041506.244 [CommandLine](IMPORTANT): CTD_Seabird.minSalinityBound=0.1 practical_salinity_unit;

2018-08-23T16:25:06.244Z,1535041506.244 [CommandLine](IMPORTANT): Depth_Keller.offset=-0.56 decibar;

2018-08-23T16:25:06.245Z,1535041506.245 [CommandLine](IMPORTANT): Express linearApproximation mass_concentration_of_chlorophyll_in_sea_water 1.000000 microgram_per_liter;

2018-08-23T16:25:06.245Z,1535041506.245 [CommandLine](IMPORTANT): IBIT.batteryHibernationTimeout=1 minute;

2018-08-23T16:25:06.245Z,1535041506.245 [CommandLine](IMPORTANT): IBIT.batteryMissingStickThreshold=12 count;

2018-08-23T16:25:06.245Z,1535041506.245 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter;

2018-08-23T16:25:06.246Z,1535041506.246 [CommandLine](IMPORTANT): VerticalControl.massDefault=0 centimeter;

2018-08-23T16:25:06.552Z,1535041506.552 [MissionManager](IMPORTANT): Started mission Startup
2018-08-23T16:25:06.552Z,1535041506.552 [Startup] Running Loop=1
2018-08-23T16:25:06.552Z,1535041506.552 [Startup](DEBUG): Aggregate::initialize Startup
2018-08-23T16:25:06.552Z,1535041506.552 [Startup:A.GoToSurface] Running Loop=1
2018-08-23T16:25:06.552Z,1535041506.552 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-23T16:25:06.553Z,1535041506.553 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-23T16:25:06.554Z,1535041506.554 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-23T16:25:06.554Z,1535041506.554 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-23T16:25:06.554Z,1535041506.554 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-23T16:25:06.555Z,1535041506.555 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-23T16:25:06.561Z,1535041506.561 [Startup:StartupSatComms] Running Loop=1
2018-08-23T16:25:06.561Z,1535041506.561 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-08-23T16:25:06.561Z,1535041506.561 [Startup:StartupSatComms:A] Running Loop=1
2018-08-23T16:25:06.939Z,1535041506.939 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-08-23T16:25:08.490Z,1535041508.490 [NAL9602](INFO): GPS fix at 20180823T162456: (41.827303, -83.413064)
2018-08-23T16:25:08.560Z,1535041508.560 [Startup:StartupSatComms:A] Stopped
2018-08-23T16:25:08.560Z,1535041508.560 [Startup:StartupSatComms:B] Running Loop=1
2018-08-23T16:25:08.960Z,1535041508.960 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-23T16:25:21.944Z,1535041521.944 [NAL9602](INFO): SBD MO Status=1, MOMSN=23434, MT Status=0, MTMSN=0
2018-08-23T16:25:21.993Z,1535041521.993 [NAL9602](INFO): Sent 45 bytes from file Logs/20180823T161357/Courier0007.lzma
2018-08-23T16:25:21.993Z,1535041521.993 [NAL9602](INFO): Packets left to send: 0
2018-08-23T16:25:21.995Z,1535041521.995 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T161357/Courier0007.lzma.parts/0000.sbd
2018-08-23T16:25:21.995Z,1535041521.995 [NAL9602](DEBUG): Completed sending Logs/20180823T161357/Courier0007.lzma
2018-08-23T16:25:33.572Z,1535041533.572 [NAL9602](INFO): SBD MO Status=1, MOMSN=23435, MT Status=0, MTMSN=0
2018-08-23T16:25:33.621Z,1535041533.621 [NAL9602](INFO): Sent 215 bytes from file Logs/20180823T162341/Courier0000.lzma
2018-08-23T16:25:33.621Z,1535041533.621 [NAL9602](INFO): Packets left to send: 0
2018-08-23T16:25:33.623Z,1535041533.623 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T162341/Courier0000.lzma.parts/0000.sbd
2018-08-23T16:25:33.623Z,1535041533.623 [NAL9602](DEBUG): Completed sending Logs/20180823T162341/Courier0000.lzma
2018-08-23T16:25:41.732Z,1535041541.732 [NAL9602](INFO): SBD MO Status=1, MOMSN=23436, MT Status=0, MTMSN=0
2018-08-23T16:25:41.781Z,1535041541.781 [NAL9602](INFO): Sent 7 bytes from file Logs/20180823T161357/Express0001.lzma
2018-08-23T16:25:41.782Z,1535041541.782 [NAL9602](INFO): Packets left to send: 0
2018-08-23T16:25:41.783Z,1535041541.783 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T161357/Express0001.lzma.parts/0000.sbd
2018-08-23T16:25:41.783Z,1535041541.783 [NAL9602](DEBUG): Completed sending Logs/20180823T161357/Express0001.lzma
2018-08-23T16:26:00.120Z,1535041560.120 [NAL9602](INFO): SBD MO Status=2, MOMSN=23437, MT Status=2, MTMSN=0
2018-08-23T16:26:00.120Z,1535041560.120 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-08-23T16:26:09.918Z,1535041569.918 [Startup:StartupSatComms:B](INFO): Timed out from 2018-08-23T16:25:08.6Z
2018-08-23T16:26:09.918Z,1535041569.918 [Startup:StartupSatComms:B] Stopped
2018-08-23T16:26:09.919Z,1535041569.919 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-08-23T16:26:09.919Z,1535041569.919 [Startup:StartupSatComms] Stopped
2018-08-23T16:26:09.919Z,1535041569.919 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-08-23T16:26:09.920Z,1535041569.920 [Startup](INFO): Completed Startup
2018-08-23T16:26:09.920Z,1535041569.920 [MissionManager](INFO): Startup is completed.
2018-08-23T16:26:09.920Z,1535041569.920 [MissionManager](INFO): Uninitializing Mission Startup
2018-08-23T16:26:09.920Z,1535041569.920 [Startup] Stopped
2018-08-23T16:26:09.920Z,1535041569.920 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-08-23T16:26:09.921Z,1535041569.921 [Startup:A.GoToSurface] Stopped
2018-08-23T16:26:09.921Z,1535041569.921 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-23T16:26:10.076Z,1535041570.076 [MissionManager](IMPORTANT): Started mission Default
2018-08-23T16:26:10.076Z,1535041570.076 [Default] Running Loop=1
2018-08-23T16:26:10.076Z,1535041570.076 [Default](DEBUG): Aggregate::initialize Default
2018-08-23T16:26:10.076Z,1535041570.076 [Default:B.GoToSurface] Running Loop=1
2018-08-23T16:26:10.076Z,1535041570.076 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-23T16:26:10.085Z,1535041570.085 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-23T16:26:10.085Z,1535041570.085 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-23T16:26:10.085Z,1535041570.085 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-23T16:26:10.086Z,1535041570.086 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-23T16:26:10.086Z,1535041570.086 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-23T16:26:10.086Z,1535041570.086 [Default:A.Wait] Running Loop=1
2018-08-23T16:26:10.087Z,1535041570.087 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-08-23T16:26:23.211Z,1535041583.211 [Default:A.Wait](INFO): Done Waiting.
2018-08-23T16:26:23.211Z,1535041583.211 [Default:A.Wait] Stopped
2018-08-23T16:26:23.211Z,1535041583.211 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T16:26:23.606Z,1535041583.606 [Default:CheckIn] Running Loop=1
2018-08-23T16:26:23.606Z,1535041583.606 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-23T16:26:23.606Z,1535041583.606 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-23T16:26:24.025Z,1535041584.025 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-08-23T16:26:25.558Z,1535041585.558 [NAL9602](INFO): GPS fix at 20180823T162614: (41.827309, -83.413055)
2018-08-23T16:26:25.628Z,1535041585.628 [Default:CheckIn:Read_GPS] Stopped
2018-08-23T16:26:25.630Z,1535041585.630 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-23T16:26:26.022Z,1535041586.022 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-23T16:26:40.476Z,1535041600.476 [NAL9602](INFO): SBD MO Status=1, MOMSN=23437, MT Status=0, MTMSN=0
2018-08-23T16:26:40.529Z,1535041600.529 [NAL9602](INFO): Sent 65 bytes from file Logs/20180823T162341/Courier0004.lzma
2018-08-23T16:26:40.529Z,1535041600.529 [NAL9602](INFO): Packets left to send: 0
2018-08-23T16:26:40.531Z,1535041600.531 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T162341/Courier0004.lzma.parts/0000.sbd
2018-08-23T16:26:40.531Z,1535041600.531 [NAL9602](DEBUG): Completed sending Logs/20180823T162341/Courier0004.lzma
2018-08-23T16:26:49.224Z,1535041609.224 [NAL9602](INFO): SBD MO Status=1, MOMSN=23438, MT Status=0, MTMSN=0
2018-08-23T16:26:49.273Z,1535041609.273 [NAL9602](INFO): Sent 94 bytes from file Logs/20180823T161357/Express0005.lzma
2018-08-23T16:26:49.274Z,1535041609.274 [NAL9602](INFO): Packets left to send: 0
2018-08-23T16:26:51.199Z,1535041611.199 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T161357/Express0005.lzma.parts/0000.sbd
2018-08-23T16:26:51.199Z,1535041611.199 [NAL9602](DEBUG): Completed sending Logs/20180823T161357/Express0005.lzma
2018-08-23T16:27:08.384Z,1535041628.384 [NAL9602](INFO): SBD MO Status=1, MOMSN=23439, MT Status=0, MTMSN=0
2018-08-23T16:27:08.434Z,1535041628.434 [NAL9602](INFO): Sent 332 bytes from file Logs/20180823T161357/Express0008.lzma
2018-08-23T16:27:08.434Z,1535041628.434 [NAL9602](INFO): Packets left to send: 1
2018-08-23T16:27:08.436Z,1535041628.436 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T161357/Express0008.lzma.parts/0001.sbd
2018-08-23T16:27:20.040Z,1535041640.040 [NAL9602](INFO): SBD MO Status=1, MOMSN=23440, MT Status=0, MTMSN=0
2018-08-23T16:27:20.089Z,1535041640.089 [NAL9602](INFO): Sent 23 bytes from file Logs/20180823T161357/Express0008.lzma
2018-08-23T16:27:20.089Z,1535041640.089 [NAL9602](INFO): Packets left to send: 0
2018-08-23T16:27:20.091Z,1535041640.091 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T161357/Express0008.lzma.parts/0000.sbd
2018-08-23T16:27:20.091Z,1535041640.091 [NAL9602](DEBUG): Completed sending Logs/20180823T161357/Express0008.lzma
2018-08-23T16:27:33.360Z,1535041653.360 [NAL9602](INFO): SBD MO Status=1, MOMSN=23441, MT Status=0, MTMSN=0
2018-08-23T16:27:33.410Z,1535041653.410 [NAL9602](INFO): Sent 332 bytes from file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:27:33.410Z,1535041653.410 [NAL9602](INFO): Packets left to send: 2
2018-08-23T16:27:33.412Z,1535041653.412 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T162341/Express0001.lzma.parts/0002.sbd
2018-08-23T16:27:46.988Z,1535041666.988 [NAL9602](INFO): SBD MO Status=1, MOMSN=23442, MT Status=0, MTMSN=0
2018-08-23T16:27:47.041Z,1535041667.041 [NAL9602](INFO): Sent 332 bytes from file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:27:47.041Z,1535041667.041 [NAL9602](INFO): Packets left to send: 1
2018-08-23T16:27:47.043Z,1535041667.043 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T162341/Express0001.lzma.parts/0001.sbd
2018-08-23T16:27:58.399Z,1535041678.399 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003932
2018-08-23T16:28:01.815Z,1535041681.815 [NAL9602](INFO): SBD MO Status=1, MOMSN=23443, MT Status=0, MTMSN=0
2018-08-23T16:28:01.865Z,1535041681.865 [NAL9602](INFO): Sent 298 bytes from file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:28:01.865Z,1535041681.865 [NAL9602](INFO): Packets left to send: 0
2018-08-23T16:28:01.867Z,1535041681.867 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180823T162341/Express0001.lzma.parts/0000.sbd
2018-08-23T16:28:01.867Z,1535041681.867 [NAL9602](DEBUG): Completed sending Logs/20180823T162341/Express0001.lzma
2018-08-23T16:28:01.937Z,1535041681.937 [Default:CheckIn:Read_Iridium] Stopped
2018-08-23T16:28:01.937Z,1535041681.937 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-23T16:28:01.937Z,1535041681.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-23T16:28:08.332Z,1535041688.332 [DataOverHttps](IMPORTANT): Could not stat file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:28:08.333Z,1535041688.333 [DataOverHttps](CRITICAL): Could not open file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:28:08.493Z,1535041688.493 [CommandLine](FAULT): Scheduling is paused
2018-08-23T16:28:08.506Z,1535041688.506 [CBIT](INFO): Critical error at 20180823T162808
2018-08-23T16:28:08.507Z,1535041688.507 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-08-23T16:28:15.615Z,1535041695.615 [DataOverHttps](IMPORTANT): Could not stat file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:28:15.616Z,1535041695.616 [DataOverHttps](CRITICAL): Could not open file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:28:15.698Z,1535041695.698 [CBIT](INFO): Critical error at 20180823T162815
2018-08-23T16:28:32.414Z,1535041712.414 [NAL9602](INFO): Powering down
2018-08-23T16:29:14.488Z,1535041754.488 [DataOverHttps](IMPORTANT): Could not stat file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:29:14.488Z,1535041754.488 [DataOverHttps](CRITICAL): Could not open file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:29:14.729Z,1535041754.729 [CBIT](INFO): Critical error at 20180823T162914
2018-08-23T16:30:16.630Z,1535041816.630 [DataOverHttps](IMPORTANT): Could not stat file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:30:16.630Z,1535041816.630 [DataOverHttps](CRITICAL): Could not open file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:30:16.873Z,1535041816.873 [CBIT](INFO): Critical error at 20180823T163016
2018-08-23T16:30:41.867Z,1535041841.867 [CommandLine](IMPORTANT): got command report touch height_above_sea_floor
2018-08-23T16:30:45.132Z,1535041845.132 [Reporter](INFO): height_above_sea_floor 2.331000 m
2018-08-23T16:30:55.507Z,1535041855.507 [Reporter](INFO): height_above_sea_floor 0.926236 m
2018-08-23T16:30:55.916Z,1535041855.916 [Reporter](INFO): height_above_sea_floor 0.923096 m
2018-08-23T16:30:56.269Z,1535041856.269 [Reporter](INFO): height_above_sea_floor 0.926236 m
2018-08-23T16:30:56.672Z,1535041856.672 [Reporter](INFO): height_above_sea_floor 0.935207 m
2018-08-23T16:30:57.170Z,1535041857.170 [Reporter](INFO): height_above_sea_floor 0.937450 m
2018-08-23T16:30:57.608Z,1535041857.608 [Reporter](INFO): height_above_sea_floor 0.931170 m
2018-08-23T16:30:58.001Z,1535041858.001 [Reporter](INFO): height_above_sea_floor 0.928927 m
2018-08-23T16:30:58.330Z,1535041858.330 [Reporter](INFO): height_above_sea_floor 0.927133 m
2018-08-23T16:30:58.774Z,1535041858.774 [Reporter](INFO): height_above_sea_floor 0.935656 m
2018-08-23T16:30:59.152Z,1535041859.152 [Reporter](INFO): height_above_sea_floor 0.943281 m
2018-08-23T16:30:59.524Z,1535041859.524 [Reporter](INFO): height_above_sea_floor 0.937450 m
2018-08-23T16:30:59.914Z,1535041859.914 [Reporter](INFO): height_above_sea_floor 0.931170 m
2018-08-23T16:31:00.272Z,1535041860.272 [Reporter](INFO): height_above_sea_floor 0.943281 m
2018-08-23T16:31:00.705Z,1535041860.705 [Reporter](INFO): height_above_sea_floor 0.943281 m
2018-08-23T16:31:01.118Z,1535041861.118 [Reporter](INFO): height_above_sea_floor 0.935656 m
2018-08-23T16:31:01.476Z,1535041861.476 [Reporter](INFO): height_above_sea_floor 0.936553 m
2018-08-23T16:31:01.947Z,1535041861.947 [Reporter](INFO): height_above_sea_floor 0.944627 m
2018-08-23T16:31:02.285Z,1535041862.285 [Reporter](INFO): height_above_sea_floor 0.925339 m
2018-08-23T16:31:02.684Z,1535041862.684 [Reporter](INFO): height_above_sea_floor 0.930722 m
2018-08-23T16:31:03.092Z,1535041863.092 [Reporter](INFO): height_above_sea_floor 0.936104 m
2018-08-23T16:31:03.492Z,1535041863.492 [Reporter](INFO): height_above_sea_floor 0.932067 m
2018-08-23T16:31:03.918Z,1535041863.918 [Reporter](INFO): height_above_sea_floor 0.933862 m
2018-08-23T16:31:04.324Z,1535041864.324 [Reporter](INFO): height_above_sea_floor 0.933862 m
2018-08-23T16:31:04.778Z,1535041864.778 [Reporter](INFO): height_above_sea_floor 0.933413 m
2018-08-23T16:31:05.169Z,1535041865.169 [Reporter](INFO): height_above_sea_floor 0.926685 m
2018-08-23T16:31:05.512Z,1535041865.512 [Reporter](INFO): height_above_sea_floor 0.933862 m
2018-08-23T16:31:05.932Z,1535041865.932 [Reporter](INFO): height_above_sea_floor 0.936553 m
2018-08-23T16:31:06.287Z,1535041866.287 [Reporter](INFO): height_above_sea_floor 3.150000 m
2018-08-23T16:31:07.914Z,1535041867.914 [Reporter](INFO): height_above_sea_floor 2.409000 m
2018-08-23T16:31:14.843Z,1535041874.843 [DataOverHttps](IMPORTANT): Could not stat file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:31:14.843Z,1535041874.843 [DataOverHttps](CRITICAL): Could not open file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:31:14.985Z,1535041874.985 [CBIT](INFO): Critical error at 20180823T163114
2018-08-23T16:31:15.306Z,1535041875.306 [Reporter](INFO): height_above_sea_floor 3.194000 m
2018-08-23T16:31:16.120Z,1535041876.120 [Reporter](INFO): height_above_sea_floor 2.357000 m
2018-08-23T16:31:23.361Z,1535041883.361 [Reporter](INFO): height_above_sea_floor 3.146000 m
2018-08-23T16:31:24.140Z,1535041884.140 [Reporter](INFO): height_above_sea_floor 2.357000 m
2018-08-23T16:31:34.552Z,1535041894.552 [Reporter](INFO): height_above_sea_floor 0.932067 m
2018-08-23T16:31:34.969Z,1535041894.969 [Reporter](INFO): height_above_sea_floor 3.181000 m
2018-08-23T16:31:36.980Z,1535041896.980 [Reporter](INFO): height_above_sea_floor 2.401000 m
2018-08-23T16:31:40.110Z,1535041900.110 [Reporter](INFO): height_above_sea_floor 2.339000 m
2018-08-23T16:31:50.513Z,1535041910.513 [Reporter](INFO): height_above_sea_floor 0.928927 m
2018-08-23T16:31:51.013Z,1535041911.013 [Reporter](INFO): height_above_sea_floor 0.936553 m
2018-08-23T16:31:51.321Z,1535041911.321 [Reporter](INFO): height_above_sea_floor 0.938796 m
2018-08-23T16:31:51.744Z,1535041911.744 [Reporter](INFO): height_above_sea_floor 0.941487 m
2018-08-23T16:31:52.165Z,1535041912.165 [Reporter](INFO): height_above_sea_floor 0.946870 m
2018-08-23T16:31:54.536Z,1535041914.536 [Reporter](INFO): height_above_sea_floor 2.366000 m
2018-08-23T16:32:01.725Z,1535041921.725 [Reporter](INFO): height_above_sea_floor 3.168000 m
2018-08-23T16:32:03.306Z,1535041923.306 [Reporter](INFO): height_above_sea_floor 2.366000 m
2018-08-23T16:32:08.114Z,1535041928.114 [Reporter](INFO): height_above_sea_floor 2.361000 m
2018-08-23T16:32:15.730Z,1535041935.730 [Reporter](INFO): height_above_sea_floor 2.348000 m
2018-08-23T16:32:16.140Z,1535041936.140 [DataOverHttps](IMPORTANT): Could not stat file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:32:16.140Z,1535041936.140 [DataOverHttps](CRITICAL): Could not open file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:32:16.230Z,1535041936.230 [CBIT](INFO): Critical error at 20180823T163216
2018-08-23T16:32:16.233Z,1535041936.233 [Reporter](INFO): height_above_sea_floor 2.382000 m
2018-08-23T16:32:21.075Z,1535041941.075 [Reporter](INFO): height_above_sea_floor 2.384000 m
2018-08-23T16:32:26.206Z,1535041946.206 [Reporter](INFO): height_above_sea_floor 2.366000 m
2018-08-23T16:32:36.600Z,1535041956.600 [Reporter](INFO): height_above_sea_floor 0.928030 m
2018-08-23T16:32:37.088Z,1535041957.088 [Reporter](INFO): height_above_sea_floor 0.930722 m
2018-08-23T16:32:37.414Z,1535041957.414 [Reporter](INFO): height_above_sea_floor 0.941039 m
2018-08-23T16:32:37.808Z,1535041957.808 [Reporter](INFO): height_above_sea_floor 0.939244 m
2018-08-23T16:32:38.252Z,1535041958.252 [Reporter](INFO): height_above_sea_floor 0.954495 m
2018-08-23T16:32:38.626Z,1535041958.626 [Reporter](INFO): height_above_sea_floor 0.939244 m
2018-08-23T16:32:39.070Z,1535041959.070 [Reporter](INFO): height_above_sea_floor 0.932516 m
2018-08-23T16:32:39.429Z,1535041959.429 [Reporter](INFO): height_above_sea_floor 0.923545 m
2018-08-23T16:32:39.812Z,1535041959.812 [Reporter](INFO): height_above_sea_floor 0.932067 m
2018-08-23T16:32:40.227Z,1535041960.227 [Reporter](INFO): height_above_sea_floor 0.941936 m
2018-08-23T16:32:40.631Z,1535041960.631 [Reporter](INFO): height_above_sea_floor 0.930722 m
2018-08-23T16:32:41.092Z,1535041961.092 [Reporter](INFO): height_above_sea_floor 0.934310 m
2018-08-23T16:32:41.434Z,1535041961.434 [Reporter](INFO): height_above_sea_floor 0.934310 m
2018-08-23T16:32:41.848Z,1535041961.848 [Reporter](INFO): height_above_sea_floor 0.935656 m
2018-08-23T16:32:42.251Z,1535041962.251 [Reporter](INFO): height_above_sea_floor 0.944627 m
2018-08-23T16:32:42.648Z,1535041962.648 [Reporter](INFO): height_above_sea_floor 0.940590 m
2018-08-23T16:32:43.108Z,1535041963.108 [Reporter](INFO): height_above_sea_floor 0.937899 m
2018-08-23T16:32:43.406Z,1535041963.406 [Reporter](INFO): height_above_sea_floor 0.934759 m
2018-08-23T16:32:43.802Z,1535041963.802 [Reporter](INFO): height_above_sea_floor 0.937450 m
2018-08-23T16:32:44.210Z,1535041964.210 [Reporter](INFO): height_above_sea_floor 0.942833 m
2018-08-23T16:32:44.617Z,1535041964.617 [Reporter](INFO): height_above_sea_floor 0.931170 m
2018-08-23T16:32:45.076Z,1535041965.076 [Reporter](INFO): height_above_sea_floor 0.940141 m
2018-08-23T16:32:45.414Z,1535041965.414 [Reporter](INFO): height_above_sea_floor 0.938796 m
2018-08-23T16:32:45.843Z,1535041965.843 [Reporter](INFO): height_above_sea_floor 0.931170 m
2018-08-23T16:32:46.224Z,1535041966.224 [Reporter](INFO): height_above_sea_floor 0.942833 m
2018-08-23T16:32:46.702Z,1535041966.702 [Reporter](INFO): height_above_sea_floor 0.933413 m
2018-08-23T16:32:47.098Z,1535041967.098 [Reporter](INFO): height_above_sea_floor 0.943730 m
2018-08-23T16:32:47.433Z,1535041967.433 [Reporter](INFO): height_above_sea_floor 0.938796 m
2018-08-23T16:32:47.826Z,1535041967.826 [Reporter](INFO): height_above_sea_floor 0.936104 m
2018-08-23T16:32:48.199Z,1535041968.199 [Reporter](INFO): height_above_sea_floor 0.944179 m
2018-08-23T16:32:48.626Z,1535041968.626 [Reporter](INFO): height_above_sea_floor 0.935207 m
2018-08-23T16:32:49.090Z,1535041969.090 [Reporter](INFO): height_above_sea_floor 0.952253 m
2018-08-23T16:32:49.400Z,1535041969.400 [Reporter](INFO): height_above_sea_floor 0.937002 m
2018-08-23T16:32:49.867Z,1535041969.867 [Reporter](INFO): height_above_sea_floor 0.956738 m
2018-08-23T16:32:50.270Z,1535041970.270 [Reporter](INFO): height_above_sea_floor 0.932516 m
2018-08-23T16:32:50.633Z,1535041970.633 [Reporter](INFO): height_above_sea_floor 0.935207 m
2018-08-23T16:32:51.092Z,1535041971.092 [Reporter](INFO): height_above_sea_floor 0.945076 m
2018-08-23T16:32:51.436Z,1535041971.436 [Reporter](INFO): height_above_sea_floor 0.932965 m
2018-08-23T16:32:51.849Z,1535041971.849 [Reporter](INFO): height_above_sea_floor 0.933413 m
2018-08-23T16:32:52.198Z,1535041972.198 [Reporter](INFO): height_above_sea_floor 0.947767 m
2018-08-23T16:32:52.640Z,1535041972.640 [Reporter](INFO): height_above_sea_floor 0.942833 m
2018-08-23T16:32:53.190Z,1535041973.190 [Reporter](INFO): height_above_sea_floor 0.930722 m
2018-08-23T16:32:53.410Z,1535041973.410 [Reporter](INFO): height_above_sea_floor 0.938796 m
2018-08-23T16:32:53.851Z,1535041973.851 [Reporter](INFO): height_above_sea_floor 0.938796 m
2018-08-23T16:32:54.226Z,1535041974.226 [Reporter](INFO): height_above_sea_floor 0.933862 m
2018-08-23T16:32:54.656Z,1535041974.656 [Reporter](INFO): height_above_sea_floor 0.926685 m
2018-08-23T16:32:55.173Z,1535041975.173 [Reporter](INFO): height_above_sea_floor 0.930273 m
2018-08-23T16:32:55.462Z,1535041975.462 [Reporter](INFO): height_above_sea_floor 0.930722 m
2018-08-23T16:32:55.850Z,1535041975.850 [Reporter](INFO): height_above_sea_floor 0.933862 m
2018-08-23T16:32:56.221Z,1535041976.221 [Reporter](INFO): height_above_sea_floor 0.940141 m
2018-08-23T16:32:56.617Z,1535041976.617 [Reporter](INFO): height_above_sea_floor 0.945973 m
2018-08-23T16:32:57.154Z,1535041977.154 [Reporter](INFO): height_above_sea_floor 0.940590 m
2018-08-23T16:32:57.424Z,1535041977.424 [Reporter](INFO): height_above_sea_floor 0.950907 m
2018-08-23T16:32:57.866Z,1535041977.866 [Reporter](INFO): height_above_sea_floor 0.944179 m
2018-08-23T16:32:58.295Z,1535041978.295 [Reporter](INFO): height_above_sea_floor 0.932516 m
2018-08-23T16:32:58.708Z,1535041978.708 [Reporter](INFO): height_above_sea_floor 0.937899 m
2018-08-23T16:32:59.132Z,1535041979.132 [Reporter](INFO): height_above_sea_floor 0.943730 m
2018-08-23T16:32:59.458Z,1535041979.458 [Reporter](INFO): height_above_sea_floor 0.937450 m
2018-08-23T16:32:59.874Z,1535041979.874 [Reporter](INFO): height_above_sea_floor 0.936553 m
2018-08-23T16:33:00.254Z,1535041980.254 [Reporter](INFO): height_above_sea_floor 0.932965 m
2018-08-23T16:33:00.627Z,1535041980.627 [Reporter](INFO): height_above_sea_floor 0.930722 m
2018-08-23T16:33:01.101Z,1535041981.101 [Reporter](INFO): height_above_sea_floor 0.942384 m
2018-08-23T16:33:01.430Z,1535041981.430 [Reporter](INFO): height_above_sea_floor 0.947767 m
2018-08-23T16:33:01.802Z,1535041981.802 [Reporter](INFO): height_above_sea_floor 0.933862 m
2018-08-23T16:33:02.281Z,1535041982.281 [Reporter](INFO): height_above_sea_floor 0.933413 m
2018-08-23T16:33:02.604Z,1535041982.604 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-23T16:33:02.605Z,1535041982.605 [Default:CheckIn:C.Wait] Stopped
2018-08-23T16:33:02.629Z,1535041982.629 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T16:33:02.629Z,1535041982.629 [Default:CheckIn:D] Running Loop=1
2018-08-23T16:33:02.666Z,1535041982.666 [Reporter](INFO): height_above_sea_floor 0.944627 m
2018-08-23T16:33:03.084Z,1535041983.084 [Default:CheckIn:D] Stopped
2018-08-23T16:33:03.084Z,1535041983.084 [Default:CheckIn:E] Running Loop=1
2018-08-23T16:33:03.132Z,1535041983.132 [Reporter](INFO): height_above_sea_floor 0.930273 m
2018-08-23T16:33:03.396Z,1535041983.396 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.883425 min 
2018-08-23T16:33:03.399Z,1535041983.399 [Default:CheckIn:E] Stopped
2018-08-23T16:33:03.399Z,1535041983.399 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-23T16:33:03.399Z,1535041983.399 [Default:CheckIn] Stopped
2018-08-23T16:33:03.399Z,1535041983.399 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-23T16:33:03.399Z,1535041983.399 [Default:CheckIn](INFO): Running loop #2
2018-08-23T16:33:03.399Z,1535041983.399 [Default:CheckIn] Running Loop=2
2018-08-23T16:33:03.400Z,1535041983.400 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-23T16:33:03.400Z,1535041983.400 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-23T16:33:03.437Z,1535041983.437 [Reporter](INFO): height_above_sea_floor 0.945524 m
2018-08-23T16:33:03.845Z,1535041983.845 [Reporter](INFO): height_above_sea_floor 0.927133 m
2018-08-23T16:33:04.142Z,1535041984.142 [NAL9602](INFO): Powering up
2018-08-23T16:33:04.232Z,1535041984.232 [Reporter](INFO): height_above_sea_floor 0.936553 m
2018-08-23T16:33:04.607Z,1535041984.607 [Reporter](INFO): height_above_sea_floor 0.933862 m
2018-08-23T16:33:05.135Z,1535041985.135 [Reporter](INFO): height_above_sea_floor 0.935656 m
2018-08-23T16:33:05.424Z,1535041985.424 [Reporter](INFO): height_above_sea_floor 0.938347 m
2018-08-23T16:33:05.857Z,1535041985.857 [Reporter](INFO): height_above_sea_floor 0.939693 m
2018-08-23T16:33:06.229Z,1535041986.229 [Reporter](INFO): height_above_sea_floor 0.949561 m
2018-08-23T16:33:06.597Z,1535041986.597 [Reporter](INFO): height_above_sea_floor 0.935207 m
2018-08-23T16:33:07.124Z,1535041987.124 [Reporter](INFO): height_above_sea_floor 0.930722 m
2018-08-23T16:33:07.417Z,1535041987.417 [Reporter](INFO): height_above_sea_floor 0.937450 m
2018-08-23T16:33:07.820Z,1535041987.820 [Reporter](INFO): height_above_sea_floor 0.945076 m
2018-08-23T16:33:08.228Z,1535041988.228 [Reporter](INFO): height_above_sea_floor 0.929376 m
2018-08-23T16:33:08.620Z,1535041988.620 [Reporter](INFO): height_above_sea_floor 0.941487 m
2018-08-23T16:33:09.133Z,1535041989.133 [Reporter](INFO): height_above_sea_floor 0.929376 m
2018-08-23T16:33:09.476Z,1535041989.476 [Reporter](INFO): height_above_sea_floor 0.947318 m
2018-08-23T16:33:09.814Z,1535041989.814 [Reporter](INFO): height_above_sea_floor 0.946421 m
2018-08-23T16:33:10.197Z,1535041990.197 [Reporter](INFO): height_above_sea_floor 0.942833 m
2018-08-23T16:33:10.615Z,1535041990.615 [Reporter](INFO): height_above_sea_floor 0.932067 m
2018-08-23T16:33:15.845Z,1535041995.845 [Reporter](INFO): height_above_sea_floor 0.933413 m
2018-08-23T16:33:15.876Z,1535041995.876 [DataOverHttps](IMPORTANT): Could not stat file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:33:15.878Z,1535041995.878 [DataOverHttps](CRITICAL): Could not open file Logs/20180823T162341/Express0001.lzma
2018-08-23T16:33:16.133Z,1535041996.133 [CBIT](INFO): Critical error at 20180823T163315
2018-08-23T16:33:16.141Z,1535041996.141 [Reporter](INFO): height_above_sea_floor 0.926236 m
2018-08-23T16:33:16.258Z,1535041996.258 [NAL9602](INFO): NAL9602 initialized
2018-08-23T16:33:16.359Z,1535041996.359 [Reporter](INFO): height_above_sea_floor 0.928927 m
2018-08-23T16:33:16.766Z,1535041996.766 [Reporter](INFO): height_above_sea_floor 0.938796 m
2018-08-23T16:33:17.225Z,1535041997.225 [Reporter](INFO): height_above_sea_floor 0.947318 m
2018-08-23T16:33:17.545Z,1535041997.545 [Reporter](INFO): height_above_sea_floor 0.938347 m
2018-08-23T16:33:17.991Z,1535041997.991 [Reporter](INFO): height_above_sea_floor 0.938347 m
2018-08-23T16:33:18.330Z,1535041998.330 [Reporter](INFO): height_above_sea_floor 0.946421 m
2018-08-23T16:33:18.747Z,1535041998.747 [Reporter](INFO): height_above_sea_floor 0.929376 m
2018-08-23T16:33:19.174Z,1535041999.174 [Reporter](INFO): height_above_sea_floor 0.924890 m
2018-08-23T16:33:19.518Z,1535041999.518 [Reporter](INFO): height_above_sea_floor 0.941487 m
2018-08-23T16:33:19.943Z,1535041999.943 [Reporter](INFO): height_above_sea_floor 0.944179 m
2018-08-23T16:33:20.373Z,1535042000.373 [Reporter](INFO): height_above_sea_floor 0.921750 m
2018-08-23T16:33:20.769Z,1535042000.769 [Reporter](INFO): height_above_sea_floor 0.943730 m
2018-08-23T16:33:21.140Z,1535042001.140 [Reporter](INFO): height_above_sea_floor 0.932516 m
2018-08-23T16:33:21.534Z,1535042001.534 [Reporter](INFO): height_above_sea_floor 0.945076 m
2018-08-23T16:33:21.920Z,1535042001.920 [Reporter](INFO): height_above_sea_floor 0.938347 m
2018-08-23T16:33:22.318Z,1535042002.318 [Reporter](INFO): height_above_sea_floor 0.936553 m
2018-08-23T16:33:22.716Z,1535042002.716 [Reporter](INFO): height_above_sea_floor 0.938347 m
2018-08-23T16:33:23.179Z,1535042003.179 [Reporter](INFO): height_above_sea_floor 0.944179 m
2018-08-23T16:33:23.538Z,1535042003.538 [Reporter](INFO): height_above_sea_floor 0.927582 m
2018-08-23T16:33:23.976Z,1535042003.976 [Reporter](INFO): height_above_sea_floor 0.927133 m
2018-08-23T16:33:24.328Z,1535042004.328 [Reporter](INFO): height_above_sea_floor 0.922199 m
2018-08-23T16:33:24.756Z,1535042004.756 [Reporter](INFO): height_above_sea_floor 0.938796 m
2018-08-23T16:33:25.153Z,1535042005.153 [Reporter](INFO): height_above_sea_floor 0.944179 m
2018-08-23T16:33:25.551Z,1535042005.551 [Reporter](INFO): height_above_sea_floor 0.930273 m
2018-08-23T16:33:25.996Z,1535042005.996 [Reporter](INFO): height_above_sea_floor 0.942833 m
2018-08-23T16:33:26.337Z,1535042006.337 [Reporter](INFO): height_above_sea_floor 2.361000 m
2018-08-23T16:33:31.224Z,1535042011.224 [Reporter](INFO): height_above_sea_floor 2.454000 m
2018-08-23T16:33:41.181Z,1535042021.181 [Reporter](INFO): height_above_sea_floor 2.399000 m
2018-08-23T16:33:46.260Z,1535042026.260 [NAL9602](INFO): SBD MO Status=0, MOMSN=23444, MT Status=0, MTMSN=0
2018-08-23T16:33:46.260Z,1535042026.260 [NAL9602](INFO): No messages in MT queue
2018-08-23T16:33:46.403Z,1535042026.403 [Reporter](INFO): height_above_sea_floor 2.428000 m
2018-08-23T16:33:47.466Z,1535042027.466 [NAL9602](INFO): GPS fix at 20180823T163334: (41.827306, -83.413057)
2018-08-23T16:33:47.534Z,1535042027.534 [Default:CheckIn:Read_GPS] Stopped
2018-08-23T16:33:47.534Z,1535042027.534 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-23T16:33:52.963Z,1535042032.963 [Reporter](INFO): height_above_sea_floor 3.137000 m
2018-08-23T16:33:53.028Z,1535042033.028 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20180823T162341/Courier0007.lzma
2018-08-23T16:33:53.775Z,1535042033.775 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0007.lzma.bak
2018-08-23T16:33:53.776Z,1535042033.776 [DataOverHttps](INFO): SBD MOMSN=8438327
2018-08-23T16:33:55.502Z,1535042035.502 [Reporter](INFO): height_above_sea_floor 2.366000 m
2018-08-23T16:34:06.224Z,1535042046.224 [Reporter](INFO): height_above_sea_floor 0.940804 m
2018-08-23T16:34:06.247Z,1535042046.247 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20180823T162341/Express0005.lzma
2018-08-23T16:34:06.361Z,1535042046.361 [Reporter](INFO): height_above_sea_floor 0.930038 m
2018-08-23T16:34:06.707Z,1535042046.707 [Reporter](INFO): height_above_sea_floor 0.931384 m
2018-08-23T16:34:07.042Z,1535042047.042 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0005.lzma.bak
2018-08-23T16:34:07.042Z,1535042047.042 [DataOverHttps](INFO): SBD MOMSN=8438337
2018-08-23T16:34:07.216Z,1535042047.216 [Reporter](INFO): height_above_sea_floor 0.937215 m
2018-08-23T16:34:09.254Z,1535042049.254 [Reporter](INFO): height_above_sea_floor 2.384000 m
2018-08-23T16:34:19.745Z,1535042059.745 [NAL9602](INFO): Powering down
2018-08-23T16:34:19.855Z,1535042059.855 [Reporter](INFO): height_above_sea_floor 2.401000 m
2018-08-23T16:34:20.267Z,1535042060.267 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20180823T162341/Express0008.lzma
2018-08-23T16:34:21.067Z,1535042061.067 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0008.lzma.bak
2018-08-23T16:34:21.068Z,1535042061.068 [DataOverHttps](INFO): SBD MOMSN=8438340
2018-08-23T16:34:22.762Z,1535042062.762 [Default:CheckIn:Read_Iridium] Stopped
2018-08-23T16:34:22.762Z,1535042062.762 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-23T16:34:22.762Z,1535042062.762 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-23T16:34:28.730Z,1535042068.730 [Reporter](INFO): height_above_sea_floor 2.388000 m
2018-08-23T16:34:31.599Z,1535042071.599 [Reporter](INFO): height_above_sea_floor 3.172000 m
2018-08-23T16:34:42.829Z,1535042082.829 [Reporter](INFO): height_above_sea_floor 0.941701 m
2018-08-23T16:34:43.265Z,1535042083.265 [Reporter](INFO): height_above_sea_floor 0.942149 m
2018-08-23T16:34:43.626Z,1535042083.626 [Reporter](INFO): height_above_sea_floor 0.935870 m
2018-08-23T16:34:44.055Z,1535042084.055 [Reporter](INFO): height_above_sea_floor 0.949326 m
2018-08-23T16:34:44.439Z,1535042084.439 [Reporter](INFO): height_above_sea_floor 0.923310 m
2018-08-23T16:34:44.804Z,1535042084.804 [Reporter](INFO): height_above_sea_floor 0.940355 m
2018-08-23T16:34:45.270Z,1535042085.270 [Reporter](INFO): height_above_sea_floor 0.937215 m
2018-08-23T16:34:45.601Z,1535042085.601 [Reporter](INFO): height_above_sea_floor 0.942598 m
2018-08-23T16:34:46.000Z,1535042086.000 [Reporter](INFO): height_above_sea_floor 0.935870 m
2018-08-23T16:34:46.428Z,1535042086.428 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:34:46.841Z,1535042086.841 [Reporter](INFO): height_above_sea_floor 0.940355 m
2018-08-23T16:34:47.293Z,1535042087.293 [Reporter](INFO): height_above_sea_floor 0.924656 m
2018-08-23T16:34:47.630Z,1535042087.630 [Reporter](INFO): height_above_sea_floor 3.132000 m
2018-08-23T16:34:58.829Z,1535042098.829 [Reporter](INFO): height_above_sea_floor 0.942598 m
2018-08-23T16:34:59.291Z,1535042099.291 [Reporter](INFO): height_above_sea_floor 0.939009 m
2018-08-23T16:34:59.654Z,1535042099.654 [Reporter](INFO): height_above_sea_floor 0.956055 m
2018-08-23T16:35:00.125Z,1535042100.125 [Reporter](INFO): height_above_sea_floor 0.930935 m
2018-08-23T16:35:00.466Z,1535042100.466 [Reporter](INFO): height_above_sea_floor 0.948429 m
2018-08-23T16:35:00.846Z,1535042100.846 [Reporter](INFO): height_above_sea_floor 0.936767 m
2018-08-23T16:35:01.271Z,1535042101.271 [Reporter](INFO): height_above_sea_floor 0.943944 m
2018-08-23T16:35:01.654Z,1535042101.654 [Reporter](INFO): height_above_sea_floor 0.938561 m
2018-08-23T16:35:02.050Z,1535042102.050 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:35:02.452Z,1535042102.452 [Reporter](INFO): height_above_sea_floor 0.939009 m
2018-08-23T16:35:02.829Z,1535042102.829 [Reporter](INFO): height_above_sea_floor 0.933627 m
2018-08-23T16:35:03.299Z,1535042103.299 [Reporter](INFO): height_above_sea_floor 0.925553 m
2018-08-23T16:35:03.599Z,1535042103.599 [Reporter](INFO): height_above_sea_floor 0.925104 m
2018-08-23T16:35:04.004Z,1535042104.004 [Reporter](INFO): height_above_sea_floor 0.931833 m
2018-08-23T16:35:04.425Z,1535042104.425 [Reporter](INFO): height_above_sea_floor 0.934524 m
2018-08-23T16:35:04.844Z,1535042104.844 [Reporter](INFO): height_above_sea_floor 0.924656 m
2018-08-23T16:35:05.317Z,1535042105.317 [Reporter](INFO): height_above_sea_floor 0.935870 m
2018-08-23T16:35:05.614Z,1535042105.614 [Reporter](INFO): height_above_sea_floor 0.927347 m
2018-08-23T16:35:06.012Z,1535042106.012 [Reporter](INFO): height_above_sea_floor 0.927795 m
2018-08-23T16:35:06.427Z,1535042106.427 [Reporter](INFO): height_above_sea_floor 0.927795 m
2018-08-23T16:35:06.800Z,1535042106.800 [Reporter](INFO): height_above_sea_floor 0.935870 m
2018-08-23T16:35:07.291Z,1535042107.291 [Reporter](INFO): height_above_sea_floor 0.934524 m
2018-08-23T16:35:07.629Z,1535042107.629 [Reporter](INFO): height_above_sea_floor 0.938561 m
2018-08-23T16:35:08.002Z,1535042108.002 [Reporter](INFO): height_above_sea_floor 0.940804 m
2018-08-23T16:35:08.400Z,1535042108.400 [Reporter](INFO): height_above_sea_floor 0.929141 m
2018-08-23T16:35:08.889Z,1535042108.889 [Reporter](INFO): height_above_sea_floor 0.935421 m
2018-08-23T16:35:09.299Z,1535042109.299 [Reporter](INFO): height_above_sea_floor 0.945738 m
2018-08-23T16:35:09.611Z,1535042109.611 [Reporter](INFO): height_above_sea_floor 0.943495 m
2018-08-23T16:35:10.011Z,1535042110.011 [Reporter](INFO): height_above_sea_floor 0.937664 m
2018-08-23T16:35:10.411Z,1535042110.411 [Reporter](INFO): height_above_sea_floor 0.925553 m
2018-08-23T16:35:10.829Z,1535042110.829 [Reporter](INFO): height_above_sea_floor 0.949326 m
2018-08-23T16:35:11.285Z,1535042111.285 [Reporter](INFO): height_above_sea_floor 2.401000 m
2018-08-23T16:35:22.049Z,1535042122.049 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:35:22.469Z,1535042122.469 [Reporter](INFO): height_above_sea_floor 0.932730 m
2018-08-23T16:35:22.798Z,1535042122.798 [Reporter](INFO): height_above_sea_floor 0.931833 m
2018-08-23T16:35:23.314Z,1535042123.314 [Reporter](INFO): height_above_sea_floor 0.938561 m
2018-08-23T16:35:27.352Z,1535042127.352 [Reporter](INFO): height_above_sea_floor 0.945289 m
2018-08-23T16:35:27.462Z,1535042127.462 [Reporter](INFO): height_above_sea_floor 0.943047 m
2018-08-23T16:35:27.845Z,1535042127.845 [Reporter](INFO): height_above_sea_floor 0.945738 m
2018-08-23T16:35:28.337Z,1535042128.337 [Reporter](INFO): height_above_sea_floor 0.931384 m
2018-08-23T16:35:28.651Z,1535042128.651 [Reporter](INFO): height_above_sea_floor 0.931833 m
2018-08-23T16:35:29.058Z,1535042129.058 [Reporter](INFO): height_above_sea_floor 0.930487 m
2018-08-23T16:35:29.489Z,1535042129.489 [Reporter](INFO): height_above_sea_floor 0.927795 m
2018-08-23T16:35:29.884Z,1535042129.884 [Reporter](INFO): height_above_sea_floor 0.932281 m
2018-08-23T16:35:30.349Z,1535042130.349 [Reporter](INFO): height_above_sea_floor 0.944841 m
2018-08-23T16:35:30.659Z,1535042130.659 [Reporter](INFO): height_above_sea_floor 0.939907 m
2018-08-23T16:35:31.039Z,1535042131.039 [Reporter](INFO): height_above_sea_floor 0.935421 m
2018-08-23T16:35:31.457Z,1535042131.457 [Reporter](INFO): height_above_sea_floor 0.943495 m
2018-08-23T16:35:31.829Z,1535042131.829 [Reporter](INFO): height_above_sea_floor 0.941252 m
2018-08-23T16:35:32.348Z,1535042132.348 [Reporter](INFO): height_above_sea_floor 0.929590 m
2018-08-23T16:35:32.656Z,1535042132.656 [Reporter](INFO): height_above_sea_floor 0.921516 m
2018-08-23T16:35:33.101Z,1535042133.101 [Reporter](INFO): height_above_sea_floor 0.922413 m
2018-08-23T16:35:33.440Z,1535042133.440 [Reporter](INFO): height_above_sea_floor 0.930935 m
2018-08-23T16:35:33.844Z,1535042133.844 [Reporter](INFO): height_above_sea_floor 0.946186 m
2018-08-23T16:35:34.357Z,1535042134.357 [Reporter](INFO): height_above_sea_floor 0.937215 m
2018-08-23T16:35:34.658Z,1535042134.658 [Reporter](INFO): height_above_sea_floor 0.941252 m
2018-08-23T16:35:35.327Z,1535042135.327 [Reporter](INFO): height_above_sea_floor 0.943495 m
2018-08-23T16:35:35.466Z,1535042135.466 [Reporter](INFO): height_above_sea_floor 0.945289 m
2018-08-23T16:35:35.964Z,1535042135.964 [Reporter](INFO): height_above_sea_floor 0.941252 m
2018-08-23T16:35:36.418Z,1535042136.418 [Reporter](INFO): height_above_sea_floor 0.934524 m
2018-08-23T16:35:36.667Z,1535042136.667 [Reporter](INFO): height_above_sea_floor 0.934075 m
2018-08-23T16:35:37.078Z,1535042137.078 [Reporter](INFO): height_above_sea_floor 0.930487 m
2018-08-23T16:35:37.428Z,1535042137.428 [Reporter](INFO): height_above_sea_floor 0.948429 m
2018-08-23T16:35:37.835Z,1535042137.835 [Reporter](INFO): height_above_sea_floor 0.924656 m
2018-08-23T16:35:38.358Z,1535042138.358 [Reporter](INFO): height_above_sea_floor 0.920170 m
2018-08-23T16:35:38.651Z,1535042138.651 [Reporter](INFO): height_above_sea_floor 0.934524 m
2018-08-23T16:35:39.057Z,1535042139.057 [Reporter](INFO): height_above_sea_floor 0.945738 m
2018-08-23T16:35:39.446Z,1535042139.446 [Reporter](INFO): height_above_sea_floor 0.936318 m
2018-08-23T16:35:39.838Z,1535042139.838 [Reporter](INFO): height_above_sea_floor 0.939907 m
2018-08-23T16:35:40.355Z,1535042140.355 [Reporter](INFO): height_above_sea_floor 0.940355 m
2018-08-23T16:35:40.624Z,1535042140.624 [Reporter](INFO): height_above_sea_floor 0.942149 m
2018-08-23T16:35:41.065Z,1535042141.065 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:35:41.441Z,1535042141.441 [Reporter](INFO): height_above_sea_floor 0.933627 m
2018-08-23T16:35:41.841Z,1535042141.841 [Reporter](INFO): height_above_sea_floor 0.927347 m
2018-08-23T16:35:42.354Z,1535042142.354 [Reporter](INFO): height_above_sea_floor 0.950224 m
2018-08-23T16:35:42.660Z,1535042142.660 [Reporter](INFO): height_above_sea_floor 0.941701 m
2018-08-23T16:35:43.050Z,1535042143.050 [Reporter](INFO): height_above_sea_floor 0.943047 m
2018-08-23T16:35:43.445Z,1535042143.445 [Reporter](INFO): height_above_sea_floor 3.163000 m
2018-08-23T16:35:44.683Z,1535042144.683 [Reporter](INFO): height_above_sea_floor 2.435000 m
2018-08-23T16:35:50.637Z,1535042150.637 [Reporter](INFO): height_above_sea_floor 3.141000 m
2018-08-23T16:35:51.451Z,1535042151.451 [Reporter](INFO): height_above_sea_floor 2.494000 m
2018-08-23T16:35:52.648Z,1535042152.648 [Reporter](INFO): height_above_sea_floor 2.414000 m
2018-08-23T16:36:02.689Z,1535042162.689 [Reporter](INFO): height_above_sea_floor 0.949326 m
2018-08-23T16:36:03.034Z,1535042163.034 [Reporter](INFO): height_above_sea_floor 0.949775 m
2018-08-23T16:36:03.434Z,1535042163.434 [Reporter](INFO): height_above_sea_floor 0.933627 m
2018-08-23T16:36:03.839Z,1535042163.839 [Reporter](INFO): height_above_sea_floor 0.934075 m
2018-08-23T16:36:04.389Z,1535042164.389 [Reporter](INFO): height_above_sea_floor 0.943047 m
2018-08-23T16:36:04.623Z,1535042164.623 [Reporter](INFO): height_above_sea_floor 0.937215 m
2018-08-23T16:36:05.024Z,1535042165.024 [Reporter](INFO): height_above_sea_floor 0.928693 m
2018-08-23T16:36:05.429Z,1535042165.429 [Reporter](INFO): height_above_sea_floor 0.930487 m
2018-08-23T16:36:05.877Z,1535042165.877 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:36:06.370Z,1535042166.370 [Reporter](INFO): height_above_sea_floor 0.931833 m
2018-08-23T16:36:06.647Z,1535042166.647 [Reporter](INFO): height_above_sea_floor 0.930038 m
2018-08-23T16:36:07.055Z,1535042167.055 [Reporter](INFO): height_above_sea_floor 0.939009 m
2018-08-23T16:36:07.449Z,1535042167.449 [Reporter](INFO): height_above_sea_floor 0.945738 m
2018-08-23T16:36:07.856Z,1535042167.856 [Reporter](INFO): height_above_sea_floor 0.938561 m
2018-08-23T16:36:08.370Z,1535042168.370 [Reporter](INFO): height_above_sea_floor 0.935421 m
2018-08-23T16:36:08.650Z,1535042168.650 [Reporter](INFO): height_above_sea_floor 0.938112 m
2018-08-23T16:36:09.050Z,1535042169.050 [Reporter](INFO): height_above_sea_floor 0.948878 m
2018-08-23T16:36:09.482Z,1535042169.482 [Reporter](INFO): height_above_sea_floor 0.939907 m
2018-08-23T16:36:09.863Z,1535042169.863 [Reporter](INFO): height_above_sea_floor 0.944392 m
2018-08-23T16:36:10.409Z,1535042170.409 [Reporter](INFO): height_above_sea_floor 0.938561 m
2018-08-23T16:36:10.697Z,1535042170.697 [Reporter](INFO): height_above_sea_floor 0.947532 m
2018-08-23T16:36:11.056Z,1535042171.056 [Reporter](INFO): height_above_sea_floor 0.944841 m
2018-08-23T16:36:11.458Z,1535042171.458 [Reporter](INFO): height_above_sea_floor 0.935870 m
2018-08-23T16:36:11.841Z,1535042171.841 [Reporter](INFO): height_above_sea_floor 0.949775 m
2018-08-23T16:36:12.346Z,1535042172.346 [Reporter](INFO): height_above_sea_floor 0.948878 m
2018-08-23T16:36:12.641Z,1535042172.641 [Reporter](INFO): height_above_sea_floor 0.946186 m
2018-08-23T16:36:13.049Z,1535042173.049 [Reporter](INFO): height_above_sea_floor 0.933627 m
2018-08-23T16:36:13.440Z,1535042173.440 [Reporter](INFO): height_above_sea_floor 0.930038 m
2018-08-23T16:36:13.842Z,1535042173.842 [Reporter](INFO): height_above_sea_floor 0.935421 m
2018-08-23T16:36:14.407Z,1535042174.407 [Reporter](INFO): height_above_sea_floor 0.939009 m
2018-08-23T16:36:14.705Z,1535042174.705 [Reporter](INFO): height_above_sea_floor 0.937664 m
2018-08-23T16:36:15.048Z,1535042175.048 [Reporter](INFO): height_above_sea_floor 0.936318 m
2018-08-23T16:36:15.449Z,1535042175.449 [Reporter](INFO): height_above_sea_floor 0.944392 m
2018-08-23T16:36:15.851Z,1535042175.851 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:36:16.382Z,1535042176.382 [Reporter](INFO): height_above_sea_floor 0.941701 m
2018-08-23T16:36:16.644Z,1535042176.644 [Reporter](INFO): height_above_sea_floor 0.934075 m
2018-08-23T16:36:17.044Z,1535042177.044 [Reporter](INFO): height_above_sea_floor 0.943944 m
2018-08-23T16:36:17.486Z,1535042177.486 [Reporter](INFO): height_above_sea_floor 0.930487 m
2018-08-23T16:36:17.841Z,1535042177.841 [Reporter](INFO): height_above_sea_floor 0.930038 m
2018-08-23T16:36:18.357Z,1535042178.357 [Reporter](INFO): height_above_sea_floor 0.929590 m
2018-08-23T16:36:18.669Z,1535042178.669 [Reporter](INFO): height_above_sea_floor 0.935421 m
2018-08-23T16:36:19.069Z,1535042179.069 [Reporter](INFO): height_above_sea_floor 0.938561 m
2018-08-23T16:36:19.446Z,1535042179.446 [Reporter](INFO): height_above_sea_floor 0.940355 m
2018-08-23T16:36:19.850Z,1535042179.850 [Reporter](INFO): height_above_sea_floor 0.933178 m
2018-08-23T16:36:20.371Z,1535042180.371 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:36:20.628Z,1535042180.628 [Reporter](INFO): height_above_sea_floor 0.944392 m
2018-08-23T16:36:21.035Z,1535042181.035 [Reporter](INFO): height_above_sea_floor 0.944392 m
2018-08-23T16:36:21.463Z,1535042181.463 [Reporter](INFO): height_above_sea_floor 0.944841 m
2018-08-23T16:36:21.906Z,1535042181.906 [Reporter](INFO): height_above_sea_floor 0.949326 m
2018-08-23T16:36:22.356Z,1535042182.356 [Reporter](INFO): height_above_sea_floor 0.930038 m
2018-08-23T16:36:22.647Z,1535042182.647 [Reporter](INFO): height_above_sea_floor 0.933627 m
2018-08-23T16:36:23.054Z,1535042183.054 [Reporter](INFO): height_above_sea_floor 0.945738 m
2018-08-23T16:36:23.462Z,1535042183.462 [Reporter](INFO): height_above_sea_floor 0.931833 m
2018-08-23T16:36:27.563Z,1535042187.563 [Reporter](INFO): height_above_sea_floor 0.940804 m
2018-08-23T16:36:27.678Z,1535042187.678 [Reporter](INFO): height_above_sea_floor 0.932281 m
2018-08-23T16:36:28.054Z,1535042188.054 [Reporter](INFO): height_above_sea_floor 0.940804 m
2018-08-23T16:36:28.531Z,1535042188.531 [Reporter](INFO): height_above_sea_floor 3.163000 m
2018-08-23T16:36:29.673Z,1535042189.673 [Reporter](INFO): height_above_sea_floor 2.366000 m
2018-08-23T16:36:40.864Z,1535042200.864 [Reporter](INFO): height_above_sea_floor 0.930487 m
2018-08-23T16:36:41.316Z,1535042201.316 [Reporter](INFO): height_above_sea_floor 0.942598 m
2018-08-23T16:36:41.649Z,1535042201.649 [Reporter](INFO): height_above_sea_floor 0.931384 m
2018-08-23T16:36:42.053Z,1535042202.053 [Reporter](INFO): height_above_sea_floor 0.936318 m
2018-08-23T16:36:42.456Z,1535042202.456 [Reporter](INFO): height_above_sea_floor 0.934075 m
2018-08-23T16:36:42.857Z,1535042202.857 [Reporter](INFO): height_above_sea_floor 0.943495 m
2018-08-23T16:36:43.399Z,1535042203.399 [Reporter](INFO): height_above_sea_floor 0.940804 m
2018-08-23T16:36:43.687Z,1535042203.687 [Reporter](INFO): height_above_sea_floor 0.930487 m
2018-08-23T16:36:44.060Z,1535042204.060 [Reporter](INFO): height_above_sea_floor 0.943047 m
2018-08-23T16:36:44.485Z,1535042204.485 [Reporter](INFO): height_above_sea_floor 0.941701 m
2018-08-23T16:36:44.861Z,1535042204.861 [Reporter](INFO): height_above_sea_floor 2.458000 m
2018-08-23T16:36:45.652Z,1535042205.652 [Reporter](INFO): height_above_sea_floor 2.445000 m
2018-08-23T16:36:46.873Z,1535042206.873 [Reporter](INFO): height_above_sea_floor 2.436000 m
2018-08-23T16:36:56.949Z,1535042216.949 [Reporter](INFO): height_above_sea_floor 0.935421 m
2018-08-23T16:36:57.363Z,1535042217.363 [Reporter](INFO): height_above_sea_floor 0.931384 m
2018-08-23T16:36:57.666Z,1535042217.666 [Reporter](INFO): height_above_sea_floor 0.930935 m
2018-08-23T16:36:58.127Z,1535042218.127 [Reporter](INFO): height_above_sea_floor 0.942149 m
2018-08-23T16:36:58.541Z,1535042218.541 [Reporter](INFO): height_above_sea_floor 0.946635 m
2018-08-23T16:36:58.877Z,1535042218.877 [Reporter](INFO): height_above_sea_floor 0.948878 m
2018-08-23T16:36:59.322Z,1535042219.322 [Reporter](INFO): height_above_sea_floor 0.933627 m
2018-08-23T16:36:59.699Z,1535042219.699 [Reporter](INFO): height_above_sea_floor 0.939907 m
2018-08-23T16:37:00.037Z,1535042220.037 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:37:00.533Z,1535042220.533 [Reporter](INFO): height_above_sea_floor 0.940804 m
2018-08-23T16:37:00.908Z,1535042220.908 [Reporter](INFO): height_above_sea_floor 0.939458 m
2018-08-23T16:37:01.286Z,1535042221.286 [Reporter](INFO): height_above_sea_floor 0.930935 m
2018-08-23T16:37:01.673Z,1535042221.673 [Reporter](INFO): height_above_sea_floor 0.929141 m
2018-08-23T16:37:02.087Z,1535042222.087 [Reporter](INFO): height_above_sea_floor 0.942149 m
2018-08-23T16:37:02.534Z,1535042222.534 [Reporter](INFO): height_above_sea_floor 0.942149 m
2018-08-23T16:37:02.933Z,1535042222.933 [Reporter](INFO): height_above_sea_floor 0.936767 m
2018-08-23T16:37:03.276Z,1535042223.276 [Reporter](INFO): height_above_sea_floor 0.947532 m
2018-08-23T16:37:03.655Z,1535042223.655 [Reporter](INFO): height_above_sea_floor 0.935421 m
2018-08-23T16:37:04.036Z,1535042224.036 [Reporter](INFO): height_above_sea_floor 3.149000 m
2018-08-23T16:37:14.855Z,1535042234.855 [Reporter](INFO): height_above_sea_floor 0.942149 m
2018-08-23T16:37:15.248Z,1535042235.248 [Reporter](INFO): height_above_sea_floor 0.935870 m
2018-08-23T16:37:15.660Z,1535042235.660 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:37:16.099Z,1535042236.099 [Reporter](INFO): height_above_sea_floor 0.938561 m
2018-08-23T16:37:16.538Z,1535042236.538 [Reporter](INFO): height_above_sea_floor 0.926450 m
2018-08-23T16:37:16.862Z,1535042236.862 [Reporter](INFO): height_above_sea_floor 0.934524 m
2018-08-23T16:37:17.262Z,1535042237.262 [Reporter](INFO): height_above_sea_floor 0.927795 m
2018-08-23T16:37:17.661Z,1535042237.661 [Reporter](INFO): height_above_sea_floor 0.949326 m
2018-08-23T16:37:18.079Z,1535042238.079 [Reporter](INFO): height_above_sea_floor 0.925104 m
2018-08-23T16:37:18.517Z,1535042238.517 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:37:18.838Z,1535042238.838 [Reporter](INFO): height_above_sea_floor 0.939907 m
2018-08-23T16:37:19.242Z,1535042239.242 [Reporter](INFO): height_above_sea_floor 0.939458 m
2018-08-23T16:37:19.814Z,1535042239.814 [Reporter](INFO): height_above_sea_floor 0.946186 m
2018-08-23T16:37:20.083Z,1535042240.083 [Reporter](INFO): height_above_sea_floor 0.932730 m
2018-08-23T16:37:20.529Z,1535042240.529 [Reporter](INFO): height_above_sea_floor 0.925553 m
2018-08-23T16:37:20.924Z,1535042240.924 [Reporter](INFO): height_above_sea_floor 0.932730 m
2018-08-23T16:37:21.480Z,1535042241.480 [Reporter](INFO): height_above_sea_floor 0.944392 m
2018-08-23T16:37:21.649Z,1535042241.649 [Reporter](INFO): height_above_sea_floor 0.928244 m
2018-08-23T16:37:22.046Z,1535042242.046 [Reporter](INFO): height_above_sea_floor 0.936767 m
2018-08-23T16:37:22.554Z,1535042242.554 [Reporter](INFO): height_above_sea_floor 0.937215 m
2018-08-23T16:37:22.853Z,1535042242.853 [Reporter](INFO): height_above_sea_floor 0.942149 m
2018-08-23T16:37:23.261Z,1535042243.261 [Reporter](INFO): height_above_sea_floor 0.942598 m
2018-08-23T16:37:23.658Z,1535042243.658 [Reporter](INFO): height_above_sea_floor 0.931384 m
2018-08-23T16:37:24.037Z,1535042244.037 [Reporter](INFO): height_above_sea_floor 0.940804 m
2018-08-23T16:37:28.031Z,1535042248.031 [Reporter](INFO): height_above_sea_floor 2.335000 m
2018-08-23T16:37:38.119Z,1535042258.119 [Reporter](INFO): height_above_sea_floor 0.935870 m
2018-08-23T16:37:38.359Z,1535042258.359 [CommandLine](IMPORTANT): got command get depth
2018-08-23T16:37:38.360Z,1535042258.360 [CommandLine](IMPORTANT): depth 0.023924 m
2018-08-23T16:37:38.574Z,1535042258.574 [Reporter](INFO): height_above_sea_floor 0.935870 m
2018-08-23T16:37:38.926Z,1535042258.926 [Reporter](INFO): height_above_sea_floor 0.938112 m
2018-08-23T16:37:39.439Z,1535042259.439 [Reporter](INFO): height_above_sea_floor 0.934972 m
2018-08-23T16:37:39.726Z,1535042259.726 [Reporter](INFO): height_above_sea_floor 0.931833 m
2018-08-23T16:37:40.132Z,1535042260.132 [Reporter](INFO): height_above_sea_floor 0.947532 m
2018-08-23T16:37:40.537Z,1535042260.537 [Reporter](INFO): height_above_sea_floor 0.931384 m
2018-08-23T16:37:40.929Z,1535042260.929 [Reporter](INFO): height_above_sea_floor 0.936767 m
2018-08-23T16:37:41.336Z,1535042261.336 [Reporter](INFO): height_above_sea_floor 0.930935 m
2018-08-23T16:37:41.708Z,1535042261.708 [Reporter](INFO): height_above_sea_floor 0.954709 m
2018-08-23T16:37:42.137Z,1535042262.137 [Reporter](INFO): height_above_sea_floor 0.933627 m
2018-08-23T16:37:42.555Z,1535042262.555 [Reporter](INFO): height_above_sea_floor 0.937664 m
2018-08-23T16:37:42.975Z,1535042262.975 [Reporter](INFO): height_above_sea_floor 0.923310 m
2018-08-23T16:37:43.372Z,1535042263.372 [Reporter](INFO): height_above_sea_floor 0.942149 m
2018-08-23T16:37:43.726Z,1535042263.726 [Reporter](INFO): height_above_sea_floor 0.938561 m
2018-08-23T16:37:44.128Z,1535042264.128 [Reporter](INFO): height_above_sea_floor 0.937215 m
2018-08-23T16:37:44.407Z,1535042264.407 [CommandLine](IMPORTANT): got command report clear
2018-08-23T16:37:56.767Z,1535042276.767 [CommandLine](IMPORTANT): got command get Depth_Keller.offset
2018-08-23T16:37:56.767Z,1535042276.767 [CommandLine](IMPORTANT): Depth_Keller.offset -0.560000 db
2018-08-23T16:39:23.040Z,1535042363.040 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-23T16:39:23.040Z,1535042363.040 [Default:CheckIn:C.Wait] Stopped
2018-08-23T16:39:23.040Z,1535042363.040 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T16:39:23.040Z,1535042363.040 [Default:CheckIn:D] Running Loop=1
2018-08-23T16:39:23.439Z,1535042363.439 [Default:CheckIn:D] Stopped
2018-08-23T16:39:23.439Z,1535042363.439 [Default:CheckIn:E] Running Loop=1
2018-08-23T16:39:23.850Z,1535042363.850 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.222720 min 
2018-08-23T16:39:23.851Z,1535042363.851 [Default:CheckIn:E] Stopped
2018-08-23T16:39:23.851Z,1535042363.851 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-23T16:39:23.851Z,1535042363.851 [Default:CheckIn] Stopped
2018-08-23T16:39:23.851Z,1535042363.851 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-23T16:39:23.851Z,1535042363.851 [Default:CheckIn](INFO): Running loop #3
2018-08-23T16:39:23.851Z,1535042363.851 [Default:CheckIn] Running Loop=3
2018-08-23T16:39:23.852Z,1535042363.852 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-23T16:39:23.852Z,1535042363.852 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-23T16:39:27.991Z,1535042367.991 [NAL9602](INFO): Powering up
2018-08-23T16:39:39.166Z,1535042379.166 [NAL9602](INFO): NAL9602 initialized
2018-08-23T16:39:56.368Z,1535042396.368 [NAL9602](INFO): SBD MO Status=0, MOMSN=23445, MT Status=0, MTMSN=0
2018-08-23T16:39:56.368Z,1535042396.368 [NAL9602](INFO): No messages in MT queue
2018-08-23T16:40:09.170Z,1535042409.170 [NAL9602](INFO): GPS fix at 20180823T163955: (41.827330, -83.413054)
2018-08-23T16:40:09.238Z,1535042409.238 [Default:CheckIn:Read_GPS] Stopped
2018-08-23T16:40:09.238Z,1535042409.238 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-23T16:40:16.452Z,1535042416.452 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20180823T162341/Courier0010.lzma
2018-08-23T16:40:17.247Z,1535042417.247 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0010.lzma.bak
2018-08-23T16:40:17.247Z,1535042417.247 [DataOverHttps](INFO): SBD MOMSN=8438354
2018-08-23T16:40:29.892Z,1535042429.892 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20180823T162341/Express0011.lzma
2018-08-23T16:40:30.686Z,1535042430.686 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0011.lzma.bak
2018-08-23T16:40:30.687Z,1535042430.687 [DataOverHttps](INFO): SBD MOMSN=8438357
2018-08-23T16:40:32.547Z,1535042432.547 [Default:CheckIn:Read_Iridium] Stopped
2018-08-23T16:40:32.547Z,1535042432.547 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-23T16:40:32.548Z,1535042432.548 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-23T16:40:45.998Z,1535042445.998 [NAL9602](INFO): Powering down
2018-08-23T16:41:42.967Z,1535042502.967 [CommandLine](IMPORTANT): got command report touch height_above_sea_floor
2018-08-23T16:41:45.343Z,1535042505.343 [Reporter](INFO): height_above_sea_floor 2.397000 m
2018-08-23T16:41:56.556Z,1535042516.556 [Reporter](INFO): height_above_sea_floor 0.949723 m
2018-08-23T16:41:56.972Z,1535042516.972 [Reporter](INFO): height_above_sea_floor 0.951518 m
2018-08-23T16:41:57.302Z,1535042517.302 [Reporter](INFO): height_above_sea_floor 0.943892 m
2018-08-23T16:41:57.742Z,1535042517.742 [Reporter](INFO): height_above_sea_floor 0.952415 m
2018-08-23T16:41:58.105Z,1535042518.105 [Reporter](INFO): height_above_sea_floor 0.955106 m
2018-08-23T16:41:58.512Z,1535042518.512 [Reporter](INFO): height_above_sea_floor 0.938509 m
2018-08-23T16:41:59.002Z,1535042519.002 [Reporter](INFO): height_above_sea_floor 0.951518 m
2018-08-23T16:41:59.340Z,1535042519.340 [Reporter](INFO): height_above_sea_floor 0.961386 m
2018-08-23T16:41:59.819Z,1535042519.819 [Reporter](INFO): height_above_sea_floor 0.949723 m
2018-08-23T16:42:00.130Z,1535042520.130 [Reporter](INFO): height_above_sea_floor 0.955106 m
2018-08-23T16:42:00.534Z,1535042520.534 [Reporter](INFO): height_above_sea_floor 0.948378 m
2018-08-23T16:42:00.943Z,1535042520.943 [Reporter](INFO): height_above_sea_floor 0.939407 m
2018-08-23T16:42:01.333Z,1535042521.333 [Reporter](INFO): height_above_sea_floor 0.950172 m
2018-08-23T16:42:01.734Z,1535042521.734 [Reporter](INFO): height_above_sea_floor 0.958695 m
2018-08-23T16:42:02.112Z,1535042522.112 [Reporter](INFO): height_above_sea_floor 0.951069 m
2018-08-23T16:42:02.531Z,1535042522.531 [Reporter](INFO): height_above_sea_floor 0.947032 m
2018-08-23T16:42:02.946Z,1535042522.946 [Reporter](INFO): height_above_sea_floor 0.947032 m
2018-08-23T16:42:03.362Z,1535042523.362 [Reporter](INFO): height_above_sea_floor 0.950620 m
2018-08-23T16:42:03.710Z,1535042523.710 [Reporter](INFO): height_above_sea_floor 0.940304 m
2018-08-23T16:42:04.106Z,1535042524.106 [Reporter](INFO): height_above_sea_floor 0.943892 m
2018-08-23T16:42:04.507Z,1535042524.507 [Reporter](INFO): height_above_sea_floor 0.962283 m
2018-08-23T16:42:04.943Z,1535042524.943 [Reporter](INFO): height_above_sea_floor 0.951518 m
2018-08-23T16:42:05.313Z,1535042525.313 [Reporter](INFO): height_above_sea_floor 2.384000 m
2018-08-23T16:42:10.124Z,1535042530.124 [Reporter](INFO): height_above_sea_floor 2.361000 m
2018-08-23T16:42:13.328Z,1535042533.328 [Reporter](INFO): height_above_sea_floor 2.361000 m
2018-08-23T16:42:19.361Z,1535042539.361 [Reporter](INFO): height_above_sea_floor 3.185000 m
2018-08-23T16:42:20.133Z,1535042540.133 [Reporter](INFO): height_above_sea_floor 2.353000 m
2018-08-23T16:42:30.139Z,1535042550.139 [Reporter](INFO): height_above_sea_floor 2.331000 m
2018-08-23T16:42:37.428Z,1535042557.428 [Reporter](INFO): height_above_sea_floor 2.331000 m
2018-08-23T16:42:45.545Z,1535042565.545 [Reporter](INFO): height_above_sea_floor 0.946583 m
2018-08-23T16:42:46.010Z,1535042566.010 [Reporter](INFO): height_above_sea_floor 0.940752 m
2018-08-23T16:42:46.299Z,1535042566.299 [Reporter](INFO): height_above_sea_floor 0.951518 m
2018-08-23T16:42:46.705Z,1535042566.705 [Reporter](INFO): height_above_sea_floor 0.944341 m
2018-08-23T16:42:47.101Z,1535042567.101 [Reporter](INFO): height_above_sea_floor 0.947929 m
2018-08-23T16:42:47.504Z,1535042567.504 [Reporter](INFO): height_above_sea_floor 3.132000 m
2018-08-23T16:42:47.992Z,1535042567.992 [Reporter](INFO): height_above_sea_floor 2.363000 m
2018-08-23T16:42:58.015Z,1535042578.015 [Reporter](INFO): height_above_sea_floor 2.388000 m
2018-08-23T16:43:03.104Z,1535042583.104 [Reporter](INFO): height_above_sea_floor 2.344000 m
2018-08-23T16:43:13.097Z,1535042593.097 [Reporter](INFO): height_above_sea_floor 2.344000 m
2018-08-23T16:43:23.163Z,1535042603.163 [Reporter](INFO): height_above_sea_floor 2.389000 m
2018-08-23T16:43:28.027Z,1535042608.027 [Reporter](INFO): height_above_sea_floor 2.361000 m
2018-08-23T16:43:28.040Z,1535042608.040 [CommandLine](IMPORTANT): got command report clear
2018-08-23T16:44:52.712Z,1535042692.712 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 31 sticks.
2018-08-23T16:44:52.712Z,1535042692.712 [BPC1] Data Fault, FailCount= 1
2018-08-23T16:44:52.712Z,1535042692.712 [BPC1](ERROR): Data Fault
2018-08-23T16:44:52.784Z,1535042692.784 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-23T16:44:53.988Z,1535042693.988 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-23T16:44:53.988Z,1535042693.988 [BPC1] No Fault, FailCount= 1
2018-08-23T16:45:31.581Z,1535042731.581 [CommandLine](IMPORTANT): got command configSet Rowe_600LCM.loadAtStartup 0.000000 bool
2018-08-23T16:45:31.582Z,1535042731.582 [CommandLine](FAULT): configSet Rowe_600LCM.loadAtStartup without persist will have no effect.
2018-08-23T16:45:31.837Z,1535042731.837 [Rowe_600LCM](IMPORTANT): Stopping now due to load at startup. No immediate restart required.
2018-08-23T16:45:31.838Z,1535042731.838 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-23T16:45:32.152Z,1535042732.152 [Rowe_600LCM](INFO): Powering down
2018-08-23T16:45:32.557Z,1535042732.557 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-08-23T16:45:32.558Z,1535042732.558 [Rowe_600LCM] Hardware Fault, FailCount= 1
2018-08-23T16:45:32.558Z,1535042732.558 [Rowe_600LCM](ERROR): Hardware Fault
2018-08-23T16:45:32.795Z,1535042732.795 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM
2018-08-23T16:45:33.164Z,1535042733.164 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-23T16:45:33.164Z,1535042733.164 [Default:CheckIn:C.Wait] Stopped
2018-08-23T16:45:33.164Z,1535042733.164 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T16:45:33.164Z,1535042733.164 [Default:CheckIn:D] Running Loop=1
2018-08-23T16:45:33.216Z,1535042733.216 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-23T16:45:33.216Z,1535042733.216 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-23T16:45:33.365Z,1535042733.365 [Rowe_600LCM](INFO): Initializing
2018-08-23T16:45:33.366Z,1535042733.366 [Rowe_600LCM](INFO): Checking LCM
2018-08-23T16:45:33.366Z,1535042733.366 [Rowe_600LCM](INFO): LCM OK
2018-08-23T16:45:33.366Z,1535042733.366 [Rowe_600LCM](INFO): Powering up
2018-08-23T16:45:33.522Z,1535042733.522 [Default:CheckIn:D] Stopped
2018-08-23T16:45:33.523Z,1535042733.523 [Default:CheckIn:E] Running Loop=1
2018-08-23T16:45:33.928Z,1535042733.928 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.390774 min 
2018-08-23T16:45:33.953Z,1535042733.953 [Default:CheckIn:E] Stopped
2018-08-23T16:45:33.953Z,1535042733.953 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-23T16:45:33.953Z,1535042733.953 [Default:CheckIn] Stopped
2018-08-23T16:45:33.954Z,1535042733.954 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-23T16:45:33.954Z,1535042733.954 [Default:CheckIn](INFO): Running loop #4
2018-08-23T16:45:33.954Z,1535042733.954 [Default:CheckIn] Running Loop=4
2018-08-23T16:45:33.954Z,1535042733.954 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-23T16:45:33.954Z,1535042733.954 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-23T16:45:37.558Z,1535042737.558 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-23T16:45:37.661Z,1535042737.661 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-23T16:45:37.662Z,1535042737.662 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-23T16:45:37.663Z,1535042737.663 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-23T16:45:37.664Z,1535042737.664 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-23T16:45:38.066Z,1535042738.066 [Rowe_600LCM](IMPORTANT): Stopping now due to load at startup. No immediate restart required.
2018-08-23T16:45:38.067Z,1535042738.067 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-23T16:45:38.264Z,1535042738.264 [Rowe_600LCM](INFO): Powering down
2018-08-23T16:45:39.420Z,1535042739.420 [NAL9602](INFO): Powering up
2018-08-23T16:45:50.606Z,1535042750.606 [NAL9602](INFO): NAL9602 initialized
2018-08-23T16:45:52.107Z,1535042752.107 [CommandLine](IMPORTANT): got command report touch height_above_sea_floor
2018-08-23T16:45:52.310Z,1535042752.310 [Reporter](INFO): height_above_sea_floor 0.956003 m
2018-08-23T16:45:52.663Z,1535042752.663 [Reporter](INFO): height_above_sea_floor 0.951966 m
2018-08-23T16:45:53.063Z,1535042753.063 [Reporter](INFO): height_above_sea_floor 0.952415 m
2018-08-23T16:45:53.471Z,1535042753.471 [Reporter](INFO): height_above_sea_floor 0.957349 m
2018-08-23T16:45:53.867Z,1535042753.867 [Reporter](INFO): height_above_sea_floor 0.956452 m
2018-08-23T16:45:54.326Z,1535042754.326 [Reporter](INFO): height_above_sea_floor 0.948826 m
2018-08-23T16:45:54.670Z,1535042754.670 [Reporter](INFO): height_above_sea_floor 0.944789 m
2018-08-23T16:45:55.117Z,1535042755.117 [Reporter](INFO): height_above_sea_floor 0.954209 m
2018-08-23T16:45:55.497Z,1535042755.497 [Reporter](INFO): height_above_sea_floor 0.951518 m
2018-08-23T16:45:55.883Z,1535042755.883 [Reporter](INFO): height_above_sea_floor 0.951518 m
2018-08-23T16:45:56.306Z,1535042756.306 [Reporter](INFO): height_above_sea_floor 0.947032 m
2018-08-23T16:45:56.681Z,1535042756.681 [Reporter](INFO): height_above_sea_floor 0.937164 m
2018-08-23T16:45:57.101Z,1535042757.101 [Reporter](INFO): height_above_sea_floor 0.941201 m
2018-08-23T16:45:57.520Z,1535042757.520 [Reporter](INFO): height_above_sea_floor 0.941649 m
2018-08-23T16:45:57.946Z,1535042757.946 [Reporter](INFO): height_above_sea_floor 0.946135 m
2018-08-23T16:45:58.271Z,1535042758.271 [Reporter](INFO): height_above_sea_floor 0.959592 m
2018-08-23T16:45:58.677Z,1535042758.677 [Reporter](INFO): height_above_sea_floor 0.969460 m
2018-08-23T16:45:59.072Z,1535042759.072 [Reporter](INFO): height_above_sea_floor 0.956452 m
2018-08-23T16:45:59.469Z,1535042759.469 [Reporter](INFO): height_above_sea_floor 0.960489 m
2018-08-23T16:45:59.870Z,1535042759.870 [Reporter](INFO): height_above_sea_floor 0.956900 m
2018-08-23T16:46:00.323Z,1535042760.323 [Reporter](INFO): height_above_sea_floor 0.951069 m
2018-08-23T16:46:00.679Z,1535042760.679 [Reporter](INFO): height_above_sea_floor 0.948826 m
2018-08-23T16:46:01.134Z,1535042761.134 [Reporter](INFO): height_above_sea_floor 0.938958 m
2018-08-23T16:46:01.494Z,1535042761.494 [Reporter](INFO): height_above_sea_floor 0.955106 m
2018-08-23T16:46:01.878Z,1535042761.878 [Reporter](INFO): height_above_sea_floor 0.957349 m
2018-08-23T16:46:02.268Z,1535042762.268 [Reporter](INFO): height_above_sea_floor 0.959143 m
2018-08-23T16:46:02.664Z,1535042762.664 [Reporter](INFO): height_above_sea_floor 0.945686 m
2018-08-23T16:46:03.072Z,1535042763.072 [Reporter](INFO): height_above_sea_floor 0.951966 m
2018-08-23T16:46:03.489Z,1535042763.489 [Reporter](INFO): height_above_sea_floor 0.945238 m
2018-08-23T16:46:03.873Z,1535042763.873 [Reporter](INFO): height_above_sea_floor 0.964526 m
2018-08-23T16:46:04.336Z,1535042764.336 [Reporter](INFO): height_above_sea_floor 0.951518 m
2018-08-23T16:46:04.682Z,1535042764.682 [Reporter](INFO): height_above_sea_floor 0.940752 m
2018-08-23T16:46:05.093Z,1535042765.093 [Reporter](INFO): height_above_sea_floor 0.955106 m
2018-08-23T16:46:05.475Z,1535042765.475 [Reporter](INFO): height_above_sea_floor 0.951069 m
2018-08-23T16:46:05.889Z,1535042765.889 [Reporter](INFO): height_above_sea_floor 0.956003 m
2018-08-23T16:46:06.270Z,1535042766.270 [Reporter](INFO): height_above_sea_floor 0.953760 m
2018-08-23T16:46:06.674Z,1535042766.674 [Reporter](INFO): height_above_sea_floor 0.954658 m
2018-08-23T16:46:06.941Z,1535042766.941 [CommandLine](IMPORTANT): got command configSet Rowe_600LCM.loadAtStartup 1.000000 bool
2018-08-23T16:46:06.941Z,1535042766.941 [CommandLine](FAULT): configSet Rowe_600LCM.loadAtStartup without persist will have no effect.
2018-08-23T16:46:06.958Z,1535042766.958 [Rowe_600LCM](INFO): Initializing
2018-08-23T16:46:06.958Z,1535042766.958 [Rowe_600LCM](INFO): Checking LCM
2018-08-23T16:46:06.958Z,1535042766.958 [Rowe_600LCM](INFO): LCM OK
2018-08-23T16:46:06.958Z,1535042766.958 [Rowe_600LCM](INFO): Powering up
2018-08-23T16:46:07.016Z,1535042767.016 [NAL9602](INFO): SBD MO Status=0, MOMSN=23446, MT Status=0, MTMSN=0
2018-08-23T16:46:07.016Z,1535042767.016 [NAL9602](INFO): No messages in MT queue
2018-08-23T16:46:07.125Z,1535042767.125 [Reporter](INFO): height_above_sea_floor 0.953760 m
2018-08-23T16:46:07.511Z,1535042767.511 [Reporter](INFO): height_above_sea_floor 0.941201 m
2018-08-23T16:46:07.906Z,1535042767.906 [Reporter](INFO): height_above_sea_floor 0.957349 m
2018-08-23T16:46:08.340Z,1535042768.340 [Reporter](INFO): height_above_sea_floor 0.949723 m
2018-08-23T16:46:08.689Z,1535042768.689 [Reporter](INFO): height_above_sea_floor 0.956900 m
2018-08-23T16:46:09.080Z,1535042769.080 [Reporter](INFO): height_above_sea_floor 0.951518 m
2018-08-23T16:46:09.495Z,1535042769.495 [Reporter](INFO): height_above_sea_floor 0.950620 m
2018-08-23T16:46:09.887Z,1535042769.887 [Reporter](INFO): height_above_sea_floor 0.953312 m
2018-08-23T16:46:10.277Z,1535042770.277 [Reporter](INFO): height_above_sea_floor 0.946135 m
2018-08-23T16:46:10.691Z,1535042770.691 [Reporter](INFO): height_above_sea_floor 0.946135 m
2018-08-23T16:46:11.190Z,1535042771.190 [Reporter](INFO): height_above_sea_floor 0.949275 m
2018-08-23T16:46:11.228Z,1535042771.228 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-23T16:46:11.308Z,1535042771.308 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-08-23T16:46:11.309Z,1535042771.309 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-23T16:46:11.310Z,1535042771.310 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-23T16:46:11.311Z,1535042771.311 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-23T16:46:11.623Z,1535042771.623 [Reporter](INFO): height_above_sea_floor 0.938958 m
2018-08-23T16:46:14.211Z,1535042774.211 [NAL9602](INFO): GPS fix at 20180823T164600: (41.827331, -83.413067)
2018-08-23T16:46:14.284Z,1535042774.284 [Default:CheckIn:Read_GPS] Stopped
2018-08-23T16:46:14.285Z,1535042774.285 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-23T16:46:20.190Z,1535042780.190 [Reporter](INFO): height_above_sea_floor 2.379000 m
2018-08-23T16:46:20.259Z,1535042780.259 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180823T162341/Courier0013.lzma
2018-08-23T16:46:20.999Z,1535042780.999 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0013.lzma.bak
2018-08-23T16:46:20.000Z,1535042781.000 [DataOverHttps](INFO): SBD MOMSN=8438366
2018-08-23T16:46:25.767Z,1535042785.767 [CommandLine](IMPORTANT): got command report clear
2018-08-23T16:46:33.270Z,1535042793.270 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20180823T162341/Express0014.lzma
2018-08-23T16:46:34.063Z,1535042794.063 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0014.lzma.bak
2018-08-23T16:46:34.064Z,1535042794.064 [DataOverHttps](INFO): SBD MOMSN=8438369
2018-08-23T16:46:36.109Z,1535042796.109 [Default:CheckIn:Read_Iridium] Stopped
2018-08-23T16:46:36.109Z,1535042796.109 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-23T16:46:36.109Z,1535042796.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-23T16:46:45.814Z,1535042805.814 [NAL9602](INFO): Powering down
2018-08-23T16:49:35.303Z,1535042975.303 [BPC1](ERROR): Bank A: No match for stick 0 serial number:FFFF in the onboard configuration file.
2018-08-23T16:49:35.303Z,1535042975.303 [BPC1](ERROR): Failed to parse Bank A battery data
2018-08-23T16:51:36.637Z,1535043096.637 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-23T16:51:36.638Z,1535043096.638 [Default:CheckIn:C.Wait] Stopped
2018-08-23T16:51:36.638Z,1535043096.638 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T16:51:36.638Z,1535043096.638 [Default:CheckIn:D] Running Loop=1
2018-08-23T16:51:37.007Z,1535043097.007 [Default:CheckIn:D] Stopped
2018-08-23T16:51:37.007Z,1535043097.007 [Default:CheckIn:E] Running Loop=1
2018-08-23T16:51:41.062Z,1535043101.062 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.448846 min 
2018-08-23T16:51:41.062Z,1535043101.062 [Default:CheckIn:E] Stopped
2018-08-23T16:51:41.063Z,1535043101.063 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-23T16:51:41.063Z,1535043101.063 [Default:CheckIn] Stopped
2018-08-23T16:51:41.063Z,1535043101.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-23T16:51:41.063Z,1535043101.063 [Default:CheckIn](INFO): Running loop #5
2018-08-23T16:51:41.063Z,1535043101.063 [Default:CheckIn] Running Loop=5
2018-08-23T16:51:41.063Z,1535043101.063 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-23T16:51:41.063Z,1535043101.063 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-23T16:51:41.514Z,1535043101.514 [NAL9602](INFO): Powering up
2018-08-23T16:51:52.716Z,1535043112.716 [NAL9602](INFO): NAL9602 initialized
2018-08-23T16:52:16.715Z,1535043136.715 [NAL9602](INFO): SBD MO Status=0, MOMSN=23447, MT Status=0, MTMSN=0
2018-08-23T16:52:16.715Z,1535043136.715 [NAL9602](INFO): No messages in MT queue
2018-08-23T16:52:17.918Z,1535043137.918 [NAL9602](INFO): GPS fix at 20180823T165203: (41.827356, -83.413086)
2018-08-23T16:52:17.988Z,1535043137.988 [Default:CheckIn:Read_GPS] Stopped
2018-08-23T16:52:17.988Z,1535043137.988 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-23T16:52:24.330Z,1535043144.330 [DataOverHttps](INFO): Sending 65 bytes from file Logs/20180823T162341/Courier0016.lzma
2018-08-23T16:52:25.128Z,1535043145.128 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0016.lzma.bak
2018-08-23T16:52:25.128Z,1535043145.128 [DataOverHttps](INFO): SBD MOMSN=8438387
2018-08-23T16:52:37.554Z,1535043157.554 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20180823T162341/Express0017.lzma
2018-08-23T16:52:38.351Z,1535043158.351 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0017.lzma.bak
2018-08-23T16:52:38.351Z,1535043158.351 [DataOverHttps](INFO): SBD MOMSN=8438390
2018-08-23T16:52:40.435Z,1535043160.435 [Default:CheckIn:Read_Iridium] Stopped
2018-08-23T16:52:40.435Z,1535043160.435 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-23T16:52:40.435Z,1535043160.435 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-23T16:52:49.752Z,1535043169.752 [NAL9602](INFO): Powering down
2018-08-23T16:54:47.605Z,1535043287.605 [BPC1](ERROR): Failed to receive data from both battery packs
2018-08-23T16:54:47.605Z,1535043287.605 [BPC1] Communications Fault, FailCount= 2
2018-08-23T16:54:47.606Z,1535043287.606 [BPC1](ERROR): Communications Fault
2018-08-23T16:54:47.631Z,1535043287.631 [CBIT](ERROR): Communications Fault in component: BPC1
2018-08-23T16:54:48.840Z,1535043288.840 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-23T16:54:48.840Z,1535043288.840 [BPC1] No Fault, FailCount= 2
2018-08-23T16:57:35.143Z,1535043455.143 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.426261 seconds old.
2018-08-23T16:57:35.523Z,1535043455.523 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.806219 seconds old.
2018-08-23T16:57:36.096Z,1535043456.096 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.379734 seconds old.
2018-08-23T16:57:40.763Z,1535043460.763 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-23T16:57:40.763Z,1535043460.763 [Default:CheckIn:C.Wait] Stopped
2018-08-23T16:57:40.763Z,1535043460.763 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T16:57:40.763Z,1535043460.763 [Default:CheckIn:D] Running Loop=1
2018-08-23T16:57:41.128Z,1535043461.128 [Default:CheckIn:D] Stopped
2018-08-23T16:57:41.128Z,1535043461.128 [Default:CheckIn:E] Running Loop=1
2018-08-23T16:57:41.532Z,1535043461.532 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.517525 min 
2018-08-23T16:57:41.532Z,1535043461.532 [Default:CheckIn:E] Stopped
2018-08-23T16:57:41.532Z,1535043461.532 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-23T16:57:41.533Z,1535043461.533 [Default:CheckIn] Stopped
2018-08-23T16:57:41.533Z,1535043461.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-23T16:57:41.533Z,1535043461.533 [Default:CheckIn](INFO): Running loop #6
2018-08-23T16:57:41.533Z,1535043461.533 [Default:CheckIn] Running Loop=6
2018-08-23T16:57:41.533Z,1535043461.533 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-23T16:57:41.533Z,1535043461.533 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-23T16:57:45.581Z,1535043465.581 [NAL9602](INFO): Powering up
2018-08-23T16:57:56.386Z,1535043476.386 [NAL9602](INFO): NAL9602 initialized
2018-08-23T16:58:07.988Z,1535043487.988 [NAL9602](INFO): SBD MO Status=0, MOMSN=23448, MT Status=0, MTMSN=0
2018-08-23T16:58:07.988Z,1535043487.988 [NAL9602](INFO): No messages in MT queue
2018-08-23T16:58:15.190Z,1535043495.190 [NAL9602](INFO): GPS fix at 20180823T165800: (41.827342, -83.413103)
2018-08-23T16:58:15.290Z,1535043495.290 [Default:CheckIn:Read_GPS] Stopped
2018-08-23T16:58:15.290Z,1535043495.290 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-23T16:58:21.248Z,1535043501.248 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180823T162341/Courier0019.lzma
2018-08-23T16:58:22.039Z,1535043502.039 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0019.lzma.bak
2018-08-23T16:58:22.039Z,1535043502.039 [DataOverHttps](INFO): SBD MOMSN=8438395
2018-08-23T16:58:34.446Z,1535043514.446 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20180823T162341/Express0020.lzma
2018-08-23T16:58:35.246Z,1535043515.246 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0020.lzma.bak
2018-08-23T16:58:35.246Z,1535043515.246 [DataOverHttps](INFO): SBD MOMSN=8438398
2018-08-23T16:58:37.240Z,1535043517.240 [Default:CheckIn:Read_Iridium] Stopped
2018-08-23T16:58:37.240Z,1535043517.240 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-23T16:58:37.240Z,1535043517.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-23T16:58:51.002Z,1535043531.002 [NAL9602](INFO): Powering down
2018-08-23T16:59:16.214Z,1535043556.214 [CommandLine](IMPORTANT): got command show variable massDefault
2018-08-23T16:59:16.268Z,1535043556.268 [CommandLine](IMPORTANT): VerticalControl.massDefault (centimeter)
2018-08-23T16:59:26.551Z,1535043566.551 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault
2018-08-23T16:59:26.552Z,1535043566.552 [CommandLine](IMPORTANT): VerticalControl.massDefault 0.000000 cm
2018-08-23T17:00:43.259Z,1535043643.259 [DataOverHttps](IMPORTANT): SBD MTMSN=20180823T170029
2018-08-23T17:00:48.540Z,1535043648.540 [DataOverHttps](INFO): Received command:configSet VerticalControl.massDefault -1.0 cm persist
2018-08-23T17:00:48.838Z,1535043648.838 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault -1.000000 centimeter persist
2018-08-23T17:03:37.578Z,1535043817.578 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-23T17:03:37.579Z,1535043817.579 [Default:CheckIn:C.Wait] Stopped
2018-08-23T17:03:37.579Z,1535043817.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T17:03:37.579Z,1535043817.579 [Default:CheckIn:D] Running Loop=1
2018-08-23T17:03:37.998Z,1535043817.998 [Default:CheckIn:D] Stopped
2018-08-23T17:03:37.998Z,1535043817.998 [Default:CheckIn:E] Running Loop=1
2018-08-23T17:03:38.415Z,1535043818.415 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.465361 min 
2018-08-23T17:03:38.416Z,1535043818.416 [Default:CheckIn:E] Stopped
2018-08-23T17:03:38.416Z,1535043818.416 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-23T17:03:38.416Z,1535043818.416 [Default:CheckIn] Stopped
2018-08-23T17:03:38.416Z,1535043818.416 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-23T17:03:38.416Z,1535043818.416 [Default:CheckIn](INFO): Running loop #7
2018-08-23T17:03:38.416Z,1535043818.416 [Default:CheckIn] Running Loop=7
2018-08-23T17:03:38.425Z,1535043818.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-23T17:03:38.425Z,1535043818.425 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-23T17:03:39.107Z,1535043819.107 [NAL9602](INFO): Powering up
2018-08-23T17:03:56.715Z,1535043836.715 [BPC1](ERROR): Failed to parse message.
2018-08-23T17:03:56.715Z,1535043836.715 [BPC1](ERROR): Failed to parse Bank A battery data
2018-08-23T17:03:57.104Z,1535043837.104 [NAL9602](INFO): NAL9602 initialized
2018-08-23T17:04:14.280Z,1535043854.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=23449, MT Status=0, MTMSN=0
2018-08-23T17:04:14.280Z,1535043854.280 [NAL9602](INFO): No messages in MT queue
2018-08-23T17:04:15.474Z,1535043855.474 [NAL9602](INFO): GPS fix at 20180823T170400: (41.827336, -83.413063)
2018-08-23T17:04:15.580Z,1535043855.580 [Default:CheckIn:Read_GPS] Stopped
2018-08-23T17:04:15.580Z,1535043855.580 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-23T17:04:28.241Z,1535043868.241 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180823T162341/Courier0022.lzma
2018-08-23T17:04:28.990Z,1535043868.990 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0022.lzma.bak
2018-08-23T17:04:28.990Z,1535043868.990 [DataOverHttps](INFO): SBD MOMSN=8438404
2018-08-23T17:04:51.515Z,1535043891.515 [NAL9602](INFO): Powering down
2018-08-23T17:04:51.712Z,1535043891.712 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180823T162341/Courier0025.lzma
2018-08-23T17:04:52.483Z,1535043892.483 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0025.lzma.bak
2018-08-23T17:04:52.483Z,1535043892.483 [DataOverHttps](INFO): SBD MOMSN=8438408
2018-08-23T17:05:15.663Z,1535043915.663 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20180823T162341/Express0023.lzma
2018-08-23T17:05:16.460Z,1535043916.460 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0023.lzma.bak
2018-08-23T17:05:16.460Z,1535043916.460 [DataOverHttps](INFO): SBD MOMSN=8438410
2018-08-23T17:05:30.315Z,1535043930.315 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20180823T162341/Express0026.lzma
2018-08-23T17:05:31.112Z,1535043931.112 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0026.lzma.bak
2018-08-23T17:05:31.112Z,1535043931.112 [DataOverHttps](INFO): SBD MOMSN=8438419
2018-08-23T17:05:33.126Z,1535043933.126 [Default:CheckIn:Read_Iridium] Stopped
2018-08-23T17:05:33.126Z,1535043933.126 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-23T17:05:33.126Z,1535043933.126 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-23T17:06:38.772Z,1535043998.772 [DataOverHttps](IMPORTANT): SBD MTMSN=20180823T170624
2018-08-23T17:06:44.067Z,1535044004.067 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 41.85 degree;set profile_station.Lon -83.1935 degree" 3fjhp 1 3
2018-08-23T17:06:44.134Z,1535044004.134 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 41.85 degree;set profile_station.Lon -83.1935 degree" 3fjhp 1 3.000000
2018-08-23T17:06:44.135Z,1535044004.135 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=3fjhp
2018-08-23T17:06:44.136Z,1535044004.136 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 3 with id='3fjhp'): "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 41.85 degree;set profile_station.Lon -83.1935 degree" ASAP
2018-08-23T17:06:44.860Z,1535044004.860 [DataOverHttps](IMPORTANT): SBD MTMSN=20180823T170630
2018-08-23T17:06:50.194Z,1535044010.194 [DataOverHttps](INFO): Received command:sched asap "set profile_station.Radius 200 meter;set profile_station.YoYoMinDepth 2.5 meter;set profile_station.YoYoMaxDepth 2.5 meter;set profile_station.YoYoMinAltitude 1 meter;set profile_station.MinAltitude .7 meter" 3fjhp 2 3
2018-08-23T17:06:50.246Z,1535044010.246 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.Radius 200 meter;set profile_station.YoYoMinDepth 2.5 meter;set profile_station.YoYoMaxDepth 2.5 meter;set profile_station.YoYoMinAltitude 1 meter;set profile_station.MinAltitude .7 meter" 3fjhp 2 3.000000
2018-08-23T17:06:50.247Z,1535044010.247 [CommandLine](IMPORTANT): Scheduling command #2 of 3 with id=3fjhp
2018-08-23T17:06:50.248Z,1535044010.248 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 3 with id='3fjhp'): "set profile_station.Radius 200 meter;set profile_station.YoYoMinDepth 2.5 meter;set profile_station.YoYoMaxDepth 2.5 meter;set profile_station.YoYoMinAltitude 1 meter;set profile_station.MinAltitude .7 meter" ASAP
2018-08-23T17:06:50.991Z,1535044010.991 [DataOverHttps](IMPORTANT): SBD MTMSN=20180823T170636
2018-08-23T17:06:55.707Z,1535044015.707 [DataOverHttps](INFO): Received command:sched asap "set profile_station.MaxDepth 4 meter;run " 3fjhp 3 3
2018-08-23T17:06:55.746Z,1535044015.746 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.MaxDepth 4 meter;run " 3fjhp 3 3.000000
2018-08-23T17:06:55.746Z,1535044015.746 [CommandLine](IMPORTANT): Scheduling command #3 of 3 with id=3fjhp
2018-08-23T17:06:55.747Z,1535044015.747 [CommandLine](IMPORTANT): Scheduled #3 (#3 of 3 with id='3fjhp'): "set profile_station.MaxDepth 4 meter;run " ASAP
2018-08-23T17:06:55.877Z,1535044015.877 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2018-08-23T17:06:55.885Z,1535044015.885 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2018-08-23T17:06:56.370Z,1535044016.370 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2018-08-23T17:06:56.389Z,1535044016.389 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2018-08-23T17:06:56.392Z,1535044016.392 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2018-08-23T17:06:56.395Z,1535044016.395 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2018-08-23T17:06:56.407Z,1535044016.407 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2018-08-23T17:06:56.410Z,1535044016.410 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2018-08-23T17:06:56.435Z,1535044016.435 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2018-08-23T17:06:56.438Z,1535044016.438 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2018-08-23T17:06:56.465Z,1535044016.465 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2018-08-23T17:06:56.468Z,1535044016.468 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2018-08-23T17:06:56.480Z,1535044016.480 [DataOverHttps](IMPORTANT): SBD MTMSN=20180823T170641
2018-08-23T17:06:56.520Z,1535044016.520 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2018-08-23T17:06:56.533Z,1535044016.533 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2018-08-23T17:06:56.536Z,1535044016.536 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2018-08-23T17:06:56.583Z,1535044016.583 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2018-08-23T17:06:56.607Z,1535044016.607 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2018-08-23T17:06:56.639Z,1535044016.639 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2018-08-23T17:06:56.639Z,1535044016.639 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-08-23T17:06:57.471Z,1535044017.471 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2018-08-23T17:06:57.478Z,1535044017.478 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-08-23T17:06:57.518Z,1535044017.518 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-08-23T17:06:57.520Z,1535044017.520 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2018-08-23T17:06:57.527Z,1535044017.527 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2018-08-23T17:06:57.550Z,1535044017.550 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2018-08-23T17:06:57.569Z,1535044017.569 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2018-08-23T17:06:57.585Z,1535044017.585 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2018-08-23T17:06:57.588Z,1535044017.588 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2018-08-23T17:06:57.600Z,1535044017.600 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2018-08-23T17:06:57.619Z,1535044017.619 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-08-23T17:06:57.623Z,1535044017.623 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 1.000000 bool
2018-08-23T17:06:57.647Z,1535044017.647 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2018-08-23T17:06:57.658Z,1535044017.658 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2018-08-23T17:06:57.663Z,1535044017.663 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-08-23T17:06:57.710Z,1535044017.710 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2018-08-23T17:06:57.718Z,1535044017.718 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2018-08-23T17:06:57.734Z,1535044017.734 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2018-08-23T17:06:57.778Z,1535044017.778 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2018-08-23T17:06:57.781Z,1535044017.781 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2018-08-23T17:06:57.784Z,1535044017.784 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2018-08-23T17:06:57.811Z,1535044017.811 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2018-08-23T17:06:57.831Z,1535044017.831 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2018-08-23T17:06:57.834Z,1535044017.834 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2018-08-23T17:06:57.878Z,1535044017.878 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2018-08-23T17:06:57.881Z,1535044017.881 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2018-08-23T17:06:57.884Z,1535044017.884 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2018-08-23T17:06:57.928Z,1535044017.928 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2018-08-23T17:06:57.957Z,1535044017.957 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2018-08-23T17:06:57.960Z,1535044017.960 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2018-08-23T17:06:57.963Z,1535044017.963 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2018-08-23T17:06:57.983Z,1535044017.983 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2018-08-23T17:06:57.986Z,1535044017.986 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2018-08-23T17:06:58.026Z,1535044018.026 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2018-08-23T17:06:58.067Z,1535044018.067 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2018-08-23T17:06:58.098Z,1535044018.098 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2018-08-23T17:06:58.122Z,1535044018.122 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2018-08-23T17:06:58.148Z,1535044018.148 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2018-08-23T17:06:58.173Z,1535044018.173 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2018-08-23T17:06:58.281Z,1535044018.281 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-08-23T17:06:58.321Z,1535044018.321 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-08-23T17:06:58.419Z,1535044018.419 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-08-23T17:06:58.469Z,1535044018.469 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-08-23T17:06:58.511Z,1535044018.511 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2018-08-23T17:06:58.525Z,1535044018.525 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2018-08-23T17:06:58.525Z,1535044018.525 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-08-23T17:06:58.697Z,1535044018.697 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2018-08-23T17:06:58.700Z,1535044018.700 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2018-08-23T17:06:58.703Z,1535044018.703 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-08-23T17:06:58.724Z,1535044018.724 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2018-08-23T17:06:58.728Z,1535044018.728 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-08-23T17:06:58.795Z,1535044018.795 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2018-08-23T17:06:58.798Z,1535044018.798 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2018-08-23T17:06:58.833Z,1535044018.833 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-23T17:06:58.910Z,1535044018.910 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-08-23T17:06:58.997Z,1535044018.997 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2018-08-23T17:06:59.000Z,1535044019.000 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2018-08-23T17:06:59.003Z,1535044019.003 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-08-23T17:06:59.050Z,1535044019.050 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-08-23T17:06:59.121Z,1535044019.121 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-08-23T17:06:59.209Z,1535044019.209 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-08-23T17:06:59.273Z,1535044019.273 [profile_station:I.Pitch](DEBUG): Construct.
2018-08-23T17:06:59.311Z,1535044019.311 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2018-08-23T17:06:59.315Z,1535044019.315 [profile_station:K.SetSpeed](DEBUG): Construct.
2018-08-23T17:06:59.344Z,1535044019.344 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-08-23T17:06:59.382Z,1535044019.382 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-08-23T17:06:59.426Z,1535044019.426 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2018-08-23T17:06:59.485Z,1535044019.485 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2018-08-23T17:06:59.679Z,1535044019.679 [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-08-23T17:06:59.680Z,1535044019.680 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2018-08-23T17:07:09.270Z,1535044029.270 [DataOverHttps](INFO): Received command:stop
2018-08-23T17:07:13.355Z,1535044033.355 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 2.000000 hour
2018-08-23T17:07:13.355Z,1535044033.355 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 20.000000 minute
2018-08-23T17:07:13.356Z,1535044033.356 [CommandLine](IMPORTANT): got command set profile_station.Lat 41.849998 degree
2018-08-23T17:07:13.357Z,1535044033.357 [CommandLine](IMPORTANT): got command set profile_station.Lon -83.193497 degree
2018-08-23T17:07:13.365Z,1535044033.365 [CommandLine](IMPORTANT): got command stop
2018-08-23T17:07:13.369Z,1535044033.369 [CommandLine](IMPORTANT): Scheduling is paused for 2 commands
2018-08-23T17:07:13.371Z,1535044033.371 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2018-08-23T17:07:28.866Z,1535044048.866 [CommandLine](IMPORTANT): got command set profile_station.Radius 200.000000 meter
2018-08-23T17:07:28.867Z,1535044048.867 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinDepth 2.500000 meter
2018-08-23T17:07:28.868Z,1535044048.868 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 2.500000 meter
2018-08-23T17:07:28.873Z,1535044048.873 [CommandLine](IMPORTANT): got command set profile_station.YoYoMinAltitude 1.000000 meter
2018-08-23T17:07:28.874Z,1535044048.874 [CommandLine](IMPORTANT): got command set profile_station.MinAltitude 0.700000 meter
2018-08-23T17:07:33.369Z,1535044053.369 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 4.000000 meter
2018-08-23T17:07:33.369Z,1535044053.369 [CommandLine](IMPORTANT): got command run 
2018-08-23T17:07:33.372Z,1535044053.372 [CommandLine](IMPORTANT): Running 
2018-08-23T17:07:33.606Z,1535044053.606 [Default] Stopped
2018-08-23T17:07:33.607Z,1535044053.607 [Default](DEBUG): Aggregate::uninitialize Default
2018-08-23T17:07:33.607Z,1535044053.607 [Default:B.GoToSurface] Stopped
2018-08-23T17:07:33.607Z,1535044053.607 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-23T17:07:33.607Z,1535044053.607 [Default:CheckIn] Stopped
2018-08-23T17:07:33.607Z,1535044053.607 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-23T17:07:33.607Z,1535044053.607 [Default:CheckIn:C.Wait] Stopped
2018-08-23T17:07:33.607Z,1535044053.607 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T17:07:33.607Z,1535044053.607 [MissionManager](IMPORTANT): Started mission profile_station
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station] Running Loop=1
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station](DEBUG): Aggregate::initialize profile_station
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station:Science] Running Loop=1
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station:Science:A] Running Loop=1
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station:Science:B] Running Loop=1
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station:Science:C] Running Loop=1
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station:Science:D] Running Loop=1
2018-08-23T17:07:33.608Z,1535044053.608 [profile_station:Science:E] Running Loop=1
2018-08-23T17:07:33.609Z,1535044053.609 [profile_station:Science:F] Running Loop=1
2018-08-23T17:07:33.609Z,1535044053.609 [profile_station:Science:G] Running Loop=1
2018-08-23T17:07:33.609Z,1535044053.609 [profile_station:Science:Read_Oil] Running Loop=1
2018-08-23T17:07:33.609Z,1535044053.609 [profile_station:Science:PeakDetectChl] Running Loop=1
2018-08-23T17:07:33.609Z,1535044053.609 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2018-08-23T17:07:33.609Z,1535044053.609 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-08-23T17:07:33.609Z,1535044053.609 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-08-23T17:07:33.612Z,1535044053.612 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2018-08-23T17:07:33.612Z,1535044053.612 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2018-08-23T17:07:33.612Z,1535044053.612 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-08-23T17:07:33.612Z,1535044053.612 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-08-23T17:07:33.626Z,1535044053.626 [profile_station:Science:PeakDetectNO3] Running Loop=1
2018-08-23T17:07:33.626Z,1535044053.626 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2018-08-23T17:07:33.626Z,1535044053.626 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-08-23T17:07:33.626Z,1535044053.626 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-08-23T17:07:33.628Z,1535044053.628 [profile_station:Science:PeakDetectOil] Running Loop=1
2018-08-23T17:07:33.628Z,1535044053.628 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2018-08-23T17:07:33.629Z,1535044053.629 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-08-23T17:07:33.629Z,1535044053.629 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-08-23T17:07:33.631Z,1535044053.631 [profile_station:StandardEnvelopes] Running Loop=1
2018-08-23T17:07:33.631Z,1535044053.631 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2018-08-23T17:07:33.631Z,1535044053.631 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-08-23T17:07:33.631Z,1535044053.631 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-08-23T17:07:33.631Z,1535044053.631 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-08-23T17:07:33.632Z,1535044053.632 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-08-23T17:07:33.632Z,1535044053.632 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-08-23T17:07:33.632Z,1535044053.632 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-08-23T17:07:33.632Z,1535044053.632 [profile_station:I.Pitch] Running Loop=1
2018-08-23T17:07:33.632Z,1535044053.632 [profile_station:I.Pitch](DEBUG): Initialize.
2018-08-23T17:07:33.632Z,1535044053.632 [profile_station:J.Buoyancy] Running Loop=1
2018-08-23T17:07:33.632Z,1535044053.632 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-08-23T17:07:33.633Z,1535044053.633 [profile_station:K.SetSpeed] Running Loop=1
2018-08-23T17:07:33.645Z,1535044053.645 [profile_station:K.SetSpeed](DEBUG): Initialize.
2018-08-23T17:07:33.645Z,1535044053.645 [profile_station:L.DepthEnvelope] Running Loop=1
2018-08-23T17:07:33.645Z,1535044053.645 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-08-23T17:07:33.645Z,1535044053.645 [profile_station:M.AltitudeEnvelope] Running Loop=1
2018-08-23T17:07:33.646Z,1535044053.646 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-08-23T17:07:33.646Z,1535044053.646 [profile_station:N.YoYo] Running Loop=1
2018-08-23T17:07:33.646Z,1535044053.646 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2018-08-23T17:07:33.646Z,1535044053.646 [profile_station:C] Running Loop=1
2018-08-23T17:07:33.647Z,1535044053.647 [profile_station:N.YoYo] Running Loop=1
2018-08-23T17:07:33.657Z,1535044053.657 [profile_station:M.AltitudeEnvelope] Running Loop=1
2018-08-23T17:07:33.664Z,1535044053.664 [profile_station:L.DepthEnvelope] Running Loop=1
2018-08-23T17:07:33.682Z,1535044053.682 [profile_station:K.SetSpeed] Running Loop=1
2018-08-23T17:07:33.687Z,1535044053.687 [profile_station:J.Buoyancy] Running Loop=1
2018-08-23T17:07:33.696Z,1535044053.696 [profile_station:I.Pitch] Running Loop=1
2018-08-23T17:07:33.718Z,1535044053.718 [profile_station:StandardEnvelopes] Running Loop=1
2018-08-23T17:07:33.718Z,1535044053.718 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-08-23T17:07:33.743Z,1535044053.743 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-08-23T17:07:33.752Z,1535044053.752 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-08-23T17:07:33.768Z,1535044053.768 [profile_station:C] Stopped
2018-08-23T17:07:33.768Z,1535044053.768 [profile_station:E] Running Loop=1
2018-08-23T17:07:33.768Z,1535044053.768 [profile_station:Science] Running Loop=1
2018-08-23T17:07:33.781Z,1535044053.781 [profile_station:Science:PeakDetectOil] Stopped
2018-08-23T17:07:33.781Z,1535044053.781 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2018-08-23T17:07:33.781Z,1535044053.781 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-08-23T17:07:33.781Z,1535044053.781 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-08-23T17:07:33.781Z,1535044053.781 [profile_station:Science:PeakDetectNO3] Stopped
2018-08-23T17:07:33.781Z,1535044053.781 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2018-08-23T17:07:33.782Z,1535044053.782 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-08-23T17:07:33.782Z,1535044053.782 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-08-23T17:07:33.782Z,1535044053.782 [profile_station:Science:HighestChlPeakReport] Stopped
2018-08-23T17:07:33.782Z,1535044053.782 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2018-08-23T17:07:33.782Z,1535044053.782 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-08-23T17:07:33.782Z,1535044053.782 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-08-23T17:07:33.782Z,1535044053.782 [profile_station:Science:PeakDetectChl] Running Loop=1
2018-08-23T17:07:33.791Z,1535044053.791 [profile_station:Science:Read_Oil] Stopped
2018-08-23T17:07:33.791Z,1535044053.791 [profile_station:Science:G] Running Loop=1
2018-08-23T17:07:33.792Z,1535044053.792 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-08-23T17:07:33.795Z,1535044053.795 [profile_station:Science:F] Stopped
2018-08-23T17:07:33.795Z,1535044053.795 [profile_station:Science:E] Stopped
2018-08-23T17:07:33.795Z,1535044053.795 [profile_station:Science:D] Running Loop=1
2018-08-23T17:07:33.796Z,1535044053.796 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2018-08-23T17:07:33.813Z,1535044053.813 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2018-08-23T17:07:33.815Z,1535044053.815 [profile_station:Science:C] Running Loop=1
2018-08-23T17:07:33.815Z,1535044053.815 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2018-08-23T17:07:33.816Z,1535044053.816 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2018-08-23T17:07:33.822Z,1535044053.822 [profile_station:Science:B] Stopped
2018-08-23T17:07:33.822Z,1535044053.822 [profile_station:Science:A] Running Loop=1
2018-08-23T17:07:33.823Z,1535044053.823 [profile_station:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-08-23T17:07:33.824Z,1535044053.824 [MissionManager](INFO): MissionManager is completed.
2018-08-23T17:07:33.824Z,1535044053.824 [MissionManager](INFO): Uninitializing Mission profile_station
2018-08-23T17:07:33.837Z,1535044053.837 [profile_station] Stopped
2018-08-23T17:07:33.837Z,1535044053.837 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2018-08-23T17:07:33.837Z,1535044053.837 [profile_station:Science] Stopped
2018-08-23T17:07:33.837Z,1535044053.837 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2018-08-23T17:07:33.837Z,1535044053.837 [profile_station:Science:A] Stopped
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:Science:C] Stopped
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:Science:D] Stopped
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:Science:G] Stopped
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:Science:PeakDetectChl] Stopped
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:StandardEnvelopes] Stopped
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-08-23T17:07:33.838Z,1535044053.838 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:E] Stopped
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:I.Pitch] Stopped
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:J.Buoyancy] Stopped
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:K.SetSpeed] Stopped
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:L.DepthEnvelope] Stopped
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2018-08-23T17:07:33.839Z,1535044053.839 [profile_station:M.AltitudeEnvelope] Stopped
2018-08-23T17:07:33.840Z,1535044053.840 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-08-23T17:07:33.840Z,1535044053.840 [profile_station:N.YoYo] Stopped
2018-08-23T17:07:33.840Z,1535044053.840 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-08-23T17:07:34.006Z,1535044054.006 [MissionManager](IMPORTANT): Started mission Default
2018-08-23T17:07:34.006Z,1535044054.006 [Default] Running Loop=1
2018-08-23T17:07:34.006Z,1535044054.006 [Default](DEBUG): Aggregate::initialize Default
2018-08-23T17:07:34.007Z,1535044054.007 [Default:B.GoToSurface] Running Loop=1
2018-08-23T17:07:34.007Z,1535044054.007 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-23T17:07:34.007Z,1535044054.007 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-23T17:07:34.008Z,1535044054.008 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-23T17:07:34.009Z,1535044054.009 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-23T17:07:34.015Z,1535044054.015 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-23T17:07:34.015Z,1535044054.015 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-23T17:07:34.016Z,1535044054.016 [Default:A.Wait] Running Loop=1
2018-08-23T17:07:34.016Z,1535044054.016 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-08-23T17:07:34.729Z,1535044054.729 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-08-23T17:07:34.729Z,1535044054.729 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-08-23T17:07:34.729Z,1535044054.729 [ThrusterServo] Communications Fault, FailCount= 1
2018-08-23T17:07:34.729Z,1535044054.729 [ThrusterServo](ERROR): Communications Fault
2018-08-23T17:07:34.733Z,1535044054.733 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-08-23T17:07:34.939Z,1535044054.939 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-08-23T17:07:34.939Z,1535044054.939 [ThrusterServo](INFO): Powering down
2018-08-23T17:07:35.717Z,1535044055.717 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-08-23T17:07:35.717Z,1535044055.717 [ThrusterServo] No Fault, FailCount= 1
2018-08-23T17:07:36.003Z,1535044056.003 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-08-23T17:07:36.004Z,1535044056.004 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-08-23T17:07:47.240Z,1535044067.240 [Default:A.Wait](INFO): Done Waiting.
2018-08-23T17:07:47.240Z,1535044067.240 [Default:A.Wait] Stopped
2018-08-23T17:07:47.240Z,1535044067.240 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-08-23T17:07:47.616Z,1535044067.616 [Default:CheckIn] Running Loop=1
2018-08-23T17:07:47.616Z,1535044067.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-23T17:07:47.617Z,1535044067.617 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-23T17:07:48.350Z,1535044068.350 [NAL9602](INFO): Powering up
2018-08-23T17:07:57.058Z,1535044077.058 [CommandLine](IMPORTANT): got command get depth
2018-08-23T17:07:57.059Z,1535044077.059 [CommandLine](IMPORTANT): depth 0.013608 m
2018-08-23T17:08:01.814Z,1535044081.814 [NAL9602](INFO): NAL9602 initialized
2018-08-23T17:08:18.214Z,1535044098.214 [NAL9602](INFO): SBD MO Status=0, MOMSN=23450, MT Status=0, MTMSN=0
2018-08-23T17:08:18.214Z,1535044098.214 [NAL9602](INFO): No messages in MT queue
2018-08-23T17:08:19.410Z,1535044099.410 [NAL9602](INFO): GPS fix at 20180823T170803: (41.827315, -83.413072)
2018-08-23T17:08:19.476Z,1535044099.476 [Default:CheckIn:Read_GPS] Stopped
2018-08-23T17:08:19.476Z,1535044099.476 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-23T17:08:28.372Z,1535044108.372 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20180823T162341/Courier0028.lzma
2018-08-23T17:08:29.172Z,1535044109.172 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0028.lzma.bak
2018-08-23T17:08:29.173Z,1535044109.173 [DataOverHttps](INFO): SBD MOMSN=8438430
2018-08-23T17:08:40.873Z,1535044120.873 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180823T162341/Courier0031.lzma
2018-08-23T17:08:41.671Z,1535044121.671 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Courier0031.lzma.bak
2018-08-23T17:08:41.672Z,1535044121.672 [DataOverHttps](INFO): SBD MOMSN=8438433
2018-08-23T17:08:53.291Z,1535044133.291 [NAL9602](INFO): Powering down
2018-08-23T17:08:53.836Z,1535044133.836 [DataOverHttps](INFO): Sending 778 bytes from file Logs/20180823T162341/Express0029.lzma
2018-08-23T17:08:56.493Z,1535044136.493 [DataOverHttps](INFO): Moved sent file to Logs/20180823T162341/Express0029.lzma.bak
2018-08-23T17:08:56.493Z,1535044136.493 [DataOverHttps](INFO): SBD MOMSN=8438435
2018-08-23T17:09:06.925Z,1535044146.925 [CommandLine](IMPORTANT): got command restart application
2018-08-23T17:09:07.940Z,1535044147.940 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-23T17:09:07.940Z,1535044147.940 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:07.957Z,1535044147.957 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-08-23T17:09:07.957Z,1535044147.957 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:07.958Z,1535044147.958 [CommandLine](INFO): Join timeout helper Thread ID is 2135
2018-08-23T17:09:07.985Z,1535044147.985 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-08-23T17:09:07.985Z,1535044147.985 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:07.985Z,1535044147.985 [NavChartDb](INFO): Join timeout helper Thread ID is 2136
2018-08-23T17:09:08.045Z,1535044148.045 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-23T17:09:08.045Z,1535044148.045 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:08.057Z,1535044148.057 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-08-23T17:09:08.057Z,1535044148.057 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:08.057Z,1535044148.057 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2137
2018-08-23T17:09:08.073Z,1535044148.073 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-23T17:09:08.073Z,1535044148.073 [WetLabsBB2FL](INFO): Powering down
2018-08-23T17:09:08.074Z,1535044148.074 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:08.077Z,1535044148.077 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-08-23T17:09:08.077Z,1535044148.077 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:08.077Z,1535044148.077 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2138
2018-08-23T17:09:08.263Z,1535044148.263 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-23T17:09:08.515Z,1535044148.515 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-23T17:09:08.515Z,1535044148.515 [CTD_Seabird](INFO): Powering down
2018-08-23T17:09:08.516Z,1535044148.516 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:08.533Z,1535044148.533 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-08-23T17:09:08.533Z,1535044148.533 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:08.534Z,1535044148.534 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 2140
2018-08-23T17:09:08.606Z,1535044148.606 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-23T17:09:08.606Z,1535044148.606 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-23T17:09:08.754Z,1535044148.754 [Rowe_600LCM](INFO): Powering down
2018-08-23T17:09:08.756Z,1535044148.756 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:08.774Z,1535044148.774 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-08-23T17:09:08.774Z,1535044148.774 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:08.775Z,1535044148.775 [Radio_Surface](INFO): Join timeout helper Thread ID is 2142
2018-08-23T17:09:09.009Z,1535044149.009 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-23T17:09:09.010Z,1535044149.010 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:09.026Z,1535044149.026 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-08-23T17:09:09.026Z,1535044149.026 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:09.026Z,1535044149.026 [logger](INFO): Join timeout helper Thread ID is 2143
2018-08-23T17:09:09.061Z,1535044149.061 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-23T17:09:09.061Z,1535044149.061 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:09.078Z,1535044149.078 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-08-23T17:09:09.078Z,1535044149.078 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:09.078Z,1535044149.078 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-08-23T17:09:09.078Z,1535044149.078 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:09.079Z,1535044149.079 [controlThread](INFO): Join timeout helper Thread ID is 2144
2018-08-23T17:09:10.885Z,1535044150.885 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-23T17:09:10.885Z,1535044150.885 [controlThread](DEBUG): Uninitializing ControlThread
2018-08-23T17:09:10.891Z,1535044150.891 [NAL9602](INFO): Powering down
2018-08-23T17:09:10.893Z,1535044150.893 [PNI_TCM](INFO): Powering down
2018-08-23T17:09:10.982Z,1535044150.982 [Aanderaa_O2](INFO): Powering down
2018-08-23T17:09:10.985Z,1535044150.985 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-23T17:09:10.986Z,1535044150.986 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-08-23T17:09:10.987Z,1535044150.987 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-08-23T17:09:10.987Z,1535044150.987 [MissionManager](INFO): Uninitializing Mission Default
2018-08-23T17:09:10.987Z,1535044150.987 [Default] Stopped
2018-08-23T17:09:10.987Z,1535044150.987 [Default](DEBUG): Aggregate::uninitialize Default
2018-08-23T17:09:10.988Z,1535044150.988 [Default:B.GoToSurface] Stopped
2018-08-23T17:09:10.988Z,1535044150.988 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-23T17:09:10.988Z,1535044150.988 [Default:CheckIn] Stopped
2018-08-23T17:09:10.988Z,1535044150.988 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-23T17:09:10.988Z,1535044150.988 [Default:CheckIn:Read_Iridium] Stopped
2018-08-23T17:09:10.992Z,1535044150.992 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-08-23T17:09:10.993Z,1535044150.993 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-08-23T17:09:10.993Z,1535044150.993 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-08-23T17:09:10.994Z,1535044150.994 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-08-23T17:09:10.994Z,1535044150.994 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-23T17:09:10.994Z,1535044150.994 [BuoyancyServo](INFO): Powering down
2018-08-23T17:09:11.009Z,1535044151.009 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-08-23T17:09:11.009Z,1535044151.009 [ElevatorServo](INFO): Powering down
2018-08-23T17:09:11.010Z,1535044151.010 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-08-23T17:09:11.010Z,1535044151.010 [MassServo](INFO): Powering down
2018-08-23T17:09:11.011Z,1535044151.011 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-08-23T17:09:11.011Z,1535044151.011 [RudderServo](INFO): Powering down
2018-08-23T17:09:11.012Z,1535044151.012 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-08-23T17:09:11.013Z,1535044151.013 [ThrusterServo](INFO): Powering down
2018-08-23T17:09:11.014Z,1535044151.014 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-08-23T17:09:11.014Z,1535044151.014 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-08-23T17:09:11.014Z,1535044151.014 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-08-23T17:09:11.015Z,1535044151.015 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:11.128Z,1535044151.128 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:11.134Z,1535044151.134 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:11.180Z,1535044151.180 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:11.184Z,1535044151.184 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:11.246Z,1535044151.246 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-23T17:09:11.310Z,1535044151.310 [logger ThreadHandler](INFO): Thread cancelled.