2018-04-30T21:03:17.481Z,1525122197.481 [Supervisor](DEBUG): Initializing supervisor. 2018-04-30T21:03:17.484Z,1525122197.484 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-04-30T21:03:17.484Z,1525122197.484 [SyncHandler](INFO): Protected caller Thread ID is 799 2018-04-30T21:03:17.484Z,1525122197.484 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-04-30T21:03:17.485Z,1525122197.485 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-04-30T21:03:17.486Z,1525122197.486 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800 2018-04-30T21:03:17.489Z,1525122197.489 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-04-30T21:03:17.500Z,1525122197.500 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-04-30T21:03:17.501Z,1525122197.501 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-04-30T21:03:17.502Z,1525122197.502 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801 2018-04-30T21:03:17.502Z,1525122197.502 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-04-30T21:03:17.503Z,1525122197.503 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-04-30T21:03:17.504Z,1525122197.504 [logger ThreadHandler](INFO): Protected caller Thread ID is 802 2018-04-30T21:03:17.506Z,1525122197.506 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-04-30T21:03:17.506Z,1525122197.506 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-04-30T21:03:17.510Z,1525122197.510 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-04-30T21:03:17.611Z,1525122197.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-04-30T21:03:17.612Z,1525122197.612 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-04-30T21:03:17.714Z,1525122197.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-04-30T21:03:17.715Z,1525122197.715 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-04-30T21:03:17.925Z,1525122197.925 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-04-30T21:03:17.925Z,1525122197.925 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-04-30T21:03:18.242Z,1525122198.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-04-30T21:03:18.244Z,1525122198.244 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-04-30T21:03:18.592Z,1525122198.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-04-30T21:03:18.594Z,1525122198.594 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-04-30T21:03:19.063Z,1525122199.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-04-30T21:03:19.065Z,1525122199.065 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-04-30T21:03:19.418Z,1525122199.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-04-30T21:03:19.419Z,1525122199.419 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-04-30T21:03:19.953Z,1525122199.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-04-30T21:03:19.953Z,1525122199.953 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-04-30T21:03:20.042Z,1525122200.042 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-04-30T21:03:20.349Z,1525122200.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-04-30T21:03:20.350Z,1525122200.350 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-04-30T21:03:20.497Z,1525122200.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-04-30T21:03:20.498Z,1525122200.498 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-04-30T21:03:20.709Z,1525122200.709 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-04-30T21:03:20.710Z,1525122200.710 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-04-30T21:03:20.985Z,1525122200.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-04-30T21:03:20.986Z,1525122200.986 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-04-30T21:03:21.387Z,1525122201.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-04-30T21:03:21.387Z,1525122201.387 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-04-30T21:03:21.531Z,1525122201.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-04-30T21:03:21.533Z,1525122201.533 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-04-30T21:03:21.536Z,1525122201.536 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-04-30T21:03:21.768Z,1525122201.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-04-30T21:03:21.768Z,1525122201.768 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-04-30T21:03:21.847Z,1525122201.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-04-30T21:03:21.994Z,1525122201.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-04-30T21:03:22.089Z,1525122202.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-04-30T21:03:22.175Z,1525122202.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-04-30T21:03:22.315Z,1525122202.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-04-30T21:03:22.487Z,1525122202.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-04-30T21:03:22.587Z,1525122202.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-04-30T21:03:22.681Z,1525122202.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-04-30T21:03:22.780Z,1525122202.780 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-04-30T21:03:22.865Z,1525122202.865 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2018-04-30T21:03:22.866Z,1525122202.866 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-04-30T21:03:22.881Z,1525122202.881 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-04-30T21:03:23.005Z,1525122203.005 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-04-30T21:03:23.006Z,1525122203.006 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-04-30T21:03:23.029Z,1525122203.029 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-04-30T21:03:23.030Z,1525122203.030 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-04-30T21:03:23.085Z,1525122203.085 [DepthRateCalculator] Loaded 2018-04-30T21:03:23.085Z,1525122203.085 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-04-30T21:03:23.091Z,1525122203.091 [PitchRateCalculator] Loaded 2018-04-30T21:03:23.091Z,1525122203.091 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-04-30T21:03:23.107Z,1525122203.107 [SpeedCalculator] Loaded 2018-04-30T21:03:23.107Z,1525122203.107 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-04-30T21:03:23.128Z,1525122203.128 [TempGradientCalculator] Loaded 2018-04-30T21:03:23.128Z,1525122203.128 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-04-30T21:03:23.143Z,1525122203.143 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-04-30T21:03:23.144Z,1525122203.144 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-04-30T21:03:23.149Z,1525122203.149 [YawRateCalculator] Loaded 2018-04-30T21:03:23.150Z,1525122203.150 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-04-30T21:03:23.189Z,1525122203.189 [ElevatorOffsetCalculator] Loaded 2018-04-30T21:03:23.189Z,1525122203.189 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-04-30T21:03:23.189Z,1525122203.189 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-04-30T21:03:23.190Z,1525122203.190 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-04-30T21:03:23.227Z,1525122203.227 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-04-30T21:03:23.227Z,1525122203.227 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-04-30T21:03:23.673Z,1525122203.673 [AcousticModem_Benthos_ATM900] Loaded 2018-04-30T21:03:23.673Z,1525122203.673 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-04-30T21:03:23.808Z,1525122203.808 [DataOverHttps] Loaded 2018-04-30T21:03:23.808Z,1525122203.808 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-04-30T21:03:23.821Z,1525122203.821 [Depth_Keller] Loaded 2018-04-30T21:03:23.822Z,1525122203.822 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-04-30T21:03:23.916Z,1525122203.916 [NAL9602] Loaded 2018-04-30T21:03:23.917Z,1525122203.917 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-04-30T21:03:23.932Z,1525122203.932 [Onboard] Loaded 2018-04-30T21:03:23.933Z,1525122203.933 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-04-30T21:03:23.942Z,1525122203.942 [Radio_Surface] Loaded 2018-04-30T21:03:23.943Z,1525122203.943 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-04-30T21:03:23.944Z,1525122203.944 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A64E0 2018-04-30T21:03:23.944Z,1525122203.944 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882 2018-04-30T21:03:24.096Z,1525122204.096 [PNI_TCM] Loaded 2018-04-30T21:03:24.096Z,1525122204.096 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-04-30T21:03:25.814Z,1525122205.814 [BPC1] Loaded 2018-04-30T21:03:25.815Z,1525122205.815 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-04-30T21:03:25.815Z,1525122205.815 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-04-30T21:03:25.817Z,1525122205.817 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-04-30T21:03:25.968Z,1525122205.968 [SBIT](DEBUG): Construct Startup Built In Test. 2018-04-30T21:03:25.991Z,1525122205.991 [SBIT] Loaded 2018-04-30T21:03:25.991Z,1525122205.991 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-04-30T21:03:25.992Z,1525122205.992 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-04-30T21:03:26.003Z,1525122206.003 [IBIT] Loaded 2018-04-30T21:03:26.003Z,1525122206.003 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-04-30T21:03:26.006Z,1525122206.006 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-04-30T21:03:26.152Z,1525122206.152 [CBIT] Loaded 2018-04-30T21:03:26.152Z,1525122206.152 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-04-30T21:03:26.152Z,1525122206.152 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-04-30T21:03:26.153Z,1525122206.153 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-04-30T21:03:26.730Z,1525122206.730 [Aanderaa_O2] Loaded 2018-04-30T21:03:26.730Z,1525122206.730 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-04-30T21:03:26.822Z,1525122206.822 [CTD_Seabird] Loaded 2018-04-30T21:03:26.822Z,1525122206.822 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-04-30T21:03:26.823Z,1525122206.823 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408664E0 2018-04-30T21:03:26.824Z,1525122206.824 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 883 2018-04-30T21:03:26.840Z,1525122206.840 [ESPComponent] Loaded 2018-04-30T21:03:26.840Z,1525122206.840 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-04-30T21:03:26.854Z,1525122206.854 [PAR_Licor] Loaded 2018-04-30T21:03:26.854Z,1525122206.854 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-04-30T21:03:26.902Z,1525122206.902 [WetLabsBB2FL] Loaded 2018-04-30T21:03:26.903Z,1525122206.903 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-04-30T21:03:26.904Z,1525122206.904 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408964E0 2018-04-30T21:03:26.904Z,1525122206.904 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 884 2018-04-30T21:03:26.905Z,1525122206.905 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-04-30T21:03:26.905Z,1525122206.905 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-04-30T21:03:27.180Z,1525122207.180 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-04-30T21:03:27.182Z,1525122207.182 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-04-30T21:03:27.267Z,1525122207.267 [VerticalControl](DEBUG): Construct VerticalControl. 2018-04-30T21:03:27.360Z,1525122207.360 [VerticalControl] Loaded 2018-04-30T21:03:27.361Z,1525122207.361 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-04-30T21:03:27.361Z,1525122207.361 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-04-30T21:03:27.421Z,1525122207.421 [HorizontalControl] Loaded 2018-04-30T21:03:27.422Z,1525122207.422 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-04-30T21:03:27.422Z,1525122207.422 [SpeedControl](DEBUG): Construct SpeedControl. 2018-04-30T21:03:27.428Z,1525122207.428 [SpeedControl] Loaded 2018-04-30T21:03:27.428Z,1525122207.428 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-04-30T21:03:27.429Z,1525122207.429 [LoopControl](DEBUG): Construct LoopControl. 2018-04-30T21:03:27.429Z,1525122207.429 [LoopControl] Loaded 2018-04-30T21:03:27.429Z,1525122207.429 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-04-30T21:03:27.430Z,1525122207.430 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-04-30T21:03:27.431Z,1525122207.431 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-04-30T21:03:27.549Z,1525122207.549 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-04-30T21:03:27.549Z,1525122207.549 [StratificationFrontDetector](DEBUG): (re)initializing 2018-04-30T21:03:27.550Z,1525122207.550 [StratificationFrontDetector] Loaded 2018-04-30T21:03:27.550Z,1525122207.550 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-04-30T21:03:27.551Z,1525122207.551 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-04-30T21:03:27.551Z,1525122207.551 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-04-30T21:03:27.668Z,1525122207.668 [BuoyancyServo] Loaded 2018-04-30T21:03:27.668Z,1525122207.668 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-04-30T21:03:27.680Z,1525122207.680 [ElevatorServo] Loaded 2018-04-30T21:03:27.680Z,1525122207.680 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-04-30T21:03:27.691Z,1525122207.691 [MassServo] Loaded 2018-04-30T21:03:27.691Z,1525122207.691 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-04-30T21:03:27.702Z,1525122207.702 [RudderServo] Loaded 2018-04-30T21:03:27.703Z,1525122207.703 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-04-30T21:03:27.713Z,1525122207.713 [ThrusterServo] Loaded 2018-04-30T21:03:27.714Z,1525122207.714 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-04-30T21:03:27.714Z,1525122207.714 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-04-30T21:03:27.715Z,1525122207.715 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-04-30T21:03:27.826Z,1525122207.826 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-04-30T21:03:27.827Z,1525122207.827 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-04-30T21:03:27.881Z,1525122207.881 [DeadReckonUsingSpeedCalculator] Loaded 2018-04-30T21:03:27.881Z,1525122207.881 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-04-30T21:03:27.897Z,1525122207.897 [NavChart] Loaded 2018-04-30T21:03:27.898Z,1525122207.898 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-04-30T21:03:27.901Z,1525122207.901 [UniversalFixResidualReporter] Loaded 2018-04-30T21:03:27.902Z,1525122207.902 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-04-30T21:03:27.902Z,1525122207.902 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-04-30T21:03:27.906Z,1525122207.906 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-04-30T21:03:27.907Z,1525122207.907 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-04-30T21:03:27.913Z,1525122207.913 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-04-30T21:03:27.914Z,1525122207.914 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0 2018-04-30T21:03:27.915Z,1525122207.915 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885 2018-04-30T21:03:27.919Z,1525122207.919 [Supervisor](INFO): Main Thread ID is 795 2018-04-30T21:03:27.919Z,1525122207.919 [Supervisor](DEBUG): Running supervisor. 2018-04-30T21:03:27.920Z,1525122207.920 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886 2018-04-30T21:03:27.923Z,1525122207.923 [controlThread ThreadHandler](INFO): Handler Thread ID is 887 2018-04-30T21:03:27.923Z,1525122207.923 [controlThread](DEBUG): Initializing ControlThread 2018-04-30T21:03:27.924Z,1525122207.924 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-04-30T21:03:27.924Z,1525122207.924 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-04-30T21:03:27.925Z,1525122207.925 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-04-30T21:03:27.925Z,1525122207.925 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-04-30T21:03:27.925Z,1525122207.925 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-04-30T21:03:27.926Z,1525122207.926 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-04-30T21:03:27.927Z,1525122207.927 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-04-30T21:03:27.932Z,1525122207.932 [SBIT](INFO): Initialize SBIT Component. 2018-04-30T21:03:27.932Z,1525122207.932 [SBIT](IMPORTANT): git: 2018-03-26-2-gc06366e 2018-04-30T21:03:27.932Z,1525122207.932 [SBIT](INFO): git hash: c06366e488986061b2c97b8bb696d868dcd187c8 2018-04-30T21:03:27.933Z,1525122207.933 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-04-30T21:03:27.934Z,1525122207.934 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #16 PREEMPT Thu Apr 12 16:20:47 PDT 2018 2018-04-30T21:03:27.935Z,1525122207.935 [SBIT](INFO): Beginning SBIT in 52.000000 seconds. 2018-04-30T21:03:27.935Z,1525122207.935 [IBIT](INFO): Initialize IBIT Component. 2018-04-30T21:03:27.936Z,1525122207.936 [CBIT](DEBUG): Initialize CBIT Component. 2018-04-30T21:03:27.936Z,1525122207.936 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-04-30T21:03:27.937Z,1525122207.937 [logger ThreadHandler](INFO): Handler Thread ID is 888 2018-04-30T21:03:27.955Z,1525122207.955 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889 2018-04-30T21:03:27.961Z,1525122207.961 [Radio_Surface](INFO): Powering up 2018-04-30T21:03:27.967Z,1525122207.967 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 890 2018-04-30T21:03:27.968Z,1525122207.968 [CTD_Seabird](INFO): Initializing 2018-04-30T21:03:27.968Z,1525122207.968 [CTD_Seabird](INFO): Checking LCM 2018-04-30T21:03:28.047Z,1525122208.047 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-04-30T21:03:28.049Z,1525122208.049 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-04-30T21:03:28.049Z,1525122208.049 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-04-30T21:03:28.050Z,1525122208.050 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-04-30T21:03:28.052Z,1525122208.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-04-30T21:03:28.052Z,1525122208.052 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-04-30T21:03:28.053Z,1525122208.053 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-04-30T21:03:28.088Z,1525122208.088 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-04-30T21:03:28.089Z,1525122208.089 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-04-30T21:03:28.089Z,1525122208.089 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-04-30T21:03:28.089Z,1525122208.089 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-04-30T21:03:28.090Z,1525122208.090 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-04-30T21:03:28.091Z,1525122208.091 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-04-30T21:03:28.102Z,1525122208.102 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 891 2018-04-30T21:03:28.103Z,1525122208.103 [WetLabsBB2FL](INFO): Powering down 2018-04-30T21:03:28.237Z,1525122208.237 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 892 2018-04-30T21:03:28.245Z,1525122208.245 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-04-30T21:03:28.245Z,1525122208.245 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-04-30T21:03:28.245Z,1525122208.245 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-04-30T21:03:28.245Z,1525122208.245 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-04-30T21:03:28.245Z,1525122208.245 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-04-30T21:03:28.246Z,1525122208.246 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-04-30T21:03:28.246Z,1525122208.246 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-04-30T21:03:28.246Z,1525122208.246 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-04-30T21:03:28.246Z,1525122208.246 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-04-30T21:03:28.246Z,1525122208.246 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-04-30T21:03:28.246Z,1525122208.246 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-04-30T21:03:28.247Z,1525122208.247 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-04-30T21:03:28.247Z,1525122208.247 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-04-30T21:03:28.247Z,1525122208.247 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-04-30T21:03:28.247Z,1525122208.247 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-04-30T21:03:28.247Z,1525122208.247 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-04-30T21:03:28.333Z,1525122208.333 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-04-30T21:03:28.352Z,1525122208.352 [MissionManager](DEBUG): 2018-04-30T21:03:28.352Z,1525122208.352 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-04-30T21:03:28.452Z,1525122208.452 [CTD_Seabird](INFO): LCM OK 2018-04-30T21:03:28.452Z,1525122208.452 [CTD_Seabird](INFO): Powering up 2018-04-30T21:03:28.459Z,1525122208.459 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-04-30T21:03:28.460Z,1525122208.460 [Default:A.Wait](DEBUG): Construct Wait. 2018-04-30T21:03:28.462Z,1525122208.462 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-04-30T21:03:28.485Z,1525122208.485 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-04-30T21:03:28.519Z,1525122208.519 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-04-30T21:03:28.525Z,1525122208.525 [Default:E.Execute](DEBUG): Construct Execute. 2018-04-30T21:03:28.548Z,1525122208.548 [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-04-30T21:03:28.553Z,1525122208.553 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-04-30T21:03:28.595Z,1525122208.595 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-04-30T21:03:28.595Z,1525122208.595 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-04-30T21:03:28.622Z,1525122208.622 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-04-30T21:03:28.981Z,1525122208.981 [ESPComponent](INFO): powering down ESP 2018-04-30T21:03:29.476Z,1525122209.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:29.573Z,1525122209.573 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:29.683Z,1525122209.683 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-04-30T21:03:29.691Z,1525122209.691 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-04-30T21:03:29.716Z,1525122209.716 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-04-30T21:03:29.723Z,1525122209.723 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-04-30T21:03:29.736Z,1525122209.736 [MassServo](DEBUG): Initializing EZServoServo. 2018-04-30T21:03:29.743Z,1525122209.743 [MassServo](DEBUG): Initializing MassServo. 2018-04-30T21:03:29.756Z,1525122209.756 [RudderServo](DEBUG): Initializing EZServoServo. 2018-04-30T21:03:29.763Z,1525122209.763 [RudderServo](DEBUG): Initializing RudderServo. 2018-04-30T21:03:29.768Z,1525122209.768 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-04-30T21:03:29.775Z,1525122209.775 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-04-30T21:03:30.039Z,1525122210.039 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:30.040Z,1525122210.040 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:30.339Z,1525122210.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:30.340Z,1525122210.340 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:30.679Z,1525122210.679 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-04-30T21:03:30.679Z,1525122210.679 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-04-30T21:03:30.679Z,1525122210.679 [BuoyancyServo] Communications Fault, FailCount= 1 2018-04-30T21:03:30.679Z,1525122210.679 [BuoyancyServo](ERROR): Communications Fault 2018-04-30T21:03:30.886Z,1525122210.886 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-04-30T21:03:30.970Z,1525122210.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:30.979Z,1525122210.979 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:30.984Z,1525122210.984 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-04-30T21:03:30.985Z,1525122210.985 [BuoyancyServo](INFO): Powering down 2018-04-30T21:03:31.366Z,1525122211.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:31.375Z,1525122211.375 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:31.747Z,1525122211.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:31.748Z,1525122211.748 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:32.138Z,1525122212.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:32.147Z,1525122212.147 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:32.543Z,1525122212.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:32.544Z,1525122212.544 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:32.947Z,1525122212.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:32.948Z,1525122212.948 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:33.358Z,1525122213.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:33.367Z,1525122213.367 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:33.519Z,1525122213.519 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-04-30T21:03:33.519Z,1525122213.519 [BuoyancyServo] No Fault, FailCount= 1 2018-04-30T21:03:33.585Z,1525122213.585 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-04-30T21:03:33.670Z,1525122213.670 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-04-30T21:03:33.753Z,1525122213.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:33.764Z,1525122213.764 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:33.792Z,1525122213.792 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-04-30T21:03:33.792Z,1525122213.792 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-04-30T21:03:33.873Z,1525122213.873 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-04-30T21:03:34.158Z,1525122214.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:34.175Z,1525122214.175 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:34.411Z,1525122214.411 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-04-30T21:03:34.411Z,1525122214.411 [CTD_Seabird](INFO): Powering down 2018-04-30T21:03:34.546Z,1525122214.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:34.563Z,1525122214.563 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:34.960Z,1525122214.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:34.961Z,1525122214.961 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:35.362Z,1525122215.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:35.375Z,1525122215.375 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:35.747Z,1525122215.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:35.748Z,1525122215.748 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:36.095Z,1525122216.095 [AcousticModem_Benthos_ATM900](DEBUG): 2018-04-30T21:03:36.176Z,1525122216.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:36.177Z,1525122216.177 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:36.516Z,1525122216.516 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-04-30T21:03:36.516Z,1525122216.516 [PNI_TCM] Data Fault, FailCount= 1 2018-04-30T21:03:36.516Z,1525122216.516 [PNI_TCM](ERROR): Data Fault 2018-04-30T21:03:36.555Z,1525122216.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:36.556Z,1525122216.556 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:36.719Z,1525122216.719 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-04-30T21:03:36.916Z,1525122216.916 [PNI_TCM](INFO): Powering down 2018-04-30T21:03:37.075Z,1525122217.075 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:37.076Z,1525122217.076 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:37.340Z,1525122217.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:37.342Z,1525122217.342 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:37.696Z,1525122217.696 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2018-04-30T21:03:37.696Z,1525122217.696 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2018-04-30T21:03:37.697Z,1525122217.697 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2018-04-30T21:03:37.697Z,1525122217.697 [AcousticModem_Benthos_ATM900](DEBUG): Mar 28 2000 17:56:55 2018-04-30T21:03:37.773Z,1525122217.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:37.774Z,1525122217.774 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:38.095Z,1525122218.095 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2018-04-30T21:03:38.155Z,1525122218.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:38.156Z,1525122218.156 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:38.223Z,1525122218.223 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-04-30T21:03:38.223Z,1525122218.223 [PNI_TCM] No Fault, FailCount= 1 2018-04-30T21:03:38.564Z,1525122218.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:38.565Z,1525122218.565 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:38.895Z,1525122218.895 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2018-04-30T21:03:38.955Z,1525122218.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:38.956Z,1525122218.956 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:39.295Z,1525122219.295 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-04-30T21:03:39.295Z,1525122219.295 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2018-04-30T21:03:39.295Z,1525122219.295 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2018-04-30T21:03:39.327Z,1525122219.327 [Aanderaa_O2](FAULT): Timed out starting 2018-04-30T21:03:39.327Z,1525122219.327 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-04-30T21:03:39.327Z,1525122219.327 [Aanderaa_O2](ERROR): Communications Fault 2018-04-30T21:03:39.356Z,1525122219.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:39.357Z,1525122219.357 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:39.536Z,1525122219.536 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-04-30T21:03:39.695Z,1525122219.695 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2018-04-30T21:03:39.843Z,1525122219.843 [Aanderaa_O2](INFO): Powering down 2018-04-30T21:03:39.904Z,1525122219.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:39.905Z,1525122219.905 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:40.095Z,1525122220.095 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2018-04-30T21:03:40.096Z,1525122220.096 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2018-04-30T21:03:40.183Z,1525122220.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:40.184Z,1525122220.184 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:40.495Z,1525122220.495 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2018-04-30T21:03:40.548Z,1525122220.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:40.549Z,1525122220.549 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:40.611Z,1525122220.611 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-04-30T21:03:40.611Z,1525122220.611 [Aanderaa_O2] No Fault, FailCount= 1 2018-04-30T21:03:40.952Z,1525122220.952 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:40.953Z,1525122220.953 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:41.379Z,1525122221.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:41.380Z,1525122221.380 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:41.736Z,1525122221.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:41.737Z,1525122221.737 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:42.143Z,1525122222.143 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:42.144Z,1525122222.144 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:42.567Z,1525122222.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:42.568Z,1525122222.568 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:42.934Z,1525122222.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:42.947Z,1525122222.947 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:43.351Z,1525122223.351 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:43.352Z,1525122223.352 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:43.755Z,1525122223.755 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:43.756Z,1525122223.756 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:44.148Z,1525122224.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:44.149Z,1525122224.149 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:44.546Z,1525122224.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:44.575Z,1525122224.575 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:44.928Z,1525122224.928 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:44.928Z,1525122224.928 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:45.341Z,1525122225.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:45.342Z,1525122225.342 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:45.717Z,1525122225.717 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-04-30T21:03:45.717Z,1525122225.717 [PNI_TCM] Data Fault, FailCount= 2 2018-04-30T21:03:45.717Z,1525122225.717 [PNI_TCM](ERROR): Data Fault 2018-04-30T21:03:45.763Z,1525122225.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:45.764Z,1525122225.764 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:45.821Z,1525122225.821 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-04-30T21:03:46.117Z,1525122226.117 [PNI_TCM](INFO): Powering down 2018-04-30T21:03:46.233Z,1525122226.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:46.234Z,1525122226.234 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:46.540Z,1525122226.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:46.541Z,1525122226.541 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:46.937Z,1525122226.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:46.938Z,1525122226.938 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:47.371Z,1525122227.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:47.372Z,1525122227.372 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:47.423Z,1525122227.423 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-04-30T21:03:47.423Z,1525122227.423 [PNI_TCM] No Fault, FailCount= 2 2018-04-30T21:03:47.787Z,1525122227.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:47.788Z,1525122227.788 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:48.151Z,1525122228.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:48.152Z,1525122228.152 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:48.579Z,1525122228.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:48.580Z,1525122228.580 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:48.943Z,1525122228.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:48.944Z,1525122228.944 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:49.346Z,1525122229.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:49.355Z,1525122229.355 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:49.772Z,1525122229.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:49.773Z,1525122229.773 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:50.128Z,1525122230.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:50.129Z,1525122230.129 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:50.555Z,1525122230.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:50.556Z,1525122230.556 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:50.943Z,1525122230.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:50.944Z,1525122230.944 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:51.317Z,1525122231.317 [Aanderaa_O2](FAULT): Timed out starting 2018-04-30T21:03:51.318Z,1525122231.318 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-04-30T21:03:51.318Z,1525122231.318 [Aanderaa_O2](ERROR): Communications Fault 2018-04-30T21:03:51.348Z,1525122231.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:51.348Z,1525122231.348 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:51.376Z,1525122231.376 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-04-30T21:03:51.835Z,1525122231.835 [Aanderaa_O2](INFO): Powering down 2018-04-30T21:03:51.860Z,1525122231.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:51.861Z,1525122231.861 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:52.159Z,1525122232.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:52.163Z,1525122232.163 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:52.555Z,1525122232.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:52.556Z,1525122232.556 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:52.591Z,1525122232.591 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-04-30T21:03:52.591Z,1525122232.591 [Aanderaa_O2] No Fault, FailCount= 2 2018-04-30T21:03:52.957Z,1525122232.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:52.958Z,1525122232.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:53.369Z,1525122233.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:53.371Z,1525122233.371 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:53.736Z,1525122233.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:53.737Z,1525122233.737 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:54.170Z,1525122234.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:54.171Z,1525122234.171 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:54.539Z,1525122234.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:54.540Z,1525122234.540 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:54.923Z,1525122234.923 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-04-30T21:03:54.923Z,1525122234.923 [PNI_TCM] Data Fault, FailCount= 3 2018-04-30T21:03:54.923Z,1525122234.923 [PNI_TCM](ERROR): Data Fault 2018-04-30T21:03:54.947Z,1525122234.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:54.948Z,1525122234.948 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:54.999Z,1525122234.999 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-04-30T21:03:55.322Z,1525122235.322 [PNI_TCM](INFO): Powering down 2018-04-30T21:03:55.436Z,1525122235.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:55.437Z,1525122235.437 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:55.773Z,1525122235.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:55.775Z,1525122235.775 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:56.148Z,1525122236.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:56.149Z,1525122236.149 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:56.571Z,1525122236.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:56.572Z,1525122236.572 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:56.595Z,1525122236.595 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-04-30T21:03:56.595Z,1525122236.595 [PNI_TCM] No Fault, FailCount= 3 2018-04-30T21:03:56.960Z,1525122236.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:56.961Z,1525122236.961 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:57.347Z,1525122237.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:57.348Z,1525122237.348 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:57.759Z,1525122237.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:57.760Z,1525122237.760 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:58.132Z,1525122238.132 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21:03:58.133Z,1525122238.133 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-04-30T21: