2018-11-16T00:02:12.000Z,1542326532.000 [Supervisor](DEBUG): Initializing supervisor. 2018-11-16T00:02:12.004Z,1542326532.004 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-16T00:02:12.004Z,1542326532.004 [SyncHandler](INFO): Protected caller Thread ID is 7557 2018-11-16T00:02:12.004Z,1542326532.004 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-11-16T00:02:12.005Z,1542326532.005 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-11-16T00:02:12.006Z,1542326532.006 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7558 2018-11-16T00:02:12.009Z,1542326532.009 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-11-16T00:02:12.021Z,1542326532.021 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-11-16T00:02:12.022Z,1542326532.022 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-11-16T00:02:12.023Z,1542326532.023 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7559 2018-11-16T00:02:12.023Z,1542326532.023 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-11-16T00:02:12.024Z,1542326532.024 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-11-16T00:02:12.025Z,1542326532.025 [logger ThreadHandler](INFO): Protected caller Thread ID is 7560 2018-11-16T00:02:12.027Z,1542326532.027 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-11-16T00:02:12.027Z,1542326532.027 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-11-16T00:02:12.126Z,1542326532.126 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-11-16T00:02:12.224Z,1542326532.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-11-16T00:02:12.225Z,1542326532.225 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-11-16T00:02:12.324Z,1542326532.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-11-16T00:02:12.325Z,1542326532.325 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-11-16T00:02:12.525Z,1542326532.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-11-16T00:02:12.526Z,1542326532.526 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-11-16T00:02:12.864Z,1542326532.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-11-16T00:02:12.864Z,1542326532.864 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-11-16T00:02:13.209Z,1542326533.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-11-16T00:02:13.210Z,1542326533.210 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-11-16T00:02:13.673Z,1542326533.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-11-16T00:02:13.673Z,1542326533.673 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-11-16T00:02:14.145Z,1542326534.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-11-16T00:02:14.145Z,1542326534.145 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-11-16T00:02:14.600Z,1542326534.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-11-16T00:02:14.601Z,1542326534.601 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-11-16T00:02:14.680Z,1542326534.680 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-11-16T00:02:15.008Z,1542326535.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-11-16T00:02:15.009Z,1542326535.009 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-11-16T00:02:15.158Z,1542326535.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-11-16T00:02:15.159Z,1542326535.159 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-11-16T00:02:15.375Z,1542326535.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-11-16T00:02:15.375Z,1542326535.375 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-11-16T00:02:15.583Z,1542326535.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-11-16T00:02:15.584Z,1542326535.584 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-11-16T00:02:15.681Z,1542326535.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-11-16T00:02:15.681Z,1542326535.681 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-11-16T00:02:15.828Z,1542326535.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-11-16T00:02:15.830Z,1542326535.830 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-11-16T00:02:15.831Z,1542326535.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-11-16T00:02:16.061Z,1542326536.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-11-16T00:02:16.061Z,1542326536.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-11-16T00:02:16.169Z,1542326536.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-11-16T00:02:16.325Z,1542326536.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-11-16T00:02:16.419Z,1542326536.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-11-16T00:02:16.504Z,1542326536.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-11-16T00:02:16.668Z,1542326536.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-11-16T00:02:16.958Z,1542326536.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-11-16T00:02:17.286Z,1542326537.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-11-16T00:02:17.413Z,1542326537.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-11-16T00:02:17.588Z,1542326537.588 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-11-16T00:02:18.263Z,1542326538.263 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-11-16T00:02:18.264Z,1542326538.264 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-11-16T00:02:18.506Z,1542326538.506 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-11-16T00:02:18.507Z,1542326538.507 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-11-16T00:02:18.541Z,1542326538.541 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-11-16T00:02:18.541Z,1542326538.541 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-11-16T00:02:18.747Z,1542326538.747 [DepthRateCalculator] Loaded 2018-11-16T00:02:18.747Z,1542326538.747 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-11-16T00:02:18.820Z,1542326538.820 [PitchRateCalculator] Loaded 2018-11-16T00:02:18.821Z,1542326538.821 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-11-16T00:02:18.837Z,1542326538.837 [SpeedCalculator] Loaded 2018-11-16T00:02:18.837Z,1542326538.837 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-11-16T00:02:18.858Z,1542326538.858 [TempGradientCalculator] Loaded 2018-11-16T00:02:18.859Z,1542326538.859 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-11-16T00:02:18.865Z,1542326538.865 [YawRateCalculator] Loaded 2018-11-16T00:02:18.865Z,1542326538.865 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-11-16T00:02:18.905Z,1542326538.905 [ElevatorOffsetCalculator] Loaded 2018-11-16T00:02:18.905Z,1542326538.905 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-11-16T00:02:18.906Z,1542326538.906 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-11-16T00:02:18.907Z,1542326538.907 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-11-16T00:02:18.933Z,1542326538.933 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-11-16T00:02:18.933Z,1542326538.933 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-11-16T00:02:19.211Z,1542326539.211 [AcousticModem_Benthos_ATM900] Loaded 2018-11-16T00:02:19.211Z,1542326539.211 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-11-16T00:02:19.291Z,1542326539.291 [DataOverHttps] Loaded 2018-11-16T00:02:19.292Z,1542326539.292 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-11-16T00:02:19.305Z,1542326539.305 [Depth_Keller] Loaded 2018-11-16T00:02:19.305Z,1542326539.305 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-11-16T00:02:19.310Z,1542326539.310 [DropWeight] Loaded 2018-11-16T00:02:19.310Z,1542326539.310 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-11-16T00:02:19.409Z,1542326539.409 [NAL9602] Loaded 2018-11-16T00:02:19.409Z,1542326539.409 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-11-16T00:02:19.425Z,1542326539.425 [Onboard] Loaded 2018-11-16T00:02:19.425Z,1542326539.425 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-11-16T00:02:19.435Z,1542326539.435 [Radio_Surface] Loaded 2018-11-16T00:02:19.436Z,1542326539.436 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-11-16T00:02:19.437Z,1542326539.437 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0 2018-11-16T00:02:19.437Z,1542326539.437 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7640 2018-11-16T00:02:19.485Z,1542326539.485 [PNI_TCM] Loaded 2018-11-16T00:02:19.485Z,1542326539.485 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-11-16T00:02:19.547Z,1542326539.547 [Rowe_600LCM] Loaded 2018-11-16T00:02:19.547Z,1542326539.547 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-11-16T00:02:19.548Z,1542326539.548 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0 2018-11-16T00:02:19.548Z,1542326539.548 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 7641 2018-11-16T00:02:21.338Z,1542326541.338 [BPC1] Loaded 2018-11-16T00:02:21.338Z,1542326541.338 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-11-16T00:02:21.338Z,1542326541.338 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-11-16T00:02:21.339Z,1542326541.339 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-11-16T00:02:21.476Z,1542326541.476 [SBIT](DEBUG): Construct Startup Built In Test. 2018-11-16T00:02:21.499Z,1542326541.499 [SBIT] Loaded 2018-11-16T00:02:21.500Z,1542326541.500 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-11-16T00:02:21.500Z,1542326541.500 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-11-16T00:02:21.512Z,1542326541.512 [IBIT] Loaded 2018-11-16T00:02:21.512Z,1542326541.512 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-11-16T00:02:21.515Z,1542326541.515 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-11-16T00:02:21.665Z,1542326541.665 [CBIT] Loaded 2018-11-16T00:02:21.666Z,1542326541.666 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-11-16T00:02:21.666Z,1542326541.666 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-11-16T00:02:21.667Z,1542326541.667 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-11-16T00:02:21.834Z,1542326541.834 [Aanderaa_O2] Loaded 2018-11-16T00:02:21.835Z,1542326541.835 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-11-16T00:02:21.892Z,1542326541.892 [CTD_Seabird] Loaded 2018-11-16T00:02:21.892Z,1542326541.892 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-11-16T00:02:21.893Z,1542326541.893 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0 2018-11-16T00:02:21.894Z,1542326541.894 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7642 2018-11-16T00:02:21.911Z,1542326541.911 [ESPComponent] Loaded 2018-11-16T00:02:21.912Z,1542326541.912 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-11-16T00:02:21.926Z,1542326541.926 [PAR_Licor] Loaded 2018-11-16T00:02:21.926Z,1542326541.926 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-11-16T00:02:21.975Z,1542326541.975 [WetLabsBB2FL] Loaded 2018-11-16T00:02:21.975Z,1542326541.975 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-11-16T00:02:21.976Z,1542326541.976 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408F74E0 2018-11-16T00:02:21.977Z,1542326541.977 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7643 2018-11-16T00:02:21.977Z,1542326541.977 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-11-16T00:02:21.978Z,1542326541.978 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-11-16T00:02:22.228Z,1542326542.228 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-11-16T00:02:22.229Z,1542326542.229 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-11-16T00:02:22.355Z,1542326542.355 [VerticalControl](DEBUG): Construct VerticalControl. 2018-11-16T00:02:22.450Z,1542326542.450 [VerticalControl] Loaded 2018-11-16T00:02:22.451Z,1542326542.451 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-11-16T00:02:22.451Z,1542326542.451 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-11-16T00:02:22.570Z,1542326542.570 [HorizontalControl] Loaded 2018-11-16T00:02:22.570Z,1542326542.570 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-11-16T00:02:22.571Z,1542326542.571 [SpeedControl](DEBUG): Construct SpeedControl. 2018-11-16T00:02:22.576Z,1542326542.576 [SpeedControl] Loaded 2018-11-16T00:02:22.576Z,1542326542.576 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-11-16T00:02:22.577Z,1542326542.577 [LoopControl](DEBUG): Construct LoopControl. 2018-11-16T00:02:22.577Z,1542326542.577 [LoopControl] Loaded 2018-11-16T00:02:22.578Z,1542326542.578 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-11-16T00:02:22.578Z,1542326542.578 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-11-16T00:02:22.579Z,1542326542.579 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-11-16T00:02:22.648Z,1542326542.648 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-11-16T00:02:22.649Z,1542326542.649 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-11-16T00:02:22.750Z,1542326542.750 [BuoyancyServo] Loaded 2018-11-16T00:02:22.751Z,1542326542.751 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-11-16T00:02:22.762Z,1542326542.762 [ElevatorServo] Loaded 2018-11-16T00:02:22.763Z,1542326542.763 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-11-16T00:02:22.774Z,1542326542.774 [MassServo] Loaded 2018-11-16T00:02:22.774Z,1542326542.774 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-11-16T00:02:22.786Z,1542326542.786 [RudderServo] Loaded 2018-11-16T00:02:22.786Z,1542326542.786 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-11-16T00:02:22.797Z,1542326542.797 [ThrusterServo] Loaded 2018-11-16T00:02:22.797Z,1542326542.797 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-11-16T00:02:22.797Z,1542326542.797 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-11-16T00:02:22.798Z,1542326542.798 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-11-16T00:02:22.891Z,1542326542.891 [DeadReckonUsingSpeedCalculator] Loaded 2018-11-16T00:02:22.892Z,1542326542.892 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-11-16T00:02:22.908Z,1542326542.908 [NavChart] Loaded 2018-11-16T00:02:22.908Z,1542326542.908 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-11-16T00:02:22.912Z,1542326542.912 [UniversalFixResidualReporter] Loaded 2018-11-16T00:02:22.913Z,1542326542.913 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-11-16T00:02:22.913Z,1542326542.913 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-11-16T00:02:22.917Z,1542326542.917 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-11-16T00:02:22.918Z,1542326542.918 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-11-16T00:02:22.924Z,1542326542.924 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-11-16T00:02:22.925Z,1542326542.925 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A504E0 2018-11-16T00:02:22.926Z,1542326542.926 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7644 2018-11-16T00:02:22.931Z,1542326542.931 [Supervisor](INFO): Main Thread ID is 7556 2018-11-16T00:02:22.931Z,1542326542.931 [Supervisor](DEBUG): Running supervisor. 2018-11-16T00:02:22.932Z,1542326542.932 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7645 2018-11-16T00:02:22.934Z,1542326542.934 [controlThread ThreadHandler](INFO): Handler Thread ID is 7646 2018-11-16T00:02:22.935Z,1542326542.935 [controlThread](DEBUG): Initializing ControlThread 2018-11-16T00:02:22.936Z,1542326542.936 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-16T00:02:22.936Z,1542326542.936 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-11-16T00:02:22.936Z,1542326542.936 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-11-16T00:02:22.937Z,1542326542.937 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-11-16T00:02:22.937Z,1542326542.937 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-11-16T00:02:22.937Z,1542326542.937 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-11-16T00:02:22.945Z,1542326542.945 [SBIT](INFO): Initialize SBIT Component. 2018-11-16T00:02:22.945Z,1542326542.945 [SBIT](IMPORTANT): git: 2018-11-05-3-g424fd37 2018-11-16T00:02:22.946Z,1542326542.946 [SBIT](INFO): git hash: 424fd377e53a63ada65ca308b07209cf4fd40d4d 2018-11-16T00:02:22.946Z,1542326542.946 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-11-16T00:02:22.946Z,1542326542.946 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-11-16T00:02:22.947Z,1542326542.947 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-11-16T00:02:22.948Z,1542326542.948 [IBIT](INFO): Initialize IBIT Component. 2018-11-16T00:02:22.949Z,1542326542.949 [CBIT](DEBUG): Initialize CBIT Component. 2018-11-16T00:02:22.949Z,1542326542.949 [CBIT](DEBUG): Initialized mux pins. 2018-11-16T00:02:22.949Z,1542326542.949 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-11-16T00:02:22.949Z,1542326542.949 [CBIT](DEBUG): Initializing the watchdog timer. 2018-11-16T00:02:22.950Z,1542326542.950 [logger ThreadHandler](INFO): Handler Thread ID is 7647 2018-11-16T00:02:22.971Z,1542326542.971 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7648 2018-11-16T00:02:22.977Z,1542326542.977 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-11-16T00:02:22.977Z,1542326542.977 [CBIT](DEBUG): Initializing heartbeat. 2018-11-16T00:02:22.978Z,1542326542.978 [Radio_Surface](INFO): Powering up 2018-11-16T00:02:22.983Z,1542326542.983 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 7649 2018-11-16T00:02:22.983Z,1542326542.983 [Rowe_600LCM](INFO): Initializing 2018-11-16T00:02:22.984Z,1542326542.984 [Rowe_600LCM](INFO): Checking LCM 2018-11-16T00:02:22.993Z,1542326542.993 [Rowe_600LCM](INFO): LCM OK 2018-11-16T00:02:22.993Z,1542326542.993 [Rowe_600LCM](INFO): Powering up 2018-11-16T00:02:22.999Z,1542326542.999 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7650 2018-11-16T00:02:22.000Z,1542326543.000 [CTD_Seabird](INFO): Initializing 2018-11-16T00:02:23.000Z,1542326543.000 [CTD_Seabird](INFO): Checking LCM 2018-11-16T00:02:23.000Z,1542326543.000 [CTD_Seabird](INFO): LCM OK 2018-11-16T00:02:23.001Z,1542326543.000 [CTD_Seabird](INFO): Powering up 2018-11-16T00:02:23.011Z,1542326543.011 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7651 2018-11-16T00:02:23.012Z,1542326543.012 [WetLabsBB2FL](INFO): Powering down 2018-11-16T00:02:23.039Z,1542326543.039 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7652 2018-11-16T00:02:23.043Z,1542326543.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-11-16T00:02:23.043Z,1542326543.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-11-16T00:02:23.043Z,1542326543.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-11-16T00:02:23.043Z,1542326543.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-11-16T00:02:23.043Z,1542326543.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-11-16T00:02:23.044Z,1542326543.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-11-16T00:02:23.044Z,1542326543.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-11-16T00:02:23.044Z,1542326543.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-11-16T00:02:23.044Z,1542326543.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-11-16T00:02:23.044Z,1542326543.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-11-16T00:02:23.045Z,1542326543.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-11-16T00:02:23.045Z,1542326543.045 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-11-16T00:02:23.045Z,1542326543.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-11-16T00:02:23.045Z,1542326543.045 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-11-16T00:02:23.045Z,1542326543.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-16T00:02:23.046Z,1542326543.046 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-11-16T00:02:23.046Z,1542326543.046 [CBIT](DEBUG): Deactivating GF circuits. 2018-11-16T00:02:23.047Z,1542326543.047 [CBIT](DEBUG): Deactivating emergency mode. 2018-11-16T00:02:23.085Z,1542326543.085 [CBIT](INFO): Reporting lpc3250 register adresses and status: LPC3Reg::P3MuxClrPhys_ (0x40418114) = 0 LPC3Reg::TimClkCtrl_ (0x400200bc) = 1 LPC3Reg::WDTimCtrl_ (0x4041f004) = 1 LPC3Reg::WDTimMCtrl_ (0x4041f00c) = 17 LPC3Reg::WDTimEMR_ (0x4041f014) = 32 LPC3Reg::PioOutpClrPhys_ (0x40418008) = 0 LPC3Reg::PioOutpSetPhys_ (0x40418004) = 0 2018-11-16T00:02:23.087Z,1542326543.087 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-11-16T00:02:23.089Z,1542326543.089 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-11-16T00:02:23.089Z,1542326543.089 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-11-16T00:02:23.090Z,1542326543.090 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-11-16T00:02:23.091Z,1542326543.091 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-16T00:02:23.092Z,1542326543.092 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-16T00:02:23.093Z,1542326543.093 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-16T00:02:23.093Z,1542326543.093 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-11-16T00:02:23.093Z,1542326543.093 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-11-16T00:02:23.099Z,1542326543.099 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-11-16T00:02:23.139Z,1542326543.139 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-16T00:02:23.166Z,1542326543.166 [MissionManager](DEBUG): 2018-11-16T00:02:23.167Z,1542326543.167 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-11-16T00:02:23.245Z,1542326543.245 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-11-16T00:02:23.261Z,1542326543.261 [Default:A.Wait](DEBUG): Construct Wait. 2018-11-16T00:02:23.279Z,1542326543.279 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-16T00:02:23.301Z,1542326543.301 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-11-16T00:02:23.304Z,1542326543.304 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-11-16T00:02:23.330Z,1542326543.330 [Default:E.Execute](DEBUG): Construct Execute. 2018-11-16T00:02:23.342Z,1542326543.342 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-11-16T00:02:23.347Z,1542326543.347 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-11-16T00:02:23.371Z,1542326543.371 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-11-16T00:02:23.371Z,1542326543.371 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-11-16T00:02:23.412Z,1542326543.412 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-11-16T00:02:23.729Z,1542326543.729 [ESPComponent](INFO): powering down ESP 2018-11-16T00:02:24.271Z,1542326544.271 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-16T00:02:24.279Z,1542326544.279 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-16T00:02:24.301Z,1542326544.301 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-16T00:02:24.307Z,1542326544.307 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-16T00:02:24.320Z,1542326544.320 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-16T00:02:24.327Z,1542326544.327 [MassServo](DEBUG): Initializing MassServo. 2018-11-16T00:02:24.333Z,1542326544.333 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-16T00:02:24.339Z,1542326544.339 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-16T00:02:24.352Z,1542326544.352 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-11-16T00:02:24.359Z,1542326544.359 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-11-16T00:02:24.565Z,1542326544.565 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-16T00:02:24.565Z,1542326544.565 [DropWeight] Hardware Fault, FailCount= 1 2018-11-16T00:02:24.565Z,1542326544.565 [DropWeight](ERROR): Hardware Fault 2018-11-16T00:02:24.656Z,1542326544.656 [CommandLine](FAULT): Scheduling is paused 2018-11-16T00:02:24.656Z,1542326544.656 [CBIT](INFO): Critical error at 20181116T000224 2018-11-16T00:02:24.656Z,1542326544.656 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-11-16T00:02:24.659Z,1542326544.659 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-11-16T00:02:24.659Z,1542326544.659 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-11-16T00:02:27.261Z,1542326547.261 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-16T00:02:27.401Z,1542326547.401 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-16T00:02:27.452Z,1542326547.452 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-16T00:02:27.452Z,1542326547.452 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-16T00:02:27.453Z,1542326547.453 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-16T00:02:28.550Z,1542326548.550 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-11-16T00:02:28.717Z,1542326548.717 [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-11-16T00:02:28.719Z,1542326548.719 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-11-16T00:02:29.074Z,1542326549.074 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-11-16T00:02:29.074Z,1542326549.074 [CTD_Seabird](INFO): Powering down 2018-11-16T00:02:33.946Z,1542326553.946 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:02:33.946Z,1542326553.946 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-16T00:02:33.947Z,1542326553.947 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:02:34.253Z,1542326554.253 [CBIT](INFO): Critical error at 20181116T000224 2018-11-16T00:02:34.256Z,1542326554.256 [CBIT](CRITICAL): Environmental Failure. Press:14.780849 PSI. Humidity:34%. Temp:26 C. ABORTING MISSION 2018-11-16T00:02:34.256Z,1542326554.256 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:02:34.263Z,1542326554.263 [AcousticModem_Benthos_ATM900](DEBUG): 2018-11-16T00:02:34.265Z,1542326554.265 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2018-11-16T00:02:34.266Z,1542326554.266 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2018-11-16T00:02:34.277Z,1542326554.277 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2018-11-16T00:02:34.278Z,1542326554.278 [AcousticModem_Benthos_ATM900](DEBUG): Nov 3 2018 10:48:22 2018-11-16T00:02:34.290Z,1542326554.290 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2018-11-16T00:02:34.335Z,1542326554.335 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:02:34.435Z,1542326554.435 [CBIT](INFO): Critical error at 20181116T000234 2018-11-16T00:02:35.063Z,1542326555.063 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2018-11-16T00:02:35.070Z,1542326555.070 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003952 2018-11-16T00:02:35.150Z,1542326555.150 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:02:35.150Z,1542326555.150 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-16T00:02:35.463Z,1542326555.463 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-11-16T00:02:35.464Z,1542326555.464 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2018-11-16T00:02:35.464Z,1542326555.464 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2018-11-16T00:02:35.864Z,1542326555.864 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2018-11-16T00:02:36.263Z,1542326556.263 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2018-11-16T00:02:36.264Z,1542326556.264 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2018-11-16T00:02:36.664Z,1542326556.664 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2018-11-16T00:02:39.883Z,1542326559.883 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:02:39.883Z,1542326559.883 [PNI_TCM] Data Fault, FailCount= 1 2018-11-16T00:02:39.883Z,1542326559.883 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:02:39.956Z,1542326559.956 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:02:40.282Z,1542326560.282 [PNI_TCM](INFO): Powering down 2018-11-16T00:02:41.571Z,1542326561.571 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:02:41.571Z,1542326561.571 [PNI_TCM] No Fault, FailCount= 1 2018-11-16T00:02:45.505Z,1542326565.505 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:02:45.505Z,1542326565.505 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-16T00:02:45.505Z,1542326565.505 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:02:45.571Z,1542326565.571 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:02:45.883Z,1542326565.883 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:02:46.762Z,1542326566.762 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:02:46.762Z,1542326566.762 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-16T00:02:47.562Z,1542326567.562 [SBIT](IMPORTANT): Beginning Startup BIT 2018-11-16T00:02:47.583Z,1542326567.583 [CBIT](IMPORTANT): Beginning ground fault scan 2018-11-16T00:02:49.094Z,1542326569.094 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:02:49.094Z,1542326569.094 [PNI_TCM] Data Fault, FailCount= 2 2018-11-16T00:02:49.094Z,1542326569.094 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:02:49.292Z,1542326569.292 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:02:49.468Z,1542326569.468 [NAL9602](INFO): Powering up NAL9602 2018-11-16T00:02:49.484Z,1542326569.484 [PNI_TCM](INFO): Powering down 2018-11-16T00:02:50.552Z,1542326570.552 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:02:50.552Z,1542326570.552 [PNI_TCM] No Fault, FailCount= 2 2018-11-16T00:02:57.194Z,1542326577.194 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:02:57.194Z,1542326577.194 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-16T00:02:57.194Z,1542326577.194 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:02:57.269Z,1542326577.269 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:02:57.269Z,1542326577.269 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-16T00:02:57.714Z,1542326577.714 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:02:57.995Z,1542326577.995 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:02:57.995Z,1542326577.995 [PNI_TCM] Data Fault, FailCount= 3 2018-11-16T00:02:57.996Z,1542326577.996 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:02:58.076Z,1542326578.076 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:02:58.396Z,1542326578.396 [PNI_TCM](INFO): Powering down 2018-11-16T00:02:58.580Z,1542326578.580 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008680 CHAN A1 (24V): -0.012766 CHAN A2 (12V): -0.005703 CHAN A3 (5V): -0.002511 CHAN B0 (3.3V): -0.000910 CHAN B1 (3.15aV): -0.000791 CHAN B2 (3.15bV): -0.001237 CHAN B3 (GND): 0.000217 OPEN: 0.004688 Full Scale Calc: 4.765 mA, -1.589 mA 2018-11-16T00:02:59.668Z,1542326579.668 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:02:59.668Z,1542326579.668 [PNI_TCM] No Fault, FailCount= 3 2018-11-16T00:03:00.376Z,1542326580.376 [NAL9602](INFO): NAL9602 initialized 2018-11-16T00:03:07.379Z,1542326587.379 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:03:07.379Z,1542326587.379 [PNI_TCM] Data Fault, FailCount= 4 2018-11-16T00:03:07.379Z,1542326587.379 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:03:07.520Z,1542326587.520 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:03:07.779Z,1542326587.779 [PNI_TCM](INFO): Powering down 2018-11-16T00:03:09.095Z,1542326589.095 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:03:09.095Z,1542326589.095 [PNI_TCM] No Fault, FailCount= 4 2018-11-16T00:03:16.580Z,1542326596.580 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:03:16.580Z,1542326596.580 [PNI_TCM] Data Fault, FailCount= 5 2018-11-16T00:03:16.580Z,1542326596.580 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:03:16.676Z,1542326596.676 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:03:16.677Z,1542326596.677 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-16T00:03:16.978Z,1542326596.978 [PNI_TCM](INFO): Powering down 2018-11-16T00:03:17.151Z,1542326597.151 [CBIT](INFO): Critical error at 20181116T000316 2018-11-16T00:03:41.080Z,1542326621.080 [SBIT](IMPORTANT): SBIT PASSED 2018-11-16T00:03:41.147Z,1542326621.147 [CommandLine](IMPORTANT): got command configSet list 2018-11-16T00:03:41.148Z,1542326621.148 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-11-16T00:03:41.149Z,1542326621.149 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2018-11-16T00:03:41.503Z,1542326621.503 [MissionManager](IMPORTANT): Started mission Startup 2018-11-16T00:03:41.503Z,1542326621.503 [Startup] Running Loop=1 2018-11-16T00:03:41.504Z,1542326621.504 [Startup](DEBUG): Aggregate::initialize Startup 2018-11-16T00:03:41.504Z,1542326621.504 [Startup:A.GoToSurface] Running Loop=1 2018-11-16T00:03:41.504Z,1542326621.504 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-16T00:03:41.504Z,1542326621.504 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-16T00:03:41.505Z,1542326621.505 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-16T00:03:41.505Z,1542326621.505 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-16T00:03:41.506Z,1542326621.506 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-16T00:03:41.506Z,1542326621.506 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-16T00:03:41.512Z,1542326621.512 [Startup:StartupSatComms] Running Loop=1 2018-11-16T00:03:41.512Z,1542326621.512 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-11-16T00:03:41.513Z,1542326621.513 [Startup:StartupSatComms:A] Running Loop=1 2018-11-16T00:03:41.874Z,1542326621.874 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-11-16T00:04:23.027Z,1542326663.027 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-16T00:04:23.027Z,1542326663.027 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-11-16T00:04:23.027Z,1542326663.027 [Rowe_600LCM](ERROR): Communications Fault 2018-11-16T00:04:23.112Z,1542326663.112 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-16T00:04:23.112Z,1542326663.112 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-11-16T00:04:23.112Z,1542326663.112 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-16T00:04:23.162Z,1542326663.162 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-16T00:04:23.171Z,1542326663.171 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-16T00:04:23.435Z,1542326663.435 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-16T00:04:23.612Z,1542326663.612 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-16T00:04:23.612Z,1542326663.612 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-11-16T00:04:23.685Z,1542326663.685 [Rowe_600LCM](INFO): Powering down 2018-11-16T00:04:23.881Z,1542326663.881 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-16T00:04:23.882Z,1542326663.882 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-16T00:04:23.882Z,1542326663.882 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-16T00:04:25.112Z,1542326665.112 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-16T00:04:25.112Z,1542326665.112 [Rowe_600LCM] No Fault, FailCount= 1 2018-11-16T00:04:25.303Z,1542326665.303 [Rowe_600LCM](INFO): Initializing 2018-11-16T00:04:25.303Z,1542326665.303 [Rowe_600LCM](INFO): Checking LCM 2018-11-16T00:04:25.303Z,1542326665.303 [Rowe_600LCM](INFO): LCM OK 2018-11-16T00:04:25.303Z,1542326665.303 [Rowe_600LCM](INFO): Powering up 2018-11-16T00:04:29.480Z,1542326669.480 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-16T00:04:29.573Z,1542326669.573 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-16T00:04:29.575Z,1542326669.575 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-16T00:04:29.575Z,1542326669.575 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-16T00:04:29.576Z,1542326669.576 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-16T00:04:41.860Z,1542326681.860 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-16T00:03:41.5Z 2018-11-16T00:04:41.861Z,1542326681.861 [Startup:StartupSatComms:A] Stopped 2018-11-16T00:04:41.861Z,1542326681.861 [Startup:StartupSatComms:B] Running Loop=1 2018-11-16T00:04:42.254Z,1542326682.254 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-16T00:04:43.642Z,1542326683.642 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-11-16T00:04:43.642Z,1542326683.642 [BPC1] Data Fault, FailCount= 1 2018-11-16T00:04:43.643Z,1542326683.643 [BPC1](ERROR): Data Fault 2018-11-16T00:04:43.687Z,1542326683.687 [CBIT](ERROR): Data Fault in component: BPC1 2018-11-16T00:04:49.240Z,1542326689.240 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20181115T233510/Courier0000.lzma 2018-11-16T00:04:50.033Z,1542326690.033 [DataOverHttps](INFO): Moved sent file to Logs/20181115T233510/Courier0000.lzma.bak 2018-11-16T00:04:50.033Z,1542326690.033 [DataOverHttps](INFO): SBD MOMSN=8886993 2018-11-16T00:05:01.497Z,1542326701.497 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181115T233510/Courier0004.lzma 2018-11-16T00:05:02.298Z,1542326702.298 [DataOverHttps](INFO): Moved sent file to Logs/20181115T233510/Courier0004.lzma.bak 2018-11-16T00:05:02.302Z,1542326702.302 [DataOverHttps](INFO): SBD MOMSN=8887001 2018-11-16T00:05:17.093Z,1542326717.093 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20181115T234215/Courier0000.lzma 2018-11-16T00:05:17.873Z,1542326717.873 [DataOverHttps](INFO): Moved sent file to Logs/20181115T234215/Courier0000.lzma.bak 2018-11-16T00:05:17.873Z,1542326717.873 [DataOverHttps](INFO): SBD MOMSN=8887003 2018-11-16T00:05:29.327Z,1542326729.327 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:05:29.327Z,1542326729.327 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-16T00:05:29.338Z,1542326729.338 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20181116T000211/Courier0000.lzma 2018-11-16T00:05:30.137Z,1542326730.137 [DataOverHttps](INFO): Moved sent file to Logs/20181116T000211/Courier0000.lzma.bak 2018-11-16T00:05:30.137Z,1542326730.137 [DataOverHttps](INFO): SBD MOMSN=8887010 2018-11-16T00:05:39.494Z,1542326739.494 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:05:39.495Z,1542326739.495 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-16T00:05:39.495Z,1542326739.495 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:05:39.539Z,1542326739.539 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:05:40.007Z,1542326740.007 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:05:40.729Z,1542326740.729 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:05:40.729Z,1542326740.729 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-16T00:05:44.593Z,1542326744.593 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-16T00:04:41.9Z 2018-11-16T00:05:44.594Z,1542326744.594 [Startup:StartupSatComms:B] Stopped 2018-11-16T00:05:44.594Z,1542326744.594 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-11-16T00:05:44.594Z,1542326744.594 [Startup:StartupSatComms] Stopped 2018-11-16T00:05:44.594Z,1542326744.594 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-11-16T00:05:44.595Z,1542326744.595 [Startup](INFO): Completed Startup 2018-11-16T00:05:44.595Z,1542326744.595 [MissionManager](INFO): Startup is completed. 2018-11-16T00:05:44.596Z,1542326744.596 [MissionManager](INFO): Uninitializing Mission Startup 2018-11-16T00:05:44.596Z,1542326744.596 [Startup] Stopped 2018-11-16T00:05:44.596Z,1542326744.596 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-11-16T00:05:44.596Z,1542326744.596 [Startup:A.GoToSurface] Stopped 2018-11-16T00:05:44.596Z,1542326744.596 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-16T00:05:44.659Z,1542326744.659 [DataOverHttps](INFO): Sending 427 bytes from file Logs/20181114T205553/Express0452.lzma 2018-11-16T00:05:44.738Z,1542326744.738 [MissionManager](IMPORTANT): Started mission Default 2018-11-16T00:05:44.738Z,1542326744.738 [Default] Running Loop=1 2018-11-16T00:05:44.738Z,1542326744.738 [Default](DEBUG): Aggregate::initialize Default 2018-11-16T00:05:44.739Z,1542326744.739 [Default:B.GoToSurface] Running Loop=1 2018-11-16T00:05:44.739Z,1542326744.739 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-16T00:05:44.739Z,1542326744.739 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-16T00:05:44.739Z,1542326744.739 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-16T00:05:44.740Z,1542326744.740 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-16T00:05:44.740Z,1542326744.740 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-16T00:05:44.740Z,1542326744.740 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-16T00:05:44.741Z,1542326744.741 [Default:A.Wait] Running Loop=1 2018-11-16T00:05:44.741Z,1542326744.741 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-11-16T00:05:45.449Z,1542326745.449 [DataOverHttps](INFO): Moved sent file to Logs/20181114T205553/Express0452.lzma.bak 2018-11-16T00:05:45.449Z,1542326745.449 [DataOverHttps](INFO): SBD MOMSN=8887017 2018-11-16T00:05:51.257Z,1542326751.257 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:05:51.257Z,1542326751.257 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-16T00:05:51.257Z,1542326751.257 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:05:51.305Z,1542326751.305 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:05:51.759Z,1542326751.759 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:05:52.465Z,1542326752.465 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:05:52.466Z,1542326752.466 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-16T00:05:58.054Z,1542326758.054 [Default:A.Wait](INFO): Done Waiting. 2018-11-16T00:05:58.054Z,1542326758.054 [Default:A.Wait] Stopped 2018-11-16T00:05:58.054Z,1542326758.054 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-11-16T00:05:58.461Z,1542326758.461 [Default:CheckIn] Running Loop=1 2018-11-16T00:05:58.461Z,1542326758.461 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-11-16T00:05:58.461Z,1542326758.461 [Default:CheckIn:Read_GPS] Running Loop=1 2018-11-16T00:05:58.855Z,1542326758.855 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-11-16T00:06:03.235Z,1542326763.235 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:06:03.235Z,1542326763.235 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-16T00:06:03.235Z,1542326763.235 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:06:03.280Z,1542326763.280 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:06:03.280Z,1542326763.280 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-16T00:06:03.747Z,1542326763.747 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:06:24.041Z,1542326784.041 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-16T00:06:24.041Z,1542326784.041 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-11-16T00:06:24.041Z,1542326784.041 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-16T00:06:24.083Z,1542326784.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-16T00:06:24.476Z,1542326784.476 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-16T00:06:24.476Z,1542326784.476 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-11-16T00:06:24.844Z,1542326784.844 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-16T00:06:24.844Z,1542326784.844 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-16T00:06:24.844Z,1542326784.844 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-16T00:06:25.810Z,1542326785.810 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-16T00:06:25.810Z,1542326785.810 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-11-16T00:06:25.810Z,1542326785.810 [Rowe_600LCM](ERROR): Communications Fault 2018-11-16T00:06:26.065Z,1542326786.065 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-16T00:06:26.214Z,1542326786.214 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-16T00:06:26.297Z,1542326786.297 [Rowe_600LCM](INFO): Powering down 2018-11-16T00:06:27.674Z,1542326787.674 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-16T00:06:27.674Z,1542326787.674 [Rowe_600LCM] No Fault, FailCount= 2 2018-11-16T00:06:27.911Z,1542326787.911 [Rowe_600LCM](INFO): Initializing 2018-11-16T00:06:27.911Z,1542326787.911 [Rowe_600LCM](INFO): Checking LCM 2018-11-16T00:06:27.911Z,1542326787.911 [Rowe_600LCM](INFO): LCM OK 2018-11-16T00:06:27.911Z,1542326787.911 [Rowe_600LCM](INFO): Powering up 2018-11-16T00:06:32.081Z,1542326792.081 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-16T00:06:32.185Z,1542326792.185 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-16T00:06:32.187Z,1542326792.187 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-16T00:06:32.188Z,1542326792.188 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-16T00:06:32.188Z,1542326792.188 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-16T00:08:03.936Z,1542326883.936 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-11-16T00:08:16.796Z,1542326896.796 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:08:16.796Z,1542326896.796 [PNI_TCM] No Fault, FailCount= 5 2018-11-16T00:08:24.352Z,1542326904.352 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:08:24.352Z,1542326904.352 [PNI_TCM] Data Fault, FailCount= 1 2018-11-16T00:08:24.352Z,1542326904.352 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:08:24.398Z,1542326904.398 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:08:24.767Z,1542326904.767 [PNI_TCM](INFO): Powering down 2018-11-16T00:08:24.871Z,1542326904.871 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-16T00:08:24.871Z,1542326904.871 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-11-16T00:08:24.871Z,1542326904.871 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-16T00:08:24.884Z,1542326904.884 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-16T00:08:25.200Z,1542326905.200 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-16T00:08:25.200Z,1542326905.200 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-11-16T00:08:25.588Z,1542326905.588 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-16T00:08:25.589Z,1542326905.589 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-16T00:08:25.589Z,1542326905.589 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-16T00:08:25.987Z,1542326905.987 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:08:25.987Z,1542326905.987 [PNI_TCM] No Fault, FailCount= 1 2018-11-16T00:08:27.922Z,1542326907.922 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-16T00:08:27.922Z,1542326907.922 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-11-16T00:08:27.922Z,1542326907.922 [Rowe_600LCM](ERROR): Communications Fault 2018-11-16T00:08:27.991Z,1542326907.991 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-16T00:08:28.335Z,1542326908.335 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-16T00:08:28.507Z,1542326908.507 [Rowe_600LCM](INFO): Powering down 2018-11-16T00:08:29.994Z,1542326909.994 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-16T00:08:29.994Z,1542326909.994 [Rowe_600LCM] No Fault, FailCount= 3 2018-11-16T00:08:30.124Z,1542326910.124 [Rowe_600LCM](INFO): Initializing 2018-11-16T00:08:30.124Z,1542326910.124 [Rowe_600LCM](INFO): Checking LCM 2018-11-16T00:08:30.124Z,1542326910.124 [Rowe_600LCM](INFO): LCM OK 2018-11-16T00:08:30.124Z,1542326910.124 [Rowe_600LCM](INFO): Powering up 2018-11-16T00:08:33.561Z,1542326913.561 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:08:33.561Z,1542326913.561 [PNI_TCM] Data Fault, FailCount= 2 2018-11-16T00:08:33.562Z,1542326913.562 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:08:33.607Z,1542326913.607 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:08:33.607Z,1542326913.607 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:08:33.607Z,1542326913.607 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-16T00:08:33.956Z,1542326913.956 [PNI_TCM](INFO): Powering down 2018-11-16T00:08:34.244Z,1542326914.244 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-16T00:08:34.302Z,1542326914.302 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-16T00:08:34.303Z,1542326914.303 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-16T00:08:34.304Z,1542326914.304 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-16T00:08:34.305Z,1542326914.305 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-16T00:08:35.189Z,1542326915.189 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:08:35.189Z,1542326915.189 [PNI_TCM] No Fault, FailCount= 2 2018-11-16T00:08:42.773Z,1542326922.773 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:08:42.773Z,1542326922.773 [PNI_TCM] Data Fault, FailCount= 3 2018-11-16T00:08:42.773Z,1542326922.773 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:08:42.804Z,1542326922.804 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:08:43.167Z,1542326923.167 [PNI_TCM](INFO): Powering down 2018-11-16T00:08:44.352Z,1542326924.352 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:08:44.352Z,1542326924.352 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-16T00:08:44.352Z,1542326924.352 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:08:44.392Z,1542326924.392 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:08:44.392Z,1542326924.392 [PNI_TCM] No Fault, FailCount= 3 2018-11-16T00:08:44.392Z,1542326924.392 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:08:44.767Z,1542326924.767 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:08:45.605Z,1542326925.605 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:08:45.605Z,1542326925.605 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-16T00:08:51.933Z,1542326931.933 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:08:51.933Z,1542326931.933 [PNI_TCM] Data Fault, FailCount= 4 2018-11-16T00:08:51.933Z,1542326931.933 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:08:51.961Z,1542326931.961 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:08:52.337Z,1542326932.337 [PNI_TCM](INFO): Powering down 2018-11-16T00:08:53.552Z,1542326933.552 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-11-16T00:08:53.552Z,1542326933.552 [PNI_TCM] No Fault, FailCount= 4 2018-11-16T00:08:56.327Z,1542326936.327 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:08:56.327Z,1542326936.327 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-16T00:08:56.327Z,1542326936.327 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:08:56.375Z,1542326936.375 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:08:56.720Z,1542326936.720 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:08:57.561Z,1542326937.561 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:08:57.561Z,1542326937.561 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-16T00:09:01.139Z,1542326941.139 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-11-16T00:09:01.139Z,1542326941.139 [PNI_TCM] Data Fault, FailCount= 5 2018-11-16T00:09:01.139Z,1542326941.139 [PNI_TCM](ERROR): Data Fault 2018-11-16T00:09:01.165Z,1542326941.165 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-11-16T00:09:01.166Z,1542326941.166 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-11-16T00:09:01.539Z,1542326941.539 [PNI_TCM](INFO): Powering down 2018-11-16T00:09:01.675Z,1542326941.675 [CBIT](INFO): Critical error at 20181116T000901 2018-11-16T00:09:08.321Z,1542326948.321 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:09:08.321Z,1542326948.321 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-16T00:09:08.321Z,1542326948.321 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:09:08.393Z,1542326948.393 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:09:08.394Z,1542326948.394 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-16T00:09:08.839Z,1542326948.839 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:10:25.891Z,1542327025.891 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-16T00:10:25.892Z,1542327025.892 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-11-16T00:10:25.892Z,1542327025.892 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-16T00:10:25.905Z,1542327025.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-16T00:10:26.304Z,1542327026.304 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-16T00:10:26.304Z,1542327026.304 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-11-16T00:10:26.692Z,1542327026.692 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-16T00:10:26.693Z,1542327026.693 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-16T00:10:26.693Z,1542327026.693 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-16T00:10:30.642Z,1542327030.642 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-16T00:10:30.642Z,1542327030.642 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-11-16T00:10:30.642Z,1542327030.642 [Rowe_600LCM](ERROR): Communications Fault 2018-11-16T00:10:30.727Z,1542327030.727 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-16T00:10:31.055Z,1542327031.055 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-16T00:10:31.219Z,1542327031.219 [Rowe_600LCM](INFO): Powering down 2018-11-16T00:10:32.706Z,1542327032.706 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-11-16T00:10:32.706Z,1542327032.706 [Rowe_600LCM] No Fault, FailCount= 4 2018-11-16T00:10:32.835Z,1542327032.835 [Rowe_600LCM](INFO): Initializing 2018-11-16T00:10:32.835Z,1542327032.835 [Rowe_600LCM](INFO): Checking LCM 2018-11-16T00:10:32.835Z,1542327032.835 [Rowe_600LCM](INFO): LCM OK 2018-11-16T00:10:32.835Z,1542327032.835 [Rowe_600LCM](INFO): Powering up 2018-11-16T00:10:36.952Z,1542327036.952 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-16T00:10:37.014Z,1542327037.014 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2018-11-16T00:10:37.015Z,1542327037.015 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-16T00:10:37.016Z,1542327037.016 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-16T00:10:37.017Z,1542327037.017 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-16T00:10:58.669Z,1542327058.669 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-16T00:05:58.5Z 2018-11-16T00:10:58.669Z,1542327058.669 [Default:CheckIn:Read_GPS] Stopped 2018-11-16T00:10:58.669Z,1542327058.669 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-11-16T00:10:59.078Z,1542327059.078 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-16T00:11:03.735Z,1542327063.735 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181116T000211/Courier0004.lzma 2018-11-16T00:11:04.533Z,1542327064.533 [DataOverHttps](INFO): Moved sent file to Logs/20181116T000211/Courier0004.lzma.bak 2018-11-16T00:11:04.533Z,1542327064.533 [DataOverHttps](INFO): SBD MOMSN=8887047 2018-11-16T00:11:14.981Z,1542327074.981 [DataOverHttps](INFO): Sending 832 bytes from file Logs/20181115T233412/Express0001.lzma 2018-11-16T00:11:15.777Z,1542327075.777 [DataOverHttps](INFO): Moved sent file to Logs/20181115T233412/Express0001.lzma.bak 2018-11-16T00:11:15.777Z,1542327075.777 [DataOverHttps](INFO): SBD MOMSN=8887050 2018-11-16T00:11:27.029Z,1542327087.029 [DataOverHttps](INFO): Sending 970 bytes from file Logs/20181115T233510/Express0001.lzma 2018-11-16T00:11:27.825Z,1542327087.825 [DataOverHttps](INFO): Moved sent file to Logs/20181115T233510/Express0001.lzma.bak 2018-11-16T00:11:27.825Z,1542327087.825 [DataOverHttps](INFO): SBD MOMSN=8887071 2018-11-16T00:11:39.117Z,1542327099.117 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:11:39.117Z,1542327099.117 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-16T00:11:39.128Z,1542327099.128 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20181115T233510/Express0005.lzma 2018-11-16T00:11:39.929Z,1542327099.929 [DataOverHttps](INFO): Moved sent file to Logs/20181115T233510/Express0005.lzma.bak 2018-11-16T00:11:39.929Z,1542327099.929 [DataOverHttps](INFO): SBD MOMSN=8887097 2018-11-16T00:11:50.490Z,1542327110.490 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:11:50.490Z,1542327110.490 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-16T00:11:50.490Z,1542327110.490 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:11:50.532Z,1542327110.532 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:11:50.545Z,1542327110.545 [DataOverHttps](INFO): Sending 831 bytes from file Logs/20181115T234215/Express0001.lzma 2018-11-16T00:11:50.731Z,1542327110.731 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:11:51.341Z,1542327111.341 [DataOverHttps](INFO): Moved sent file to Logs/20181115T234215/Express0001.lzma.bak 2018-11-16T00:11:51.341Z,1542327111.341 [DataOverHttps](INFO): SBD MOMSN=8887103 2018-11-16T00:11:51.419Z,1542327111.419 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:11:51.419Z,1542327111.419 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-16T00:12:01.845Z,1542327121.845 [DataOverHttps](INFO): Sending 1014 bytes from file Logs/20181116T000211/Express0001.lzma 2018-11-16T00:12:02.642Z,1542327122.642 [DataOverHttps](INFO): Moved sent file to Logs/20181116T000211/Express0001.lzma.bak 2018-11-16T00:12:02.644Z,1542327122.644 [DataOverHttps](INFO): SBD MOMSN=8887128 2018-11-16T00:12:05.140Z,1542327125.140 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:12:05.140Z,1542327125.140 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-16T00:12:05.140Z,1542327125.140 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:12:05.188Z,1542327125.188 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:12:05.375Z,1542327125.375 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:12:08.477Z,1542327128.477 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-16T00:12:08.477Z,1542327128.477 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-16T00:12:13.930Z,1542327133.930 [DataOverHttps](INFO): Sending 519 bytes from file Logs/20181116T000211/Express0005.lzma 2018-11-16T00:12:14.701Z,1542327134.701 [DataOverHttps](INFO): Moved sent file to Logs/20181116T000211/Express0005.lzma.bak 2018-11-16T00:12:14.701Z,1542327134.701 [DataOverHttps](INFO): SBD MOMSN=8887155 2018-11-16T00:12:17.236Z,1542327137.236 [Default:CheckIn:Read_Iridium] Stopped 2018-11-16T00:12:17.236Z,1542327137.236 [Default:CheckIn:C.Wait] Running Loop=1 2018-11-16T00:12:17.236Z,1542327137.236 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-11-16T00:12:20.373Z,1542327140.373 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-16T00:12:20.374Z,1542327140.374 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-16T00:12:20.374Z,1542327140.374 [Aanderaa_O2](ERROR): Communications Fault 2018-11-16T00:12:20.393Z,1542327140.393 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-16T00:12:20.393Z,1542327140.393 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-16T00:12:20.608Z,1542327140.608 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:12:26.848Z,1542327146.848 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-16T00:12:26.848Z,1542327146.848 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-11-16T00:12:26.849Z,1542327146.849 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-16T00:12:26.878Z,1542327146.878 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-16T00:12:27.251Z,1542327147.251 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-16T00:12:27.251Z,1542327147.251 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-11-16T00:12:27.637Z,1542327147.637 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-16T00:12:27.638Z,1542327147.638 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-16T00:12:27.638Z,1542327147.638 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-16T00:12:33.270Z,1542327153.270 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-11-16T00:12:33.270Z,1542327153.270 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-11-16T00:12:33.271Z,1542327153.271 [Rowe_600LCM](ERROR): Communications Fault 2018-11-16T00:12:33.651Z,1542327153.651 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-11-16T00:12:33.651Z,1542327153.651 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2018-11-16T00:12:33.675Z,1542327153.675 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-16T00:12:33.798Z,1542327153.798 [Rowe_600LCM](INFO): Powering down 2018-11-16T00:12:36.120Z,1542327156.120 [CommandLine](IMPORTANT): got command restart application 2018-11-16T00:12:37.130Z,1542327157.130 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-16T00:12:37.131Z,1542327157.131 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.270Z,1542327157.270 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-11-16T00:12:37.271Z,1542327157.271 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.271Z,1542327157.271 [CommandLine](INFO): Join timeout helper Thread ID is 7725 2018-11-16T00:12:37.278Z,1542327157.278 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-11-16T00:12:37.279Z,1542327157.279 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.279Z,1542327157.279 [NavChartDb](INFO): Join timeout helper Thread ID is 7726 2018-11-16T00:12:37.551Z,1542327157.551 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-16T00:12:37.551Z,1542327157.551 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.566Z,1542327157.566 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-11-16T00:12:37.567Z,1542327157.567 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.567Z,1542327157.567 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7727 2018-11-16T00:12:37.703Z,1542327157.703 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-16T00:12:37.703Z,1542327157.703 [WetLabsBB2FL](INFO): Powering down 2018-11-16T00:12:37.704Z,1542327157.704 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.718Z,1542327157.718 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2018-11-16T00:12:37.719Z,1542327157.719 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.719Z,1542327157.719 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7728 2018-11-16T00:12:37.803Z,1542327157.803 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-16T00:12:37.885Z,1542327157.885 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-11-16T00:12:37.885Z,1542327157.885 [CTD_Seabird](INFO): Powering down 2018-11-16T00:12:37.887Z,1542327157.887 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.899Z,1542327157.899 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2018-11-16T00:12:37.899Z,1542327157.899 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:37.899Z,1542327157.899 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 7730 2018-11-16T00:12:38.242Z,1542327158.242 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-16T00:12:38.243Z,1542327158.243 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-16T00:12:38.327Z,1542327158.327 [Rowe_600LCM](INFO): Powering down 2018-11-16T00:12:38.328Z,1542327158.328 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:38.336Z,1542327158.336 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-11-16T00:12:38.336Z,1542327158.336 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:38.336Z,1542327158.336 [Radio_Surface](INFO): Join timeout helper Thread ID is 7732 2018-11-16T00:12:38.635Z,1542327158.635 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-16T00:12:38.635Z,1542327158.635 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:38.655Z,1542327158.655 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-11-16T00:12:38.655Z,1542327158.655 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:38.656Z,1542327158.656 [logger](INFO): Join timeout helper Thread ID is 7733 2018-11-16T00:12:38.694Z,1542327158.694 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-16T00:12:38.695Z,1542327158.695 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:38.715Z,1542327158.715 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-11-16T00:12:38.715Z,1542327158.715 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:38.715Z,1542327158.715 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-11-16T00:12:38.716Z,1542327158.716 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:38.716Z,1542327158.716 [controlThread](INFO): Join timeout helper Thread ID is 7734 2018-11-16T00:12:38.798Z,1542327158.798 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-16T00:12:38.799Z,1542327158.799 [controlThread](DEBUG): Uninitializing ControlThread 2018-11-16T00:12:38.799Z,1542327158.799 [AcousticModem_Benthos_ATM900](INFO): Powering down 2018-11-16T00:12:38.919Z,1542327158.919 [NAL9602](INFO): Powering down 2018-11-16T00:12:38.921Z,1542327158.921 [PNI_TCM](INFO): Powering down 2018-11-16T00:12:38.922Z,1542327158.922 [Aanderaa_O2](INFO): Powering down 2018-11-16T00:12:38.925Z,1542327158.925 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-16T00:12:38.926Z,1542327158.926 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-11-16T00:12:38.927Z,1542327158.927 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-11-16T00:12:38.927Z,1542327158.927 [MissionManager](INFO): Uninitializing Mission Default 2018-11-16T00:12:38.927Z,1542327158.927 [Default] Stopped 2018-11-16T00:12:38.928Z,1542327158.928 [Default](DEBUG): Aggregate::uninitialize Default 2018-11-16T00:12:38.928Z,1542327158.928 [Default:B.GoToSurface] Stopped 2018-11-16T00:12:38.928Z,1542327158.928 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-16T00:12:38.928Z,1542327158.928 [Default:CheckIn] Stopped 2018-11-16T00:12:38.928Z,1542327158.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-11-16T00:12:38.928Z,1542327158.928 [Default:CheckIn:C.Wait] Stopped 2018-11-16T00:12:38.928Z,1542327158.928 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-11-16T00:12:38.932Z,1542327158.932 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-11-16T00:12:38.932Z,1542327158.932 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-11-16T00:12:38.932Z,1542327158.932 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-11-16T00:12:38.933Z,1542327158.933 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-11-16T00:12:38.933Z,1542327158.933 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-16T00:12:38.933Z,1542327158.933 [BuoyancyServo](INFO): Powering down 2018-11-16T00:12:38.947Z,1542327158.947 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-16T00:12:38.947Z,1542327158.947 [ElevatorServo](INFO): Powering down 2018-11-16T00:12:38.948Z,1542327158.948 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-16T00:12:38.948Z,1542327158.948 [MassServo](INFO): Powering down 2018-11-16T00:12:38.949Z,1542327158.949 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-16T00:12:38.949Z,1542327158.949 [RudderServo](INFO): Powering down 2018-11-16T00:12:38.950Z,1542327158.950 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-16T00:12:38.950Z,1542327158.950 [ThrusterServo](INFO): Powering down 2018-11-16T00:12:38.951Z,1542327158.951 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-11-16T00:12:38.951Z,1542327158.951 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-11-16T00:12:38.952Z,1542327158.952 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-16T00:12:38.952Z,1542327158.952 [CBIT](DEBUG): Powering off loads. 2018-11-16T00:12:38.952Z,1542327158.952 [CBIT](DEBUG): Disabling WDT. 2018-11-16T00:12:38.952Z,1542327158.952 [CBIT](DEBUG): Opening all GF detection circuits. 2018-11-16T00:12:38.953Z,1542327158.953 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:39.041Z,1542327159.041 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:39.048Z,1542327159.048 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:39.087Z,1542327159.087 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:39.092Z,1542327159.092 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:39.140Z,1542327159.140 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-16T00:12:39.198Z,1542327159.198 [logger ThreadHandler](INFO): Thread cancelled.