2018-01-21T22:39:12.127Z,1516574352.127 [Supervisor](DEBUG): Initializing supervisor. 2018-01-21T22:39:12.129Z,1516574352.129 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-01-21T22:39:12.130Z,1516574352.130 [SyncHandler](INFO): Protected caller Thread ID is 799 2018-01-21T22:39:12.130Z,1516574352.130 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-01-21T22:39:12.131Z,1516574352.131 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-01-21T22:39:12.131Z,1516574352.131 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800 2018-01-21T22:39:12.134Z,1516574352.134 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-01-21T22:39:12.145Z,1516574352.145 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-01-21T22:39:12.146Z,1516574352.146 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-01-21T22:39:12.146Z,1516574352.146 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801 2018-01-21T22:39:12.147Z,1516574352.147 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-01-21T22:39:12.148Z,1516574352.148 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-01-21T22:39:12.148Z,1516574352.148 [logger ThreadHandler](INFO): Protected caller Thread ID is 802 2018-01-21T22:39:12.150Z,1516574352.150 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-01-21T22:39:12.150Z,1516574352.150 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-01-21T22:39:12.156Z,1516574352.156 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-01-21T22:39:12.327Z,1516574352.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-01-21T22:39:12.328Z,1516574352.328 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-01-21T22:39:12.463Z,1516574352.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-01-21T22:39:12.464Z,1516574352.464 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-01-21T22:39:12.762Z,1516574352.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-01-21T22:39:12.762Z,1516574352.762 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-01-21T22:39:12.890Z,1516574352.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-01-21T22:39:12.892Z,1516574352.892 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-01-21T22:39:12.972Z,1516574352.972 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-01-21T22:39:13.156Z,1516574353.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-01-21T22:39:13.157Z,1516574353.157 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-01-21T22:39:13.252Z,1516574353.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-01-21T22:39:13.253Z,1516574353.253 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-01-21T22:39:13.557Z,1516574353.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-01-21T22:39:13.558Z,1516574353.558 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-01-21T22:39:13.877Z,1516574353.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-01-21T22:39:13.878Z,1516574353.878 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-01-21T22:39:14.150Z,1516574354.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-01-21T22:39:14.150Z,1516574354.150 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-01-21T22:39:14.609Z,1516574354.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-01-21T22:39:14.610Z,1516574354.610 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-01-21T22:39:14.796Z,1516574354.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-01-21T22:39:14.797Z,1516574354.797 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-01-21T22:39:14.892Z,1516574354.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-01-21T22:39:14.893Z,1516574354.893 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-01-21T22:39:15.321Z,1516574355.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-01-21T22:39:15.322Z,1516574355.322 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-01-21T22:39:15.425Z,1516574355.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-01-21T22:39:15.427Z,1516574355.427 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/ 2018-01-21T22:39:15.429Z,1516574355.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg 2018-01-21T22:39:15.663Z,1516574355.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-01-21T22:39:15.664Z,1516574355.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg 2018-01-21T22:39:23.026Z,1516574363.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg 2018-01-21T22:39:23.125Z,1516574363.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg 2018-01-21T22:39:23.218Z,1516574363.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg 2018-01-21T22:39:23.302Z,1516574363.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg 2018-01-21T22:39:23.446Z,1516574363.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg 2018-01-21T22:39:23.602Z,1516574363.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg 2018-01-21T22:39:23.703Z,1516574363.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg 2018-01-21T22:39:23.796Z,1516574363.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg 2018-01-21T22:39:23.878Z,1516574363.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg 2018-01-21T22:39:24.032Z,1516574364.032 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-01-21T22:39:24.036Z,1516574364.036 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-01-21T22:39:24.187Z,1516574364.187 [SBIT](DEBUG): Construct Startup Built In Test. 2018-01-21T22:39:24.216Z,1516574364.216 [SBIT] Loaded 2018-01-21T22:39:24.216Z,1516574364.216 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-01-21T22:39:24.217Z,1516574364.217 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-01-21T22:39:24.243Z,1516574364.243 [IBIT] Loaded 2018-01-21T22:39:24.243Z,1516574364.243 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-01-21T22:39:24.246Z,1516574364.246 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-01-21T22:39:24.394Z,1516574364.394 [CBIT] Loaded 2018-01-21T22:39:24.394Z,1516574364.394 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-01-21T22:39:24.395Z,1516574364.395 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-01-21T22:39:24.397Z,1516574364.397 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-01-21T22:39:24.482Z,1516574364.482 [VerticalControl](DEBUG): Construct VerticalControl. 2018-01-21T22:39:24.577Z,1516574364.577 [VerticalControl] Loaded 2018-01-21T22:39:24.577Z,1516574364.577 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-01-21T22:39:24.578Z,1516574364.578 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-01-21T22:39:24.636Z,1516574364.636 [HorizontalControl] Loaded 2018-01-21T22:39:24.636Z,1516574364.636 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-01-21T22:39:24.637Z,1516574364.637 [SpeedControl](DEBUG): Construct SpeedControl. 2018-01-21T22:39:24.642Z,1516574364.642 [SpeedControl] Loaded 2018-01-21T22:39:24.643Z,1516574364.643 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-01-21T22:39:24.643Z,1516574364.643 [LoopControl](DEBUG): Construct LoopControl. 2018-01-21T22:39:24.644Z,1516574364.644 [LoopControl] Loaded 2018-01-21T22:39:24.644Z,1516574364.644 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-01-21T22:39:24.645Z,1516574364.645 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-01-21T22:39:24.645Z,1516574364.645 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-01-21T22:39:24.699Z,1516574364.699 [DepthRateCalculator] Loaded 2018-01-21T22:39:24.700Z,1516574364.700 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-01-21T22:39:24.705Z,1516574364.705 [PitchRateCalculator] Loaded 2018-01-21T22:39:24.705Z,1516574364.705 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-01-21T22:39:24.720Z,1516574364.720 [SpeedCalculator] Loaded 2018-01-21T22:39:24.721Z,1516574364.721 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-01-21T22:39:24.741Z,1516574364.741 [TempGradientCalculator] Loaded 2018-01-21T22:39:24.741Z,1516574364.741 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-01-21T22:39:24.756Z,1516574364.756 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-01-21T22:39:24.756Z,1516574364.756 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-01-21T22:39:24.761Z,1516574364.761 [YawRateCalculator] Loaded 2018-01-21T22:39:24.761Z,1516574364.761 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-01-21T22:39:24.761Z,1516574364.761 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-01-21T22:39:24.762Z,1516574364.762 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-01-21T22:39:24.883Z,1516574364.883 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-01-21T22:39:24.883Z,1516574364.883 [StratificationFrontDetector](DEBUG): (re)initializing 2018-01-21T22:39:24.883Z,1516574364.883 [StratificationFrontDetector] Loaded 2018-01-21T22:39:24.883Z,1516574364.883 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-01-21T22:39:24.884Z,1516574364.884 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-01-21T22:39:24.885Z,1516574364.885 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-01-21T22:39:25.192Z,1516574365.192 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-01-21T22:39:25.193Z,1516574365.193 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-01-21T22:39:25.302Z,1516574365.302 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-01-21T22:39:25.303Z,1516574365.303 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-01-21T22:39:25.354Z,1516574365.354 [DeadReckonUsingSpeedCalculator] Loaded 2018-01-21T22:39:25.354Z,1516574365.354 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-01-21T22:39:25.406Z,1516574365.406 [DeadReckonWithRespectToSeafloor] Loaded 2018-01-21T22:39:25.407Z,1516574365.407 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2018-01-21T22:39:25.422Z,1516574365.422 [NavChart] Loaded 2018-01-21T22:39:25.423Z,1516574365.422 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-01-21T22:39:25.426Z,1516574365.426 [UniversalFixResidualReporter] Loaded 2018-01-21T22:39:25.427Z,1516574365.427 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-01-21T22:39:25.427Z,1516574365.427 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-01-21T22:39:25.428Z,1516574365.428 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-01-21T22:39:25.452Z,1516574365.452 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-01-21T22:39:25.452Z,1516574365.452 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-01-21T22:39:25.647Z,1516574365.647 [Aanderaa_O2] Loaded 2018-01-21T22:39:25.647Z,1516574365.647 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-01-21T22:39:25.693Z,1516574365.693 [CTD_Seabird] Loaded 2018-01-21T22:39:25.693Z,1516574365.693 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-01-21T22:39:25.694Z,1516574365.694 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406D34E0 2018-01-21T22:39:25.695Z,1516574365.695 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 882 2018-01-21T22:39:25.710Z,1516574365.710 [ESPComponent] Loaded 2018-01-21T22:39:25.711Z,1516574365.711 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-01-21T22:39:25.724Z,1516574365.724 [PAR_Licor] Loaded 2018-01-21T22:39:25.724Z,1516574365.724 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-01-21T22:39:25.771Z,1516574365.771 [WetLabsBB2FL] Loaded 2018-01-21T22:39:25.772Z,1516574365.772 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-01-21T22:39:25.773Z,1516574365.773 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407034E0 2018-01-21T22:39:25.773Z,1516574365.773 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 883 2018-01-21T22:39:25.774Z,1516574365.774 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-01-21T22:39:25.774Z,1516574365.774 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-01-21T22:39:26.299Z,1516574366.299 [DataOverHttps] Loaded 2018-01-21T22:39:26.300Z,1516574366.300 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-01-21T22:39:26.312Z,1516574366.312 [Depth_Keller] Loaded 2018-01-21T22:39:26.313Z,1516574366.313 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-01-21T22:39:26.318Z,1516574366.318 [DropWeight] Loaded 2018-01-21T22:39:26.318Z,1516574366.318 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-01-21T22:39:26.405Z,1516574366.405 [NAL9602] Loaded 2018-01-21T22:39:26.405Z,1516574366.405 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-01-21T22:39:26.410Z,1516574366.410 [Onboard] Loaded 2018-01-21T22:39:26.410Z,1516574366.410 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-01-21T22:39:26.417Z,1516574366.417 [Radio_Surface] Loaded 2018-01-21T22:39:26.417Z,1516574366.417 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-01-21T22:39:26.418Z,1516574366.418 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409474E0 2018-01-21T22:39:26.419Z,1516574366.419 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884 2018-01-21T22:39:26.542Z,1516574366.542 [DAT] Loaded 2018-01-21T22:39:26.543Z,1516574366.543 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2018-01-21T22:39:26.694Z,1516574366.694 [PNI_TCM] Loaded 2018-01-21T22:39:26.694Z,1516574366.694 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-01-21T22:39:26.751Z,1516574366.751 [Rowe_600LCM] Loaded 2018-01-21T22:39:26.752Z,1516574366.752 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-01-21T22:39:26.753Z,1516574366.753 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409774E0 2018-01-21T22:39:26.753Z,1516574366.753 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 885 2018-01-21T22:39:28.468Z,1516574368.468 [BPC1] Loaded 2018-01-21T22:39:28.468Z,1516574368.468 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-01-21T22:39:28.468Z,1516574368.468 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-01-21T22:39:28.469Z,1516574368.469 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-01-21T22:39:28.619Z,1516574368.619 [BuoyancyServo] Loaded 2018-01-21T22:39:28.619Z,1516574368.619 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-01-21T22:39:28.630Z,1516574368.630 [ElevatorServo] Loaded 2018-01-21T22:39:28.631Z,1516574368.631 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-01-21T22:39:28.641Z,1516574368.641 [MassServo] Loaded 2018-01-21T22:39:28.641Z,1516574368.641 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-01-21T22:39:28.652Z,1516574368.652 [RudderServo] Loaded 2018-01-21T22:39:28.652Z,1516574368.652 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-01-21T22:39:28.663Z,1516574368.663 [ThrusterServo] Loaded 2018-01-21T22:39:28.663Z,1516574368.663 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-01-21T22:39:28.663Z,1516574368.663 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-01-21T22:39:28.664Z,1516574368.664 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-01-21T22:39:28.786Z,1516574368.786 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-01-21T22:39:28.787Z,1516574368.787 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-01-21T22:39:28.823Z,1516574368.823 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-01-21T22:39:28.826Z,1516574368.826 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-01-21T22:39:28.827Z,1516574368.827 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-01-21T22:39:28.833Z,1516574368.833 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-01-21T22:39:28.834Z,1516574368.834 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0 2018-01-21T22:39:28.835Z,1516574368.835 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 886 2018-01-21T22:39:28.839Z,1516574368.839 [Supervisor](INFO): Main Thread ID is 795 2018-01-21T22:39:28.839Z,1516574368.839 [Supervisor](DEBUG): Running supervisor. 2018-01-21T22:39:28.840Z,1516574368.840 [CommandLine ThreadHandler](INFO): Handler Thread ID is 887 2018-01-21T22:39:28.842Z,1516574368.842 [controlThread ThreadHandler](INFO): Handler Thread ID is 888 2018-01-21T22:39:28.843Z,1516574368.843 [controlThread](DEBUG): Initializing ControlThread 2018-01-21T22:39:28.844Z,1516574368.844 [SBIT](INFO): Initialize SBIT Component. 2018-01-21T22:39:28.844Z,1516574368.844 [SBIT](IMPORTANT): git: 2017-12-12-30-gb0f7ff6 2018-01-21T22:39:28.844Z,1516574368.844 [SBIT](INFO): git hash: b0f7ff6b53ac09de70ba82b711a5c931a61d8eb6 2018-01-21T22:39:28.845Z,1516574368.845 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-01-21T22:39:28.846Z,1516574368.846 [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-01-21T22:39:28.847Z,1516574368.847 [SBIT](INFO): Beginning SBIT in 79.000000 seconds. 2018-01-21T22:39:28.848Z,1516574368.848 [IBIT](INFO): Initialize IBIT Component. 2018-01-21T22:39:28.848Z,1516574368.848 [CBIT](DEBUG): Initialize CBIT Component. 2018-01-21T22:39:28.849Z,1516574368.849 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-01-21T22:39:28.849Z,1516574368.849 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-01-21T22:39:28.850Z,1516574368.850 [logger ThreadHandler](INFO): Handler Thread ID is 889 2018-01-21T22:39:28.877Z,1516574368.877 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 890 2018-01-21T22:39:28.878Z,1516574368.878 [CTD_Seabird](INFO): Initializing 2018-01-21T22:39:28.913Z,1516574368.913 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 892 2018-01-21T22:39:28.915Z,1516574368.915 [WetLabsBB2FL](INFO): Powering down 2018-01-21T22:39:28.949Z,1516574368.949 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 893 2018-01-21T22:39:28.955Z,1516574368.955 [Radio_Surface](INFO): Powering up 2018-01-21T22:39:28.957Z,1516574368.957 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-01-21T22:39:28.960Z,1516574368.960 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-01-21T22:39:28.960Z,1516574368.960 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-01-21T22:39:28.961Z,1516574368.961 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-01-21T22:39:28.961Z,1516574368.961 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-01-21T22:39:28.961Z,1516574368.961 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-01-21T22:39:28.962Z,1516574368.962 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-01-21T22:39:28.962Z,1516574368.962 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-01-21T22:39:28.963Z,1516574368.963 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-01-21T22:39:28.964Z,1516574368.964 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-01-21T22:39:28.964Z,1516574368.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-01-21T22:39:28.965Z,1516574368.965 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-01-21T22:39:28.966Z,1516574368.966 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-01-21T22:39:28.966Z,1516574368.966 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-01-21T22:39:28.967Z,1516574368.967 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-01-21T22:39:28.967Z,1516574368.967 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-01-21T22:39:28.967Z,1516574368.967 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-01-21T22:39:28.968Z,1516574368.968 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-01-21T22:39:28.968Z,1516574368.968 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-01-21T22:39:28.969Z,1516574368.969 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-01-21T22:39:28.969Z,1516574368.969 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-01-21T22:39:28.979Z,1516574368.979 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-01-21T22:39:28.990Z,1516574368.990 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 894 2018-01-21T22:39:28.991Z,1516574368.991 [Rowe_600LCM](INFO): Initializing 2018-01-21T22:39:28.992Z,1516574368.992 [Rowe_600LCM](INFO): Checking LCM 2018-01-21T22:39:29.116Z,1516574369.116 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-01-21T22:39:29.116Z,1516574369.116 [CTD_Seabird](INFO): Powering down 2018-01-21T22:39:29.137Z,1516574369.137 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 895 2018-01-21T22:39:29.148Z,1516574369.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-01-21T22:39:29.148Z,1516574369.148 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-01-21T22:39:29.148Z,1516574369.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-01-21T22:39:29.148Z,1516574369.148 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-01-21T22:39:29.148Z,1516574369.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-01-21T22:39:29.149Z,1516574369.149 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-01-21T22:39:29.149Z,1516574369.149 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-01-21T22:39:29.149Z,1516574369.149 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-01-21T22:39:29.149Z,1516574369.149 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-01-21T22:39:29.149Z,1516574369.149 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-01-21T22:39:29.150Z,1516574369.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-01-21T22:39:29.150Z,1516574369.150 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-01-21T22:39:29.150Z,1516574369.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-01-21T22:39:29.150Z,1516574369.150 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-01-21T22:39:29.150Z,1516574369.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-01-21T22:39:29.150Z,1516574369.150 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-01-21T22:39:29.177Z,1516574369.177 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-01-21T22:39:29.334Z,1516574369.334 [MissionManager](DEBUG): 2018-01-21T22:39:29.342Z,1516574369.342 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-01-21T22:39:29.450Z,1516574369.450 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-01-21T22:39:29.451Z,1516574369.451 [Default:A.Wait](DEBUG): Construct Wait. 2018-01-21T22:39:29.452Z,1516574369.452 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-01-21T22:39:29.488Z,1516574369.488 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-01-21T22:39:29.511Z,1516574369.511 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-01-21T22:39:29.543Z,1516574369.543 [Default:E.Execute](DEBUG): Construct Execute. 2018-01-21T22:39:29.563Z,1516574369.563 [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-01-21T22:39:29.569Z,1516574369.569 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-01-21T22:39:29.581Z,1516574369.581 [Rowe_600LCM](INFO): LCM OK 2018-01-21T22:39:29.581Z,1516574369.581 [Rowe_600LCM](INFO): Powering up 2018-01-21T22:39:29.599Z,1516574369.599 [ESPComponent](INFO): powering down ESP 2018-01-21T22:39:29.903Z,1516574369.903 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-01-21T22:39:29.946Z,1516574369.946 [Depth_Keller](ERROR): Pressure reading out of range: 1546.657349 decibar 2018-01-21T22:39:30.024Z,1516574370.024 [DAT](INFO): Powering up 2018-01-21T22:39:30.024Z,1516574370.024 [DAT](DEBUG): Initializing DAT. 2018-01-21T22:39:30.174Z,1516574370.174 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-01-21T22:39:30.263Z,1516574370.263 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:30.263Z,1516574370.263 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:30.378Z,1516574370.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:30.396Z,1516574370.396 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:30.430Z,1516574370.430 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:30.529Z,1516574370.529 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-01-21T22:39:30.537Z,1516574370.537 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-01-21T22:39:30.547Z,1516574370.547 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-01-21T22:39:30.554Z,1516574370.554 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-01-21T22:39:30.572Z,1516574370.572 [MassServo](DEBUG): Initializing EZServoServo. 2018-01-21T22:39:30.577Z,1516574370.577 [MassServo](DEBUG): Initializing MassServo. 2018-01-21T22:39:30.599Z,1516574370.599 [RudderServo](DEBUG): Initializing EZServoServo. 2018-01-21T22:39:30.605Z,1516574370.605 [RudderServo](DEBUG): Initializing RudderServo. 2018-01-21T22:39:30.622Z,1516574370.622 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-01-21T22:39:30.629Z,1516574370.629 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-01-21T22:39:30.646Z,1516574370.646 [CommandLine](FAULT): Scheduling is paused 2018-01-21T22:39:30.646Z,1516574370.646 [CBIT](INFO): Critical error at 20180121T223928 2018-01-21T22:39:30.647Z,1516574370.647 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-01-21T22:39:31.020Z,1516574371.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:31.020Z,1516574371.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:31.021Z,1516574371.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:31.029Z,1516574371.029 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:31.030Z,1516574371.030 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:31.369Z,1516574371.369 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-01-21T22:39:31.369Z,1516574371.369 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-01-21T22:39:31.369Z,1516574371.369 [BuoyancyServo] Communications Fault, FailCount= 1 2018-01-21T22:39:31.369Z,1516574371.369 [BuoyancyServo](ERROR): Communications Fault 2018-01-21T22:39:31.657Z,1516574371.657 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-01-21T22:39:31.723Z,1516574371.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:31.723Z,1516574371.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:31.724Z,1516574371.724 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:31.725Z,1516574371.725 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:31.726Z,1516574371.726 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:31.747Z,1516574371.747 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-01-21T22:39:31.747Z,1516574371.747 [BuoyancyServo](INFO): Powering down 2018-01-21T22:39:32.128Z,1516574372.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:32.128Z,1516574372.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:32.129Z,1516574372.129 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:32.137Z,1516574372.137 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:32.138Z,1516574372.138 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:32.509Z,1516574372.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:32.510Z,1516574372.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:32.511Z,1516574372.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:32.511Z,1516574372.511 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:32.512Z,1516574372.512 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:32.985Z,1516574372.985 [Aanderaa_O2](INFO): Powering down 2018-01-21T22:39:33.136Z,1516574373.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:33.136Z,1516574373.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:33.137Z,1516574373.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:33.138Z,1516574373.138 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:33.138Z,1516574373.138 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:33.374Z,1516574373.374 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:33.374Z,1516574373.374 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:33.375Z,1516574373.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:33.376Z,1516574373.376 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:33.377Z,1516574373.377 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-01-21T22:39:33.507Z,1516574373.507 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-01-21T22:39:33.596Z,1516574373.596 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2018-01-21T22:39:33.795Z,1516574373.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:33.796Z,1516574373.796 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:33.843Z,1516574373.843 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-01-21T22:39:33.844Z,1516574373.844 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-01-21T22:39:33.844Z,1516574373.844 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-01-21T22:39:33.974Z,1516574373.974 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-01-21T22:39:33.974Z,1516574373.974 [BuoyancyServo] No Fault, FailCount= 1 2018-01-21T22:39:34.164Z,1516574374.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:34.174Z,1516574374.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:34.199Z,1516574374.199 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-01-21T22:39:34.199Z,1516574374.199 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-01-21T22:39:34.612Z,1516574374.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2018-01-21T22:39:34.626Z,1516574374.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:35.062Z,1516574375.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:35.443Z,1516574375.443 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:35.857Z,1516574375.857 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:36.251Z,1516574376.251 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:36.680Z,1516574376.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:37.058Z,1516574377.058 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:37.463Z,1516574377.463 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:37.909Z,1516574377.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:38.259Z,1516574378.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:38.639Z,1516574378.639 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:39.062Z,1516574379.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:39.482Z,1516574379.482 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:39.908Z,1516574379.908 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:40.246Z,1516574380.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:40.639Z,1516574380.639 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:41.062Z,1516574381.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:41.418Z,1516574381.418 [PNI_TCM](ERROR): CRC does not match. Expected:0x27084 got:0x154 2018-01-21T22:39:41.451Z,1516574381.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:41.884Z,1516574381.884 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:42.214Z,1516574382.214 [DAT](DEBUG): Teledyne Benthos DAT-900 Series MF Frequency Band Directional Acoustic Transponder version 8.10.9 Jan 12 2000 19:09:00 WARNING: battery low Features enabled [Bearing] CONNECT 2018-01-21T22:39:42.214Z,1516574382.214 [DAT](INFO): Got CONNECT 2018-01-21T22:39:42.229Z,1516574382.229 [DAT](INFO): entering command mode 2018-01-21T22:39:42.260Z,1516574382.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:42.607Z,1516574382.607 [DAT](DEBUG): checking for command mode acknowledgment 2018-01-21T22:39:42.670Z,1516574382.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:43.010Z,1516574383.010 [DAT](DEBUG): checking for command mode acknowledgment 2018-01-21T22:39:43.020Z,1516574383.020 [PNI_TCM](ERROR): CRC does not match. Expected:0x39914 got:0x7361 2018-01-21T22:39:43.063Z,1516574383.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:43.410Z,1516574383.410 [DAT](DEBUG): checking for command mode acknowledgment 2018-01-21T22:39:43.411Z,1516574383.411 [DAT](DEBUG): read user prompt 1: user:1> 2018-01-21T22:39:43.411Z,1516574383.411 [DAT](INFO): command mode acknowledged 2018-01-21T22:39:43.439Z,1516574383.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:43.810Z,1516574383.810 [DAT](INFO): setting local address to 2 2018-01-21T22:39:43.904Z,1516574383.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:44.205Z,1516574384.205 [DAT](DEBUG): checking for local address setting acknowledgment 2018-01-21T22:39:44.206Z,1516574384.206 [DAT](INFO): set local address to 2 2018-01-21T22:39:44.246Z,1516574384.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:44.610Z,1516574384.610 [DAT](DEBUG): read user prompt 2: user:2> 2018-01-21T22:39:44.691Z,1516574384.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:45.062Z,1516574385.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:45.517Z,1516574385.517 [BPC1](INFO): Initialized 2018-01-21T22:39:45.547Z,1516574385.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:45.925Z,1516574385.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:46.263Z,1516574386.263 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:46.662Z,1516574386.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:47.036Z,1516574387.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:47.455Z,1516574387.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:47.848Z,1516574387.848 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:48.244Z,1516574388.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:48.635Z,1516574388.635 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:49.041Z,1516574389.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:49.491Z,1516574389.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:49.870Z,1516574389.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:50.319Z,1516574390.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:50.663Z,1516574390.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:51.045Z,1516574391.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:51.474Z,1516574391.474 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:51.832Z,1516574391.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:52.279Z,1516574392.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:52.642Z,1516574392.642 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:53.053Z,1516574393.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:53.470Z,1516574393.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:53.886Z,1516574393.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:54.245Z,1516574394.245 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:54.653Z,1516574394.653 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:55.047Z,1516574395.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:55.455Z,1516574395.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:55.892Z,1516574395.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:56.274Z,1516574396.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:56.591Z,1516574396.591 [NAL9602](INFO): Powering up NAL9602 2018-01-21T22:39:56.670Z,1516574396.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:57.065Z,1516574397.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:57.438Z,1516574397.438 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:57.843Z,1516574397.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:58.252Z,1516574398.252 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:58.650Z,1516574398.650 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:59.058Z,1516574399.058 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:59.494Z,1516574399.494 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:39:59.847Z,1516574399.847 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:00.264Z,1516574400.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:00.671Z,1516574400.671 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:01.027Z,1516574401.027 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:01.474Z,1516574401.474 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:01.879Z,1516574401.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:02.288Z,1516574402.288 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:02.659Z,1516574402.659 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:03.056Z,1516574403.056 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:03.467Z,1516574403.467 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:03.859Z,1516574403.859 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-01-21T22:40:04.319Z,1516574404.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan