2017-10-27T06:27:52.492Z,1509085672.492 [Supervisor](DEBUG): Initializing supervisor.
2017-10-27T06:27:52.496Z,1509085672.496 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-10-27T06:27:52.496Z,1509085672.496 [SyncHandler](INFO): Protected caller Thread ID is 767
2017-10-27T06:27:52.497Z,1509085672.497 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-10-27T06:27:52.498Z,1509085672.498 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-10-27T06:27:52.499Z,1509085672.499 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 768
2017-10-27T06:27:52.501Z,1509085672.501 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-10-27T06:27:52.513Z,1509085672.513 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-10-27T06:27:52.514Z,1509085672.514 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-10-27T06:27:52.515Z,1509085672.515 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 769
2017-10-27T06:27:52.516Z,1509085672.516 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-10-27T06:27:52.518Z,1509085672.518 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-10-27T06:27:52.519Z,1509085672.519 [logger ThreadHandler](INFO): Protected caller Thread ID is 770
2017-10-27T06:27:52.521Z,1509085672.521 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-10-27T06:27:52.521Z,1509085672.521 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-10-27T06:27:52.527Z,1509085672.527 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-10-27T06:27:52.731Z,1509085672.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-10-27T06:27:52.732Z,1509085672.732 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-10-27T06:27:52.875Z,1509085672.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-10-27T06:27:52.876Z,1509085672.876 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-10-27T06:27:53.233Z,1509085673.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-10-27T06:27:53.234Z,1509085673.234 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-10-27T06:27:53.384Z,1509085673.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-10-27T06:27:53.385Z,1509085673.385 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-10-27T06:27:53.480Z,1509085673.480 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-10-27T06:27:53.697Z,1509085673.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-10-27T06:27:53.699Z,1509085673.699 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-10-27T06:27:53.808Z,1509085673.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-10-27T06:27:53.808Z,1509085673.808 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-10-27T06:27:54.122Z,1509085674.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-10-27T06:27:54.122Z,1509085674.122 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-10-27T06:27:54.506Z,1509085674.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-10-27T06:27:54.507Z,1509085674.507 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-10-27T06:27:54.831Z,1509085674.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-10-27T06:27:54.831Z,1509085674.831 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-10-27T06:27:55.380Z,1509085675.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-10-27T06:27:55.381Z,1509085675.381 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-10-27T06:27:55.729Z,1509085675.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-10-27T06:27:55.729Z,1509085675.729 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-10-27T06:28:01.020Z,1509085681.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-10-27T06:28:01.021Z,1509085681.021 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-10-27T06:28:01.598Z,1509085681.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-10-27T06:28:01.599Z,1509085681.599 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-10-27T06:28:02.202Z,1509085682.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-10-27T06:28:02.204Z,1509085682.204 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2017-10-27T06:28:02.207Z,1509085682.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2017-10-27T06:28:02.467Z,1509085682.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-10-27T06:28:02.467Z,1509085682.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2017-10-27T06:28:02.579Z,1509085682.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg
2017-10-27T06:28:02.691Z,1509085682.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2017-10-27T06:28:02.799Z,1509085682.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2017-10-27T06:28:02.899Z,1509085682.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2017-10-27T06:28:03.072Z,1509085683.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2017-10-27T06:28:03.256Z,1509085683.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2017-10-27T06:28:03.377Z,1509085683.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2017-10-27T06:28:03.484Z,1509085683.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2017-10-27T06:28:03.584Z,1509085683.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2017-10-27T06:28:03.765Z,1509085683.765 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-10-27T06:28:03.770Z,1509085683.770 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-10-27T06:28:03.942Z,1509085683.942 [SBIT](DEBUG): Construct Startup Built In Test.
2017-10-27T06:28:03.977Z,1509085683.977 [SBIT] Loaded
2017-10-27T06:28:03.978Z,1509085683.978 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-10-27T06:28:03.978Z,1509085683.978 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-10-27T06:28:04.006Z,1509085684.006 [IBIT] Loaded
2017-10-27T06:28:04.006Z,1509085684.006 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-10-27T06:28:04.014Z,1509085684.014 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-10-27T06:28:04.181Z,1509085684.181 [CBIT] Loaded
2017-10-27T06:28:04.182Z,1509085684.182 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-10-27T06:28:04.182Z,1509085684.182 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-10-27T06:28:04.183Z,1509085684.183 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-10-27T06:28:04.281Z,1509085684.281 [VerticalControl](DEBUG): Construct VerticalControl.
2017-10-27T06:28:04.393Z,1509085684.393 [VerticalControl] Loaded
2017-10-27T06:28:04.394Z,1509085684.394 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-10-27T06:28:04.394Z,1509085684.394 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-10-27T06:28:04.461Z,1509085684.461 [HorizontalControl] Loaded
2017-10-27T06:28:04.461Z,1509085684.461 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-10-27T06:28:04.462Z,1509085684.462 [SpeedControl](DEBUG): Construct SpeedControl.
2017-10-27T06:28:04.468Z,1509085684.468 [SpeedControl] Loaded
2017-10-27T06:28:04.468Z,1509085684.468 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-10-27T06:28:04.469Z,1509085684.469 [LoopControl](DEBUG): Construct LoopControl.
2017-10-27T06:28:04.469Z,1509085684.469 [LoopControl] Loaded
2017-10-27T06:28:04.469Z,1509085684.469 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-10-27T06:28:04.470Z,1509085684.470 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-10-27T06:28:04.470Z,1509085684.470 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-10-27T06:28:04.512Z,1509085684.512 [DepthRateCalculator] Loaded
2017-10-27T06:28:04.512Z,1509085684.512 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-10-27T06:28:04.517Z,1509085684.517 [PitchRateCalculator] Loaded
2017-10-27T06:28:04.518Z,1509085684.518 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-10-27T06:28:04.534Z,1509085684.534 [SpeedCalculator] Loaded
2017-10-27T06:28:04.534Z,1509085684.534 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-10-27T06:28:04.560Z,1509085684.560 [TempGradientCalculator] Loaded
2017-10-27T06:28:04.560Z,1509085684.560 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-10-27T06:28:04.580Z,1509085684.580 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-10-27T06:28:04.580Z,1509085684.580 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-10-27T06:28:04.586Z,1509085684.586 [YawRateCalculator] Loaded
2017-10-27T06:28:04.586Z,1509085684.586 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-10-27T06:28:04.586Z,1509085684.586 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-10-27T06:28:04.587Z,1509085684.587 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-10-27T06:28:04.719Z,1509085684.719 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-10-27T06:28:04.719Z,1509085684.719 [StratificationFrontDetector](DEBUG): (re)initializing
2017-10-27T06:28:04.719Z,1509085684.719 [StratificationFrontDetector] Loaded
2017-10-27T06:28:04.719Z,1509085684.719 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-10-27T06:28:04.720Z,1509085684.720 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-10-27T06:28:04.721Z,1509085684.721 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-10-27T06:28:05.027Z,1509085685.027 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-10-27T06:28:05.027Z,1509085685.027 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-10-27T06:28:05.152Z,1509085685.152 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-10-27T06:28:05.152Z,1509085685.152 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-10-27T06:28:05.216Z,1509085685.216 [DeadReckonUsingSpeedCalculator] Loaded
2017-10-27T06:28:05.217Z,1509085685.217 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-10-27T06:28:05.281Z,1509085685.281 [DeadReckonWithRespectToSeafloor] Loaded
2017-10-27T06:28:05.281Z,1509085685.281 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2017-10-27T06:28:05.298Z,1509085685.297 [NavChart] Loaded
2017-10-27T06:28:05.298Z,1509085685.298 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-10-27T06:28:05.302Z,1509085685.302 [UniversalFixResidualReporter] Loaded
2017-10-27T06:28:05.302Z,1509085685.302 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-10-27T06:28:05.303Z,1509085685.303 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-10-27T06:28:05.303Z,1509085685.303 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-10-27T06:28:05.329Z,1509085685.329 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-10-27T06:28:05.329Z,1509085685.329 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-10-27T06:28:05.534Z,1509085685.534 [Aanderaa_O2] Loaded
2017-10-27T06:28:05.534Z,1509085685.534 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-10-27T06:28:05.574Z,1509085685.574 [CTD_Seabird] Loaded
2017-10-27T06:28:05.575Z,1509085685.575 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-10-27T06:28:05.579Z,1509085685.579 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407864E0
2017-10-27T06:28:05.579Z,1509085685.579 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 850
2017-10-27T06:28:05.597Z,1509085685.597 [ESPComponent] Loaded
2017-10-27T06:28:05.597Z,1509085685.597 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2017-10-27T06:28:05.615Z,1509085685.615 [PAR_Licor] Loaded
2017-10-27T06:28:05.616Z,1509085685.616 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-10-27T06:28:05.670Z,1509085685.670 [WetLabsBB2FL] Loaded
2017-10-27T06:28:05.670Z,1509085685.670 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-10-27T06:28:05.675Z,1509085685.675 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407B64E0
2017-10-27T06:28:05.675Z,1509085685.675 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 851
2017-10-27T06:28:05.676Z,1509085685.676 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-10-27T06:28:05.676Z,1509085685.676 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-10-27T06:28:06.369Z,1509085686.369 [DataOverHttps] Loaded
2017-10-27T06:28:06.369Z,1509085686.369 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-10-27T06:28:06.383Z,1509085686.383 [Depth_Keller] Loaded
2017-10-27T06:28:06.383Z,1509085686.383 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-10-27T06:28:06.388Z,1509085686.388 [DropWeight] Loaded
2017-10-27T06:28:06.389Z,1509085686.389 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-10-27T06:28:06.488Z,1509085686.488 [NAL9602] Loaded
2017-10-27T06:28:06.489Z,1509085686.489 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-10-27T06:28:06.502Z,1509085686.502 [Onboard] Loaded
2017-10-27T06:28:06.502Z,1509085686.502 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-10-27T06:28:06.510Z,1509085686.510 [Radio_Surface] Loaded
2017-10-27T06:28:06.510Z,1509085686.510 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-10-27T06:28:06.512Z,1509085686.512 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409FA4E0
2017-10-27T06:28:06.512Z,1509085686.512 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 852
2017-10-27T06:28:06.728Z,1509085686.728 [PNI_TCM] Loaded
2017-10-27T06:28:06.733Z,1509085686.733 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-10-27T06:28:06.806Z,1509085686.806 [Rowe_600LCM] Loaded
2017-10-27T06:28:06.806Z,1509085686.806 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-10-27T06:28:06.807Z,1509085686.807 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2017-10-27T06:28:06.808Z,1509085686.808 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 853
2017-10-27T06:28:08.843Z,1509085688.843 [BPC1] Loaded
2017-10-27T06:28:08.843Z,1509085688.843 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-10-27T06:28:08.844Z,1509085688.844 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-10-27T06:28:08.844Z,1509085688.844 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-10-27T06:28:10.421Z,1509085690.421 [BuoyancyServo] Loaded
2017-10-27T06:28:10.421Z,1509085690.421 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-10-27T06:28:10.433Z,1509085690.433 [ElevatorServo] Loaded
2017-10-27T06:28:10.433Z,1509085690.433 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-10-27T06:28:10.445Z,1509085690.445 [MassServo] Loaded
2017-10-27T06:28:10.445Z,1509085690.445 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-10-27T06:28:10.457Z,1509085690.457 [RudderServo] Loaded
2017-10-27T06:28:10.458Z,1509085690.458 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-10-27T06:28:10.473Z,1509085690.473 [ThrusterServo] Loaded
2017-10-27T06:28:10.474Z,1509085690.474 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-10-27T06:28:10.474Z,1509085690.474 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-10-27T06:28:10.475Z,1509085690.475 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-10-27T06:28:10.617Z,1509085690.617 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-10-27T06:28:10.618Z,1509085690.618 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-10-27T06:28:10.656Z,1509085690.656 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-10-27T06:28:10.660Z,1509085690.660 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-10-27T06:28:10.661Z,1509085690.661 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-10-27T06:28:10.668Z,1509085690.668 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-10-27T06:28:10.671Z,1509085690.671 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD84E0
2017-10-27T06:28:10.671Z,1509085690.671 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 854
2017-10-27T06:28:10.676Z,1509085690.676 [Supervisor](INFO): Main Thread ID is 766
2017-10-27T06:28:10.676Z,1509085690.676 [Supervisor](DEBUG): Running supervisor.
2017-10-27T06:28:10.676Z,1509085690.676 [CommandLine ThreadHandler](INFO): Handler Thread ID is 855
2017-10-27T06:28:10.683Z,1509085690.683 [controlThread ThreadHandler](INFO): Handler Thread ID is 856
2017-10-27T06:28:10.684Z,1509085690.684 [controlThread](DEBUG): Initializing ControlThread
2017-10-27T06:28:10.684Z,1509085690.684 [SBIT](INFO): Initialize SBIT Component.
2017-10-27T06:28:10.685Z,1509085690.685 [SBIT](IMPORTANT): git: 2017-10-16-13-g367f5fc
2017-10-27T06:28:10.685Z,1509085690.685 [SBIT](INFO): git hash: 367f5fc68b1aab1c629e49c722347fbe67b83aa3
2017-10-27T06:28:10.687Z,1509085690.687 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-10-27T06:28:10.687Z,1509085690.687 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-10-27T06:28:10.688Z,1509085690.688 [SBIT](INFO): Beginning SBIT in 79.000000 seconds.
2017-10-27T06:28:10.689Z,1509085690.689 [IBIT](INFO): Initialize IBIT Component.
2017-10-27T06:28:10.690Z,1509085690.690 [CBIT](DEBUG): Initialize CBIT Component.
2017-10-27T06:28:10.690Z,1509085690.690 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-10-27T06:28:10.690Z,1509085690.690 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2017-10-27T06:28:10.695Z,1509085690.695 [logger ThreadHandler](INFO): Handler Thread ID is 857
2017-10-27T06:28:10.739Z,1509085690.739 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 858
2017-10-27T06:28:10.740Z,1509085690.740 [CTD_Seabird](INFO): Initializing
2017-10-27T06:28:10.771Z,1509085690.771 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-10-27T06:28:10.774Z,1509085690.774 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-10-27T06:28:10.774Z,1509085690.774 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-10-27T06:28:10.775Z,1509085690.775 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-10-27T06:28:10.776Z,1509085690.776 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-10-27T06:28:10.776Z,1509085690.776 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-10-27T06:28:10.776Z,1509085690.776 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-10-27T06:28:10.777Z,1509085690.777 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-10-27T06:28:10.777Z,1509085690.777 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-10-27T06:28:10.778Z,1509085690.778 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-10-27T06:28:10.780Z,1509085690.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-10-27T06:28:10.781Z,1509085690.781 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-10-27T06:28:10.781Z,1509085690.781 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-10-27T06:28:10.781Z,1509085690.781 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-27T06:28:10.782Z,1509085690.782 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-27T06:28:10.782Z,1509085690.782 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-27T06:28:10.783Z,1509085690.783 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-10-27T06:28:10.783Z,1509085690.783 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-10-27T06:28:10.784Z,1509085690.784 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-10-27T06:28:10.784Z,1509085690.784 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-10-27T06:28:10.784Z,1509085690.784 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-10-27T06:28:10.796Z,1509085690.796 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 860
2017-10-27T06:28:10.797Z,1509085690.797 [WetLabsBB2FL](INFO): Powering down
2017-10-27T06:28:10.832Z,1509085690.832 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-10-27T06:28:10.844Z,1509085690.844 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 861
2017-10-27T06:28:10.852Z,1509085690.852 [Radio_Surface](INFO): Powering up
2017-10-27T06:28:10.876Z,1509085690.876 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 862
2017-10-27T06:28:10.877Z,1509085690.877 [Rowe_600LCM](INFO): Initializing
2017-10-27T06:28:10.878Z,1509085690.878 [Rowe_600LCM](INFO): Checking LCM
2017-10-27T06:28:10.881Z,1509085690.881 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-10-27T06:28:10.959Z,1509085690.959 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-10-27T06:28:10.959Z,1509085690.959 [CTD_Seabird](INFO): Powering down
2017-10-27T06:28:11.024Z,1509085691.024 [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>


2017-10-27T06:28:11.029Z,1509085691.029 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-10-27T06:28:11.056Z,1509085691.056 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 863
2017-10-27T06:28:11.137Z,1509085691.137 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-10-27T06:28:11.138Z,1509085691.138 [Default:A.Wait](DEBUG): Construct Wait.
2017-10-27T06:28:11.140Z,1509085691.140 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-10-27T06:28:11.161Z,1509085691.161 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-10-27T06:28:11.180Z,1509085691.180 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-10-27T06:28:11.186Z,1509085691.186 [Default:E.Execute](DEBUG): Construct Execute.
2017-10-27T06:28:11.304Z,1509085691.304 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <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>


2017-10-27T06:28:11.317Z,1