2018-07-13T14:34:31.530Z,1531492471.530 [Supervisor](DEBUG): Initializing supervisor. 2018-07-13T14:34:31.533Z,1531492471.533 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-07-13T14:34:31.533Z,1531492471.533 [SyncHandler](INFO): Protected caller Thread ID is 799 2018-07-13T14:34:31.534Z,1531492471.534 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-07-13T14:34:31.535Z,1531492471.535 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-07-13T14:34:31.535Z,1531492471.535 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800 2018-07-13T14:34:31.538Z,1531492471.538 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-07-13T14:34:31.549Z,1531492471.549 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-07-13T14:34:31.550Z,1531492471.550 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-07-13T14:34:31.551Z,1531492471.551 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801 2018-07-13T14:34:31.551Z,1531492471.551 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-07-13T14:34:31.552Z,1531492471.552 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-07-13T14:34:31.553Z,1531492471.553 [logger ThreadHandler](INFO): Protected caller Thread ID is 802 2018-07-13T14:34:31.555Z,1531492471.555 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-07-13T14:34:31.555Z,1531492471.555 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-07-13T14:34:31.559Z,1531492471.559 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-07-13T14:34:31.755Z,1531492471.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-07-13T14:34:31.757Z,1531492471.757 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-07-13T14:34:31.901Z,1531492471.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-07-13T14:34:31.903Z,1531492471.903 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-07-13T14:34:32.249Z,1531492472.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-07-13T14:34:32.250Z,1531492472.250 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-07-13T14:34:32.389Z,1531492472.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-07-13T14:34:32.390Z,1531492472.390 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-07-13T14:34:32.471Z,1531492472.471 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-07-13T14:34:32.674Z,1531492472.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-07-13T14:34:32.675Z,1531492472.675 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-07-13T14:34:32.774Z,1531492472.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-07-13T14:34:32.774Z,1531492472.774 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-07-13T14:34:33.113Z,1531492473.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-07-13T14:34:33.114Z,1531492473.114 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-07-13T14:34:33.570Z,1531492473.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-07-13T14:34:33.571Z,1531492473.571 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-07-13T14:34:33.878Z,1531492473.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-07-13T14:34:33.878Z,1531492473.878 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-07-13T14:34:34.382Z,1531492474.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-07-13T14:34:34.383Z,1531492474.383 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-07-13T14:34:34.591Z,1531492474.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-07-13T14:34:34.592Z,1531492474.592 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-07-13T14:34:34.690Z,1531492474.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-07-13T14:34:34.691Z,1531492474.691 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-07-13T14:34:35.248Z,1531492475.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-07-13T14:34:35.249Z,1531492475.249 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-07-13T14:34:38.978Z,1531492478.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-07-13T14:34:38.979Z,1531492478.979 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-07-13T14:34:38.985Z,1531492478.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-07-13T14:34:39.219Z,1531492479.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-07-13T14:34:39.221Z,1531492479.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-07-13T14:34:39.321Z,1531492479.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-07-13T14:34:39.417Z,1531492479.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-07-13T14:34:39.521Z,1531492479.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-07-13T14:34:39.608Z,1531492479.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-07-13T14:34:39.742Z,1531492479.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-07-13T14:34:39.910Z,1531492479.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-07-13T14:34:40.023Z,1531492480.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-07-13T14:34:40.108Z,1531492480.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-07-13T14:34:40.194Z,1531492480.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-07-13T14:34:40.388Z,1531492480.388 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-07-13T14:34:40.815Z,1531492480.815 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-07-13T14:34:41.160Z,1531492481.160 [SBIT](DEBUG): Construct Startup Built In Test. 2018-07-13T14:34:41.190Z,1531492481.190 [SBIT] Loaded 2018-07-13T14:34:41.190Z,1531492481.190 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-07-13T14:34:41.191Z,1531492481.191 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-07-13T14:34:41.219Z,1531492481.219 [IBIT] Loaded 2018-07-13T14:34:41.219Z,1531492481.219 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-07-13T14:34:41.222Z,1531492481.222 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-07-13T14:34:41.377Z,1531492481.377 [CBIT] Loaded 2018-07-13T14:34:41.378Z,1531492481.378 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-07-13T14:34:41.378Z,1531492481.378 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-07-13T14:34:41.379Z,1531492481.379 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-07-13T14:34:41.466Z,1531492481.466 [VerticalControl](DEBUG): Construct VerticalControl. 2018-07-13T14:34:41.566Z,1531492481.566 [VerticalControl] Loaded 2018-07-13T14:34:41.566Z,1531492481.566 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-07-13T14:34:41.567Z,1531492481.567 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-07-13T14:34:41.627Z,1531492481.627 [HorizontalControl] Loaded 2018-07-13T14:34:41.627Z,1531492481.627 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-07-13T14:34:41.628Z,1531492481.628 [SpeedControl](DEBUG): Construct SpeedControl. 2018-07-13T14:34:41.634Z,1531492481.634 [SpeedControl] Loaded 2018-07-13T14:34:41.634Z,1531492481.634 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-07-13T14:34:41.634Z,1531492481.634 [LoopControl](DEBUG): Construct LoopControl. 2018-07-13T14:34:41.635Z,1531492481.635 [LoopControl] Loaded 2018-07-13T14:34:41.635Z,1531492481.635 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-07-13T14:34:41.636Z,1531492481.636 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-07-13T14:34:41.636Z,1531492481.636 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-07-13T14:34:41.692Z,1531492481.692 [DepthRateCalculator] Loaded 2018-07-13T14:34:41.692Z,1531492481.692 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-07-13T14:34:41.697Z,1531492481.697 [PitchRateCalculator] Loaded 2018-07-13T14:34:41.698Z,1531492481.698 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-07-13T14:34:41.714Z,1531492481.714 [SpeedCalculator] Loaded 2018-07-13T14:34:41.714Z,1531492481.714 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-07-13T14:34:41.735Z,1531492481.735 [TempGradientCalculator] Loaded 2018-07-13T14:34:41.736Z,1531492481.736 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-07-13T14:34:41.751Z,1531492481.751 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-07-13T14:34:41.751Z,1531492481.751 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-07-13T14:34:41.756Z,1531492481.756 [YawRateCalculator] Loaded 2018-07-13T14:34:41.757Z,1531492481.757 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-07-13T14:34:41.786Z,1531492481.786 [ElevatorOffsetCalculator] Loaded 2018-07-13T14:34:41.786Z,1531492481.786 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-07-13T14:34:41.786Z,1531492481.786 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-07-13T14:34:41.787Z,1531492481.787 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-07-13T14:34:41.908Z,1531492481.908 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-07-13T14:34:41.908Z,1531492481.908 [StratificationFrontDetector](DEBUG): (re)initializing 2018-07-13T14:34:41.909Z,1531492481.909 [StratificationFrontDetector] Loaded 2018-07-13T14:34:41.909Z,1531492481.909 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-07-13T14:34:41.910Z,1531492481.910 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-07-13T14:34:41.910Z,1531492481.910 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-07-13T14:34:42.194Z,1531492482.194 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-07-13T14:34:42.195Z,1531492482.195 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-07-13T14:34:42.307Z,1531492482.307 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-07-13T14:34:42.308Z,1531492482.308 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-07-13T14:34:42.362Z,1531492482.362 [DeadReckonUsingSpeedCalculator] Loaded 2018-07-13T14:34:42.362Z,1531492482.362 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-07-13T14:34:42.379Z,1531492482.379 [NavChart] Loaded 2018-07-13T14:34:42.379Z,1531492482.379 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-07-13T14:34:42.383Z,1531492482.383 [UniversalFixResidualReporter] Loaded 2018-07-13T14:34:42.384Z,1531492482.384 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-07-13T14:34:42.384Z,1531492482.384 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-07-13T14:34:42.385Z,1531492482.385 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-07-13T14:34:42.409Z,1531492482.409 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-07-13T14:34:42.410Z,1531492482.410 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-07-13T14:34:42.605Z,1531492482.605 [PAR_Licor] Loaded 2018-07-13T14:34:42.605Z,1531492482.605 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-07-13T14:34:42.655Z,1531492482.655 [WetLabsBB2FL] Loaded 2018-07-13T14:34:42.655Z,1531492482.655 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-07-13T14:34:42.656Z,1531492482.656 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406D24E0 2018-07-13T14:34:42.657Z,1531492482.657 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 881 2018-07-13T14:34:42.700Z,1531492482.700 [WetLabsSeaOWL_UV_A] Loaded 2018-07-13T14:34:42.700Z,1531492482.700 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-07-13T14:34:42.701Z,1531492482.701 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407024E0 2018-07-13T14:34:42.702Z,1531492482.702 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 882 2018-07-13T14:34:42.702Z,1531492482.702 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-07-13T14:34:42.703Z,1531492482.703 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-07-13T14:34:43.304Z,1531492483.304 [AHRS_M2] Loaded 2018-07-13T14:34:43.304Z,1531492483.304 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-07-13T14:34:43.437Z,1531492483.437 [DataOverHttps] Loaded 2018-07-13T14:34:43.437Z,1531492483.437 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-07-13T14:34:43.451Z,1531492483.451 [Depth_Keller] Loaded 2018-07-13T14:34:43.451Z,1531492483.451 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-07-13T14:34:43.542Z,1531492483.542 [NAL9602] Loaded 2018-07-13T14:34:43.542Z,1531492483.542 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-07-13T14:34:43.548Z,1531492483.548 [Onboard] Loaded 2018-07-13T14:34:43.548Z,1531492483.548 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-07-13T14:34:43.555Z,1531492483.555 [Radio_Surface] Loaded 2018-07-13T14:34:43.555Z,1531492483.555 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-07-13T14:34:43.556Z,1531492483.556 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409584E0 2018-07-13T14:34:43.557Z,1531492483.557 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883 2018-07-13T14:34:43.585Z,1531492483.585 [RDI_Pathfinder] Loaded 2018-07-13T14:34:43.586Z,1531492483.586 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-07-13T14:34:43.592Z,1531492483.592 [SCPI] Loaded 2018-07-13T14:34:43.592Z,1531492483.592 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-07-13T14:34:45.386Z,1531492485.386 [BPC1] Loaded 2018-07-13T14:34:45.386Z,1531492485.386 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-07-13T14:34:45.386Z,1531492485.386 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-07-13T14:34:45.387Z,1531492485.387 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-07-13T14:34:45.551Z,1531492485.551 [BuoyancyServo] Loaded 2018-07-13T14:34:45.552Z,1531492485.552 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-07-13T14:34:45.564Z,1531492485.564 [ElevatorServo] Loaded 2018-07-13T14:34:45.564Z,1531492485.564 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-07-13T14:34:45.575Z,1531492485.575 [MassServo] Loaded 2018-07-13T14:34:45.576Z,1531492485.576 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-07-13T14:34:45.587Z,1531492485.587 [RudderServo] Loaded 2018-07-13T14:34:45.587Z,1531492485.587 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-07-13T14:34:45.598Z,1531492485.598 [ThrusterServo] Loaded 2018-07-13T14:34:45.599Z,1531492485.599 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-07-13T14:34:45.599Z,1531492485.599 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-07-13T14:34:45.600Z,1531492485.600 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-07-13T14:34:45.725Z,1531492485.725 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-07-13T14:34:45.726Z,1531492485.726 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-07-13T14:34:45.763Z,1531492485.763 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-07-13T14:34:45.767Z,1531492485.767 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-07-13T14:34:45.768Z,1531492485.768 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-07-13T14:34:45.775Z,1531492485.775 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-07-13T14:34:45.776Z,1531492485.776 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A014E0 2018-07-13T14:34:45.776Z,1531492485.776 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 884 2018-07-13T14:34:45.780Z,1531492485.780 [Supervisor](INFO): Main Thread ID is 795 2018-07-13T14:34:45.781Z,1531492485.781 [Supervisor](DEBUG): Running supervisor. 2018-07-13T14:34:45.781Z,1531492485.781 [CommandLine ThreadHandler](INFO): Handler Thread ID is 885 2018-07-13T14:34:45.784Z,1531492485.784 [controlThread ThreadHandler](INFO): Handler Thread ID is 886 2018-07-13T14:34:45.784Z,1531492485.784 [controlThread](DEBUG): Initializing ControlThread 2018-07-13T14:34:45.785Z,1531492485.785 [SBIT](INFO): Initialize SBIT Component. 2018-07-13T14:34:45.786Z,1531492485.786 [SBIT](IMPORTANT): git: 2018-07-13 2018-07-13T14:34:45.786Z,1531492485.786 [SBIT](INFO): git hash: ae065fbea347cf1ac973ed35c66daddf0c5c0441 2018-07-13T14:34:45.786Z,1531492485.786 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-07-13T14:34:45.788Z,1531492485.788 [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-13T14:34:45.789Z,1531492485.789 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-07-13T14:34:45.789Z,1531492485.789 [IBIT](INFO): Initialize IBIT Component. 2018-07-13T14:34:45.790Z,1531492485.790 [CBIT](DEBUG): Initialize CBIT Component. 2018-07-13T14:34:45.790Z,1531492485.790 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-07-13T14:34:45.791Z,1531492485.791 [logger ThreadHandler](INFO): Handler Thread ID is 887 2018-07-13T14:34:45.809Z,1531492485.809 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 888 2018-07-13T14:34:45.821Z,1531492485.821 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 889 2018-07-13T14:34:45.822Z,1531492485.822 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-07-13T14:34:45.841Z,1531492485.841 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 890 2018-07-13T14:34:45.857Z,1531492485.857 [Radio_Surface](INFO): Powering up 2018-07-13T14:34:45.859Z,1531492485.859 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 891 2018-07-13T14:34:45.870Z,1531492485.870 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-07-13T14:34:45.870Z,1531492485.870 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-07-13T14:34:45.871Z,1531492485.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-07-13T14:34:45.871Z,1531492485.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-07-13T14:34:45.871Z,1531492485.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-07-13T14:34:45.871Z,1531492485.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-07-13T14:34:45.871Z,1531492485.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-07-13T14:34:45.872Z,1531492485.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-07-13T14:34:45.872Z,1531492485.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-07-13T14:34:45.872Z,1531492485.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-07-13T14:34:45.872Z,1531492485.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-07-13T14:34:45.872Z,1531492485.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-07-13T14:34:45.873Z,1531492485.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-07-13T14:34:45.873Z,1531492485.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-07-13T14:34:45.873Z,1531492485.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-07-13T14:34:45.874Z,1531492485.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-07-13T14:34:45.897Z,1531492485.897 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-07-13T14:34:45.899Z,1531492485.899 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-07-13T14:34:45.900Z,1531492485.900 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-07-13T14:34:45.900Z,1531492485.900 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-07-13T14:34:45.900Z,1531492485.900 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-07-13T14:34:45.901Z,1531492485.901 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-07-13T14:34:45.901Z,1531492485.901 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-07-13T14:34:45.902Z,1531492485.902 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-07-13T14:34:45.902Z,1531492485.902 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-07-13T14:34:45.903Z,1531492485.903 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-07-13T14:34:45.903Z,1531492485.903 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-07-13T14:34:45.904Z,1531492485.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-07-13T14:34:45.905Z,1531492485.905 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-07-13T14:34:45.905Z,1531492485.905 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-07-13T14:34:45.906Z,1531492485.906 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-07-13T14:34:45.906Z,1531492485.906 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-07-13T14:34:45.907Z,1531492485.907 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-07-13T14:34:45.907Z,1531492485.907 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-07-13T14:34:45.907Z,1531492485.907 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-07-13T14:34:45.928Z,1531492485.928 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-07-13T14:34:45.994Z,1531492485.994 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-07-13T14:34:46.033Z,1531492486.033 [MissionManager](DEBUG): 2018-07-13T14:34:46.034Z,1531492486.034 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-07-13T14:34:46.112Z,1531492486.112 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-07-13T14:34:46.113Z,1531492486.113 [Default:A.Wait](DEBUG): Construct Wait. 2018-07-13T14:34:46.115Z,1531492486.115 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-07-13T14:34:46.165Z,1531492486.165 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-07-13T14:34:46.168Z,1531492486.168 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-07-13T14:34:46.198Z,1531492486.198 [Default:E.Execute](DEBUG): Construct Execute. 2018-07-13T14:34:46.222Z,1531492486.222 [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-13T14:34:46.227Z,1531492486.227 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,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-13T14:34:46.289Z,1531492486.289 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-07-13T14:34:46.396Z,1531492486.396 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-07-13T14:34:46.420Z,1531492486.420 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-07-13T14:34:46.671Z,1531492486.671 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-07-13T14:34:46.781Z,1531492486.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:46.835Z,1531492486.835 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:46.955Z,1531492486.955 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T14:34:46.961Z,1531492486.961 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T14:34:46.968Z,1531492486.968 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-07-13T14:34:46.973Z,1531492486.973 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-07-13T14:34:46.991Z,1531492486.991 [MassServo](DEBUG): Initializing EZServoServo. 2018-07-13T14:34:46.997Z,1531492486.997 [MassServo](DEBUG): Initializing MassServo. 2018-07-13T14:34:47.011Z,1531492487.011 [RudderServo](DEBUG): Initializing EZServoServo. 2018-07-13T14:34:47.017Z,1531492487.017 [RudderServo](DEBUG): Initializing RudderServo. 2018-07-13T14:34:47.031Z,1531492487.031 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-07-13T14:34:47.037Z,1531492487.037 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-07-13T14:34:47.249Z,1531492487.249 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:47.250Z,1531492487.250 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:47.558Z,1531492487.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:47.559Z,1531492487.559 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:47.897Z,1531492487.897 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-07-13T14:34:47.897Z,1531492487.897 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-07-13T14:34:47.897Z,1531492487.897 [BuoyancyServo] Communications Fault, FailCount= 1 2018-07-13T14:34:47.897Z,1531492487.897 [BuoyancyServo](ERROR): Communications Fault 2018-07-13T14:34:48.101Z,1531492488.101 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-07-13T14:34:48.441Z,1531492488.441 [AHRS_M2](ERROR): Stop data-stream UART error: serial timeout 2018-07-13T14:34:48.497Z,1531492488.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:48.498Z,1531492488.498 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:48.511Z,1531492488.511 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T14:34:48.511Z,1531492488.511 [BuoyancyServo](INFO): Powering down 2018-07-13T14:34:48.957Z,1531492488.957 [AHRS_M2](ERROR): Stop data-stream UART error: serial timeout 2018-07-13T14:34:49.005Z,1531492489.005 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:49.006Z,1531492489.006 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:49.291Z,1531492489.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:49.293Z,1531492489.293 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:49.482Z,1531492489.482 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:49.483Z,1531492489.483 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:49.889Z,1531492489.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:49.890Z,1531492489.890 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:50.289Z,1531492490.289 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:50.290Z,1531492490.290 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:50.715Z,1531492490.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:50.716Z,1531492490.716 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:51.105Z,1531492491.105 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T14:34:51.105Z,1531492491.105 [BuoyancyServo] No Fault, FailCount= 1 2018-07-13T14:34:51.218Z,1531492491.218 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:51.220Z,1531492491.220 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:51.242Z,1531492491.242 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T14:34:51.363Z,1531492491.363 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T14:34:51.578Z,1531492491.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:51.579Z,1531492491.579 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:51.994Z,1531492491.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:51.995Z,1531492491.995 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:52.379Z,1531492492.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:52.380Z,1531492492.380 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:52.802Z,1531492492.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:52.803Z,1531492492.803 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:53.165Z,1531492493.165 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:53.167Z,1531492493.167 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:53.562Z,1531492493.562 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:53.563Z,1531492493.563 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:53.958Z,1531492493.958 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:53.959Z,1531492493.959 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:54.391Z,1531492494.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:54.392Z,1531492494.392 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:54.772Z,1531492494.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:54.802Z,1531492494.802 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:55.210Z,1531492495.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:55.211Z,1531492495.211 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:55.590Z,1531492495.590 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:55.591Z,1531492495.591 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:55.978Z,1531492495.978 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:55.979Z,1531492495.979 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:56.398Z,1531492496.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:56.399Z,1531492496.399 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:56.795Z,1531492496.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:56.796Z,1531492496.796 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:57.167Z,1531492497.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:57.168Z,1531492497.168 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:57.557Z,1531492497.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:57.558Z,1531492497.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:57.957Z,1531492497.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:57.958Z,1531492497.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:58.366Z,1531492498.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:58.367Z,1531492498.367 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:58.773Z,1531492498.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:58.775Z,1531492498.775 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:59.150Z,1531492499.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:59.151Z,1531492499.151 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:59.550Z,1531492499.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:59.551Z,1531492499.551 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:34:59.996Z,1531492499.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:35:00.005Z,1531492500.005 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:35:00.395Z,1531492500.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:35:00.396Z,1531492500.396 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-13T14:35:13.926Z,1531492513.926 [NAL9602](INFO): Powering up NAL9602 2018-07-13T14:35:14.400Z,1531492514.400 [SBIT](IMPORTANT): Beginning Startup BIT 2018-07-13T14:35:14.404Z,1531492514.404 [CBIT](IMPORTANT): Beginning ground fault scan 2018-07-13T14:35:24.866Z,1531492524.866 [NAL9602](INFO): NAL9602 initialized 2018-07-13T14:35:25.418Z,1531492525.418 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.009545 CHAN A1 (24V): -0.013441 CHAN A2 (12V): -0.007062 CHAN A3 (5V): -0.001999 CHAN B0 (3.3V): 0.000125 CHAN B1 (3.15aV): 0.000090 CHAN B2 (3.15bV): 0.000033 CHAN B3 (GND): 0.002318 OPEN: 0.006518 Full Scale Calc: 4.765 mA, -1.589 mA 2018-07-13T14:36:08.488Z,1531492568.488 [SBIT](IMPORTANT): SBIT PASSED 2018-07-13T14:36:08.547Z,1531492568.547 [CommandLine](IMPORTANT): got command configSet list 2018-07-13T14:36:08.548Z,1531492568.548 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-07-13T14:36:08.549Z,1531492568.549 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2018-07-13T14:36:08.550Z,1531492568.550 [CommandLine](IMPORTANT): BuoyancyServo.limitHi=9600 count; 2018-07-13T14:36:08.550Z,1531492568.550 [CommandLine](IMPORTANT): BuoyancyServo.limitLo=1200 count; 2018-07-13T14:36:08.550Z,1531492568.550 [CommandLine](IMPORTANT): BuoyancyServo.offsetVolume=200 cubic_centimeter; 2018-07-13T14:36:08.550Z,1531492568.550 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2018-07-13T14:36:08.550Z,1531492568.550 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2018-07-13T14:36:08.550Z,1531492568.550 [CommandLine](IMPORTANT): VerticalControl.buoyancyDefault=1050 cubic_centimeter; 2018-07-13T14:36:08.550Z,1531492568.550 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitHiCC=1075 cubic_centimeter; 2018-07-13T14:36:08.550Z,1531492568.550 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC=100 cubic_centimeter; 2018-07-13T14:36:08.551Z,1531492568.551 [CommandLine](IMPORTANT): VerticalControl.massDefault=2 millimeter; 2018-07-13T14:36:08.883Z,1531492568.883 [MissionManager](IMPORTANT): Started mission Startup 2018-07-13T14:36:08.883Z,1531492568.883 [Startup] Running Loop=1 2018-07-13T14:36:08.883Z,1531492568.883 [Startup](DEBUG): Aggregate::initialize Startup 2018-07-13T14:36:08.883Z,1531492568.883 [Startup:A.GoToSurface] Running Loop=1 2018-07-13T14:36:08.884Z,1531492568.884 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-07-13T14:36:08.884Z,1531492568.884 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-07-13T14:36:08.885Z,1531492568.885 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-07-13T14:36:08.885Z,1531492568.885 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-07-13T14:36:08.886Z,1531492568.886 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-07-13T14:36:08.886Z,1531492568.886 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-07-13T14:36:08.892Z,1531492568.892 [Startup:StartupSatComms] Running Loop=1 2018-07-13T14:36:08.892Z,1531492568.892 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-07-13T14:36:08.892Z,1531492568.892 [Startup:StartupSatComms:A] Running Loop=1 2018-07-13T14:36:09.267Z,1531492569.267 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-07-13T14:37:09.067Z,1531492629.067 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-13T14:36:08.9Z 2018-07-13T14:37:09.067Z,1531492629.067 [Startup:StartupSatComms:A] Stopped 2018-07-13T14:37:09.067Z,1531492629.067 [Startup:StartupSatComms:B] Running Loop=1 2018-07-13T14:37:09.422Z,1531492629.422 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-07-13T14:37:09.759Z,1531492629.759 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004030 2018-07-13T14:37:13.883Z,1531492633.883 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180713T021130/Courier0040.lzma 2018-07-13T14:37:14.665Z,1531492634.665 [DataOverHttps](INFO): Moved sent file to Logs/20180713T021130/Courier0040.lzma.bak 2018-07-13T14:37:14.668Z,1531492634.668 [DataOverHttps](INFO): SBD MOMSN=8367562 2018-07-13T14:37:23.748Z,1531492643.748 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180713T021130/Express0041.lzma 2018-07-13T14:37:24.533Z,1531492644.533 [DataOverHttps](INFO): Moved sent file to Logs/20180713T021130/Express0041.lzma.bak 2018-07-13T14:37:24.533Z,1531492644.533 [DataOverHttps](INFO): SBD MOMSN=8367564 2018-07-13T14:37:33.587Z,1531492653.587 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180713T143258/Express0001.lzma 2018-07-13T14:37:34.391Z,1531492654.391 [DataOverHttps](INFO): Moved sent file to Logs/20180713T143258/Express0001.lzma.bak 2018-07-13T14:37:34.392Z,1531492654.392 [DataOverHttps](INFO): SBD MOMSN=8367566 2018-07-13T14:37:43.458Z,1531492663.458 [DataOverHttps](INFO): Sending 793 bytes from file Logs/20180713T143431/Express0001.lzma 2018-07-13T14:37:44.204Z,1531492664.204 [DataOverHttps](INFO): Moved sent file to Logs/20180713T143431/Express0001.lzma.bak 2018-07-13T14:37:44.204Z,1531492664.204 [DataOverHttps](INFO): SBD MOMSN=8367569 2018-07-13T14:37:44.668Z,1531492664.668 [Startup:StartupSatComms:B] Stopped 2018-07-13T14:37:44.669Z,1531492664.669 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-07-13T14:37:44.669Z,1531492664.669 [Startup:StartupSatComms] Stopped 2018-07-13T14:37:44.669Z,1531492664.669 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-07-13T14:37:44.670Z,1531492664.670 [Startup](INFO): Completed Startup 2018-07-13T14:37:44.670Z,1531492664.670 [MissionManager](INFO): Startup is completed. 2018-07-13T14:37:44.670Z,1531492664.670 [MissionManager](INFO): Uninitializing Mission Startup 2018-07-13T14:37:44.670Z,1531492664.670 [Startup] Stopped 2018-07-13T14:37:44.671Z,1531492664.671 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-07-13T14:37:44.671Z,1531492664.671 [Startup:A.GoToSurface] Stopped 2018-07-13T14:37:44.671Z,1531492664.671 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-07-13T14:37:45.063Z,1531492665.063 [MissionManager](IMPORTANT): Started mission Default 2018-07-13T14:37:45.063Z,1531492665.063 [Default] Running Loop=1 2018-07-13T14:37:45.064Z,1531492665.064 [Default](DEBUG): Aggregate::initialize Default 2018-07-13T14:37:45.064Z,1531492665.064 [Default:B.GoToSurface] Running Loop=1 2018-07-13T14:37:45.064Z,1531492665.064 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-07-13T14:37:45.064Z,1531492665.064 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-07-13T14:37:45.064Z,1531492665.064 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-07-13T14:37:45.069Z,1531492665.069 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-07-13T14:37:45.069Z,1531492665.069 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-07-13T14:37:45.070Z,1531492665.070 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-07-13T14:37:45.070Z,1531492665.070 [Default:A.Wait] Running Loop=1 2018-07-13T14:37:45.070Z,1531492665.070 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-07-13T14:37:48.276Z,1531492668.276 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-13T14:37:48.276Z,1531492668.276 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-07-13T14:37:48.276Z,1531492668.276 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-07-13T14:37:48.278Z,1531492668.278 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-13T14:37:48.278Z,1531492668.278 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-07-13T14:37:48.278Z,1531492668.278 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-07-13T14:37:48.291Z,1531492668.291 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-07-13T14:37:48.292Z,1531492668.292 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-07-13T14:37:48.475Z,1531492668.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-07-13T14:37:48.475Z,1531492668.475 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-07-13T14:37:48.476Z,1531492668.476 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-07-13T14:37:48.476Z,1531492668.476 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-07-13T14:37:48.781Z,1531492668.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-07-13T14:37:48.781Z,1531492668.781 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-07-13T14:37:48.782Z,1531492668.782 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-07-13T14:37:48.782Z,1531492668.782 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-07-13T14:37:48.783Z,1531492668.783 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-07-13T14:37:48.783Z,1531492668.783 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-07-13T14:37:49.208Z,1531492669.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s. 2018-07-13T14:37:49.578Z,1531492669.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s. 2018-07-13T14:37:49.966Z,1531492669.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s. 2018-07-13T14:37:50.352Z,1531492670.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s. 2018-07-13T14:37:50.770Z,1531492670.770 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s. 2018-07-13T14:37:51.196Z,1531492671.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s. 2018-07-13T14:37:51.558Z,1531492671.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s. 2018-07-13T14:37:51.970Z,1531492671.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s. 2018-07-13T14:37:52.363Z,1531492672.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s. 2018-07-13T14:37:52.756Z,1531492672.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s. 2018-07-13T14:37:53.183Z,1531492673.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s. 2018-07-13T14:37:53.578Z,1531492673.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s. 2018-07-13T14:37:53.975Z,1531492673.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s. 2018-07-13T14:37:54.368Z,1531492674.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s. 2018-07-13T14:37:54.762Z,1531492674.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s. 2018-07-13T14:37:55.176Z,1531492675.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s. 2018-07-13T14:37:55.539Z,1531492675.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s. 2018-07-13T14:37:55.979Z,1531492675.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s. 2018-07-13T14:37:56.349Z,1531492676.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s. 2018-07-13T14:37:56.749Z,1531492676.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s. 2018-07-13T14:37:57.177Z,1531492677.177 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s. 2018-07-13T14:37:57.547Z,1531492677.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s. 2018-07-13T14:37:57.953Z,1531492677.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s. 2018-07-13T14:37:58.384Z,1531492678.384 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s. 2018-07-13T14:37:58.388Z,1531492678.388 [Default:A.Wait](INFO): Done Waiting. 2018-07-13T14:37:58.388Z,1531492678.388 [Default:A.Wait] Stopped 2018-07-13T14:37:58.388Z,1531492678.388 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-07-13T14:37:58.781Z,1531492678.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s. 2018-07-13T14:37:58.785Z,1531492678.785 [Default:CheckIn] Running Loop=1 2018-07-13T14:37:58.785Z,1531492678.785 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-13T14:37:58.785Z,1531492678.785 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-13T14:37:59.200Z,1531492679.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s. 2018-07-13T14:37:59.204Z,1531492679.204 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-07-13T14:37:59.580Z,1531492679.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s. 2018-07-13T14:37:59.983Z,1531492679.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s. 2018-07-13T14:38:00.362Z,1531492680.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s. 2018-07-13T14:38:00.759Z,1531492680.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s. 2018-07-13T14:38:01.161Z,1531492681.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s. 2018-07-13T14:38:01.560Z,1531492681.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s. 2018-07-13T14:38:01.965Z,1531492681.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s. 2018-07-13T14:38:02.355Z,1531492682.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s. 2018-07-13T14:38:02.760Z,1531492682.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s. 2018-07-13T14:38:03.179Z,1531492683.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s. 2018-07-13T14:38:03.608Z,1531492683.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s. 2018-07-13T14:38:04.011Z,1531492684.011 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s. 2018-07-13T14:38:04.380Z,1531492684.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s. 2018-07-13T14:38:04.771Z,1531492684.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s. 2018-07-13T14:38:05.170Z,1531492685.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s. 2018-07-13T14:38:05.567Z,1531492685.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s. 2018-07-13T14:38:05.977Z,1531492685.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s. 2018-07-13T14:38:06.360Z,1531492686.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s. 2018-07-13T14:38:06.768Z,1531492686.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s. 2018-07-13T14:38:07.182Z,1531492687.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s. 2018-07-13T14:38:07.547Z,1531492687.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s. 2018-07-13T14:38:07.972Z,1531492687.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s. 2018-07-13T14:39:54.931Z,1531492794.931 [CommandLine](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2018-07-13T14:39:54.931Z,1531492794.931 [CommandLine](IMPORTANT): Onboard.Pressure 10.459530 psi 2018-07-13T14:40:18.258Z,1531492818.258 [CommandLine](IMPORTANT): got command get 2018-07-13T14:40:18.258Z,1531492818.258 [CommandLine](FAULT): Incomplete syntax. Try: help get 2018-07-13T14:40:22.002Z,1531492822.002 [CommandLine](IMPORTANT): got command get Onboard.Humidity 2018-07-13T14:40:22.003Z,1531492822.003 [CommandLine](IMPORTANT): Onboard.Humidity 18.312782 % 2018-07-13T14:40:28.107Z,1531492828.107 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-07-13T14:40:45.298Z,1531492845.298 [CommandLine](IMPORTANT): got command get Onboard.Temperature 2018-07-13T14:40:45.299Z,1531492845.299 [CommandLine](IMPORTANT): Onboard.Temperature 29.547357 degC 2018-07-13T14:40:48.784Z,1531492848.784 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-13T14:40:48.784Z,1531492848.784 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2018-07-13T14:40:48.784Z,1531492848.784 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-07-13T14:40:48.790Z,1531492848.790 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-13T14:40:48.791Z,1531492848.791 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-07-13T14:40:48.791Z,1531492848.791 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-07-13T14:40:48.820Z,1531492848.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-07-13T14:40:48.826Z,1531492848.826 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-07-13T14:40:49.131Z,1531492849.131 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-07-13T14:40:49.131Z,1531492849.131 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2018-07-13T14:40:49.132Z,1531492849.132 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-07-13T14:40:49.132Z,1531492849.132 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-07-13T14:40:49.525Z,1531492849.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-07-13T14:40:49.526Z,1531492849.526 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-07-13T14:40:49.526Z,1531492849.526 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-07-13T14:40:49.527Z,1531492849.527 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-07-13T14:40:49.527Z,1531492849.527 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-07-13T14:40:49.527Z,1531492849.527 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-07-13T14:40:49.925Z,1531492849.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.1 s old, using for 20.0 s. 2018-07-13T14:40:50.302Z,1531492850.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.5 s old, using for 20.0 s. 2018-07-13T14:40:50.695Z,1531492850.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.9 s old, using for 20.0 s. 2018-07-13T14:40:51.107Z,1531492851.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.3 s old, using for 20.0 s. 2018-07-13T14:40:51.499Z,1531492851.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.7 s old, using for 20.0 s. 2018-07-13T14:40:51.906Z,1531492851.906 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.1 s old, using for 20.0 s. 2018-07-13T14:40:52.293Z,1531492852.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.5 s old, using for 20.0 s. 2018-07-13T14:40:52.696Z,1531492852.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.9 s old, using for 20.0 s. 2018-07-13T14:40:53.104Z,1531492853.104 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s. 2018-07-13T14:40:53.511Z,1531492853.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.7 s old, using for 20.0 s. 2018-07-13T14:40:53.941Z,1531492853.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s. 2018-07-13T14:40:54.305Z,1531492854.305 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s. 2018-07-13T14:40:54.672Z,1531492854.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s. 2018-07-13T14:40:55.111Z,1531492855.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s. 2018-07-13T14:40:55.512Z,1531492855.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s. 2018-07-13T14:40:55.916Z,1531492855.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s. 2018-07-13T14:40:56.291Z,1531492856.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.5 s old, using for 20.0 s. 2018-07-13T14:40:56.696Z,1531492856.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.9 s old, using for 20.0 s. 2018-07-13T14:40:57.128Z,1531492857.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.3 s old, using for 20.0 s. 2018-07-13T14:40:57.515Z,1531492857.515 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.7 s old, using for 20.0 s. 2018-07-13T14:40:57.919Z,1531492857.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.1 s old, using for 20.0 s. 2018-07-13T14:40:58.323Z,1531492858.323 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.5 s old, using for 20.0 s. 2018-07-13T14:40:58.690Z,1531492858.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s. 2018-07-13T14:40:59.096Z,1531492859.096 [DeadReckonUsingM