2017-04-19T15:37:05.354Z,1492616225.354 [Supervisor](DEBUG): Initializing supervisor. 2017-04-19T15:37:05.358Z,1492616225.358 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-04-19T15:37:05.359Z,1492616225.359 [SyncHandler](INFO): Protected caller Thread ID is 6667 2017-04-19T15:37:05.360Z,1492616225.360 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-04-19T15:37:05.361Z,1492616225.361 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-04-19T15:37:05.361Z,1492616225.362 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6668 2017-04-19T15:37:05.365Z,1492616225.365 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-04-19T15:37:05.377Z,1492616225.377 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-04-19T15:37:05.378Z,1492616225.378 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-04-19T15:37:05.379Z,1492616225.378 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6669 2017-04-19T15:37:05.379Z,1492616225.379 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-04-19T15:37:05.380Z,1492616225.380 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-04-19T15:37:05.381Z,1492616225.381 [logger ThreadHandler](INFO): Protected caller Thread ID is 6670 2017-04-19T15:37:05.383Z,1492616225.383 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-04-19T15:37:05.383Z,1492616225.383 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-04-19T15:37:05.385Z,1492616225.385 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-04-19T15:37:05.469Z,1492616225.469 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-04-19T15:37:05.792Z,1492616225.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-04-19T15:37:05.793Z,1492616225.793 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-04-19T15:37:05.926Z,1492616225.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-04-19T15:37:05.927Z,1492616225.927 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-04-19T15:37:06.125Z,1492616226.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-04-19T15:37:06.126Z,1492616226.126 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-04-19T15:37:06.227Z,1492616226.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-04-19T15:37:06.228Z,1492616226.228 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-04-19T15:37:06.423Z,1492616226.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-04-19T15:37:06.424Z,1492616226.424 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-04-19T15:37:06.896Z,1492616226.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-04-19T15:37:06.897Z,1492616226.897 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-04-19T15:37:07.183Z,1492616227.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-04-19T15:37:07.184Z,1492616227.184 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-04-19T15:37:07.270Z,1492616227.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-04-19T15:37:07.271Z,1492616227.271 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-04-19T15:37:07.413Z,1492616227.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-04-19T15:37:07.414Z,1492616227.414 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-04-19T15:37:07.610Z,1492616227.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-04-19T15:37:07.611Z,1492616227.611 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-04-19T15:37:08.106Z,1492616228.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-04-19T15:37:08.106Z,1492616228.106 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-04-19T15:37:08.472Z,1492616228.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-04-19T15:37:08.472Z,1492616228.473 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-04-19T15:37:08.789Z,1492616228.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-04-19T15:37:08.789Z,1492616228.789 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-04-19T15:37:08.901Z,1492616228.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-04-19T15:37:08.903Z,1492616228.903 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/ 2017-04-19T15:37:08.904Z,1492616228.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg 2017-04-19T15:37:08.992Z,1492616228.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg 2017-04-19T15:37:09.160Z,1492616229.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg 2017-04-19T15:37:09.394Z,1492616229.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-04-19T15:37:09.395Z,1492616229.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg 2017-04-19T15:37:09.494Z,1492616229.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg 2017-04-19T15:37:09.644Z,1492616229.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg 2017-04-19T15:37:09.751Z,1492616229.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg 2017-04-19T15:37:10.652Z,1492616230.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg 2017-04-19T15:37:10.818Z,1492616230.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg 2017-04-19T15:37:10.928Z,1492616230.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg 2017-04-19T15:37:11.017Z,1492616231.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg 2017-04-19T15:37:11.119Z,1492616231.119 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/root/ 2017-04-19T15:37:11.119Z,1492616231.119 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2017-04-19T15:37:11.121Z,1492616231.121 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-04-19T15:37:11.171Z,1492616231.171 [VerticalControl](DEBUG): Construct VerticalControl. 2017-04-19T15:37:11.291Z,1492616231.291 [VerticalControl] Loaded 2017-04-19T15:37:11.291Z,1492616231.291 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-04-19T15:37:11.292Z,1492616231.292 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-04-19T15:37:11.362Z,1492616231.362 [HorizontalControl] Loaded 2017-04-19T15:37:11.362Z,1492616231.362 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-04-19T15:37:11.363Z,1492616231.363 [SpeedControl](DEBUG): Construct SpeedControl. 2017-04-19T15:37:11.369Z,1492616231.369 [SpeedControl] Loaded 2017-04-19T15:37:11.369Z,1492616231.369 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-04-19T15:37:11.370Z,1492616231.370 [LoopControl](DEBUG): Construct LoopControl. 2017-04-19T15:37:11.370Z,1492616231.370 [LoopControl] Loaded 2017-04-19T15:37:11.370Z,1492616231.371 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-04-19T15:37:11.371Z,1492616231.371 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-04-19T15:37:11.372Z,1492616231.372 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-04-19T15:37:11.503Z,1492616231.503 [SBIT](DEBUG): Construct Startup Built In Test. 2017-04-19T15:37:11.515Z,1492616231.514 [SBIT] Loaded 2017-04-19T15:37:11.515Z,1492616231.515 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-04-19T15:37:11.516Z,1492616231.516 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-04-19T15:37:11.544Z,1492616231.544 [IBIT] Loaded 2017-04-19T15:37:11.544Z,1492616231.545 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-04-19T15:37:11.548Z,1492616231.548 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-04-19T15:37:11.697Z,1492616231.697 [CBIT] Loaded 2017-04-19T15:37:11.697Z,1492616231.697 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-04-19T15:37:11.697Z,1492616231.697 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-04-19T15:37:11.698Z,1492616231.698 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-04-19T15:37:11.724Z,1492616231.724 [DepthRateCalculator] Loaded 2017-04-19T15:37:11.724Z,1492616231.724 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-04-19T15:37:11.730Z,1492616231.730 [PitchRateCalculator] Loaded 2017-04-19T15:37:11.730Z,1492616231.730 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-04-19T15:37:11.741Z,1492616231.741 [SpeedCalculator] Loaded 2017-04-19T15:37:11.742Z,1492616231.741 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-04-19T15:37:11.763Z,1492616231.763 [TempGradientCalculator] Loaded 2017-04-19T15:37:11.764Z,1492616231.764 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-04-19T15:37:11.780Z,1492616231.780 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-04-19T15:37:11.780Z,1492616231.780 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-04-19T15:37:11.785Z,1492616231.785 [YawRateCalculator] Loaded 2017-04-19T15:37:11.785Z,1492616231.786 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-04-19T15:37:11.786Z,1492616231.786 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-04-19T15:37:11.787Z,1492616231.787 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-04-19T15:37:11.867Z,1492616231.867 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-04-19T15:37:11.868Z,1492616231.868 [StratificationFrontDetector](DEBUG): (re)initializing 2017-04-19T15:37:11.868Z,1492616231.868 [StratificationFrontDetector] Loaded 2017-04-19T15:37:11.868Z,1492616231.868 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-04-19T15:37:11.892Z,1492616231.892 [DepAvgTempFrontDetector] Loaded 2017-04-19T15:37:11.892Z,1492616231.892 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread. 2017-04-19T15:37:11.892Z,1492616231.892 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-04-19T15:37:11.893Z,1492616231.893 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-04-19T15:37:12.136Z,1492616232.136 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-04-19T15:37:12.137Z,1492616232.137 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-04-19T15:37:12.234Z,1492616232.234 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-04-19T15:37:12.234Z,1492616232.234 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-04-19T15:37:12.289Z,1492616232.289 [DeadReckonUsingSpeedCalculator] Loaded 2017-04-19T15:37:12.290Z,1492616232.290 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-04-19T15:37:12.345Z,1492616232.345 [DeadReckonWithRespectToSeafloor] Loaded 2017-04-19T15:37:12.346Z,1492616232.346 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-04-19T15:37:12.363Z,1492616232.363 [NavChart] Loaded 2017-04-19T15:37:12.363Z,1492616232.363 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-04-19T15:37:12.368Z,1492616232.368 [UniversalFixResidualReporter] Loaded 2017-04-19T15:37:12.368Z,1492616232.368 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-04-19T15:37:12.368Z,1492616232.368 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-04-19T15:37:12.369Z,1492616232.369 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-04-19T15:37:12.375Z,1492616232.375 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-04-19T15:37:12.376Z,1492616232.376 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-04-19T15:37:12.522Z,1492616232.522 [Aanderaa_O2] Loaded 2017-04-19T15:37:12.523Z,1492616232.523 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-04-19T15:37:12.538Z,1492616232.538 [PAR_Licor] Loaded 2017-04-19T15:37:12.538Z,1492616232.538 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-04-19T15:37:12.589Z,1492616232.589 [WetLabsBB2FL] Loaded 2017-04-19T15:37:12.590Z,1492616232.590 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-04-19T15:37:12.591Z,1492616232.591 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407734E0 2017-04-19T15:37:12.591Z,1492616232.591 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6749 2017-04-19T15:37:12.592Z,1492616232.592 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-04-19T15:37:12.592Z,1492616232.592 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-04-19T15:37:12.890Z,1492616232.890 [DataOverHttps] Loaded 2017-04-19T15:37:12.890Z,1492616232.890 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-04-19T15:37:12.904Z,1492616232.904 [Depth_Keller] Loaded 2017-04-19T15:37:12.905Z,1492616232.905 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-04-19T15:37:12.910Z,1492616232.909 [DropWeight] Loaded 2017-04-19T15:37:12.910Z,1492616232.910 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-04-19T15:37:13.004Z,1492616233.004 [NAL9602] Loaded 2017-04-19T15:37:13.004Z,1492616233.004 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-04-19T15:37:13.014Z,1492616233.014 [Onboard] Loaded 2017-04-19T15:37:13.014Z,1492616233.014 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-04-19T15:37:13.021Z,1492616233.021 [Radio_Surface] Loaded 2017-04-19T15:37:13.021Z,1492616233.021 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-04-19T15:37:13.023Z,1492616233.023 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409BE4E0 2017-04-19T15:37:13.023Z,1492616233.023 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6750 2017-04-19T15:37:13.161Z,1492616233.161 [DAT] Loaded 2017-04-19T15:37:13.162Z,1492616233.162 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2017-04-19T15:37:13.168Z,1492616233.168 [SCPI] Loaded 2017-04-19T15:37:13.169Z,1492616233.168 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2017-04-19T15:37:13.217Z,1492616233.217 [PNI_TCM] Loaded 2017-04-19T15:37:13.218Z,1492616233.218 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-04-19T15:37:13.286Z,1492616233.286 [Rowe_600LCM] Loaded 2017-04-19T15:37:13.287Z,1492616233.287 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-04-19T15:37:13.288Z,1492616233.288 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409EE4E0 2017-04-19T15:37:13.288Z,1492616233.288 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6751 2017-04-19T15:37:15.202Z,1492616235.202 [BPC1] Loaded 2017-04-19T15:37:15.202Z,1492616235.202 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-04-19T15:37:15.203Z,1492616235.203 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-04-19T15:37:15.203Z,1492616235.203 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-04-19T15:37:15.340Z,1492616235.340 [BuoyancyServo] Loaded 2017-04-19T15:37:15.341Z,1492616235.341 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-04-19T15:37:15.353Z,1492616235.353 [ElevatorServo] Loaded 2017-04-19T15:37:15.353Z,1492616235.353 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-04-19T15:37:15.365Z,1492616235.365 [MassServo] Loaded 2017-04-19T15:37:15.365Z,1492616235.365 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-04-19T15:37:15.377Z,1492616235.377 [RudderServo] Loaded 2017-04-19T15:37:15.377Z,1492616235.377 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-04-19T15:37:15.388Z,1492616235.388 [ThrusterServo] Loaded 2017-04-19T15:37:15.389Z,1492616235.389 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-04-19T15:37:15.389Z,1492616235.389 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-04-19T15:37:15.390Z,1492616235.390 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-04-19T15:37:15.491Z,1492616235.491 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-04-19T15:37:15.491Z,1492616235.491 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-04-19T15:37:15.516Z,1492616235.516 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-04-19T15:37:15.520Z,1492616235.520 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-04-19T15:37:15.521Z,1492616235.521 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-04-19T15:37:15.528Z,1492616235.528 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-04-19T15:37:15.529Z,1492616235.529 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0 2017-04-19T15:37:15.530Z,1492616235.530 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6752 2017-04-19T15:37:15.535Z,1492616235.534 [Supervisor](INFO): Main Thread ID is 6549 2017-04-19T15:37:15.535Z,1492616235.535 [Supervisor](DEBUG): Running supervisor. 2017-04-19T15:37:15.535Z,1492616235.535 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6753 2017-04-19T15:37:15.538Z,1492616235.538 [controlThread ThreadHandler](INFO): Handler Thread ID is 6754 2017-04-19T15:37:15.539Z,1492616235.539 [controlThread](DEBUG): Initializing ControlThread 2017-04-19T15:37:15.540Z,1492616235.540 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-04-19T15:37:15.542Z,1492616235.542 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-04-19T15:37:15.543Z,1492616235.543 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-04-19T15:37:15.543Z,1492616235.543 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-04-19T15:37:15.544Z,1492616235.543 [SBIT](INFO): Initialize SBIT Component. 2017-04-19T15:37:15.544Z,1492616235.544 [SBIT](IMPORTANT): git: 2017-04-12-5-g071e189 2017-04-19T15:37:15.544Z,1492616235.544 [SBIT](INFO): git hash: 071e18912e7779044b4eb542ad1ada326376074c 2017-04-19T15:37:15.546Z,1492616235.546 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-04-19T15:37:15.546Z,1492616235.546 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016 2017-04-19T15:37:15.547Z,1492616235.547 [SBIT](INFO): Beginning SBIT in 79.000000 seconds. 2017-04-19T15:37:15.548Z,1492616235.548 [IBIT](INFO): Initialize IBIT Component. 2017-04-19T15:37:15.549Z,1492616235.549 [CBIT](DEBUG): Initialize CBIT Component. 2017-04-19T15:37:15.549Z,1492616235.549 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-04-19T15:37:15.550Z,1492616235.550 [logger ThreadHandler](INFO): Handler Thread ID is 6755 2017-04-19T15:37:15.576Z,1492616235.576 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6756 2017-04-19T15:37:15.577Z,1492616235.577 [WetLabsBB2FL](INFO): Powering down 2017-04-19T15:37:15.604Z,1492616235.604 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6757 2017-04-19T15:37:15.610Z,1492616235.610 [Radio_Surface](INFO): Powering up 2017-04-19T15:37:15.620Z,1492616235.620 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6758 2017-04-19T15:37:15.620Z,1492616235.620 [Rowe_600LCM](INFO): Initializing 2017-04-19T15:37:15.621Z,1492616235.621 [Rowe_600LCM](INFO): Checking LCM 2017-04-19T15:37:15.621Z,1492616235.621 [Rowe_600LCM](INFO): LCM OK 2017-04-19T15:37:15.621Z,1492616235.621 [Rowe_600LCM](INFO): Powering up 2017-04-19T15:37:15.628Z,1492616235.628 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6759 2017-04-19T15:37:15.632Z,1492616235.632 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-04-19T15:37:15.632Z,1492616235.632 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-04-19T15:37:15.632Z,1492616235.632 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-04-19T15:37:15.633Z,1492616235.633 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-04-19T15:37:15.633Z,1492616235.633 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-04-19T15:37:15.634Z,1492616235.634 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-04-19T15:37:15.635Z,1492616235.635 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-04-19T15:37:15.636Z,1492616235.636 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-04-19T15:37:15.636Z,1492616235.636 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-04-19T15:37:15.637Z,1492616235.637 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-04-19T15:37:15.637Z,1492616235.637 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-04-19T15:37:15.638Z,1492616235.638 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-04-19T15:37:15.638Z,1492616235.638 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-04-19T15:37:15.638Z,1492616235.638 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-04-19T15:37:15.639Z,1492616235.639 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-04-19T15:37:15.631Z,1492616235.631 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-04-19T15:37:15.639Z,1492616235.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-04-19T15:37:15.639Z,1492616235.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-04-19T15:37:15.640Z,1492616235.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-04-19T15:37:15.640Z,1492616235.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-04-19T15:37:15.640Z,1492616235.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-04-19T15:37:15.640Z,1492616235.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-04-19T15:37:15.640Z,1492616235.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-04-19T15:37:15.641Z,1492616235.641 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-04-19T15:37:15.641Z,1492616235.641 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-04-19T15:37:15.641Z,1492616235.641 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-04-19T15:37:15.641Z,1492616235.641 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-04-19T15:37:15.641Z,1492616235.641 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-04-19T15:37:15.641Z,1492616235.641 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-04-19T15:37:15.642Z,1492616235.642 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-04-19T15:37:15.642Z,1492616235.642 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-04-19T15:37:15.643Z,1492616235.643 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-04-19T15:37:15.644Z,1492616235.644 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-04-19T15:37:15.673Z,1492616235.672 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-04-19T15:37:15.707Z,1492616235.707 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-19T15:37:15.734Z,1492616235.734 [MissionManager](DEBUG): 2017-04-19T15:37:15.747Z,1492616235.747 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-04-19T15:37:15.801Z,1492616235.801 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-04-19T15:37:15.803Z,1492616235.803 [Default:A.Wait](DEBUG): Construct Wait. 2017-04-19T15:37:15.820Z,1492616235.820 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-19T15:37:15.845Z,1492616235.845 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-04-19T15:37:15.863Z,1492616235.863 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-04-19T15:37:15.869Z,1492616235.869 [Default:E.Execute](DEBUG): Construct Execute. 2017-04-19T15:37:15.893Z,1492616235.893 [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 2017-04-19T15:37:15.900Z,1492616235.900 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,SCPI,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DepAvgTempFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-04-19T15:37:16.082Z,1492616236.082 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-04-19T15:37:16.128Z,1492616236.128 [Depth_Keller](ERROR): Pressure reading out of range: 1658.386841 decibar 2017-04-19T15:37:16.245Z,1492616236.245 [DAT](INFO): Powering up 2017-04-19T15:37:16.245Z,1492616236.245 [DAT](DEBUG): Initializing DAT. 2017-04-19T15:37:16.451Z,1492616236.451 [DepthRateCalculator](ERROR): Depth measurement is not active 2017-04-19T15:37:16.545Z,1492616236.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:16.545Z,1492616236.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:16.549Z,1492616236.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:16.570Z,1492616236.570 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:16.593Z,1492616236.593 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:16.712Z,1492616236.712 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-04-19T15:37:16.712Z,1492616236.712 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-04-19T15:37:16.730Z,1492616236.730 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-04-19T15:37:16.731Z,1492616236.731 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-04-19T15:37:16.755Z,1492616236.755 [MassServo](DEBUG): Initializing EZServoServo. 2017-04-19T15:37:16.756Z,1492616236.756 [MassServo](DEBUG): Initializing MassServo. 2017-04-19T15:37:16.761Z,1492616236.761 [RudderServo](DEBUG): Initializing EZServoServo. 2017-04-19T15:37:16.762Z,1492616236.762 [RudderServo](DEBUG): Initializing RudderServo. 2017-04-19T15:37:16.788Z,1492616236.788 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-04-19T15:37:16.789Z,1492616236.789 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-04-19T15:37:17.045Z,1492616237.045 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2017-04-19T15:37:17.045Z,1492616237.045 [DropWeight] Hardware Fault, FailCount= 1 2017-04-19T15:37:17.045Z,1492616237.045 [DropWeight](ERROR): Hardware Fault 2017-04-19T15:37:17.127Z,1492616237.127 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:17.127Z,1492616237.127 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:17.128Z,1492616237.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:17.129Z,1492616237.129 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:17.130Z,1492616237.130 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:17.152Z,1492616237.152 [CommandLine](FAULT): Scheduling is paused 2017-04-19T15:37:17.153Z,1492616237.153 [CBIT](INFO): Critical error at 20170419T153717 2017-04-19T15:37:17.153Z,1492616237.153 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-04-19T15:37:17.160Z,1492616237.160 [CBIT](ERROR): Hardware Fault in component: DropWeight 2017-04-19T15:37:17.160Z,1492616237.160 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2017-04-19T15:37:17.278Z,1492616237.278 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 454 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 11 2017-04-19T15:37:17.278Z,1492616237.278 [Aanderaa_O2] Data Fault, FailCount= 1 2017-04-19T15:37:17.278Z,1492616237.278 [Aanderaa_O2](ERROR): Data Fault 2017-04-19T15:37:17.332Z,1492616237.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:17.332Z,1492616237.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:17.334Z,1492616237.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:17.343Z,1492616237.343 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:17.345Z,1492616237.345 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:17.733Z,1492616237.733 [CBIT](INFO): Critical error at 20170419T153717 2017-04-19T15:37:17.735Z,1492616237.735 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2017-04-19T15:37:17.742Z,1492616237.741 [Aanderaa_O2](INFO): Powering down 2017-04-19T15:37:17.955Z,1492616237.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:17.956Z,1492616237.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:17.957Z,1492616237.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:17.957Z,1492616237.957 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:17.958Z,1492616237.958 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.153Z,1492616238.153 [NAL9602](INFO): Powering up NAL9602 2017-04-19T15:37:18.207Z,1492616238.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.208Z,1492616238.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:18.209Z,1492616238.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:18.209Z,1492616238.209 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.210Z,1492616238.210 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.591Z,1492616238.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.591Z,1492616238.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:18.592Z,1492616238.592 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:18.593Z,1492616238.593 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.594Z,1492616238.594 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.609Z,1492616238.609 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-04-19T15:37:18.610Z,1492616238.610 [Aanderaa_O2] No Fault, FailCount= 1 2017-04-19T15:37:18.979Z,1492616238.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.979Z,1492616238.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:18.980Z,1492616238.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:18.981Z,1492616238.981 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:18.982Z,1492616238.981 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:19.419Z,1492616239.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:19.419Z,1492616239.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:19.420Z,1492616239.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:19.421Z,1492616239.421 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:19.422Z,1492616239.422 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-19T15:37:19.842Z,1492616239.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:19.856Z,1492616239.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:19.993Z,1492616239.993 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-04-19T15:37:20.126Z,1492616240.126 [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 & 2017-04-19T15:37:20.230Z,1492616240.230 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-04-19T15:37:20.248Z,1492616240.248 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-04-19T15:37:20.249Z,1492616240.249 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-04-19T15:37:20.262Z,1492616240.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:20.275Z,1492616240.275 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:20.679Z,1492616240.679 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-19T15:37:20.680Z,1492616240.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:21.018Z,1492616241.018 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:21.406Z,1492616241.406 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:21.852Z,1492616241.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:22.232Z,1492616242.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:22.634Z,1492616242.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:23.013Z,1492616243.013 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:23.573Z,1492616243.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:23.842Z,1492616243.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:24.199Z,1492616244.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:24.641Z,1492616244.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:25.039Z,1492616245.039 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:25.468Z,1492616245.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:25.779Z,1492616245.779 [DAT](DEBUG): 2017-04-19T15:37:25.830Z,1492616245.830 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:26.231Z,1492616246.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:26.641Z,1492616246.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:27.028Z,1492616247.028 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2017-04-19T15:37:27.030Z,1492616247.030 [DAT](DEBUG): MF Frequency Band 2017-04-19T15:37:27.043Z,1492616247.043 [DAT](DEBUG): Directional Acoustic Transponder version 8.10.9 2017-04-19T15:37:27.061Z,1492616247.061 [DAT](DEBUG): Apr 19 2017 15:37:18 2017-04-19T15:37:27.200Z,1492616247.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:27.633Z,1492616247.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:27.914Z,1492616247.914 [DAT](DEBUG): WARNING: battery low 2017-04-19T15:37:27.919Z,1492616247.920 [DAT](DEBUG): Features enabled [Bearing] 2017-04-19T15:37:27.995Z,1492616247.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:28.271Z,1492616248.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:28.645Z,1492616248.645 [DAT](INFO): entering command mode 2017-04-19T15:37:28.722Z,1492616248.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:29.013Z,1492616249.013 [Aanderaa_O2](FAULT): Timed out starting 2017-04-19T15:37:29.013Z,1492616249.013 [Aanderaa_O2] Communications Fault, FailCount= 2 2017-04-19T15:37:29.014Z,1492616249.014 [Aanderaa_O2](ERROR): Communications Fault 2017-04-19T15:37:29.034Z,1492616249.034 [NAL9602](INFO): NAL9602 initialized 2017-04-19T15:37:29.066Z,1492616249.066 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:29.227Z,1492616249.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:29.340Z,1492616249.340 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-04-19T15:37:29.415Z,1492616249.415 [Aanderaa_O2](INFO): Powering down 2017-04-19T15:37:29.588Z,1492616249.588 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:29.720Z,1492616249.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:29.964Z,1492616249.964 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:30.051Z,1492616250.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:30.331Z,1492616250.331 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:30.358Z,1492616250.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:30.424Z,1492616250.424 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-04-19T15:37:30.424Z,1492616250.424 [Aanderaa_O2] No Fault, FailCount= 2 2017-04-19T15:37:30.721Z,1492616250.721 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:30.763Z,1492616250.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:31.128Z,1492616251.128 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:31.286Z,1492616251.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:31.585Z,1492616251.585 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:31.775Z,1492616251.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:31.949Z,1492616251.949 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 454 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 11 2017-04-19T15:37:31.949Z,1492616251.949 [Aanderaa_O2] Data Fault, FailCount= 3 2017-04-19T15:37:31.949Z,1492616251.949 [Aanderaa_O2](ERROR): Data Fault 2017-04-19T15:37:31.997Z,1492616251.997 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:32.078Z,1492616252.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:32.166Z,1492616252.165 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2017-04-19T15:37:32.166Z,1492616252.166 [CBIT](FAULT): Data Fault in component: Aanderaa_O2 2017-04-19T15:37:32.343Z,1492616252.343 [Aanderaa_O2](INFO): Powering down 2017-04-19T15:37:32.381Z,1492616252.381 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:32.446Z,1492616252.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:32.784Z,1492616252.784 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:32.944Z,1492616252.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:33.234Z,1492616253.234 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:33.408Z,1492616253.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:33.674Z,1492616253.674 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:33.845Z,1492616253.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:34.009Z,1492616254.009 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:34.059Z,1492616254.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:34.401Z,1492616254.401 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:34.466Z,1492616254.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:34.805Z,1492616254.805 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:34.841Z,1492616254.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:35.256Z,1492616255.256 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:35.373Z,1492616255.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:35.665Z,1492616255.665 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:35.795Z,1492616255.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:36.056Z,1492616256.056 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:36.098Z,1492616256.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:36.401Z,1492616256.401 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:36.462Z,1492616256.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:36.811Z,1492616256.811 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:36.910Z,1492616256.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:37.301Z,1492616257.301 [DAT](FAULT): invalid communications rate; deviceResponse_:Teledyne Benthos DAT-900 Series MF Frequency Band Directional Acoustic Transponder version 8.10.9 Apr 19 2017 15:37:28 WARNING: battery low Features enabled [Bearing] WARNING: Modem reset during xmit; lowering xmit power level by 3 dB. WARNING: Reduced xmit power level to 6 due to weak battery. CONNECT 00800 bits/sec 2017-04-19T15:37:37.301Z,1492616257.301 [DAT] Communications Fault, FailCount= 1 2017-04-19T15:37:37.302Z,1492616257.302 [DAT](ERROR): Communications Fault 2017-04-19T15:37:37.469Z,1492616257.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:37.591Z,1492616257.591 [CBIT](ERROR): Communications Fault in component: DAT 2017-04-19T15:37:37.705Z,1492616257.705 [DAT](INFO): Powering down 2017-04-19T15:37:38.074Z,1492616258.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:38.262Z,1492616258.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:38.660Z,1492616258.660 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:38.805Z,1492616258.805 [CBIT](INFO): Clearing failed state for component DAT 2017-04-19T15:37:38.805Z,1492616258.805 [DAT] No Fault, FailCount= 1 2017-04-19T15:37:39.040Z,1492616259.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:39.626Z,1492616259.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:40.103Z,1492616260.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:40.486Z,1492616260.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:40.761Z,1492616260.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:41.102Z,1492616261.102 [DAT](INFO): Powering up 2017-04-19T15:37:41.102Z,1492616261.102 [DAT](DEBUG): Initializing DAT. 2017-04-19T15:37:41.139Z,1492616261.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:41.667Z,1492616261.667 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:42.140Z,1492616262.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:42.444Z,1492616262.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:42.758Z,1492616262.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:43.274Z,1492616263.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:43.785Z,1492616263.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:44.002Z,1492616264.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:44.454Z,1492616264.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:44.804Z,1492616264.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:45.358Z,1492616265.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:45.744Z,1492616265.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:46.043Z,1492616266.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:46.515Z,1492616266.515 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:46.810Z,1492616266.810 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:47.450Z,1492616267.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:47.938Z,1492616267.938 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:48.139Z,1492616268.139 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:48.518Z,1492616268.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:49.070Z,1492616269.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:49.777Z,1492616269.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:50.007Z,1492616270.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:50.378Z,1492616270.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:50.785Z,1492616270.785 [DAT](DEBUG): 2017-04-19T15:37:50.811Z,1492616270.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:51.329Z,1492616271.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:51.738Z,1492616271.738 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:52.006Z,1492616272.006 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2017-04-19T15:37:52.009Z,1492616272.009 [DAT](DEBUG): MF Frequency Band 2017-04-19T15:37:52.012Z,1492616272.012 [DAT](DEBUG): Directional Acoustic Transponder version 8.10.9 2017-04-19T15:37:52.014Z,1492616272.014 [DAT](DEBUG): Apr 19 2017 15:37:44 2017-04-19T15:37:52.149Z,1492616272.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:52.397Z,1492616272.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:52.773Z,1492616272.773 [DAT](DEBUG): WARNING: battery low 2017-04-19T15:37:52.773Z,1492616272.773 [DAT](DEBUG): Features enabled [Bearing] 2017-04-19T15:37:52.799Z,1492616272.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:53.186Z,1492616273.186 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:53.600Z,1492616273.600 [DAT](INFO): entering command mode 2017-04-19T15:37:53.818Z,1492616273.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:54.054Z,1492616274.054 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:54.211Z,1492616274.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:54.434Z,1492616274.434 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:54.473Z,1492616274.473 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:54.817Z,1492616274.817 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:54.846Z,1492616274.846 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:55.217Z,1492616275.217 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:55.274Z,1492616275.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:55.682Z,1492616275.682 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:55.824Z,1492616275.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:56.077Z,1492616276.077 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:56.201Z,1492616276.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:56.434Z,1492616276.434 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:56.451Z,1492616276.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:56.850Z,1492616276.851 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:57.067Z,1492616277.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:57.313Z,1492616277.313 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:57.530Z,1492616277.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:57.783Z,1492616277.783 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:57.917Z,1492616277.917 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:58.096Z,1492616278.096 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:58.153Z,1492616278.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:58.492Z,1492616278.492 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:58.546Z,1492616278.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:58.910Z,1492616278.910 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:59.073Z,1492616279.073 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:59.346Z,1492616279.346 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:59.509Z,1492616279.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:37:59.749Z,1492616279.750 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:37:59.886Z,1492616279.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:00.081Z,1492616280.081 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:38:00.153Z,1492616280.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:00.481Z,1492616280.481 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:38:00.534Z,1492616280.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:00.926Z,1492616280.926 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:38:01.067Z,1492616281.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:01.322Z,1492616281.322 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:38:01.452Z,1492616281.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:01.720Z,1492616281.720 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-19T15:38:01.882Z,1492616281.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:02.091Z,1492616282.091 [DAT](FAULT): invalid communications rate; deviceResponse_:Teledyne Benthos DAT-900 Series MF Frequency Band Directional Acoustic Transponder version 8.10.9 Apr 19 2017 15:37:53 WARNING: battery low Features enabled [Bearing] WARNING: Modem reset during xmit; lowering xmit power level by 3 dB. WARNING: Reduced xmit power level to 6 due to weak battery. CONNECT 00800 bits/sec 2017-04-19T15:38:02.091Z,1492616282.091 [DAT] Communications Fault, FailCount= 2 2017-04-19T15:38:02.091Z,1492616282.092 [DAT](ERROR): Communications Fault 2017-04-19T15:38:02.142Z,1492616282.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:02.218Z,1492616282.218 [CBIT](ERROR): Communications Fault in component: DAT 2017-04-19T15:38:02.520Z,1492616282.520 [DAT](INFO): Powering down 2017-04-19T15:38:02.557Z,1492616282.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:03.125Z,1492616283.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:03.511Z,1492616283.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:03.624Z,1492616283.624 [CBIT](INFO): Clearing failed state for component DAT 2017-04-19T15:38:03.625Z,1492616283.625 [DAT] No Fault, FailCount= 2 2017-04-19T15:38:03.894Z,1492616283.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:04.129Z,1492616284.129 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:04.545Z,1492616284.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:04.941Z,1492616284.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:05.448Z,1492616285.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:05.743Z,1492616285.743 [DAT](INFO): Powering up 2017-04-19T15:38:05.743Z,1492616285.743 [DAT](DEBUG): Initializing DAT. 2017-04-19T15:38:05.933Z,1492616285.933 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:06.172Z,1492616286.172 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:06.499Z,1492616286.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:06.820Z,1492616286.820 [CommandLine](IMPORTANT): got command quit 2017-04-19T15:38:06.927Z,1492616286.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:07.430Z,1492616287.430 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:07.835Z,1492616287.835 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-19T15:38:07.836Z,1492616287.836 [CommandLine ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:07.872Z,1492616287.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:07.932Z,1492616287.932 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2017-04-19T15:38:07.932Z,1492616287.932 [CommandLine ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:07.934Z,1492616287.934 [CommandLine](INFO): Join timeout helper Thread ID is 6765 2017-04-19T15:38:07.964Z,1492616287.964 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2017-04-19T15:38:07.964Z,1492616287.964 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:07.966Z,1492616287.966 [NavChartDb](INFO): Join timeout helper Thread ID is 6766 2017-04-19T15:38:08.326Z,1492616288.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:08.564Z,1492616288.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:08.945Z,1492616288.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:09.436Z,1492616289.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:09.921Z,1492616289.921 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:09.000Z,1492616290.000 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-19T15:38:10.001Z,1492616290.001 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.012Z,1492616290.012 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2017-04-19T15:38:10.012Z,1492616290.012 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.013Z,1492616290.013 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6767 2017-04-19T15:38:10.111Z,1492616290.111 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-19T15:38:10.111Z,1492616290.111 [Rowe_600LCM](INFO): Powering down 2017-04-19T15:38:10.114Z,1492616290.114 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.128Z,1492616290.128 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2017-04-19T15:38:10.128Z,1492616290.128 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.129Z,1492616290.129 [Radio_Surface](INFO): Join timeout helper Thread ID is 6768 2017-04-19T15:38:10.181Z,1492616290.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:10.371Z,1492616290.372 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-19T15:38:10.372Z,1492616290.372 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.387Z,1492616290.387 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2017-04-19T15:38:10.387Z,1492616290.387 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.388Z,1492616290.388 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6769 2017-04-19T15:38:10.530Z,1492616290.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-19T15:38:10.656Z,1492616290.656 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-19T15:38:10.656Z,1492616290.656 [WetLabsBB2FL](INFO): Powering down 2017-04-19T15:38:10.656Z,1492616290.657 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.676Z,1492616290.676 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2017-04-19T15:38:10.676Z,1492616290.676 [logger ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.677Z,1492616290.677 [logger](INFO): Join timeout helper Thread ID is 6770 2017-04-19T15:38:10.705Z,1492616290.705 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-19T15:38:10.705Z,1492616290.705 [logger ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.708Z,1492616290.708 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2017-04-19T15:38:10.708Z,1492616290.708 [CommandLine ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.708Z,1492616290.708 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2017-04-19T15:38:10.708Z,1492616290.708 [controlThread ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.709Z,1492616290.709 [controlThread](INFO): Join timeout helper Thread ID is 6771 2017-04-19T15:38:10.855Z,1492616290.855 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2017-04-19T15:38:10.855Z,1492616290.855 [controlThread](DEBUG): Uninitializing ControlThread 2017-04-19T15:38:10.856Z,1492616290.856 [Aanderaa_O2](INFO): Powering down 2017-04-19T15:38:10.858Z,1492616290.858 [NAL9602](INFO): Powering down 2017-04-19T15:38:10.859Z,1492616290.859 [DAT](INFO): Powering down 2017-04-19T15:38:10.860Z,1492616290.860 [PNI_TCM](INFO): Powering down 2017-04-19T15:38:10.861Z,1492616290.861 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-04-19T15:38:10.862Z,1492616290.862 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2017-04-19T15:38:10.863Z,1492616290.863 [MissionManager](INFO): Uninitializing Mission Default 2017-04-19T15:38:10.865Z,1492616290.865 [MissionManager](INFO): Uninitializing Mission Startup 2017-04-19T15:38:10.866Z,1492616290.866 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2017-04-19T15:38:10.866Z,1492616290.866 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2017-04-19T15:38:10.866Z,1492616290.866 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2017-04-19T15:38:10.867Z,1492616290.867 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2017-04-19T15:38:10.867Z,1492616290.867 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-04-19T15:38:10.867Z,1492616290.867 [BuoyancyServo](INFO): Powering down 2017-04-19T15:38:10.879Z,1492616290.879 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2017-04-19T15:38:10.879Z,1492616290.879 [ElevatorServo](INFO): Powering down 2017-04-19T15:38:10.899Z,1492616290.899 [MassServo](DEBUG): Uninitialize Mass Servo. 2017-04-19T15:38:10.899Z,1492616290.899 [MassServo](INFO): Powering down 2017-04-19T15:38:10.900Z,1492616290.900 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2017-04-19T15:38:10.900Z,1492616290.900 [RudderServo](INFO): Powering down 2017-04-19T15:38:10.901Z,1492616290.901 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-04-19T15:38:10.901Z,1492616290.901 [ThrusterServo](INFO): Powering down 2017-04-19T15:38:10.901Z,1492616290.901 [SBIT](DEBUG): Uninitialize SBIT Component. 2017-04-19T15:38:10.902Z,1492616290.902 [IBIT](DEBUG): Uninitialize IBIT Component. 2017-04-19T15:38:10.902Z,1492616290.902 [CBIT](DEBUG): Uninitialize CBIT Component. 2017-04-19T15:38:10.903Z,1492616290.903 [controlThread ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:10.964Z,1492616290.964 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:11.023Z,1492616291.023 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:11.053Z,1492616291.053 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:11.093Z,1492616291.093 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2017-04-19T15:38:11.138Z,1492616291.138 [logger ThreadHandler](INFO): Thread cancelled.