2016-12-09T17:27:27.110Z,1481304447.110 [Supervisor](DEBUG): Initializing supervisor. 2016-12-09T17:27:27.113Z,1481304447.113 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2016-12-09T17:27:27.114Z,1481304447.114 [SyncHandler](INFO): Protected caller Thread ID is 1039 2016-12-09T17:27:27.114Z,1481304447.114 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2016-12-09T17:27:27.115Z,1481304447.115 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2016-12-09T17:27:27.116Z,1481304447.116 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1040 2016-12-09T17:27:27.118Z,1481304447.118 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2016-12-09T17:27:27.130Z,1481304447.130 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2016-12-09T17:27:27.131Z,1481304447.131 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2016-12-09T17:27:27.132Z,1481304447.132 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1041 2016-12-09T17:27:27.132Z,1481304447.132 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2016-12-09T17:27:27.133Z,1481304447.133 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2016-12-09T17:27:27.134Z,1481304447.134 [logger ThreadHandler](INFO): Protected caller Thread ID is 1042 2016-12-09T17:27:27.136Z,1481304447.136 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2016-12-09T17:27:27.136Z,1481304447.136 [Supervisor](INFO): Looking for Config files in directory: Config/ 2016-12-09T17:27:27.138Z,1481304447.138 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2016-12-09T17:27:27.447Z,1481304447.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2016-12-09T17:27:27.447Z,1481304447.447 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2016-12-09T17:27:27.608Z,1481304447.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2016-12-09T17:27:27.608Z,1481304447.608 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2016-12-09T17:27:27.732Z,1481304447.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2016-12-09T17:27:27.732Z,1481304447.732 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2016-12-09T17:27:27.859Z,1481304447.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2016-12-09T17:27:27.860Z,1481304447.860 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2016-12-09T17:27:27.945Z,1481304447.945 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2016-12-09T17:27:28.137Z,1481304448.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2016-12-09T17:27:28.138Z,1481304448.138 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2016-12-09T17:27:28.224Z,1481304448.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2016-12-09T17:27:28.224Z,1481304448.224 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2016-12-09T17:27:28.486Z,1481304448.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2016-12-09T17:27:28.486Z,1481304448.486 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2016-12-09T17:27:28.909Z,1481304448.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2016-12-09T17:27:28.910Z,1481304448.910 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2016-12-09T17:27:29.194Z,1481304449.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2016-12-09T17:27:29.194Z,1481304449.194 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2016-12-09T17:27:29.672Z,1481304449.672 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2016-12-09T17:27:29.672Z,1481304449.672 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2016-12-09T17:27:29.867Z,1481304449.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2016-12-09T17:27:29.867Z,1481304449.867 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2016-12-09T17:27:29.968Z,1481304449.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2016-12-09T17:27:29.968Z,1481304449.968 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2016-12-09T17:27:30.397Z,1481304450.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2016-12-09T17:27:30.398Z,1481304450.398 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2016-12-09T17:27:30.506Z,1481304450.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2016-12-09T17:27:30.508Z,1481304450.508 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2016-12-09T17:27:30.509Z,1481304450.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2016-12-09T17:27:30.744Z,1481304450.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2016-12-09T17:27:30.744Z,1481304450.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2016-12-09T17:27:30.856Z,1481304450.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2016-12-09T17:27:30.952Z,1481304450.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2016-12-09T17:27:31.046Z,1481304451.046 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2016-12-09T17:27:31.134Z,1481304451.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2016-12-09T17:27:31.272Z,1481304451.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2016-12-09T17:27:31.408Z,1481304451.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2016-12-09T17:27:31.507Z,1481304451.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2016-12-09T17:27:31.589Z,1481304451.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2016-12-09T17:27:31.676Z,1481304451.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2016-12-09T17:27:31.850Z,1481304451.850 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2016-12-09T17:27:31.850Z,1481304451.850 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2016-12-09T17:27:31.852Z,1481304451.852 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2016-12-09T17:27:31.995Z,1481304451.995 [SBIT](DEBUG): Construct Startup Built In Test. 2016-12-09T17:27:32.025Z,1481304452.025 [SBIT] Loaded 2016-12-09T17:27:32.025Z,1481304452.025 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2016-12-09T17:27:32.026Z,1481304452.026 [IBIT](DEBUG): Construct Initiated Built In Test. 2016-12-09T17:27:32.055Z,1481304452.055 [IBIT] Loaded 2016-12-09T17:27:32.055Z,1481304452.055 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2016-12-09T17:27:32.057Z,1481304452.057 [CBIT](DEBUG): Construct Continuous Built In Test. 2016-12-09T17:27:32.195Z,1481304452.195 [CBIT] Loaded 2016-12-09T17:27:32.196Z,1481304452.196 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2016-12-09T17:27:32.196Z,1481304452.196 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2016-12-09T17:27:32.197Z,1481304452.197 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2016-12-09T17:27:32.245Z,1481304452.245 [VerticalControl](DEBUG): Construct VerticalControl. 2016-12-09T17:27:32.344Z,1481304452.344 [VerticalControl] Loaded 2016-12-09T17:27:32.344Z,1481304452.344 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2016-12-09T17:27:32.345Z,1481304452.345 [HorizontalControl](DEBUG): Construct HorizontalControl. 2016-12-09T17:27:32.407Z,1481304452.407 [HorizontalControl] Loaded 2016-12-09T17:27:32.407Z,1481304452.407 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2016-12-09T17:27:32.408Z,1481304452.408 [SpeedControl](DEBUG): Construct SpeedControl. 2016-12-09T17:27:32.414Z,1481304452.414 [SpeedControl] Loaded 2016-12-09T17:27:32.414Z,1481304452.414 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2016-12-09T17:27:32.415Z,1481304452.415 [LoopControl](DEBUG): Construct LoopControl. 2016-12-09T17:27:32.415Z,1481304452.415 [LoopControl] Loaded 2016-12-09T17:27:32.416Z,1481304452.416 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2016-12-09T17:27:32.416Z,1481304452.416 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2016-12-09T17:27:32.417Z,1481304452.417 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2016-12-09T17:27:32.440Z,1481304452.440 [DepthRateCalculator] Loaded 2016-12-09T17:27:32.440Z,1481304452.440 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2016-12-09T17:27:32.446Z,1481304452.446 [PitchRateCalculator] Loaded 2016-12-09T17:27:32.446Z,1481304452.446 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2016-12-09T17:27:32.457Z,1481304452.457 [SpeedCalculator] Loaded 2016-12-09T17:27:32.457Z,1481304452.457 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2016-12-09T17:27:32.474Z,1481304452.474 [TempGradientCalculator] Loaded 2016-12-09T17:27:32.474Z,1481304452.474 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2016-12-09T17:27:32.490Z,1481304452.490 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2016-12-09T17:27:32.490Z,1481304452.490 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2016-12-09T17:27:32.495Z,1481304452.495 [YawRateCalculator] Loaded 2016-12-09T17:27:32.496Z,1481304452.496 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2016-12-09T17:27:32.496Z,1481304452.496 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2016-12-09T17:27:32.497Z,1481304452.497 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2016-12-09T17:27:32.574Z,1481304452.574 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2016-12-09T17:27:32.574Z,1481304452.574 [StratificationFrontDetector](DEBUG): (re)initializing 2016-12-09T17:27:32.574Z,1481304452.574 [StratificationFrontDetector] Loaded 2016-12-09T17:27:32.575Z,1481304452.575 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2016-12-09T17:27:32.575Z,1481304452.575 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2016-12-09T17:27:32.576Z,1481304452.576 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2016-12-09T17:27:32.798Z,1481304452.798 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2016-12-09T17:27:32.799Z,1481304452.799 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2016-12-09T17:27:32.896Z,1481304452.896 [DeadReckonUsingMultipleVelocitySources] Loaded 2016-12-09T17:27:32.896Z,1481304452.896 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2016-12-09T17:27:32.952Z,1481304452.952 [DeadReckonUsingSpeedCalculator] Loaded 2016-12-09T17:27:32.952Z,1481304452.952 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2016-12-09T17:27:32.969Z,1481304452.969 [NavChart] Loaded 2016-12-09T17:27:32.970Z,1481304452.970 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2016-12-09T17:27:32.974Z,1481304452.974 [UniversalFixResidualReporter] Loaded 2016-12-09T17:27:32.974Z,1481304452.974 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2016-12-09T17:27:32.975Z,1481304452.975 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2016-12-09T17:27:32.975Z,1481304452.975 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2016-12-09T17:27:32.982Z,1481304452.982 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2016-12-09T17:27:32.982Z,1481304452.982 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2016-12-09T17:27:33.165Z,1481304453.165 [Aanderaa_O2] Loaded 2016-12-09T17:27:33.165Z,1481304453.165 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2016-12-09T17:27:33.242Z,1481304453.242 [CTD_NeilBrown] Loaded 2016-12-09T17:27:33.243Z,1481304453.243 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2016-12-09T17:27:33.244Z,1481304453.244 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4076C4E0 2016-12-09T17:27:33.244Z,1481304453.244 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1122 2016-12-09T17:27:33.261Z,1481304453.261 [ESPComponent] Loaded 2016-12-09T17:27:33.261Z,1481304453.261 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2016-12-09T17:27:33.276Z,1481304453.276 [PAR_Licor] Loaded 2016-12-09T17:27:33.277Z,1481304453.277 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2016-12-09T17:27:33.326Z,1481304453.326 [WetLabsBB2FL] Loaded 2016-12-09T17:27:33.326Z,1481304453.326 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2016-12-09T17:27:33.327Z,1481304453.327 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4079C4E0 2016-12-09T17:27:33.328Z,1481304453.328 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1123 2016-12-09T17:27:33.328Z,1481304453.328 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2016-12-09T17:27:33.329Z,1481304453.329 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2016-12-09T17:27:33.575Z,1481304453.575 [AcousticModem_Benthos_ATM900] Loaded 2016-12-09T17:27:33.575Z,1481304453.575 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2016-12-09T17:27:33.659Z,1481304453.659 [DataOverHttps] Loaded 2016-12-09T17:27:33.659Z,1481304453.659 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2016-12-09T17:27:33.673Z,1481304453.673 [Depth_Keller] Loaded 2016-12-09T17:27:33.674Z,1481304453.674 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2016-12-09T17:27:33.679Z,1481304453.679 [DropWeight] Loaded 2016-12-09T17:27:33.679Z,1481304453.679 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2016-12-09T17:27:33.772Z,1481304453.772 [NAL9602] Loaded 2016-12-09T17:27:33.772Z,1481304453.772 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2016-12-09T17:27:33.813Z,1481304453.813 [Onboard] Loaded 2016-12-09T17:27:33.814Z,1481304453.814 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2016-12-09T17:27:33.821Z,1481304453.821 [Radio_Surface] Loaded 2016-12-09T17:27:33.821Z,1481304453.821 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2016-12-09T17:27:33.822Z,1481304453.822 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E84E0 2016-12-09T17:27:33.823Z,1481304453.823 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1124 2016-12-09T17:27:33.870Z,1481304453.870 [PNI_TCM] Loaded 2016-12-09T17:27:33.871Z,1481304453.871 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2016-12-09T17:27:34.213Z,1481304454.213 [Rowe_600LCM] Loaded 2016-12-09T17:27:34.213Z,1481304454.213 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2016-12-09T17:27:34.214Z,1481304454.214 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A184E0 2016-12-09T17:27:34.215Z,1481304454.215 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1125 2016-12-09T17:27:36.042Z,1481304456.042 [BPC1] Loaded 2016-12-09T17:27:36.042Z,1481304456.042 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2016-12-09T17:27:36.042Z,1481304456.042 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2016-12-09T17:27:36.043Z,1481304456.043 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2016-12-09T17:27:36.142Z,1481304456.142 [BuoyancyServo] Loaded 2016-12-09T17:27:36.143Z,1481304456.143 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2016-12-09T17:27:36.155Z,1481304456.155 [ElevatorServo] Loaded 2016-12-09T17:27:36.155Z,1481304456.155 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2016-12-09T17:27:36.167Z,1481304456.167 [MassServo] Loaded 2016-12-09T17:27:36.167Z,1481304456.167 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2016-12-09T17:27:36.179Z,1481304456.179 [RudderServo] Loaded 2016-12-09T17:27:36.179Z,1481304456.179 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2016-12-09T17:27:36.190Z,1481304456.190 [ThrusterServo] Loaded 2016-12-09T17:27:36.191Z,1481304456.191 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2016-12-09T17:27:36.191Z,1481304456.191 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2016-12-09T17:27:36.192Z,1481304456.192 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2016-12-09T17:27:36.288Z,1481304456.288 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2016-12-09T17:27:36.289Z,1481304456.289 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2016-12-09T17:27:36.314Z,1481304456.314 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2016-12-09T17:27:36.318Z,1481304456.318 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2016-12-09T17:27:36.319Z,1481304456.319 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2016-12-09T17:27:36.326Z,1481304456.326 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2016-12-09T17:27:36.327Z,1481304456.327 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC64E0 2016-12-09T17:27:36.328Z,1481304456.328 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1126 2016-12-09T17:27:36.333Z,1481304456.333 [Supervisor](INFO): Main Thread ID is 1038 2016-12-09T17:27:36.333Z,1481304456.333 [Supervisor](DEBUG): Running supervisor. 2016-12-09T17:27:36.333Z,1481304456.333 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1127 2016-12-09T17:27:36.336Z,1481304456.336 [controlThread ThreadHandler](INFO): Handler Thread ID is 1128 2016-12-09T17:27:36.337Z,1481304456.337 [controlThread](DEBUG): Initializing ControlThread 2016-12-09T17:27:36.338Z,1481304456.338 [SBIT](INFO): Initialize SBIT Component. 2016-12-09T17:27:36.338Z,1481304456.338 [SBIT](IMPORTANT): git: 2016-09-21-21-gf4e48cf 2016-12-09T17:27:36.338Z,1481304456.338 [SBIT](INFO): git hash: f4e48cf2501b12024ad64fc26ea2ec3fcbf77fe4 2016-12-09T17:27:36.339Z,1481304456.339 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2016-12-09T17:27:36.339Z,1481304456.339 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2016-12-09T17:27:36.340Z,1481304456.340 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2016-12-09T17:27:36.341Z,1481304456.341 [IBIT](INFO): Initialize IBIT Component. 2016-12-09T17:27:36.341Z,1481304456.341 [CBIT](DEBUG): Initialize CBIT Component. 2016-12-09T17:27:36.342Z,1481304456.342 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2016-12-09T17:27:36.342Z,1481304456.342 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2016-12-09T17:27:36.343Z,1481304456.343 [logger ThreadHandler](INFO): Handler Thread ID is 1129 2016-12-09T17:27:36.363Z,1481304456.363 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1130 2016-12-09T17:27:36.364Z,1481304456.364 [CTD_NeilBrown](INFO): Powering down 2016-12-09T17:27:36.368Z,1481304456.368 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2016-12-09T17:27:36.369Z,1481304456.369 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2016-12-09T17:27:36.370Z,1481304456.370 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2016-12-09T17:27:36.370Z,1481304456.370 [LoopControl](DEBUG): Initialize LoopControlComponent. 2016-12-09T17:27:36.375Z,1481304456.375 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-12-09T17:27:36.375Z,1481304456.375 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2016-12-09T17:27:36.376Z,1481304456.376 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2016-12-09T17:27:36.376Z,1481304456.376 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2016-12-09T17:27:36.377Z,1481304456.377 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2016-12-09T17:27:36.377Z,1481304456.377 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2016-12-09T17:27:36.378Z,1481304456.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-12-09T17:27:36.379Z,1481304456.379 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-12-09T17:27:36.379Z,1481304456.379 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-12-09T17:27:36.380Z,1481304456.380 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-12-09T17:27:36.380Z,1481304456.380 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-12-09T17:27:36.380Z,1481304456.380 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-12-09T17:27:36.381Z,1481304456.381 [NavChart](DEBUG): Initialize NavChart Navigation. 2016-12-09T17:27:36.381Z,1481304456.381 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2016-12-09T17:27:36.394Z,1481304456.394 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2016-12-09T17:27:36.414Z,1481304456.414 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2016-12-09T17:27:36.416Z,1481304456.416 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1131 2016-12-09T17:27:36.417Z,1481304456.417 [WetLabsBB2FL](INFO): Powering down 2016-12-09T17:27:36.455Z,1481304456.455 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1132 2016-12-09T17:27:36.462Z,1481304456.462 [Radio_Surface](INFO): Powering up 2016-12-09T17:27:36.465Z,1481304456.465 [MissionManager](DEBUG): 2016-12-09T17:27:36.467Z,1481304456.467 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2016-12-09T17:27:36.471Z,1481304456.471 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1133 2016-12-09T17:27:36.472Z,1481304456.472 [Rowe_600LCM](INFO): Initializing 2016-12-09T17:27:36.473Z,1481304456.473 [Rowe_600LCM](INFO): Checking LCM 2016-12-09T17:27:36.473Z,1481304456.473 [Rowe_600LCM](INFO): LCM OK 2016-12-09T17:27:36.473Z,1481304456.473 [Rowe_600LCM](INFO): Powering up 2016-12-09T17:27:36.488Z,1481304456.488 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1134 2016-12-09T17:27:36.491Z,1481304456.491 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2016-12-09T17:27:36.491Z,1481304456.491 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2016-12-09T17:27:36.491Z,1481304456.491 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2016-12-09T17:27:36.492Z,1481304456.492 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2016-12-09T17:27:36.492Z,1481304456.492 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2016-12-09T17:27:36.492Z,1481304456.492 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2016-12-09T17:27:36.492Z,1481304456.492 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2016-12-09T17:27:36.492Z,1481304456.492 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2016-12-09T17:27:36.492Z,1481304456.492 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2016-12-09T17:27:36.493Z,1481304456.493 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2016-12-09T17:27:36.493Z,1481304456.493 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2016-12-09T17:27:36.493Z,1481304456.493 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2016-12-09T17:27:36.493Z,1481304456.493 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2016-12-09T17:27:36.493Z,1481304456.493 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2016-12-09T17:27:36.494Z,1481304456.494 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2016-12-09T17:27:36.494Z,1481304456.494 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2016-12-09T17:27:36.541Z,1481304456.541 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2016-12-09T17:27:36.542Z,1481304456.542 [Default:A.Wait](DEBUG): Construct Wait. 2016-12-09T17:27:36.560Z,1481304456.560 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2016-12-09T17:27:36.590Z,1481304456.590 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2016-12-09T17:27:36.593Z,1481304456.593 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2016-12-09T17:27:36.614Z,1481304456.614 [Default:E.Execute](DEBUG): Construct Execute. 2016-12-09T17:27:36.618Z,1481304456.618 [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 2016-12-09T17:27:36.628Z,1481304456.628 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2016-12-09T17:27:36.682Z,1481304456.682 [ESPComponent](INFO): powering down ESP 2016-12-09T17:27:37.042Z,1481304457.042 [AcousticModem_Benthos_ATM900](INFO): Powering up 2016-12-09T17:27:37.042Z,1481304457.042 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2016-12-09T17:27:37.485Z,1481304457.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:37.509Z,1481304457.509 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:37.617Z,1481304457.617 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-12-09T17:27:37.625Z,1481304457.625 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-12-09T17:27:37.640Z,1481304457.640 [ElevatorServo](DEBUG): Initializing EZServoServo. 2016-12-09T17:27:37.649Z,1481304457.649 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2016-12-09T17:27:37.671Z,1481304457.671 [MassServo](DEBUG): Initializing EZServoServo. 2016-12-09T17:27:37.677Z,1481304457.677 [MassServo](DEBUG): Initializing MassServo. 2016-12-09T17:27:37.695Z,1481304457.695 [RudderServo](DEBUG): Initializing EZServoServo. 2016-12-09T17:27:37.701Z,1481304457.701 [RudderServo](DEBUG): Initializing RudderServo. 2016-12-09T17:27:37.715Z,1481304457.715 [ThrusterServo](DEBUG): Initializing EZServoServo. 2016-12-09T17:27:37.721Z,1481304457.721 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2016-12-09T17:27:37.778Z,1481304457.778 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:38.015Z,1481304458.015 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:38.016Z,1481304458.016 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:38.041Z,1481304458.041 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:38.381Z,1481304458.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:38.390Z,1481304458.390 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:38.688Z,1481304458.688 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:38.954Z,1481304458.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:38.963Z,1481304458.963 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:38.992Z,1481304458.992 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:39.105Z,1481304459.105 [NAL9602](INFO): Powering up NAL9602 2016-12-09T17:27:39.179Z,1481304459.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:39.180Z,1481304459.180 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:39.204Z,1481304459.204 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:39.743Z,1481304459.743 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:39.745Z,1481304459.745 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:39.773Z,1481304459.773 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:39.956Z,1481304459.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:39.964Z,1481304459.964 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:40.122Z,1481304460.122 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:40.302Z,1481304460.302 [AcousticModem_Benthos_ATM900](DEBUG): 2016-12-09T17:27:40.345Z,1481304460.345 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:40.347Z,1481304460.347 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:40.364Z,1481304460.364 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:40.735Z,1481304460.735 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-12-09T17:27:40.849Z,1481304460.849 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyA1 -b 38400 >& /dev/null & 2016-12-09T17:27:40.883Z,1481304460.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:40.884Z,1481304460.884 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:41.109Z,1481304461.109 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:41.252Z,1481304461.252 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:41.253Z,1481304461.253 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:41.619Z,1481304461.619 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-12-09T17:27:41.621Z,1481304461.621 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-12-09T17:27:41.652Z,1481304461.652 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:41.670Z,1481304461.670 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2016-12-09T17:27:41.670Z,1481304461.670 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2016-12-09T17:27:41.671Z,1481304461.671 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2016-12-09T17:27:41.764Z,1481304461.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:41.766Z,1481304461.766 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:41.879Z,1481304461.879 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:42.123Z,1481304462.123 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:42.133Z,1481304462.133 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:42.207Z,1481304462.207 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:42.474Z,1481304462.474 [AcousticModem_Benthos_ATM900](DEBUG): Dec 9 2016 17:31:52 2016-12-09T17:27:42.518Z,1481304462.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:42.519Z,1481304462.519 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:42.603Z,1481304462.603 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:42.965Z,1481304462.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:42.966Z,1481304462.966 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:43.017Z,1481304463.017 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:43.266Z,1481304463.266 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2016-12-09T17:27:43.329Z,1481304463.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:43.331Z,1481304463.331 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:43.382Z,1481304463.382 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:43.667Z,1481304463.667 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2016-12-09T17:27:43.668Z,1481304463.668 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2016-12-09T17:27:43.668Z,1481304463.668 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2016-12-09T17:27:43.718Z,1481304463.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:43.720Z,1481304463.720 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:43.781Z,1481304463.781 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:44.068Z,1481304464.068 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2016-12-09T17:27:44.178Z,1481304464.178 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:44.187Z,1481304464.187 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:44.238Z,1481304464.238 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:44.464Z,1481304464.464 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2016-12-09T17:27:44.465Z,1481304464.465 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2016-12-09T17:27:44.474Z,1481304464.474 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2016-12-09T17:27:44.475Z,1481304464.475 [PNI_TCM] Data Fault, FailCount= 1 2016-12-09T17:27:44.475Z,1481304464.475 [PNI_TCM](ERROR): Data Fault 2016-12-09T17:27:44.483Z,1481304464.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:44.484Z,1481304464.484 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:44.538Z,1481304464.538 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:44.539Z,1481304464.539 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-12-09T17:27:44.866Z,1481304464.866 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2016-12-09T17:27:44.875Z,1481304464.875 [PNI_TCM](INFO): Powering down 2016-12-09T17:27:44.930Z,1481304464.930 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:44.931Z,1481304464.931 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:44.983Z,1481304464.983 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:45.326Z,1481304465.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:45.327Z,1481304465.327 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:45.379Z,1481304465.379 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:45.754Z,1481304465.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:45.755Z,1481304465.755 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:45.792Z,1481304465.792 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:46.120Z,1481304466.120 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:46.130Z,1481304466.130 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:46.181Z,1481304466.181 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:46.182Z,1481304466.182 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-12-09T17:27:46.182Z,1481304466.182 [PNI_TCM] No Fault, FailCount= 1 2016-12-09T17:27:46.524Z,1481304466.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:46.525Z,1481304466.525 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:46.566Z,1481304466.566 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:46.859Z,1481304466.859 [Aanderaa_O2](FAULT): Timed out starting 2016-12-09T17:27:46.859Z,1481304466.859 [Aanderaa_O2] Communications Fault, FailCount= 1 2016-12-09T17:27:46.859Z,1481304466.859 [Aanderaa_O2](ERROR): Communications Fault 2016-12-09T17:27:46.936Z,1481304466.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:46.937Z,1481304466.937 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:46.990Z,1481304466.990 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:46.991Z,1481304466.991 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2016-12-09T17:27:47.260Z,1481304467.260 [Aanderaa_O2](INFO): Powering down 2016-12-09T17:27:47.377Z,1481304467.377 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:47.378Z,1481304467.378 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:47.417Z,1481304467.417 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:47.722Z,1481304467.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:47.723Z,1481304467.723 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:47.767Z,1481304467.767 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:48.123Z,1481304468.123 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:48.124Z,1481304468.124 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:48.176Z,1481304468.176 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:48.177Z,1481304468.177 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2016-12-09T17:27:48.177Z,1481304468.177 [Aanderaa_O2] No Fault, FailCount= 1 2016-12-09T17:27:48.523Z,1481304468.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:48.524Z,1481304468.524 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:48.577Z,1481304468.577 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:48.990Z,1481304468.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:48.992Z,1481304468.992 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:49.036Z,1481304469.036 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:49.375Z,1481304469.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:49.376Z,1481304469.376 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:49.439Z,1481304469.439 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:49.788Z,1481304469.788 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:49.790Z,1481304469.790 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:49.834Z,1481304469.834 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:50.082Z,1481304470.082 [NAL9602](INFO): NAL9602 initialized 2016-12-09T17:27:50.114Z,1481304470.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:50.115Z,1481304470.115 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:50.163Z,1481304470.163 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:50.506Z,1481304470.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:50.507Z,1481304470.507 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:50.547Z,1481304470.547 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:50.973Z,1481304470.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:50.975Z,1481304470.975 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:51.032Z,1481304471.032 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:51.315Z,1481304471.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:51.316Z,1481304471.316 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:51.368Z,1481304471.368 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:51.767Z,1481304471.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:51.768Z,1481304471.768 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:51.828Z,1481304471.828 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:52.130Z,1481304472.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:52.139Z,1481304472.139 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:52.183Z,1481304472.183 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:52.537Z,1481304472.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:52.538Z,1481304472.538 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:52.598Z,1481304472.598 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:52.927Z,1481304472.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:52.945Z,1481304472.945 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:53.003Z,1481304473.003 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:53.347Z,1481304473.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:53.348Z,1481304473.348 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:53.396Z,1481304473.396 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:53.729Z,1481304473.729 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2016-12-09T17:27:53.729Z,1481304473.729 [PNI_TCM] Data Fault, FailCount= 2 2016-12-09T17:27:53.729Z,1481304473.729 [PNI_TCM](ERROR): Data Fault 2016-12-09T17:27:53.764Z,1481304473.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:53.765Z,1481304473.765 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:53.825Z,1481304473.825 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:53.826Z,1481304473.826 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-12-09T17:27:54.295Z,1481304474.295 [PNI_TCM](INFO): Powering down 2016-12-09T17:27:54.347Z,1481304474.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:54.348Z,1481304474.348 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:54.398Z,1481304474.398 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:54.510Z,1481304474.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:54.519Z,1481304474.519 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:54.575Z,1481304474.575 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:54.873Z,1481304474.873 [CommandLine](IMPORTANT): got command restart application 2016-12-09T17:27:54.901Z,1481304474.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:54.902Z,1481304474.902 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:54.962Z,1481304474.962 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:55.535Z,1481304475.535 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:55.536Z,1481304475.536 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:55.595Z,1481304475.595 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:55.596Z,1481304475.596 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-12-09T17:27:55.596Z,1481304475.596 [PNI_TCM] No Fault, FailCount= 2 2016-12-09T17:27:55.722Z,1481304475.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:55.723Z,1481304475.723 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:55.775Z,1481304475.775 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:55.880Z,1481304475.880 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2016-12-09T17:27:55.881Z,1481304475.881 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:55.997Z,1481304475.997 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2016-12-09T17:27:55.997Z,1481304475.997 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:55.998Z,1481304475.998 [CommandLine](INFO): Join timeout helper Thread ID is 1149 2016-12-09T17:27:56.013Z,1481304476.013 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2016-12-09T17:27:56.014Z,1481304476.014 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:56.014Z,1481304476.014 [NavChartDb](INFO): Join timeout helper Thread ID is 1150 2016-12-09T17:27:56.118Z,1481304476.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:56.119Z,1481304476.119 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:56.160Z,1481304476.160 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:56.535Z,1481304476.535 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:56.536Z,1481304476.536 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:56.593Z,1481304476.593 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:56.839Z,1481304476.839 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2016-12-09T17:27:56.840Z,1481304476.840 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:56.855Z,1481304476.855 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2016-12-09T17:27:56.855Z,1481304476.855 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:56.855Z,1481304476.855 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1151 2016-12-09T17:27:56.924Z,1481304476.924 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:56.925Z,1481304476.925 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:56.969Z,1481304476.969 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:57.111Z,1481304477.111 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2016-12-09T17:27:57.111Z,1481304477.111 [Rowe_600LCM](INFO): Powering down 2016-12-09T17:27:57.112Z,1481304477.112 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.115Z,1481304477.115 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2016-12-09T17:27:57.115Z,1481304477.115 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.116Z,1481304477.116 [Radio_Surface](INFO): Join timeout helper Thread ID is 1152 2016-12-09T17:27:57.123Z,1481304477.123 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2016-12-09T17:27:57.123Z,1481304477.123 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.125Z,1481304477.125 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2016-12-09T17:27:57.125Z,1481304477.125 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.125Z,1481304477.125 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1153 2016-12-09T17:27:57.337Z,1481304477.337 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:57.339Z,1481304477.339 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:57.386Z,1481304477.386 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:57.528Z,1481304477.528 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2016-12-09T17:27:57.528Z,1481304477.528 [WetLabsBB2FL](INFO): Powering down 2016-12-09T17:27:57.529Z,1481304477.529 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.533Z,1481304477.533 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2016-12-09T17:27:57.533Z,1481304477.533 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.533Z,1481304477.533 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1154 2016-12-09T17:27:57.712Z,1481304477.712 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:57.713Z,1481304477.713 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-12-09T17:27:57.745Z,1481304477.745 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2016-12-09T17:27:57.745Z,1481304477.745 [CTD_NeilBrown](INFO): Powering down 2016-12-09T17:27:57.746Z,1481304477.746 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.750Z,1481304477.750 [CBIT](DEBUG): Strobing Watchdog. 2016-12-09T17:27:57.761Z,1481304477.761 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2016-12-09T17:27:57.761Z,1481304477.761 [logger ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.761Z,1481304477.761 [logger](INFO): Join timeout helper Thread ID is 1155 2016-12-09T17:27:57.778Z,1481304477.778 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2016-12-09T17:27:57.778Z,1481304477.778 [logger ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.793Z,1481304477.793 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2016-12-09T17:27:57.793Z,1481304477.793 [CommandLine ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.793Z,1481304477.793 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2016-12-09T17:27:57.793Z,1481304477.793 [controlThread ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:57.794Z,1481304477.794 [controlThread](INFO): Join timeout helper Thread ID is 1156 2016-12-09T17:27:58.057Z,1481304478.057 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2016-12-09T17:27:58.057Z,1481304478.057 [controlThread](DEBUG): Uninitializing ControlThread 2016-12-09T17:27:58.058Z,1481304478.058 [Aanderaa_O2](INFO): Powering down 2016-12-09T17:27:58.059Z,1481304478.059 [AcousticModem_Benthos_ATM900](INFO): Powering down 2016-12-09T17:27:58.061Z,1481304478.061 [NAL9602](INFO): Powering down 2016-12-09T17:27:58.062Z,1481304478.062 [PNI_TCM](INFO): Powering down 2016-12-09T17:27:58.063Z,1481304478.063 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-12-09T17:27:58.064Z,1481304478.064 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2016-12-09T17:27:58.067Z,1481304478.067 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2016-12-09T17:27:58.068Z,1481304478.068 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2016-12-09T17:27:58.068Z,1481304478.068 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2016-12-09T17:27:58.068Z,1481304478.068 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2016-12-09T17:27:58.069Z,1481304478.069 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2016-12-09T17:27:58.069Z,1481304478.069 [BuoyancyServo](INFO): Powering down 2016-12-09T17:27:58.081Z,1481304478.081 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2016-12-09T17:27:58.081Z,1481304478.081 [ElevatorServo](INFO): Powering down 2016-12-09T17:27:58.105Z,1481304478.105 [MassServo](DEBUG): Uninitialize Mass Servo. 2016-12-09T17:27:58.105Z,1481304478.105 [MassServo](INFO): Powering down 2016-12-09T17:27:58.126Z,1481304478.126 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2016-12-09T17:27:58.126Z,1481304478.126 [RudderServo](INFO): Powering down 2016-12-09T17:27:58.127Z,1481304478.127 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2016-12-09T17:27:58.127Z,1481304478.127 [ThrusterServo](INFO): Powering down 2016-12-09T17:27:58.127Z,1481304478.127 [SBIT](DEBUG): Uninitialize SBIT Component. 2016-12-09T17:27:58.128Z,1481304478.128 [IBIT](DEBUG): Uninitialize IBIT Component. 2016-12-09T17:27:58.128Z,1481304478.128 [CBIT](DEBUG): Uninitialize CBIT Component. 2016-12-09T17:27:58.161Z,1481304478.161 [controlThread ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:58.218Z,1481304478.218 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:58.223Z,1481304478.223 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:58.286Z,1481304478.286 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:58.311Z,1481304478.311 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:58.354Z,1481304478.354 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2016-12-09T17:27:58.402Z,1481304478.402 [logger ThreadHandler](INFO): Thread cancelled.