2018-07-24T19:50:59.959Z,1532461859.959 [Supervisor](DEBUG): Initializing supervisor. 2018-07-24T19:50:59.962Z,1532461859.962 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-07-24T19:50:59.963Z,1532461859.963 [SyncHandler](INFO): Protected caller Thread ID is 4957 2018-07-24T19:50:59.963Z,1532461859.963 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-07-24T19:50:59.964Z,1532461859.964 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-07-24T19:50:59.965Z,1532461859.965 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4958 2018-07-24T19:50:59.969Z,1532461859.969 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-07-24T19:50:59.981Z,1532461859.981 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-07-24T19:50:59.982Z,1532461859.982 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-07-24T19:50:59.982Z,1532461859.982 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4959 2018-07-24T19:50:59.983Z,1532461859.983 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-07-24T19:50:59.984Z,1532461859.984 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-07-24T19:50:59.984Z,1532461859.984 [logger ThreadHandler](INFO): Protected caller Thread ID is 4960 2018-07-24T19:50:59.986Z,1532461859.986 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-07-24T19:50:59.987Z,1532461859.987 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-07-24T19:50:59.988Z,1532461859.988 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-07-24T19:51:00.182Z,1532461860.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-07-24T19:51:00.183Z,1532461860.183 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-07-24T19:51:00.323Z,1532461860.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-07-24T19:51:00.323Z,1532461860.323 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-07-24T19:51:00.664Z,1532461860.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-07-24T19:51:00.665Z,1532461860.665 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-07-24T19:51:00.801Z,1532461860.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-07-24T19:51:00.801Z,1532461860.801 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-07-24T19:51:00.881Z,1532461860.881 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-07-24T19:51:01.080Z,1532461861.080 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-07-24T19:51:01.081Z,1532461861.081 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-07-24T19:51:01.178Z,1532461861.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-07-24T19:51:01.179Z,1532461861.179 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-07-24T19:51:01.512Z,1532461861.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-07-24T19:51:01.513Z,1532461861.513 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-07-24T19:51:01.962Z,1532461861.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-07-24T19:51:01.963Z,1532461861.963 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-07-24T19:51:02.264Z,1532461862.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-07-24T19:51:02.265Z,1532461862.265 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-07-24T19:51:02.821Z,1532461862.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-07-24T19:51:02.822Z,1532461862.822 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-07-24T19:51:03.032Z,1532461863.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-07-24T19:51:03.032Z,1532461863.032 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-07-24T19:51:03.129Z,1532461863.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-07-24T19:51:03.130Z,1532461863.130 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-07-24T19:51:03.647Z,1532461863.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-07-24T19:51:03.647Z,1532461863.647 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-07-24T19:51:03.919Z,1532461863.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-07-24T19:51:03.921Z,1532461863.921 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-07-24T19:51:03.922Z,1532461863.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-07-24T19:51:04.150Z,1532461864.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-07-24T19:51:04.151Z,1532461864.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-07-24T19:51:04.248Z,1532461864.248 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-07-24T19:51:04.340Z,1532461864.340 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-07-24T19:51:04.443Z,1532461864.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-07-24T19:51:04.528Z,1532461864.528 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-07-24T19:51:04.657Z,1532461864.657 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-07-24T19:51:04.823Z,1532461864.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-07-24T19:51:04.932Z,1532461864.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-07-24T19:51:05.016Z,1532461865.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-07-24T19:51:05.099Z,1532461865.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-07-24T19:51:05.283Z,1532461865.283 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2018-07-24T19:51:05.283Z,1532461865.283 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-07-24T19:51:05.288Z,1532461865.288 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-07-24T19:51:05.414Z,1532461865.414 [SBIT](DEBUG): Construct Startup Built In Test. 2018-07-24T19:51:05.444Z,1532461865.444 [SBIT] Loaded 2018-07-24T19:51:05.444Z,1532461865.444 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-07-24T19:51:05.445Z,1532461865.445 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-07-24T19:51:05.472Z,1532461865.472 [IBIT] Loaded 2018-07-24T19:51:05.472Z,1532461865.472 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-07-24T19:51:05.476Z,1532461865.476 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-07-24T19:51:05.630Z,1532461865.630 [CBIT] Loaded 2018-07-24T19:51:05.631Z,1532461865.631 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-07-24T19:51:05.631Z,1532461865.631 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-07-24T19:51:05.632Z,1532461865.632 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-07-24T19:51:05.700Z,1532461865.700 [VerticalControl](DEBUG): Construct VerticalControl. 2018-07-24T19:51:05.799Z,1532461865.799 [VerticalControl] Loaded 2018-07-24T19:51:05.799Z,1532461865.799 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-07-24T19:51:05.800Z,1532461865.800 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-07-24T19:51:05.860Z,1532461865.860 [HorizontalControl] Loaded 2018-07-24T19:51:05.860Z,1532461865.860 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-07-24T19:51:05.861Z,1532461865.861 [SpeedControl](DEBUG): Construct SpeedControl. 2018-07-24T19:51:05.866Z,1532461865.866 [SpeedControl] Loaded 2018-07-24T19:51:05.867Z,1532461865.867 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-07-24T19:51:05.867Z,1532461865.867 [LoopControl](DEBUG): Construct LoopControl. 2018-07-24T19:51:05.868Z,1532461865.868 [LoopControl] Loaded 2018-07-24T19:51:05.868Z,1532461865.868 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-07-24T19:51:05.868Z,1532461865.868 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-07-24T19:51:05.869Z,1532461865.869 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-07-24T19:51:05.909Z,1532461865.909 [DepthRateCalculator] Loaded 2018-07-24T19:51:05.910Z,1532461865.910 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-07-24T19:51:05.915Z,1532461865.915 [PitchRateCalculator] Loaded 2018-07-24T19:51:05.915Z,1532461865.915 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-07-24T19:51:05.931Z,1532461865.931 [SpeedCalculator] Loaded 2018-07-24T19:51:05.931Z,1532461865.931 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-07-24T19:51:05.952Z,1532461865.952 [TempGradientCalculator] Loaded 2018-07-24T19:51:05.953Z,1532461865.953 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-07-24T19:51:05.968Z,1532461865.968 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-07-24T19:51:05.969Z,1532461865.969 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-07-24T19:51:05.974Z,1532461865.974 [YawRateCalculator] Loaded 2018-07-24T19:51:05.974Z,1532461865.974 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-07-24T19:51:06.004Z,1532461866.004 [ElevatorOffsetCalculator] Loaded 2018-07-24T19:51:06.004Z,1532461866.004 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-07-24T19:51:06.005Z,1532461866.005 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-07-24T19:51:06.005Z,1532461866.005 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-07-24T19:51:06.087Z,1532461866.087 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-07-24T19:51:06.087Z,1532461866.087 [StratificationFrontDetector](DEBUG): (re)initializing 2018-07-24T19:51:06.088Z,1532461866.088 [StratificationFrontDetector] Loaded 2018-07-24T19:51:06.088Z,1532461866.088 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-07-24T19:51:06.089Z,1532461866.089 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-07-24T19:51:06.089Z,1532461866.089 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-07-24T19:51:06.328Z,1532461866.328 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-07-24T19:51:06.329Z,1532461866.329 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-07-24T19:51:06.424Z,1532461866.424 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-07-24T19:51:06.425Z,1532461866.425 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-07-24T19:51:06.479Z,1532461866.479 [DeadReckonUsingSpeedCalculator] Loaded 2018-07-24T19:51:06.479Z,1532461866.479 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-07-24T19:51:06.496Z,1532461866.496 [NavChart] Loaded 2018-07-24T19:51:06.496Z,1532461866.496 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-07-24T19:51:06.500Z,1532461866.500 [UniversalFixResidualReporter] Loaded 2018-07-24T19:51:06.500Z,1532461866.500 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-07-24T19:51:06.501Z,1532461866.501 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-07-24T19:51:06.501Z,1532461866.501 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-07-24T19:51:06.515Z,1532461866.515 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-07-24T19:51:06.515Z,1532461866.515 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-07-24T19:51:06.669Z,1532461866.669 [PAR_Licor] Loaded 2018-07-24T19:51:06.669Z,1532461866.669 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-07-24T19:51:06.719Z,1532461866.719 [WetLabsBB2FL] Loaded 2018-07-24T19:51:06.719Z,1532461866.719 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-07-24T19:51:06.720Z,1532461866.720 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406D24E0 2018-07-24T19:51:06.721Z,1532461866.721 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5039 2018-07-24T19:51:06.764Z,1532461866.764 [WetLabsSeaOWL_UV_A] Loaded 2018-07-24T19:51:06.764Z,1532461866.764 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-07-24T19:51:06.765Z,1532461866.765 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407024E0 2018-07-24T19:51:06.765Z,1532461866.765 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 5040 2018-07-24T19:51:06.766Z,1532461866.766 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-07-24T19:51:06.766Z,1532461866.766 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-07-24T19:51:07.079Z,1532461867.079 [AHRS_M2] Loaded 2018-07-24T19:51:07.079Z,1532461867.079 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-07-24T19:51:07.145Z,1532461867.145 [DataOverHttps] Loaded 2018-07-24T19:51:07.146Z,1532461867.146 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-07-24T19:51:07.159Z,1532461867.159 [Depth_Keller] Loaded 2018-07-24T19:51:07.160Z,1532461867.160 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-07-24T19:51:07.165Z,1532461867.165 [DropWeight] Loaded 2018-07-24T19:51:07.165Z,1532461867.165 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-07-24T19:51:07.255Z,1532461867.255 [NAL9602] Loaded 2018-07-24T19:51:07.255Z,1532461867.255 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-07-24T19:51:07.260Z,1532461867.260 [Onboard] Loaded 2018-07-24T19:51:07.260Z,1532461867.260 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-07-24T19:51:07.267Z,1532461867.267 [Radio_Surface] Loaded 2018-07-24T19:51:07.268Z,1532461867.268 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-07-24T19:51:07.269Z,1532461867.269 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409584E0 2018-07-24T19:51:07.269Z,1532461867.269 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5041 2018-07-24T19:51:07.297Z,1532461867.297 [RDI_Pathfinder] Loaded 2018-07-24T19:51:07.298Z,1532461867.298 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-07-24T19:51:07.304Z,1532461867.304 [SCPI] Loaded 2018-07-24T19:51:07.304Z,1532461867.304 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-07-24T19:51:09.131Z,1532461869.131 [BPC1] Loaded 2018-07-24T19:51:09.131Z,1532461869.131 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-07-24T19:51:09.131Z,1532461869.131 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-07-24T19:51:09.132Z,1532461869.132 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-07-24T19:51:09.228Z,1532461869.228 [BuoyancyServo] Loaded 2018-07-24T19:51:09.229Z,1532461869.229 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-07-24T19:51:09.241Z,1532461869.241 [ElevatorServo] Loaded 2018-07-24T19:51:09.241Z,1532461869.241 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-07-24T19:51:09.252Z,1532461869.252 [MassServo] Loaded 2018-07-24T19:51:09.252Z,1532461869.252 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-07-24T19:51:09.263Z,1532461869.263 [RudderServo] Loaded 2018-07-24T19:51:09.264Z,1532461869.264 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-07-24T19:51:09.274Z,1532461869.274 [ThrusterServo] Loaded 2018-07-24T19:51:09.275Z,1532461869.275 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-07-24T19:51:09.275Z,1532461869.275 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-07-24T19:51:09.276Z,1532461869.276 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-07-24T19:51:09.373Z,1532461869.373 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-07-24T19:51:09.374Z,1532461869.374 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-07-24T19:51:09.398Z,1532461869.398 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-07-24T19:51:09.402Z,1532461869.402 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-07-24T19:51:09.403Z,1532461869.403 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-07-24T19:51:09.410Z,1532461869.410 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-07-24T19:51:09.411Z,1532461869.411 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A014E0 2018-07-24T19:51:09.411Z,1532461869.411 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5042 2018-07-24T19:51:09.416Z,1532461869.416 [Supervisor](INFO): Main Thread ID is 4210 2018-07-24T19:51:09.416Z,1532461869.416 [Supervisor](DEBUG): Running supervisor. 2018-07-24T19:51:09.417Z,1532461869.417 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5043 2018-07-24T19:51:09.419Z,1532461869.419 [controlThread ThreadHandler](INFO): Handler Thread ID is 5044 2018-07-24T19:51:09.420Z,1532461869.420 [controlThread](DEBUG): Initializing ControlThread 2018-07-24T19:51:09.421Z,1532461869.421 [SBIT](INFO): Initialize SBIT Component. 2018-07-24T19:51:09.421Z,1532461869.421 [SBIT](IMPORTANT): git: 2018-07-13-4-g11fcf70 2018-07-24T19:51:09.422Z,1532461869.422 [SBIT](INFO): git hash: 11fcf7063fbf2dc5e8a6d077ff17eabc4a3a9f45 2018-07-24T19:51:09.422Z,1532461869.422 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-07-24T19:51:09.423Z,1532461869.423 [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-07-24T19:51:09.424Z,1532461869.424 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-07-24T19:51:09.425Z,1532461869.425 [IBIT](INFO): Initialize IBIT Component. 2018-07-24T19:51:09.426Z,1532461869.426 [CBIT](DEBUG): Initialize CBIT Component. 2018-07-24T19:51:09.426Z,1532461869.426 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-07-24T19:51:09.427Z,1532461869.427 [logger ThreadHandler](INFO): Handler Thread ID is 5045 2018-07-24T19:51:09.453Z,1532461869.453 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5046 2018-07-24T19:51:09.473Z,1532461869.473 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 5047 2018-07-24T19:51:09.474Z,1532461869.474 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-07-24T19:51:09.509Z,1532461869.509 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5048 2018-07-24T19:51:09.515Z,1532461869.515 [Radio_Surface](INFO): Powering up 2018-07-24T19:51:09.533Z,1532461869.533 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-07-24T19:51:09.535Z,1532461869.535 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-07-24T19:51:09.536Z,1532461869.536 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-07-24T19:51:09.536Z,1532461869.536 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-07-24T19:51:09.536Z,1532461869.536 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-07-24T19:51:09.537Z,1532461869.537 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-07-24T19:51:09.537Z,1532461869.537 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-07-24T19:51:09.537Z,1532461869.537 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-07-24T19:51:09.538Z,1532461869.538 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-07-24T19:51:09.539Z,1532461869.539 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-07-24T19:51:09.539Z,1532461869.539 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-07-24T19:51:09.540Z,1532461869.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-07-24T19:51:09.540Z,1532461869.540 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-07-24T19:51:09.541Z,1532461869.541 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-07-24T19:51:09.541Z,1532461869.541 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-07-24T19:51:09.542Z,1532461869.542 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-07-24T19:51:09.542Z,1532461869.542 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-07-24T19:51:09.542Z,1532461869.542 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-07-24T19:51:09.543Z,1532461869.543 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-07-24T19:51:09.549Z,1532461869.549 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5049 2018-07-24T19:51:09.553Z,1532461869.553 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-07-24T19:51:09.553Z,1532461869.553 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-07-24T19:51:09.553Z,1532461869.553 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-07-24T19:51:09.554Z,1532461869.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-07-24T19:51:09.554Z,1532461869.554 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-07-24T19:51:09.554Z,1532461869.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-07-24T19:51:09.554Z,1532461869.554 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-07-24T19:51:09.554Z,1532461869.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-07-24T19:51:09.555Z,1532461869.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-07-24T19:51:09.555Z,1532461869.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-07-24T19:51:09.555Z,1532461869.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-07-24T19:51:09.555Z,1532461869.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-07-24T19:51:09.556Z,1532461869.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-07-24T19:51:09.556Z,1532461869.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-07-24T19:51:09.556Z,1532461869.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-07-24T19:51:09.556Z,1532461869.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-07-24T19:51:09.578Z,1532461869.578 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-07-24T19:51:09.611Z,1532461869.611 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-07-24T19:51:09.642Z,1532461869.642 [MissionManager](DEBUG): 2018-07-24T19:51:09.643Z,1532461869.643 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-07-24T19:51:09.843Z,1532461869.843 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-07-24T19:51:09.844Z,1532461869.844 [Default:A.Wait](DEBUG): Construct Wait. 2018-07-24T19:51:09.866Z,1532461869.866 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-07-24T19:51:09.906Z,1532461869.906 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-07-24T19:51:09.949Z,1532461869.949 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-07-24T19:51:09.954Z,1532461869.954 [Default:E.Execute](DEBUG): Construct Execute. 2018-07-24T19:51:09.982Z,1532461869.982 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-07-24T19:51:09.987Z,1532461869.987 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-07-24T19:51:10.029Z,1532461870.029 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-07-24T19:51:10.100Z,1532461870.100 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-07-24T19:51:10.137Z,1532461870.137 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-07-24T19:51:10.212Z,1532461870.212 [RDI_Pathfinder](INFO): Start 2018-07-24T19:51:10.385Z,1532461870.385 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-07-24T19:51:10.497Z,1532461870.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:10.525Z,1532461870.525 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:10.637Z,1532461870.637 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-24T19:51:10.649Z,1532461870.649 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-24T19:51:10.663Z,1532461870.663 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-07-24T19:51:10.669Z,1532461870.669 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-07-24T19:51:10.683Z,1532461870.683 [MassServo](DEBUG): Initializing EZServoServo. 2018-07-24T19:51:10.689Z,1532461870.689 [MassServo](DEBUG): Initializing MassServo. 2018-07-24T19:51:10.696Z,1532461870.696 [RudderServo](DEBUG): Initializing EZServoServo. 2018-07-24T19:51:10.713Z,1532461870.713 [RudderServo](DEBUG): Initializing RudderServo. 2018-07-24T19:51:10.720Z,1532461870.720 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-07-24T19:51:10.726Z,1532461870.726 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-07-24T19:51:10.915Z,1532461870.915 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:10.941Z,1532461870.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:10.942Z,1532461870.942 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:11.250Z,1532461871.250 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:11.277Z,1532461871.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:11.279Z,1532461871.279 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:11.629Z,1532461871.629 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-07-24T19:51:11.629Z,1532461871.629 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-07-24T19:51:11.629Z,1532461871.629 [BuoyancyServo] Communications Fault, FailCount= 1 2018-07-24T19:51:11.629Z,1532461871.629 [BuoyancyServo](ERROR): Communications Fault 2018-07-24T19:51:11.833Z,1532461871.833 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-07-24T19:51:11.877Z,1532461871.877 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:11.905Z,1532461871.905 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:11.907Z,1532461871.907 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:11.924Z,1532461871.924 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-24T19:51:11.925Z,1532461871.925 [BuoyancyServo](INFO): Powering down 2018-07-24T19:51:12.295Z,1532461872.295 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:12.317Z,1532461872.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:12.318Z,1532461872.318 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:12.741Z,1532461872.741 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:12.748Z,1532461872.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:12.749Z,1532461872.749 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:13.091Z,1532461873.091 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:13.117Z,1532461873.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:13.118Z,1532461873.118 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:13.492Z,1532461873.492 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:13.522Z,1532461873.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:13.541Z,1532461873.541 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:13.907Z,1532461873.907 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:13.937Z,1532461873.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:13.938Z,1532461873.938 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:14.054Z,1532461874.054 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-24T19:51:14.054Z,1532461874.054 [BuoyancyServo] No Fault, FailCount= 1 2018-07-24T19:51:14.311Z,1532461874.311 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:14.323Z,1532461874.323 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:14.324Z,1532461874.324 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:14.356Z,1532461874.356 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-24T19:51:14.474Z,1532461874.474 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-24T19:51:14.707Z,1532461874.707 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:14.718Z,1532461874.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:14.719Z,1532461874.719 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:15.091Z,1532461875.091 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:15.114Z,1532461875.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:15.116Z,1532461875.116 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:15.503Z,1532461875.503 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:15.522Z,1532461875.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:15.523Z,1532461875.523 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:15.867Z,1532461875.867 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:15.890Z,1532461875.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:15.891Z,1532461875.891 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:16.274Z,1532461876.274 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:16.293Z,1532461876.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:16.294Z,1532461876.294 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:16.691Z,1532461876.691 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:16.702Z,1532461876.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:16.703Z,1532461876.703 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-24T19:51:17.095Z,1532461877.095 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:17.491Z,1532461877.491 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:17.891Z,1532461877.891 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:18.292Z,1532461878.292 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:18.691Z,1532461878.691 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:19.091Z,1532461879.091 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:19.493Z,1532461879.493 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:19.905Z,1532461879.905 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:20.291Z,1532461880.291 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:20.691Z,1532461880.691 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:21.091Z,1532461881.091 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:21.492Z,1532461881.492 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:21.883Z,1532461881.883 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:22.279Z,1532461882.279 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:23.879Z,1532461883.879 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:24.279Z,1532461884.279 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:24.733Z,1532461884.733 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:25.080Z,1532461885.080 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:25.105Z,1532461885.105 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:51:25.105Z,1532461885.105 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:51:25.105Z,1532461885.105 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:51:25.106Z,1532461885.106 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:51:25.238Z,1532461885.238 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:51:25.480Z,1532461885.480 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:51:25.549Z,1532461885.549 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:51:25.879Z,1532461885.879 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:51:26.279Z,1532461886.279 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:51:26.409Z,1532461886.409 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:51:26.409Z,1532461886.409 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:51:26.679Z,1532461886.679 [RDI_Pathfinder](INFO): Start 2018-07-24T19:51:27.079Z,1532461887.079 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:27.479Z,1532461887.479 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:27.880Z,1532461887.880 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:28.279Z,1532461888.279 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:28.679Z,1532461888.679 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:29.079Z,1532461889.079 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:29.471Z,1532461889.471 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:29.871Z,1532461889.871 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:30.271Z,1532461890.271 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:30.675Z,1532461890.675 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:31.072Z,1532461891.072 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:31.478Z,1532461891.478 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:31.871Z,1532461891.871 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:32.277Z,1532461892.277 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:32.671Z,1532461892.671 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:33.068Z,1532461893.068 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:33.471Z,1532461893.471 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:33.871Z,1532461893.871 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:34.271Z,1532461894.271 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:34.671Z,1532461894.671 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:35.082Z,1532461895.082 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:35.476Z,1532461895.476 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:35.874Z,1532461895.874 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:36.271Z,1532461896.271 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:36.671Z,1532461896.671 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:37.086Z,1532461897.086 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:37.463Z,1532461897.463 [NAL9602](INFO): Powering up NAL9602 2018-07-24T19:51:37.482Z,1532461897.482 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:37.872Z,1532461897.872 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:37.986Z,1532461897.986 [SBIT](IMPORTANT): Beginning Startup BIT 2018-07-24T19:51:37.991Z,1532461897.991 [CBIT](IMPORTANT): Beginning ground fault scan 2018-07-24T19:51:38.272Z,1532461898.272 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:38.672Z,1532461898.672 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:39.073Z,1532461899.073 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:40.462Z,1532461900.462 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:40.846Z,1532461900.846 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:41.248Z,1532461901.248 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:41.449Z,1532461901.449 [RudderServo](FAULT): Overload Error 2018-07-24T19:51:41.449Z,1532461901.449 [RudderServo] Hardware Fault, FailCount= 1 2018-07-24T19:51:41.449Z,1532461901.449 [RudderServo](ERROR): Hardware Fault 2018-07-24T19:51:41.492Z,1532461901.492 [CBIT](ERROR): Hardware Fault in component: RudderServo 2018-07-24T19:51:41.639Z,1532461901.639 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:41.665Z,1532461901.665 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:51:41.665Z,1532461901.665 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:51:41.665Z,1532461901.665 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:51:41.665Z,1532461901.665 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:51:41.886Z,1532461901.886 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-07-24T19:51:41.886Z,1532461901.886 [RudderServo](INFO): Powering down 2018-07-24T19:51:41.892Z,1532461901.892 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:51:42.035Z,1532461902.035 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:51:42.105Z,1532461902.105 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:51:42.445Z,1532461902.445 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:51:42.546Z,1532461902.546 [RudderServo](DEBUG): Initializing EZServoServo. 2018-07-24T19:51:42.666Z,1532461902.666 [RudderServo](DEBUG): Initializing RudderServo. 2018-07-24T19:51:42.671Z,1532461902.671 [CBIT](INFO): Clearing failed state for component RudderServo 2018-07-24T19:51:42.671Z,1532461902.671 [RudderServo] No Fault, FailCount= 1 2018-07-24T19:51:42.845Z,1532461902.845 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:51:42.947Z,1532461902.947 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:51:42.947Z,1532461902.947 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:51:43.245Z,1532461903.245 [RDI_Pathfinder](INFO): Start 2018-07-24T19:51:43.637Z,1532461903.637 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:44.079Z,1532461904.079 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:44.437Z,1532461904.437 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:44.833Z,1532461904.833 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:45.231Z,1532461905.231 [CommandLine](IMPORTANT): got command show variable wrt_ground 2018-07-24T19:51:45.236Z,1532461905.236 [CommandLine](IMPORTANT): platform_distance_wrt_ground (unknown) 2018-07-24T19:51:45.239Z,1532461905.239 [CommandLine](IMPORTANT): platform_speed_wrt_ground (unknown) 2018-07-24T19:51:45.241Z,1532461905.241 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:45.249Z,1532461905.249 [CommandLine](IMPORTANT): platform_velocity_wrt_ground (unknown) 2018-07-24T19:51:45.250Z,1532461905.250 [CommandLine](IMPORTANT): platform_x_velocity_wrt_ground (unknown) 2018-07-24T19:51:45.250Z,1532461905.250 [CommandLine](IMPORTANT): platform_y_velocity_wrt_ground (unknown) 2018-07-24T19:51:45.251Z,1532461905.251 [CommandLine](IMPORTANT): platform_z_velocity_wrt_ground (unknown) 2018-07-24T19:51:45.442Z,1532461905.442 [CommandLine](IMPORTANT): RDI_Pathfinder.platform_velocity_wrt_ground (meter_per_second) 2018-07-24T19:51:45.442Z,1532461905.442 [CommandLine](IMPORTANT): RDI_Pathfinder.platform_x_velocity_wrt_ground (meter_per_second) 2018-07-24T19:51:45.443Z,1532461905.443 [CommandLine](IMPORTANT): RDI_Pathfinder.platform_y_velocity_wrt_ground (meter_per_second) 2018-07-24T19:51:45.443Z,1532461905.443 [CommandLine](IMPORTANT): RDI_Pathfinder.platform_z_velocity_wrt_ground (meter_per_second) 2018-07-24T19:51:45.643Z,1532461905.643 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:46.064Z,1532461906.064 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:46.451Z,1532461906.451 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:46.848Z,1532461906.848 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:47.247Z,1532461907.247 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:47.641Z,1532461907.641 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:47.777Z,1532461907.777 [RudderServo](FAULT): Overload Error 2018-07-24T19:51:47.777Z,1532461907.777 [RudderServo] Hardware Fault, FailCount= 2 2018-07-24T19:51:47.777Z,1532461907.777 [RudderServo](ERROR): Hardware Fault 2018-07-24T19:51:47.818Z,1532461907.818 [CBIT](ERROR): Hardware Fault in component: RudderServo 2018-07-24T19:51:48.050Z,1532461908.050 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:48.150Z,1532461908.150 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-07-24T19:51:48.150Z,1532461908.150 [RudderServo](INFO): Powering down 2018-07-24T19:51:48.431Z,1532461908.431 [NAL9602](INFO): NAL9602 initialized 2018-07-24T19:51:48.448Z,1532461908.448 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:48.889Z,1532461908.889 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:49.010Z,1532461909.010 [RudderServo](DEBUG): Initializing EZServoServo. 2018-07-24T19:51:49.130Z,1532461909.130 [RudderServo](DEBUG): Initializing RudderServo. 2018-07-24T19:51:49.135Z,1532461909.135 [CBIT](INFO): Clearing failed state for component RudderServo 2018-07-24T19:51:49.135Z,1532461909.135 [RudderServo] No Fault, FailCount= 2 2018-07-24T19:51:49.139Z,1532461909.139 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.019396 CHAN A1 (24V): -0.022655 CHAN A2 (12V): -0.006397 CHAN A3 (5V): -0.002262 CHAN B0 (3.3V): 0.000205 CHAN B1 (3.15aV): 0.000049 CHAN B2 (3.15bV): 0.000260 CHAN B3 (GND): 0.002318 OPEN: 0.006675 Full Scale Calc: 4.765 mA, -1.589 mA 2018-07-24T19:51:49.285Z,1532461909.285 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:49.662Z,1532461909.662 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:50.097Z,1532461910.097 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:50.448Z,1532461910.448 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:50.848Z,1532461910.848 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:51.264Z,1532461911.264 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:51.364Z,1532461911.364 [SBIT](ERROR): Could not read rudderAngleReader_. 2018-07-24T19:51:51.693Z,1532461911.693 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:52.050Z,1532461912.050 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:52.453Z,1532461912.453 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:52.845Z,1532461912.845 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:53.244Z,1532461913.244 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:53.811Z,1532461913.811 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:54.221Z,1532461914.221 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:54.624Z,1532461914.624 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:55.029Z,1532461915.029 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:55.425Z,1532461915.425 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:56.623Z,1532461916.623 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:57.026Z,1532461917.026 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:57.428Z,1532461917.428 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:51:57.441Z,1532461917.441 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:51:57.441Z,1532461917.441 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:51:57.441Z,1532461917.441 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:51:57.442Z,1532461917.442 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:51:57.611Z,1532461917.611 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:51:57.813Z,1532461917.813 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:51:57.885Z,1532461917.885 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:51:58.218Z,1532461918.218 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:51:58.623Z,1532461918.623 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:51:58.849Z,1532461918.849 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:51:58.849Z,1532461918.849 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:51:59.017Z,1532461919.017 [RDI_Pathfinder](INFO): Start 2018-07-24T19:51:59.416Z,1532461919.416 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:51:59.822Z,1532461919.822 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:00.220Z,1532461920.220 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:00.619Z,1532461920.619 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:01.020Z,1532461921.020 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:01.422Z,1532461921.422 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:01.833Z,1532461921.833 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:02.235Z,1532461922.235 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:02.633Z,1532461922.633 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:03.026Z,1532461923.026 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:03.430Z,1532461923.430 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:03.809Z,1532461923.809 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:04.209Z,1532461924.209 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:04.612Z,1532461924.612 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:05.023Z,1532461925.023 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:05.442Z,1532461925.442 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:05.819Z,1532461925.819 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:06.212Z,1532461926.212 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:06.651Z,1532461926.651 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:07.053Z,1532461927.053 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:07.424Z,1532461927.424 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:07.826Z,1532461927.826 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:08.229Z,1532461928.229 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:08.621Z,1532461928.621 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:09.004Z,1532461929.004 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:09.426Z,1532461929.426 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:09.809Z,1532461929.809 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:10.214Z,1532461930.214 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:10.615Z,1532461930.615 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:11.009Z,1532461931.009 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:11.409Z,1532461931.409 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:12.635Z,1532461932.635 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:13.035Z,1532461933.035 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:13.412Z,1532461933.412 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:13.808Z,1532461933.808 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:13.857Z,1532461933.857 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:52:13.857Z,1532461933.857 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:52:13.857Z,1532461933.857 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:52:13.857Z,1532461933.857 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:52:13.962Z,1532461933.962 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:52:14.213Z,1532461934.213 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:52:14.285Z,1532461934.285 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:52:14.612Z,1532461934.612 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:52:15.020Z,1532461935.020 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:52:15.088Z,1532461935.088 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:52:15.097Z,1532461935.097 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:52:15.430Z,1532461935.430 [RDI_Pathfinder](INFO): Start 2018-07-24T19:52:15.825Z,1532461935.825 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:16.208Z,1532461936.208 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:16.613Z,1532461936.613 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:17.033Z,1532461937.033 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:17.430Z,1532461937.430 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:17.838Z,1532461937.838 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:18.222Z,1532461938.222 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:18.611Z,1532461938.611 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:19.007Z,1532461939.007 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:19.411Z,1532461939.411 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:19.820Z,1532461939.820 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:20.231Z,1532461940.231 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:20.783Z,1532461940.783 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:21.184Z,1532461941.184 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:21.587Z,1532461941.587 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:22.011Z,1532461942.011 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:22.414Z,1532461942.414 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:22.801Z,1532461942.801 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:23.209Z,1532461943.209 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:23.592Z,1532461943.592 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:23.987Z,1532461943.987 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:24.392Z,1532461944.392 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:24.794Z,1532461944.794 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:25.197Z,1532461945.197 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:25.594Z,1532461945.594 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:26.001Z,1532461946.001 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:26.397Z,1532461946.397 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:26.796Z,1532461946.796 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:27.205Z,1532461947.205 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:27.606Z,1532461947.606 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:28.803Z,1532461948.803 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:29.200Z,1532461949.200 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:29.583Z,1532461949.583 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:29.597Z,1532461949.597 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:52:29.597Z,1532461949.597 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:52:29.597Z,1532461949.597 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:52:29.598Z,1532461949.598 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:52:29.778Z,1532461949.778 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:52:29.995Z,1532461949.995 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:52:30.065Z,1532461950.065 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:52:30.394Z,1532461950.394 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:52:30.796Z,1532461950.796 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:52:30.902Z,1532461950.902 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:52:30.902Z,1532461950.902 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:52:31.198Z,1532461951.198 [RDI_Pathfinder](INFO): Start 2018-07-24T19:52:31.591Z,1532461951.591 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:31.706Z,1532461951.706 [SBIT](CRITICAL): SBIT FAILED 2018-07-24T19:52:31.745Z,1532461951.745 [CommandLine](FAULT): Scheduling is paused 2018-07-24T19:52:31.745Z,1532461951.745 [CBIT](INFO): Critical error at 20180724T195231 2018-07-24T19:52:31.746Z,1532461951.746 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-07-24T19:52:31.777Z,1532461951.777 [CommandLine](IMPORTANT): got command configSet list 2018-07-24T19:52:31.778Z,1532461951.778 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-07-24T19:52:31.778Z,1532461951.778 [CommandLine](IMPORTANT): AHRS_M2.magDeviation=0 degree; 2018-07-24T19:52:31.778Z,1532461951.778 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=300 cubic_centimeter; 2018-07-24T19:52:31.779Z,1532461951.779 [CommandLine](IMPORTANT): VerticalControl.massDefault=2 millimeter; 2018-07-24T19:52:32.004Z,1532461952.004 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:32.021Z,1532461952.021 [MissionManager](IMPORTANT): Started mission Startup 2018-07-24T19:52:32.022Z,1532461952.022 [Startup] Running Loop=1 2018-07-24T19:52:32.022Z,1532461952.022 [Startup](DEBUG): Aggregate::initialize Startup 2018-07-24T19:52:32.022Z,1532461952.022 [Startup:A.GoToSurface] Running Loop=1 2018-07-24T19:52:32.022Z,1532461952.022 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-07-24T19:52:32.023Z,1532461952.023 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-07-24T19:52:32.023Z,1532461952.023 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-07-24T19:52:32.023Z,1532461952.023 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-07-24T19:52:32.024Z,1532461952.024 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-07-24T19:52:32.041Z,1532461952.041 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-07-24T19:52:32.053Z,1532461952.053 [Startup:StartupSatComms] Running Loop=1 2018-07-24T19:52:32.053Z,1532461952.053 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-07-24T19:52:32.053Z,1532461952.053 [Startup:StartupSatComms:A] Running Loop=1 2018-07-24T19:52:32.412Z,1532461952.412 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:32.423Z,1532461952.423 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-07-24T19:52:32.801Z,1532461952.801 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:33.205Z,1532461953.205 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:33.594Z,1532461953.594 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:33.979Z,1532461953.979 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:34.385Z,1532461954.385 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:34.789Z,1532461954.789 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:35.189Z,1532461955.189 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:35.594Z,1532461955.594 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:35.993Z,1532461955.993 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:36.390Z,1532461956.390 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:36.794Z,1532461956.794 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:37.197Z,1532461957.197 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:37.602Z,1532461957.602 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:38.001Z,1532461958.001 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:38.403Z,1532461958.403 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:38.801Z,1532461958.801 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:39.196Z,1532461959.196 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:39.592Z,1532461959.592 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:40.001Z,1532461960.001 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:40.396Z,1532461960.396 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:40.801Z,1532461960.801 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:41.201Z,1532461961.201 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:41.604Z,1532461961.604 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:41.983Z,1532461961.983 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:42.378Z,1532461962.378 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:42.810Z,1532461962.810 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:43.215Z,1532461963.215 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:44.784Z,1532461964.784 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:45.188Z,1532461965.188 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:45.583Z,1532461965.583 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:45.988Z,1532461965.988 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:52:46.017Z,1532461966.017 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:52:46.017Z,1532461966.017 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:52:46.017Z,1532461966.017 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:52:46.018Z,1532461966.018 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:52:46.137Z,1532461966.137 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:52:46.399Z,1532461966.399 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:52:46.465Z,1532461966.465 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:52:46.797Z,1532461966.797 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:52:47.185Z,1532461967.185 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:52:47.289Z,1532461967.289 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:52:47.289Z,1532461967.289 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:52:47.603Z,1532461967.603 [RDI_Pathfinder](INFO): Start 2018-07-24T19:52:48.007Z,1532461968.007 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:48.404Z,1532461968.404 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:48.805Z,1532461968.805 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:49.206Z,1532461969.206 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:49.600Z,1532461969.600 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:49.996Z,1532461969.996 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:50.402Z,1532461970.402 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:50.801Z,1532461970.801 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:51.200Z,1532461971.200 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:51.601Z,1532461971.601 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:52.006Z,1532461972.006 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:52.396Z,1532461972.396 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:52.802Z,1532461972.802 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:53.209Z,1532461973.209 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:53.608Z,1532461973.608 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:54.011Z,1532461974.011 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:54.406Z,1532461974.406 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:54.784Z,1532461974.784 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:55.201Z,1532461975.201 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:55.583Z,1532461975.583 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:55.983Z,1532461975.983 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:56.401Z,1532461976.401 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:56.783Z,1532461976.783 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:57.183Z,1532461977.183 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:57.602Z,1532461977.602 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:57.983Z,1532461977.983 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:58.402Z,1532461978.402 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:58.815Z,1532461978.815 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:59.211Z,1532461979.211 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:52:59.610Z,1532461979.610 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:01.188Z,1532461981.188 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:01.584Z,1532461981.584 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:01.985Z,1532461981.985 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:02.384Z,1532461982.384 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:02.413Z,1532461982.413 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:53:02.413Z,1532461982.413 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:53:02.413Z,1532461982.413 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:53:02.414Z,1532461982.414 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:53:02.553Z,1532461982.553 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:53:02.797Z,1532461982.797 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:53:02.867Z,1532461982.867 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:53:03.193Z,1532461983.193 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:53:03.605Z,1532461983.605 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:53:03.727Z,1532461983.727 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:53:03.727Z,1532461983.727 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:53:04.000Z,1532461984.000 [RDI_Pathfinder](INFO): Start 2018-07-24T19:53:04.398Z,1532461984.398 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:04.809Z,1532461984.809 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:05.195Z,1532461985.195 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:05.594Z,1532461985.594 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:05.996Z,1532461985.996 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:06.396Z,1532461986.396 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:06.800Z,1532461986.800 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:07.195Z,1532461987.195 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:07.601Z,1532461987.601 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:07.999Z,1532461987.999 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:08.401Z,1532461988.401 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:08.803Z,1532461988.803 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:09.202Z,1532461989.202 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:09.604Z,1532461989.604 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:09.998Z,1532461989.998 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:10.394Z,1532461990.394 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:10.796Z,1532461990.796 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:11.192Z,1532461991.192 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:11.598Z,1532461991.598 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:11.979Z,1532461991.979 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:12.380Z,1532461992.380 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:12.779Z,1532461992.779 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:13.179Z,1532461993.179 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:13.582Z,1532461993.582 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:14.019Z,1532461994.019 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:14.414Z,1532461994.414 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:14.813Z,1532461994.813 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:15.209Z,1532461995.209 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:15.587Z,1532461995.587 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:15.988Z,1532461995.988 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:16.387Z,1532461996.387 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:17.598Z,1532461997.598 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:17.994Z,1532461997.994 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:18.399Z,1532461998.399 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:18.791Z,1532461998.791 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:18.817Z,1532461998.817 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:53:18.817Z,1532461998.817 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:53:18.817Z,1532461998.817 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:53:18.817Z,1532461998.817 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:53:18.961Z,1532461998.961 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:53:19.221Z,1532461999.221 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:53:19.293Z,1532461999.293 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:53:19.608Z,1532461999.608 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:53:20.009Z,1532462000.009 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:53:20.103Z,1532462000.103 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:53:20.103Z,1532462000.103 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:53:20.409Z,1532462000.409 [RDI_Pathfinder](INFO): Start 2018-07-24T19:53:20.802Z,1532462000.802 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:21.196Z,1532462001.196 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:21.597Z,1532462001.597 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:21.979Z,1532462001.979 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:22.395Z,1532462002.395 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:22.800Z,1532462002.800 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:23.179Z,1532462003.179 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:23.596Z,1532462003.596 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:23.998Z,1532462003.998 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:24.410Z,1532462004.410 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:24.806Z,1532462004.806 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:25.211Z,1532462005.211 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:25.610Z,1532462005.610 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:25.999Z,1532462005.999 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:26.402Z,1532462006.402 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:26.783Z,1532462006.783 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:27.199Z,1532462007.199 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:27.601Z,1532462007.601 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:27.000Z,1532462008.000 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:28.384Z,1532462008.384 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:28.784Z,1532462008.784 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:29.182Z,1532462009.182 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:29.615Z,1532462009.615 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:30.015Z,1532462010.015 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:30.410Z,1532462010.410 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:30.809Z,1532462010.809 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:31.184Z,1532462011.184 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:31.579Z,1532462011.579 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:31.983Z,1532462011.983 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:32.386Z,1532462012.386 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:32.397Z,1532462012.397 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-24T19:52:32.1Z 2018-07-24T19:53:32.397Z,1532462012.397 [Startup:StartupSatComms:A] Stopped 2018-07-24T19:53:32.397Z,1532462012.397 [Startup:StartupSatComms:B] Running Loop=1 2018-07-24T19:53:32.792Z,1532462012.792 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:32.811Z,1532462012.811 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-07-24T19:53:33.997Z,1532462013.997 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:34.387Z,1532462014.387 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:34.811Z,1532462014.811 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:35.210Z,1532462015.210 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:35.241Z,1532462015.241 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:53:35.241Z,1532462015.241 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:53:35.241Z,1532462015.241 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:53:35.241Z,1532462015.241 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:53:35.358Z,1532462015.358 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:53:35.616Z,1532462015.616 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:53:35.689Z,1532462015.689 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:53:35.998Z,1532462015.997 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:53:36.387Z,1532462016.387 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:53:36.501Z,1532462016.501 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:53:36.501Z,1532462016.501 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:53:36.791Z,1532462016.791 [RDI_Pathfinder](INFO): Start 2018-07-24T19:53:37.188Z,1532462017.188 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:37.593Z,1532462017.593 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:37.998Z,1532462017.998 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:38.386Z,1532462018.386 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:38.796Z,1532462018.796 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:39.189Z,1532462019.189 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:39.599Z,1532462019.599 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:39.998Z,1532462019.998 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:40.397Z,1532462020.397 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:40.803Z,1532462020.803 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:41.202Z,1532462021.202 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:41.596Z,1532462021.596 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:42.001Z,1532462022.001 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:42.395Z,1532462022.395 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:42.795Z,1532462022.795 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:43.201Z,1532462023.201 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:43.595Z,1532462023.595 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:43.984Z,1532462023.984 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:44.377Z,1532462024.377 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:44.780Z,1532462024.780 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:45.210Z,1532462025.210 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:45.611Z,1532462025.611 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:45.982Z,1532462025.982 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:46.387Z,1532462026.387 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:46.781Z,1532462026.781 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:47.188Z,1532462027.188 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:47.589Z,1532462027.589 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:47.990Z,1532462027.990 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:48.389Z,1532462028.389 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:48.788Z,1532462028.788 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:49.201Z,1532462029.201 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:50.026Z,1532462030.026 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:50.405Z,1532462030.405 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:50.793Z,1532462030.793 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:53:50.807Z,1532462030.807 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:53:50.807Z,1532462030.807 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:53:50.807Z,1532462030.807 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:53:50.808Z,1532462030.808 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:53:50.937Z,1532462030.937 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:53:51.199Z,1532462031.199 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:53:51.269Z,1532462031.269 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:53:51.601Z,1532462031.601 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:53:51.989Z,1532462031.989 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:53:52.098Z,1532462032.098 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:53:52.098Z,1532462032.098 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:53:52.400Z,1532462032.400 [RDI_Pathfinder](INFO): Start 2018-07-24T19:53:52.783Z,1532462032.783 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:53.184Z,1532462033.184 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:53.584Z,1532462033.584 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:54.002Z,1532462034.002 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:54.410Z,1532462034.410 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:54.809Z,1532462034.809 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:55.210Z,1532462035.210 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:55.610Z,1532462035.610 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:55.983Z,1532462035.983 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:56.384Z,1532462036.384 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:56.786Z,1532462036.786 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:57.195Z,1532462037.195 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:57.598Z,1532462037.598 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:57.997Z,1532462037.997 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:58.395Z,1532462038.395 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:58.791Z,1532462038.791 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:59.196Z,1532462039.196 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:53:59.602Z,1532462039.602 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:00.001Z,1532462040.001 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:00.403Z,1532462040.403 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:00.813Z,1532462040.813 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:01.192Z,1532462041.192 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:01.592Z,1532462041.592 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:01.000Z,1532462042.000 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:02.377Z,1532462042.377 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:02.799Z,1532462042.799 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:03.201Z,1532462043.201 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:03.601Z,1532462043.601 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:03.000Z,1532462044.000 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:04.400Z,1532462044.400 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:04.811Z,1532462044.811 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:06.002Z,1532462046.002 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:54:06.410Z,1532462046.410 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:54:06.810Z,1532462046.810 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:54:07.216Z,1532462047.216 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:54:07.241Z,1532462047.241 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2018-07-24T19:54:07.241Z,1532462047.241 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-07-24T19:54:07.241Z,1532462047.241 [RDI_Pathfinder](ERROR): Communications Fault 2018-07-24T19:54:07.241Z,1532462047.241 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-24T19:54:07.378Z,1532462047.378 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-07-24T19:54:07.608Z,1532462047.608 [RDI_Pathfinder](INFO): Stop 2018-07-24T19:54:07.677Z,1532462047.677 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:54:07.987Z,1532462047.987 [RDI_Pathfinder](INFO): Stopping 2018-07-24T19:54:08.383Z,1532462048.383 [RDI_Pathfinder](INFO): Stopped 2018-07-24T19:54:08.525Z,1532462048.525 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-07-24T19:54:08.525Z,1532462048.525 [RDI_Pathfinder] No Fault, FailCount= 1 2018-07-24T19:54:08.787Z,1532462048.787 [RDI_Pathfinder](INFO): Start 2018-07-24T19:54:09.195Z,1532462049.195 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:09.597Z,1532462049.597 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:09.605Z,1532462049.605 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-24T19:54:09.606Z,1532462049.606 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-07-24T19:54:09.606Z,1532462049.606 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-07-24T19:54:09.607Z,1532462049.607 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-24T19:54:09.608Z,1532462049.608 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-07-24T19:54:09.608Z,1532462049.608 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-07-24T19:54:09.726Z,1532462049.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-07-24T19:54:09.726Z,1532462049.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-07-24T19:54:10.001Z,1532462050.001 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:10.089Z,1532462050.089 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-07-24T19:54:10.089Z,1532462050.089 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-07-24T19:54:10.090Z,1532462050.090 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-07-24T19:54:10.090Z,1532462050.090 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-07-24T19:54:10.399Z,1532462050.399 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:10.411Z,1532462050.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-07-24T19:54:10.411Z,1532462050.411 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-07-24T19:54:10.412Z,1532462050.412 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-07-24T19:54:10.412Z,1532462050.412 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-07-24T19:54:10.413Z,1532462050.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-07-24T19:54:10.413Z,1532462050.413 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-07-24T19:54:10.810Z,1532462050.810 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:10.818Z,1532462050.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2018-07-24T19:54:17.423Z,1532462057.423 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:17.463Z,1532462057.463 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.9 s old, using for 20.0 s. 2018-07-24T19:54:17.605Z,1532462057.605 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:17.644Z,1532462057.644 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2018-07-24T19:54:18.014Z,1532462058.014 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:18.029Z,1532462058.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2018-07-24T19:54:18.365Z,1532462058.365 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004290 2018-07-24T19:54:18.406Z,1532462058.406 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:18.431Z,1532462058.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2018-07-24T19:54:18.896Z,1532462058.896 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:18.925Z,1532462058.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s. 2018-07-24T19:54:19.192Z,1532462059.192 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:19.233Z,1532462059.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2018-07-24T19:54:21.793Z,1532462061.793 [CommandLine](IMPORTANT): got command restart application 2018-07-24T19:54:22.397Z,1532462062.397 [RDI_Pathfinder](INFO): Starting 2018-07-24T19:54:22.439Z,1532462062.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s. 2018-07-24T19:54:22.551Z,1532462062.551 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180724T182326/Courier0031.lzma 2018-07-24T19:54:22.598Z,1532462062.598 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:54:22.695Z,1532462062.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.1 s old, using for 20.0 s. 2018-07-24T19:54:22.800Z,1532462062.800 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-24T19:54:22.800Z,1532462062.800 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:22.897Z,1532462062.897 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-07-24T19:54:22.897Z,1532462062.897 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:22.897Z,1532462062.897 [CommandLine](INFO): Join timeout helper Thread ID is 5083 2018-07-24T19:54:22.898Z,1532462062.898 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-07-24T19:54:22.898Z,1532462062.898 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:22.899Z,1532462062.899 [NavChartDb](INFO): Join timeout helper Thread ID is 5084 2018-07-24T19:54:22.985Z,1532462062.985 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:54:23.041Z,1532462063.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s. 2018-07-24T19:54:23.141Z,1532462063.141 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-24T19:54:23.141Z,1532462063.141 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.157Z,1532462063.157 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-07-24T19:54:23.157Z,1532462063.157 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.157Z,1532462063.157 [Radio_Surface](INFO): Join timeout helper Thread ID is 5085 2018-07-24T19:54:23.225Z,1532462063.225 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-24T19:54:23.225Z,1532462063.225 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.237Z,1532462063.237 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2018-07-24T19:54:23.237Z,1532462063.237 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.237Z,1532462063.237 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 5086 2018-07-24T19:54:23.354Z,1532462063.354 [DataOverHttps](INFO): Moved sent file to Logs/20180724T182326/Courier0031.lzma.bak 2018-07-24T19:54:23.354Z,1532462063.354 [DataOverHttps](INFO): SBD MOMSN=8374541 2018-07-24T19:54:23.403Z,1532462063.403 [RDI_Pathfinder](INFO): Message in queue 2018-07-24T19:54:23.440Z,1532462063.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s. 2018-07-24T19:54:23.551Z,1532462063.551 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-24T19:54:23.551Z,1532462063.551 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-07-24T19:54:23.552Z,1532462063.552 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.557Z,1532462063.557 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-07-24T19:54:23.557Z,1532462063.557 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.557Z,1532462063.557 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5087 2018-07-24T19:54:23.597Z,1532462063.597 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-24T19:54:23.597Z,1532462063.597 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.599Z,1532462063.599 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-07-24T19:54:23.599Z,1532462063.599 [logger ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.599Z,1532462063.599 [logger](INFO): Join timeout helper Thread ID is 5088 2018-07-24T19:54:23.614Z,1532462063.614 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-24T19:54:23.615Z,1532462063.615 [logger ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.617Z,1532462063.617 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-07-24T19:54:23.618Z,1532462063.618 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.618Z,1532462063.618 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-07-24T19:54:23.618Z,1532462063.618 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:23.618Z,1532462063.618 [controlThread](INFO): Join timeout helper Thread ID is 5089 2018-07-24T19:54:23.741Z,1532462063.741 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-24T19:54:23.741Z,1532462063.741 [controlThread](DEBUG): Uninitializing ControlThread 2018-07-24T19:54:23.741Z,1532462063.741 [AHRS_M2](INFO): Powering down 2018-07-24T19:54:23.814Z,1532462063.814 [NAL9602](INFO): Powering down 2018-07-24T19:54:23.885Z,1532462063.885 [RDI_Pathfinder](INFO): Powering down 2018-07-24T19:54:23.887Z,1532462063.887 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-07-24T19:54:23.888Z,1532462063.888 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-07-24T19:54:23.890Z,1532462063.890 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-07-24T19:54:23.891Z,1532462063.891 [MissionManager](INFO): Uninitializing Mission Default 2018-07-24T19:54:23.902Z,1532462063.902 [MissionManager](INFO): Uninitializing Mission Startup 2018-07-24T19:54:23.902Z,1532462063.902 [Startup] Stopped 2018-07-24T19:54:23.902Z,1532462063.902 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-07-24T19:54:23.902Z,1532462063.902 [Startup:A.GoToSurface] Stopped 2018-07-24T19:54:23.902Z,1532462063.902 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-07-24T19:54:23.902Z,1532462063.902 [Startup:StartupSatComms] Stopped 2018-07-24T19:54:23.903Z,1532462063.903 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-07-24T19:54:23.903Z,1532462063.903 [Startup:StartupSatComms:B] Stopped 2018-07-24T19:54:23.904Z,1532462063.904 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-07-24T19:54:23.905Z,1532462063.905 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-07-24T19:54:23.905Z,1532462063.905 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-07-24T19:54:23.905Z,1532462063.905 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-07-24T19:54:23.906Z,1532462063.906 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-24T19:54:23.906Z,1532462063.906 [BuoyancyServo](INFO): Powering down 2018-07-24T19:54:23.921Z,1532462063.921 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-07-24T19:54:23.921Z,1532462063.921 [ElevatorServo](INFO): Powering down 2018-07-24T19:54:23.922Z,1532462063.922 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-07-24T19:54:23.922Z,1532462063.922 [MassServo](INFO): Powering down 2018-07-24T19:54:23.923Z,1532462063.923 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-07-24T19:54:23.923Z,1532462063.923 [RudderServo](INFO): Powering down 2018-07-24T19:54:23.924Z,1532462063.924 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-07-24T19:54:23.924Z,1532462063.924 [ThrusterServo](INFO): Powering down 2018-07-24T19:54:23.925Z,1532462063.925 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-07-24T19:54:23.926Z,1532462063.926 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-07-24T19:54:23.926Z,1532462063.926 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-07-24T19:54:23.927Z,1532462063.927 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:24.028Z,1532462064.028 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:24.031Z,1532462064.031 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:24.088Z,1532462064.088 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:24.251Z,1532462064.251 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-24T19:54:24.306Z,1532462064.306 [logger ThreadHandler](INFO): Thread cancelled.