2019-03-19T01:24:09.782Z,1552958649.782 [Supervisor](DEBUG): Initializing supervisor. 2019-03-19T01:24:09.785Z,1552958649.785 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-03-19T01:24:09.785Z,1552958649.785 [SyncHandler](INFO): Protected caller Thread ID is 1464 2019-03-19T01:24:09.785Z,1552958649.785 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-03-19T01:24:09.786Z,1552958649.786 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-03-19T01:24:09.787Z,1552958649.787 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1465 2019-03-19T01:24:09.790Z,1552958649.790 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-03-19T01:24:09.803Z,1552958649.803 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-03-19T01:24:09.804Z,1552958649.804 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-03-19T01:24:09.805Z,1552958649.805 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1466 2019-03-19T01:24:09.805Z,1552958649.805 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-03-19T01:24:09.806Z,1552958649.806 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-03-19T01:24:09.807Z,1552958649.807 [logger ThreadHandler](INFO): Protected caller Thread ID is 1467 2019-03-19T01:24:09.809Z,1552958649.809 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-03-19T01:24:09.809Z,1552958649.809 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-03-19T01:24:09.811Z,1552958649.811 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-03-19T01:24:10.246Z,1552958650.246 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-03-19T01:24:10.247Z,1552958650.247 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-03-19T01:24:10.717Z,1552958650.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-03-19T01:24:10.718Z,1552958650.718 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-03-19T01:24:10.927Z,1552958650.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-03-19T01:24:10.927Z,1552958650.927 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-03-19T01:24:11.132Z,1552958651.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-03-19T01:24:11.133Z,1552958651.133 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-03-19T01:24:11.594Z,1552958651.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-03-19T01:24:11.594Z,1552958651.594 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-03-19T01:24:11.691Z,1552958651.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-03-19T01:24:11.691Z,1552958651.691 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-03-19T01:24:11.834Z,1552958651.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-03-19T01:24:11.835Z,1552958651.835 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-03-19T01:24:11.935Z,1552958651.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-03-19T01:24:11.936Z,1552958651.936 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-03-19T01:24:12.040Z,1552958652.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-03-19T01:24:12.041Z,1552958652.041 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-03-19T01:24:12.428Z,1552958652.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-03-19T01:24:12.429Z,1552958652.429 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-03-19T01:24:12.629Z,1552958652.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-03-19T01:24:12.630Z,1552958652.630 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-03-19T01:24:12.850Z,1552958652.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-03-19T01:24:12.851Z,1552958652.851 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-03-19T01:24:12.932Z,1552958652.932 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-03-19T01:24:13.273Z,1552958653.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-03-19T01:24:13.274Z,1552958653.274 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-03-19T01:24:13.594Z,1552958653.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-03-19T01:24:13.596Z,1552958653.596 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2019-03-19T01:24:13.597Z,1552958653.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2019-03-19T01:24:13.683Z,1552958653.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2019-03-19T01:24:13.836Z,1552958653.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2019-03-19T01:24:13.935Z,1552958653.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2019-03-19T01:24:14.113Z,1552958654.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2019-03-19T01:24:14.196Z,1552958654.196 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2019-03-19T01:24:14.422Z,1552958654.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-03-19T01:24:14.423Z,1552958654.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2019-03-19T01:24:14.539Z,1552958654.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2019-03-19T01:24:14.638Z,1552958654.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2019-03-19T01:24:14.733Z,1552958654.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2019-03-19T01:24:14.835Z,1552958654.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2019-03-19T01:24:14.946Z,1552958654.946 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2019-03-19T01:24:14.946Z,1552958654.946 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-03-19T01:24:14.951Z,1552958654.951 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-03-19T01:24:14.993Z,1552958654.993 [DepthRateCalculator] Loaded 2019-03-19T01:24:14.993Z,1552958654.993 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-03-19T01:24:14.999Z,1552958654.999 [PitchRateCalculator] Loaded 2019-03-19T01:24:14.999Z,1552958654.999 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-03-19T01:24:15.015Z,1552958655.015 [SpeedCalculator] Loaded 2019-03-19T01:24:15.015Z,1552958655.015 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-03-19T01:24:15.036Z,1552958655.036 [TempGradientCalculator] Loaded 2019-03-19T01:24:15.036Z,1552958655.036 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-03-19T01:24:15.042Z,1552958655.042 [YawRateCalculator] Loaded 2019-03-19T01:24:15.042Z,1552958655.042 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-03-19T01:24:15.081Z,1552958655.081 [ElevatorOffsetCalculator] Loaded 2019-03-19T01:24:15.082Z,1552958655.082 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-03-19T01:24:15.082Z,1552958655.082 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-03-19T01:24:15.083Z,1552958655.083 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-03-19T01:24:15.310Z,1552958655.310 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-03-19T01:24:15.311Z,1552958655.311 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-03-19T01:24:15.615Z,1552958655.615 [DataOverHttps] Loaded 2019-03-19T01:24:15.615Z,1552958655.615 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-03-19T01:24:15.616Z,1552958655.616 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407CE4E0 2019-03-19T01:24:15.617Z,1552958655.617 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1546 2019-03-19T01:24:15.656Z,1552958655.656 [Depth_Keller] Loaded 2019-03-19T01:24:15.656Z,1552958655.656 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-03-19T01:24:15.752Z,1552958655.752 [NAL9602] Loaded 2019-03-19T01:24:15.752Z,1552958655.752 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-03-19T01:24:15.767Z,1552958655.767 [Onboard] Loaded 2019-03-19T01:24:15.767Z,1552958655.767 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-03-19T01:24:15.774Z,1552958655.774 [Radio_Surface] Loaded 2019-03-19T01:24:15.774Z,1552958655.774 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-03-19T01:24:15.775Z,1552958655.775 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407FE4E0 2019-03-19T01:24:15.776Z,1552958655.776 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1547 2019-03-19T01:24:18.048Z,1552958658.048 [BPC1] Loaded 2019-03-19T01:24:18.049Z,1552958658.049 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-03-19T01:24:18.049Z,1552958658.049 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-03-19T01:24:18.050Z,1552958658.050 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-03-19T01:24:18.111Z,1552958658.111 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-03-19T01:24:18.112Z,1552958658.112 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-03-19T01:24:18.213Z,1552958658.213 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-03-19T01:24:18.213Z,1552958658.213 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-03-19T01:24:18.271Z,1552958658.271 [DeadReckonUsingSpeedCalculator] Loaded 2019-03-19T01:24:18.271Z,1552958658.271 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-03-19T01:24:18.291Z,1552958658.291 [NavChart] Loaded 2019-03-19T01:24:18.292Z,1552958658.292 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-03-19T01:24:18.296Z,1552958658.296 [UniversalFixResidualReporter] Loaded 2019-03-19T01:24:18.296Z,1552958658.296 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-03-19T01:24:18.296Z,1552958658.296 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-03-19T01:24:18.297Z,1552958658.297 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-03-19T01:24:18.538Z,1552958658.538 [CTD_NeilBrown] Loaded 2019-03-19T01:24:18.538Z,1552958658.538 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-03-19T01:24:18.539Z,1552958658.539 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409524E0 2019-03-19T01:24:18.540Z,1552958658.540 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1548 2019-03-19T01:24:18.587Z,1552958658.587 [WetLabsBB2FL] Loaded 2019-03-19T01:24:18.588Z,1552958658.588 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-03-19T01:24:18.589Z,1552958658.589 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409824E0 2019-03-19T01:24:18.589Z,1552958658.589 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1549 2019-03-19T01:24:18.590Z,1552958658.590 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-03-19T01:24:18.590Z,1552958658.590 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-03-19T01:24:18.655Z,1552958658.655 [VerticalControl](DEBUG): Construct VerticalControl. 2019-03-19T01:24:18.752Z,1552958658.752 [VerticalControl] Loaded 2019-03-19T01:24:18.753Z,1552958658.753 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-03-19T01:24:18.753Z,1552958658.753 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-03-19T01:24:18.820Z,1552958658.820 [HorizontalControl] Loaded 2019-03-19T01:24:18.821Z,1552958658.821 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-03-19T01:24:18.821Z,1552958658.821 [SpeedControl](DEBUG): Construct SpeedControl. 2019-03-19T01:24:18.826Z,1552958658.826 [SpeedControl] Loaded 2019-03-19T01:24:18.827Z,1552958658.827 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-03-19T01:24:18.827Z,1552958658.827 [LoopControl](DEBUG): Construct LoopControl. 2019-03-19T01:24:18.828Z,1552958658.828 [LoopControl] Loaded 2019-03-19T01:24:18.828Z,1552958658.828 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-03-19T01:24:18.829Z,1552958658.829 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-03-19T01:24:18.829Z,1552958658.829 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-03-19T01:24:18.842Z,1552958658.842 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-03-19T01:24:18.843Z,1552958658.843 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-03-19T01:24:18.973Z,1552958658.973 [SBIT](DEBUG): Construct Startup Built In Test. 2019-03-19T01:24:18.984Z,1552958658.984 [SBIT] Loaded 2019-03-19T01:24:18.984Z,1552958658.984 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-03-19T01:24:18.985Z,1552958658.985 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-03-19T01:24:18.997Z,1552958658.997 [IBIT] Loaded 2019-03-19T01:24:18.997Z,1552958658.997 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-03-19T01:24:18.000Z,1552958659.000 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-03-19T01:24:19.137Z,1552958659.137 [CBIT] Loaded 2019-03-19T01:24:19.137Z,1552958659.137 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-03-19T01:24:19.138Z,1552958659.138 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-03-19T01:24:19.139Z,1552958659.139 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-03-19T01:24:19.234Z,1552958659.234 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-03-19T01:24:19.235Z,1552958659.235 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-03-19T01:24:19.262Z,1552958659.262 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-03-19T01:24:19.262Z,1552958659.262 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-03-19T01:24:19.359Z,1552958659.359 [BuoyancyServo] Loaded 2019-03-19T01:24:19.359Z,1552958659.359 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-03-19T01:24:19.370Z,1552958659.370 [ElevatorServo] Loaded 2019-03-19T01:24:19.371Z,1552958659.371 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-03-19T01:24:19.377Z,1552958659.377 [MassServo](DEBUG): LcmSlateWriter::add(): platform_mass_position 2019-03-19T01:24:19.382Z,1552958659.382 [MassServo](INFO): created writer for : platform_mass_position 2019-03-19T01:24:19.384Z,1552958659.384 [MassServo](DEBUG): LcmSlateWriter::add(): fdi_state 2019-03-19T01:24:19.388Z,1552958659.388 [MassServo](INFO): created writer for : fdi_state 2019-03-19T01:24:19.389Z,1552958659.389 [MassServo] Loaded 2019-03-19T01:24:19.390Z,1552958659.390 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-03-19T01:24:19.401Z,1552958659.401 [RudderServo] Loaded 2019-03-19T01:24:19.401Z,1552958659.401 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-03-19T01:24:19.412Z,1552958659.412 [ThrusterServo] Loaded 2019-03-19T01:24:19.412Z,1552958659.412 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-03-19T01:24:19.412Z,1552958659.412 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-03-19T01:24:19.416Z,1552958659.416 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-03-19T01:24:19.417Z,1552958659.417 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-03-19T01:24:19.423Z,1552958659.423 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-03-19T01:24:19.424Z,1552958659.424 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2019-03-19T01:24:19.425Z,1552958659.425 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1550 2019-03-19T01:24:19.429Z,1552958659.429 [Supervisor](INFO): Main Thread ID is 798 2019-03-19T01:24:19.429Z,1552958659.429 [Supervisor](DEBUG): Running supervisor. 2019-03-19T01:24:19.430Z,1552958659.430 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1551 2019-03-19T01:24:19.434Z,1552958659.434 [controlThread ThreadHandler](INFO): Handler Thread ID is 1552 2019-03-19T01:24:19.434Z,1552958659.434 [controlThread](DEBUG): Initializing ControlThread 2019-03-19T01:24:19.435Z,1552958659.435 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-03-19T01:24:19.435Z,1552958659.435 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-03-19T01:24:19.435Z,1552958659.435 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-03-19T01:24:19.436Z,1552958659.436 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-03-19T01:24:19.436Z,1552958659.436 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-03-19T01:24:19.437Z,1552958659.437 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-03-19T01:24:19.442Z,1552958659.442 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-19T01:24:19.443Z,1552958659.443 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-03-19T01:24:19.443Z,1552958659.443 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-03-19T01:24:19.444Z,1552958659.444 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-03-19T01:24:19.445Z,1552958659.445 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-03-19T01:24:19.446Z,1552958659.446 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-03-19T01:24:19.446Z,1552958659.446 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-03-19T01:24:19.447Z,1552958659.447 [SBIT](INFO): Initialize SBIT Component. 2019-03-19T01:24:19.447Z,1552958659.447 [SBIT](IMPORTANT): git: 2019-03-04-46-gd6610ff 2019-03-19T01:24:19.447Z,1552958659.447 [SBIT](INFO): git hash: d6610ff838e6b7a25a908fc1e9fd291f97bf1870 2019-03-19T01:24:19.448Z,1552958659.448 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-03-19T01:24:19.448Z,1552958659.448 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-03-19T01:24:19.449Z,1552958659.449 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-03-19T01:24:19.450Z,1552958659.450 [IBIT](INFO): Initialize IBIT Component. 2019-03-19T01:24:19.451Z,1552958659.451 [CBIT](DEBUG): Initialize CBIT Component. 2019-03-19T01:24:19.451Z,1552958659.451 [logger ThreadHandler](INFO): Handler Thread ID is 1553 2019-03-19T01:24:19.465Z,1552958659.465 [CBIT](DEBUG): Initialized mux pins. 2019-03-19T01:24:19.465Z,1552958659.465 [CBIT](DEBUG): Initializing the watchdog timer. 2019-03-19T01:24:19.469Z,1552958659.469 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1554 2019-03-19T01:24:19.470Z,1552958659.470 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-03-19T01:24:19.477Z,1552958659.477 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1555 2019-03-19T01:24:19.485Z,1552958659.485 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1556 2019-03-19T01:24:19.489Z,1552958659.489 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-03-19T01:24:19.489Z,1552958659.489 [CBIT](DEBUG): Initializing heartbeat. 2019-03-19T01:24:19.497Z,1552958659.497 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1557 2019-03-19T01:24:19.498Z,1552958659.498 [WetLabsBB2FL](INFO): Powering down 2019-03-19T01:24:19.525Z,1552958659.525 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1558 2019-03-19T01:24:19.528Z,1552958659.528 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-19T01:24:19.528Z,1552958659.528 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-03-19T01:24:19.529Z,1552958659.529 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-19T01:24:19.529Z,1552958659.529 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-03-19T01:24:19.529Z,1552958659.529 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-19T01:24:19.529Z,1552958659.529 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-03-19T01:24:19.529Z,1552958659.529 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-19T01:24:19.530Z,1552958659.530 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-03-19T01:24:19.530Z,1552958659.530 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-19T01:24:19.530Z,1552958659.530 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-03-19T01:24:19.530Z,1552958659.530 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-19T01:24:19.530Z,1552958659.530 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-03-19T01:24:19.531Z,1552958659.531 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-19T01:24:19.531Z,1552958659.531 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-03-19T01:24:19.531Z,1552958659.531 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-19T01:24:19.531Z,1552958659.531 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-03-19T01:24:19.561Z,1552958659.561 [CBIT](DEBUG): Deactivating GF circuits. 2019-03-19T01:24:19.561Z,1552958659.561 [CBIT](DEBUG): Deactivating emergency mode. 2019-03-19T01:24:19.599Z,1552958659.599 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-03-19T01:24:19.609Z,1552958659.609 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-19T01:24:19.620Z,1552958659.620 [MissionManager](DEBUG): 2019-03-19T01:24:19.620Z,1552958659.620 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-03-19T01:24:19.688Z,1552958659.688 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-03-19T01:24:19.706Z,1552958659.706 [Default:A.Wait](DEBUG): Construct Wait. 2019-03-19T01:24:19.707Z,1552958659.707 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-19T01:24:19.727Z,1552958659.727 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-03-19T01:24:19.751Z,1552958659.751 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-03-19T01:24:19.792Z,1552958659.792 [Default:E.Execute](DEBUG): Construct Execute. 2019-03-19T01:24:19.795Z,1552958659.795 [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 2019-03-19T01:24:19.808Z,1552958659.808 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-19T01:24:19.838Z,1552958659.838 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar 2019-03-19T01:24:19.881Z,1552958659.881 [Radio_Surface](INFO): Powering up 2019-03-19T01:24:19.901Z,1552958659.901 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-19T01:24:19.935Z,1552958659.935 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:19.949Z,1552958659.949 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-03-19T01:24:19.950Z,1552958659.950 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:19.961Z,1552958659.961 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-19T01:24:19.962Z,1552958659.962 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:19.969Z,1552958659.969 [MassServo](DEBUG): Initializing MassServo. 2019-03-19T01:24:19.970Z,1552958659.970 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:19.977Z,1552958659.977 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-19T01:24:19.978Z,1552958659.978 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:19.985Z,1552958659.985 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-03-19T01:24:20.245Z,1552958660.245 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-19T01:24:24.757Z,1552958664.757 [RudderServo](FAULT): Overload Error 2019-03-19T01:24:24.757Z,1552958664.757 [RudderServo] Hardware Fault, FailCount= 1 2019-03-19T01:24:24.757Z,1552958664.757 [RudderServo](ERROR): Hardware Fault 2019-03-19T01:24:24.797Z,1552958664.797 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-19T01:24:25.126Z,1552958665.126 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-19T01:24:25.127Z,1552958665.127 [RudderServo](INFO): Powering down 2019-03-19T01:24:25.935Z,1552958665.935 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:26.054Z,1552958666.054 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-19T01:24:26.058Z,1552958666.058 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-19T01:24:26.058Z,1552958666.058 [RudderServo] No Fault, FailCount= 1 2019-03-19T01:24:30.769Z,1552958670.769 [RudderServo](FAULT): Overload Error 2019-03-19T01:24:30.769Z,1552958670.769 [RudderServo] Hardware Fault, FailCount= 2 2019-03-19T01:24:30.769Z,1552958670.769 [RudderServo](ERROR): Hardware Fault 2019-03-19T01:24:30.809Z,1552958670.809 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-19T01:24:31.150Z,1552958671.150 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-19T01:24:31.150Z,1552958671.150 [RudderServo](INFO): Powering down 2019-03-19T01:24:31.958Z,1552958671.958 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:32.078Z,1552958672.078 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-19T01:24:32.082Z,1552958672.082 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-19T01:24:32.082Z,1552958672.082 [RudderServo] No Fault, FailCount= 2 2019-03-19T01:24:36.829Z,1552958676.829 [RudderServo](FAULT): Overload Error 2019-03-19T01:24:36.829Z,1552958676.829 [RudderServo] Hardware Fault, FailCount= 3 2019-03-19T01:24:36.829Z,1552958676.829 [RudderServo](ERROR): Hardware Fault 2019-03-19T01:24:36.869Z,1552958676.869 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-19T01:24:36.869Z,1552958676.869 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2019-03-19T01:24:37.210Z,1552958677.210 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-19T01:24:37.210Z,1552958677.210 [RudderServo](INFO): Powering down 2019-03-19T01:24:37.212Z,1552958677.212 [CommandLine](FAULT): Scheduling is paused 2019-03-19T01:24:37.212Z,1552958677.212 [CBIT](INFO): Critical error at 20190319T012436 2019-03-19T01:24:37.213Z,1552958677.213 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-03-19T01:24:38.018Z,1552958678.018 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:38.138Z,1552958678.138 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-19T01:24:46.074Z,1552958686.074 [NAL9602](INFO): Powering up NAL9602 2019-03-19T01:24:48.126Z,1552958688.126 [SBIT](IMPORTANT): Beginning Startup BIT 2019-03-19T01:24:48.131Z,1552958688.131 [CBIT](IMPORTANT): Beginning ground fault scan 2019-03-19T01:24:50.181Z,1552958690.181 [ElevatorServo](FAULT): Overload Error 2019-03-19T01:24:50.181Z,1552958690.181 [ElevatorServo] Hardware Fault, FailCount= 1 2019-03-19T01:24:50.181Z,1552958690.181 [ElevatorServo](ERROR): Hardware Fault 2019-03-19T01:24:50.598Z,1552958690.598 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-19T01:24:50.641Z,1552958690.641 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-19T01:24:50.641Z,1552958690.641 [ElevatorServo](INFO): Powering down 2019-03-19T01:24:51.437Z,1552958691.437 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:51.558Z,1552958691.558 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-19T01:24:51.602Z,1552958691.602 [CBIT](INFO): Clearing failed state for component ElevatorServo 2019-03-19T01:24:51.602Z,1552958691.602 [ElevatorServo] No Fault, FailCount= 1 2019-03-19T01:24:56.357Z,1552958696.357 [ElevatorServo](FAULT): Overload Error 2019-03-19T01:24:56.357Z,1552958696.357 [ElevatorServo] Hardware Fault, FailCount= 2 2019-03-19T01:24:56.357Z,1552958696.357 [ElevatorServo](ERROR): Hardware Fault 2019-03-19T01:24:56.398Z,1552958696.398 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-19T01:24:56.711Z,1552958696.711 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-19T01:24:56.711Z,1552958696.711 [ElevatorServo](INFO): Powering down 2019-03-19T01:24:57.078Z,1552958697.078 [NAL9602](INFO): NAL9602 initialized 2019-03-19T01:24:57.509Z,1552958697.509 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:24:57.626Z,1552958697.626 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-19T01:24:57.670Z,1552958697.670 [CBIT](INFO): Clearing failed state for component ElevatorServo 2019-03-19T01:24:57.670Z,1552958697.670 [ElevatorServo] No Fault, FailCount= 2 2019-03-19T01:24:57.893Z,1552958697.893 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:24:59.162Z,1552958699.162 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.007144 CHAN A1 (24V): 0.001904 CHAN A2 (12V): -0.005755 CHAN A3 (5V): -0.002736 CHAN B0 (3.3V): -0.001387 CHAN B1 (3.15aV): -0.001561 CHAN B2 (3.15bV): -0.001072 CHAN B3 (GND): -0.000839 OPEN: -0.001412 Full Scale Calc: 4.765 mA, -1.589 mA 2019-03-19T01:25:01.251Z,1552958701.251 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-19T01:25:02.789Z,1552958702.789 [ElevatorServo](FAULT): Overload Error 2019-03-19T01:25:02.789Z,1552958702.789 [ElevatorServo] Hardware Fault, FailCount= 3 2019-03-19T01:25:02.789Z,1552958702.789 [ElevatorServo](ERROR): Hardware Fault 2019-03-19T01:25:02.830Z,1552958702.830 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-19T01:25:02.830Z,1552958702.830 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo 2019-03-19T01:25:03.166Z,1552958703.166 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-19T01:25:03.166Z,1552958703.166 [ElevatorServo](INFO): Powering down 2019-03-19T01:25:03.174Z,1552958703.174 [CBIT](INFO): Critical error at 20190319T012502 2019-03-19T01:25:03.965Z,1552958703.965 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:25:04.082Z,1552958704.082 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-19T01:25:27.958Z,1552958727.958 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-03-19T01:25:27.959Z,1552958727.959 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-19T01:25:27.959Z,1552958727.959 [SBIT](FAULT): Control surface position failure. 2019-03-19T01:25:41.726Z,1552958741.726 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-03-19T01:25:41.726Z,1552958741.726 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-19T01:25:41.726Z,1552958741.726 [SBIT](FAULT): Control surface position failure. 2019-03-19T01:25:42.117Z,1552958742.117 [SBIT](CRITICAL): SBIT FAILED 2019-03-19T01:25:42.134Z,1552958742.134 [CommandLine](IMPORTANT): got command configSet list 2019-03-19T01:25:42.134Z,1552958742.134 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-03-19T01:25:42.135Z,1552958742.135 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2019-03-19T01:25:42.135Z,1552958742.135 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2019-03-19T01:25:42.135Z,1552958742.135 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-03-19T01:25:42.141Z,1552958742.141 [CBIT](INFO): Critical error at 20190319T012542 2019-03-19T01:25:42.501Z,1552958742.501 [MissionManager](IMPORTANT): Started mission Startup 2019-03-19T01:25:42.502Z,1552958742.502 [Startup] Running Loop=1 2019-03-19T01:25:42.502Z,1552958742.502 [Startup](DEBUG): Aggregate::initialize Startup 2019-03-19T01:25:42.502Z,1552958742.502 [Startup:A.GoToSurface] Running Loop=1 2019-03-19T01:25:42.502Z,1552958742.502 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-19T01:25:42.503Z,1552958742.503 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-19T01:25:42.503Z,1552958742.503 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-19T01:25:42.503Z,1552958742.503 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-19T01:25:42.504Z,1552958742.504 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-19T01:25:42.504Z,1552958742.504 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-19T01:25:42.530Z,1552958742.530 [Startup:StartupSatComms] Running Loop=1 2019-03-19T01:25:42.530Z,1552958742.530 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-03-19T01:25:42.530Z,1552958742.530 [Startup:StartupSatComms:A] Running Loop=1 2019-03-19T01:25:42.898Z,1552958742.898 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-03-19T01:26:19.665Z,1552958779.665 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:26:19.665Z,1552958779.665 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-19T01:26:19.665Z,1552958779.665 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-19T01:26:19.666Z,1552958779.666 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:26:19.667Z,1552958779.667 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-19T01:26:19.667Z,1552958779.667 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-19T01:26:19.677Z,1552958779.677 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-19T01:26:19.677Z,1552958779.677 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-19T01:26:20.087Z,1552958780.087 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-19T01:26:20.088Z,1552958780.088 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-19T01:26:20.088Z,1552958780.088 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-19T01:26:20.088Z,1552958780.088 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-19T01:26:20.464Z,1552958780.464 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-19T01:26:42.686Z,1552958802.686 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-19T01:25:42.5Z 2019-03-19T01:26:42.686Z,1552958802.686 [Startup:StartupSatComms:A] Stopped 2019-03-19T01:26:42.686Z,1552958802.686 [Startup:StartupSatComms:B] Running Loop=1 2019-03-19T01:26:43.094Z,1552958803.094 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-19T01:26:45.479Z,1552958805.479 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005076 2019-03-19T01:26:51.700Z,1552958811.700 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190319T010151/Courier0010.lzma 2019-03-19T01:26:52.506Z,1552958812.506 [DataOverHttps](INFO): Moved sent file to Logs/20190319T010151/Courier0010.lzma.bak 2019-03-19T01:26:52.506Z,1552958812.506 [DataOverHttps](INFO): SBD MOMSN=10190431 2019-03-19T01:27:04.264Z,1552958824.264 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20190319T012409/Courier0000.lzma 2019-03-19T01:27:05.070Z,1552958825.070 [DataOverHttps](INFO): Moved sent file to Logs/20190319T012409/Courier0000.lzma.bak 2019-03-19T01:27:05.070Z,1552958825.070 [DataOverHttps](INFO): SBD MOMSN=10190433 2019-03-19T01:27:18.044Z,1552958838.044 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20190319T010151/Express0011.lzma 2019-03-19T01:27:18.850Z,1552958838.850 [DataOverHttps](INFO): Moved sent file to Logs/20190319T010151/Express0011.lzma.bak 2019-03-19T01:27:18.850Z,1552958838.850 [DataOverHttps](INFO): SBD MOMSN=10190439 2019-03-19T01:27:32.154Z,1552958852.154 [DataOverHttps](INFO): Sending 905 bytes from file Logs/20190319T012409/Express0001.lzma 2019-03-19T01:27:32.958Z,1552958852.958 [DataOverHttps](INFO): Moved sent file to Logs/20190319T012409/Express0001.lzma.bak 2019-03-19T01:27:32.958Z,1552958852.958 [DataOverHttps](INFO): SBD MOMSN=10190445 2019-03-19T01:27:36.081Z,1552958856.081 [Startup:StartupSatComms:B] Stopped 2019-03-19T01:27:36.081Z,1552958856.081 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-03-19T01:27:36.081Z,1552958856.081 [Startup:StartupSatComms] Stopped 2019-03-19T01:27:36.082Z,1552958856.082 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-03-19T01:27:36.082Z,1552958856.082 [Startup](INFO): Completed Startup 2019-03-19T01:27:36.082Z,1552958856.082 [MissionManager](INFO): Startup is completed. 2019-03-19T01:27:36.083Z,1552958856.083 [MissionManager](INFO): Uninitializing Mission Startup 2019-03-19T01:27:36.083Z,1552958856.083 [Startup] Stopped 2019-03-19T01:27:36.083Z,1552958856.083 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-03-19T01:27:36.083Z,1552958856.083 [Startup:A.GoToSurface] Stopped 2019-03-19T01:27:36.083Z,1552958856.083 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-19T01:27:36.498Z,1552958856.498 [MissionManager](IMPORTANT): Started mission Default 2019-03-19T01:27:36.498Z,1552958856.498 [Default] Running Loop=1 2019-03-19T01:27:36.498Z,1552958856.498 [Default](DEBUG): Aggregate::initialize Default 2019-03-19T01:27:36.498Z,1552958856.498 [Default:B.GoToSurface] Running Loop=1 2019-03-19T01:27:36.498Z,1552958856.498 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-19T01:27:36.498Z,1552958856.498 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-19T01:27:36.499Z,1552958856.499 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-19T01:27:36.499Z,1552958856.499 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-19T01:27:36.499Z,1552958856.499 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-19T01:27:36.500Z,1552958856.500 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-19T01:27:36.500Z,1552958856.500 [Default:A.Wait] Running Loop=1 2019-03-19T01:27:36.500Z,1552958856.500 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-03-19T01:27:49.826Z,1552958869.826 [Default:A.Wait](INFO): Done Waiting. 2019-03-19T01:27:49.826Z,1552958869.826 [Default:A.Wait] Stopped 2019-03-19T01:27:49.826Z,1552958869.826 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-03-19T01:27:50.226Z,1552958870.226 [Default:CheckIn] Running Loop=1 2019-03-19T01:27:50.226Z,1552958870.226 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-19T01:27:50.226Z,1552958870.226 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-19T01:27:50.638Z,1552958870.638 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-03-19T01:28:20.520Z,1552958900.520 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:28:20.520Z,1552958900.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-19T01:28:20.520Z,1552958900.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-19T01:28:20.521Z,1552958900.521 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:28:20.521Z,1552958900.521 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-19T01:28:20.522Z,1552958900.522 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-19T01:28:20.542Z,1552958900.542 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-19T01:28:20.542Z,1552958900.542 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-19T01:28:20.953Z,1552958900.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-19T01:28:20.953Z,1552958900.953 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-19T01:28:20.953Z,1552958900.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-19T01:28:20.953Z,1552958900.953 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-19T01:28:21.332Z,1552958901.332 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-19T01:28:25.943Z,1552958905.943 [CommandLine](IMPORTANT): got command failComponent hardware MassServo 2019-03-19T01:28:25.943Z,1552958905.943 [MassServo] Hardware Fault, FailCount= 1 2019-03-19T01:28:25.943Z,1552958905.943 [MassServo](ERROR): Hardware Fault 2019-03-19T01:28:25.943Z,1552958905.943 [CommandLine](IMPORTANT): MassServo failureMode is Hardware Fault 2019-03-19T01:28:26.186Z,1552958906.186 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-03-19T01:28:26.186Z,1552958906.186 [MassServo](INFO): Powering down 2019-03-19T01:28:26.191Z,1552958906.191 [CBIT](ERROR): Hardware Fault in component: MassServo 2019-03-19T01:28:26.994Z,1552958906.994 [CBIT](INFO): Clearing failed state for component MassServo 2019-03-19T01:28:26.994Z,1552958906.994 [MassServo] No Fault, FailCount= 1 2019-03-19T01:28:27.387Z,1552958907.387 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:28:27.388Z,1552958907.388 [MassServo](DEBUG): Initializing MassServo. 2019-03-19T01:29:27.489Z,1552958967.489 [CommandLine](IMPORTANT): got command failComponent none MassServo 2019-03-19T01:29:27.490Z,1552958967.490 [CommandLine](IMPORTANT): MassServo failureMode is No Fault 2019-03-19T01:29:31.798Z,1552958971.798 [CommandLine](IMPORTANT): got command failComponent hardware MassServo 2019-03-19T01:29:31.799Z,1552958971.799 [MassServo] Hardware Fault, FailCount= 1 2019-03-19T01:29:31.799Z,1552958971.799 [MassServo](ERROR): Hardware Fault 2019-03-19T01:29:31.799Z,1552958971.799 [CommandLine](IMPORTANT): MassServo failureMode is Hardware Fault 2019-03-19T01:29:32.058Z,1552958972.058 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-03-19T01:29:32.058Z,1552958972.058 [MassServo](INFO): Powering down 2019-03-19T01:29:32.063Z,1552958972.063 [CBIT](ERROR): Hardware Fault in component: MassServo 2019-03-19T01:29:32.862Z,1552958972.862 [CBIT](INFO): Clearing failed state for component MassServo 2019-03-19T01:29:32.862Z,1552958972.862 [MassServo] No Fault, FailCount= 1 2019-03-19T01:29:33.270Z,1552958973.270 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-19T01:29:33.277Z,1552958973.277 [MassServo](DEBUG): Initializing MassServo. 2019-03-19T01:30:00.290Z,1552959000.290 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-03-19T01:30:01.109Z,1552959001.109 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:01.448Z,1552959001.448 [CommandLine](IMPORTANT): got command failComponent none MassServo 2019-03-19T01:30:01.449Z,1552959001.449 [CommandLine](IMPORTANT): MassServo failureMode is No Fault 2019-03-19T01:30:05.957Z,1552959005.957 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:07.981Z,1552959007.981 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:09.997Z,1552959009.997 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:13.229Z,1552959013.229 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:16.057Z,1552959016.057 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:19.289Z,1552959019.289 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:21.716Z,1552959021.716 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:30:21.716Z,1552959021.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-03-19T01:30:21.716Z,1552959021.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-19T01:30:21.718Z,1552959021.718 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:30:21.718Z,1552959021.718 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-03-19T01:30:21.718Z,1552959021.718 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-19T01:30:21.728Z,1552959021.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-19T01:30:21.728Z,1552959021.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-19T01:30:22.113Z,1552959022.113 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:22.145Z,1552959022.145 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-19T01:30:22.145Z,1552959022.145 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-03-19T01:30:22.145Z,1552959022.145 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-19T01:30:22.145Z,1552959022.145 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-03-19T01:30:22.552Z,1552959022.552 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-19T01:30:25.349Z,1552959025.349 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:28.177Z,1552959028.177 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:30.997Z,1552959030.997 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:34.237Z,1552959034.237 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:37.065Z,1552959037.065 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:40.297Z,1552959040.297 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:43.121Z,1552959043.121 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:45.957Z,1552959045.957 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:47.973Z,1552959047.973 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:49.993Z,1552959049.993 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:53.225Z,1552959053.225 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:56.053Z,1552959056.053 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:30:59.285Z,1552959059.285 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:02.113Z,1552959062.113 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:05.345Z,1552959065.345 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:08.173Z,1552959068.173 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:10.993Z,1552959070.993 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:14.233Z,1552959074.233 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:17.065Z,1552959077.065 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:20.289Z,1552959080.289 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:23.113Z,1552959083.113 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:25.949Z,1552959085.949 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:27.969Z,1552959087.969 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:29.989Z,1552959089.989 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:32.005Z,1552959092.005 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:35.241Z,1552959095.241 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:38.069Z,1552959098.069 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:41.305Z,1552959101.305 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:44.125Z,1552959104.125 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:44.153Z,1552959104.153 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2019-03-19T01:31:46.957Z,1552959106.957 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:48.977Z,1552959108.977 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:50.989Z,1552959110.989 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:54.225Z,1552959114.225 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:31:57.049Z,1552959117.049 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:00.289Z,1552959120.289 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:03.109Z,1552959123.109 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:05.945Z,1552959125.945 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:07.961Z,1552959127.961 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:09.985Z,1552959129.985 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:12.005Z,1552959132.005 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:15.237Z,1552959135.237 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:18.061Z,1552959138.061 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:21.297Z,1552959141.297 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:22.924Z,1552959142.924 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:32:22.924Z,1552959142.924 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-03-19T01:32:22.924Z,1552959142.924 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-19T01:32:22.926Z,1552959142.926 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:32:22.926Z,1552959142.926 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2019-03-19T01:32:22.926Z,1552959142.926 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-19T01:32:22.936Z,1552959142.936 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-19T01:32:22.936Z,1552959142.936 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-19T01:32:23.350Z,1552959143.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-19T01:32:23.350Z,1552959143.350 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-03-19T01:32:23.351Z,1552959143.351 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-19T01:32:23.351Z,1552959143.351 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2019-03-19T01:32:23.728Z,1552959143.728 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-19T01:32:24.121Z,1552959144.121 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:26.953Z,1552959146.953 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:28.969Z,1552959148.969 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:30.985Z,1552959150.985 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:34.225Z,1552959154.225 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:37.049Z,1552959157.049 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:40.285Z,1552959160.285 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:43.105Z,1552959163.105 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:45.937Z,1552959165.937 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:47.953Z,1552959167.953 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:49.985Z,1552959169.985 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:50.414Z,1552959170.414 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-19T01:27:50.2Z 2019-03-19T01:32:50.414Z,1552959170.414 [Default:CheckIn:Read_GPS] Stopped 2019-03-19T01:32:50.414Z,1552959170.414 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-19T01:32:50.813Z,1552959170.813 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-19T01:32:51.997Z,1552959171.997 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:55.229Z,1552959175.229 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:32:56.692Z,1552959176.692 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190319T012409/Courier0004.lzma 2019-03-19T01:32:57.498Z,1552959177.498 [DataOverHttps](INFO): Moved sent file to Logs/20190319T012409/Courier0004.lzma.bak 2019-03-19T01:32:57.498Z,1552959177.498 [DataOverHttps](INFO): SBD MOMSN=10190473 2019-03-19T01:32:58.057Z,1552959178.057 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:01.293Z,1552959181.293 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:04.117Z,1552959184.117 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:06.945Z,1552959186.945 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:08.965Z,1552959188.965 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:10.237Z,1552959190.237 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20190319T012409/Express0005.lzma 2019-03-19T01:33:10.981Z,1552959190.981 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:11.042Z,1552959191.042 [DataOverHttps](INFO): Moved sent file to Logs/20190319T012409/Express0005.lzma.bak 2019-03-19T01:33:11.042Z,1552959191.042 [DataOverHttps](INFO): SBD MOMSN=10190475 2019-03-19T01:33:13.005Z,1552959193.005 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:14.245Z,1552959194.245 [Default:CheckIn:Read_Iridium] Stopped 2019-03-19T01:33:14.245Z,1552959194.245 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-19T01:33:14.245Z,1552959194.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-19T01:33:16.237Z,1552959196.237 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:19.065Z,1552959199.065 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:22.297Z,1552959202.297 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:25.125Z,1552959205.125 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:27.949Z,1552959207.949 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:29.977Z,1552959209.977 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:31.997Z,1552959211.997 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:35.229Z,1552959215.229 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:38.061Z,1552959218.061 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:41.293Z,1552959221.293 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:44.121Z,1552959224.121 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:46.949Z,1552959226.949 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:48.969Z,1552959228.969 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:50.981Z,1552959230.981 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:54.217Z,1552959234.217 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:33:57.041Z,1552959237.041 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:00.277Z,1552959240.277 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:03.101Z,1552959243.101 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:06.341Z,1552959246.341 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:09.169Z,1552959249.169 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:11.997Z,1552959251.997 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:15.221Z,1552959255.221 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:18.057Z,1552959258.057 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:21.289Z,1552959261.289 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:24.113Z,1552959264.113 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:24.142Z,1552959264.142 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:34:24.142Z,1552959264.142 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-03-19T01:34:24.142Z,1552959264.142 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-19T01:34:24.143Z,1552959264.143 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:34:24.144Z,1552959264.144 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2019-03-19T01:34:24.144Z,1552959264.144 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-19T01:34:24.153Z,1552959264.153 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-19T01:34:24.154Z,1552959264.154 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-19T01:34:24.566Z,1552959264.566 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-19T01:34:24.566Z,1552959264.566 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-03-19T01:34:24.566Z,1552959264.566 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-19T01:34:24.567Z,1552959264.567 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2019-03-19T01:34:24.940Z,1552959264.940 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-19T01:34:27.349Z,1552959267.349 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:30.177Z,1552959270.177 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:33.005Z,1552959273.005 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:36.233Z,1552959276.233 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:39.065Z,1552959279.065 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:42.293Z,1552959282.293 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:45.125Z,1552959285.125 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:47.949Z,1552959287.949 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:49.969Z,1552959289.969 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:51.989Z,1552959291.989 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:55.217Z,1552959295.217 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:34:58.042Z,1552959298.042 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-03-19T01:34:58.042Z,1552959298.042 [NAL9602] Data Fault, FailCount= 1 2019-03-19T01:34:58.042Z,1552959298.042 [NAL9602](ERROR): Data Fault 2019-03-19T01:34:58.100Z,1552959298.100 [CBIT](ERROR): Data Fault in component: NAL9602 2019-03-19T01:34:58.446Z,1552959298.446 [NAL9602](INFO): Powering down 2019-03-19T01:34:59.281Z,1552959299.281 [CBIT](INFO): Clearing failed state for component NAL9602 2019-03-19T01:34:59.282Z,1552959299.282 [NAL9602] No Fault, FailCount= 1 2019-03-19T01:35:28.746Z,1552959328.746 [NAL9602](INFO): Powering up NAL9602 2019-03-19T01:35:39.654Z,1552959339.654 [NAL9602](INFO): NAL9602 initialized 2019-03-19T01:35:40.473Z,1552959340.473 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:35:42.893Z,1552959342.893 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:35:45.721Z,1552959345.721 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:35:48.953Z,1552959348.953 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:35:51.785Z,1552959351.785 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:35:55.013Z,1552959355.013 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:35:57.845Z,1552959357.845 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:00.673Z,1552959360.673 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:03.905Z,1552959363.905 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:06.733Z,1552959366.733 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:09.965Z,1552959369.965 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:12.793Z,1552959372.793 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:15.621Z,1552959375.621 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:17.641Z,1552959377.641 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:19.657Z,1552959379.657 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:21.677Z,1552959381.677 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:24.913Z,1552959384.913 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:25.338Z,1552959385.338 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:36:25.338Z,1552959385.338 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-03-19T01:36:25.339Z,1552959385.339 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-19T01:36:25.340Z,1552959385.340 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:36:25.340Z,1552959385.340 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2019-03-19T01:36:25.340Z,1552959385.340 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-19T01:36:25.384Z,1552959385.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-19T01:36:25.384Z,1552959385.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-19T01:36:25.749Z,1552959385.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-19T01:36:25.749Z,1552959385.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-03-19T01:36:25.749Z,1552959385.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-19T01:36:25.749Z,1552959385.749 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2019-03-19T01:36:26.136Z,1552959386.136 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-19T01:36:27.745Z,1552959387.745 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:30.977Z,1552959390.977 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:33.801Z,1552959393.801 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:36.629Z,1552959396.629 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:38.645Z,1552959398.645 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:40.669Z,1552959400.669 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:43.901Z,1552959403.901 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:46.725Z,1552959406.725 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:49.961Z,1552959409.961 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:52.789Z,1552959412.789 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:56.017Z,1552959416.017 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:36:58.845Z,1552959418.845 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:01.677Z,1552959421.677 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:04.909Z,1552959424.909 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:07.737Z,1552959427.737 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:10.969Z,1552959430.969 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:13.797Z,1552959433.797 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:16.625Z,1552959436.625 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:18.641Z,1552959438.641 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:20.665Z,1552959440.665 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:23.897Z,1552959443.897 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:26.725Z,1552959446.725 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:29.953Z,1552959449.953 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:32.785Z,1552959452.785 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:36.017Z,1552959456.017 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:38.849Z,1552959458.849 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:41.673Z,1552959461.673 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:44.901Z,1552959464.901 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:47.729Z,1552959467.729 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:50.965Z,1552959470.965 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:53.793Z,1552959473.793 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:56.621Z,1552959476.621 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:37:58.637Z,1552959478.637 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:00.661Z,1552959480.661 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:02.681Z,1552959482.681 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:05.909Z,1552959485.909 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:08.741Z,1552959488.741 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:11.969Z,1552959491.969 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:14.801Z,1552959494.801 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:14.834Z,1552959494.834 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-03-19T01:38:14.834Z,1552959494.834 [Default:CheckIn:C.Wait] Stopped 2019-03-19T01:38:14.834Z,1552959494.834 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-19T01:38:14.835Z,1552959494.835 [Default:CheckIn:D] Running Loop=1 2019-03-19T01:38:15.229Z,1552959495.229 [Default:CheckIn:D] Stopped 2019-03-19T01:38:15.229Z,1552959495.229 [Default:CheckIn:E] Running Loop=1 2019-03-19T01:38:15.628Z,1552959495.628 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.645523 min 2019-03-19T01:38:15.628Z,1552959495.628 [Default:CheckIn:E] Stopped 2019-03-19T01:38:15.628Z,1552959495.628 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-03-19T01:38:15.628Z,1552959495.628 [Default:CheckIn] Stopped 2019-03-19T01:38:15.628Z,1552959495.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-19T01:38:15.629Z,1552959495.629 [Default:CheckIn](INFO): Running loop #2 2019-03-19T01:38:15.629Z,1552959495.629 [Default:CheckIn] Running Loop=2 2019-03-19T01:38:15.629Z,1552959495.629 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-19T01:38:15.629Z,1552959495.629 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-19T01:38:17.629Z,1552959497.629 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:19.649Z,1552959499.649 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:21.669Z,1552959501.669 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:24.901Z,1552959504.901 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:26.524Z,1552959506.524 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:38:26.524Z,1552959506.524 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2019-03-19T01:38:26.524Z,1552959506.524 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-19T01:38:26.525Z,1552959506.525 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-19T01:38:26.526Z,1552959506.526 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2019-03-19T01:38:26.526Z,1552959506.526 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-19T01:38:26.536Z,1552959506.536 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-19T01:38:26.536Z,1552959506.536 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-19T01:38:26.942Z,1552959506.942 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-19T01:38:26.942Z,1552959506.942 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2019-03-19T01:38:26.943Z,1552959506.943 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-19T01:38:26.943Z,1552959506.943 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2019-03-19T01:38:27.333Z,1552959507.333 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-19T01:38:27.729Z,1552959507.729 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:30.961Z,1552959510.961 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:33.789Z,1552959513.789 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:37.021Z,1552959517.021 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:39.849Z,1552959519.849 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:42.673Z,1552959522.673 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:45.905Z,1552959525.905 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:48.737Z,1552959528.737 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:51.965Z,1552959531.965 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:54.797Z,1552959534.797 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:57.625Z,1552959537.625 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:38:59.645Z,1552959539.645 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:01.665Z,1552959541.665 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:04.897Z,1552959544.897 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:07.725Z,1552959547.725 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:10.957Z,1552959550.957 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:13.781Z,1552959553.781 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:17.013Z,1552959557.013 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:19.845Z,1552959559.845 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:22.673Z,1552959562.673 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:25.901Z,1552959565.901 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:28.350Z,1552959568.350 [CommandLine](IMPORTANT): got command restart application 2019-03-19T01:39:28.733Z,1552959568.733 [NAL9602](DEBUG): Fix Requested 2019-03-19T01:39:29.357Z,1552959569.357 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-19T01:39:29.357Z,1552959569.357 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:29.361Z,1552959569.361 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-03-19T01:39:29.361Z,1552959569.361 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:29.362Z,1552959569.362 [CommandLine](INFO): Join timeout helper Thread ID is 1590 2019-03-19T01:39:29.362Z,1552959569.362 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-03-19T01:39:29.362Z,1552959569.362 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:29.362Z,1552959569.362 [NavChartDb](INFO): Join timeout helper Thread ID is 1591 2019-03-19T01:39:29.757Z,1552959569.757 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-19T01:39:29.757Z,1552959569.757 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:29.773Z,1552959569.773 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-03-19T01:39:29.773Z,1552959569.773 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:29.773Z,1552959569.773 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1592 2019-03-19T01:39:29.942Z,1552959569.942 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-19T01:39:29.942Z,1552959569.942 [WetLabsBB2FL](INFO): Powering down 2019-03-19T01:39:29.943Z,1552959569.943 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:29.953Z,1552959569.953 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-03-19T01:39:29.953Z,1552959569.953 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:29.953Z,1552959569.953 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1593 2019-03-19T01:39:29.997Z,1552959569.997 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-19T01:39:29.997Z,1552959569.997 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.005Z,1552959570.005 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-03-19T01:39:30.005Z,1552959570.005 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.005Z,1552959570.005 [Radio_Surface](INFO): Join timeout helper Thread ID is 1594 2019-03-19T01:39:30.117Z,1552959570.117 [Radio_Surface](INFO): Powering down 2019-03-19T01:39:30.118Z,1552959570.118 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-19T01:39:30.118Z,1552959570.118 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.130Z,1552959570.130 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-03-19T01:39:30.130Z,1552959570.130 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.130Z,1552959570.130 [DataOverHttps](INFO): Join timeout helper Thread ID is 1595 2019-03-19T01:39:30.497Z,1552959570.497 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-19T01:39:30.497Z,1552959570.497 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.509Z,1552959570.509 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-03-19T01:39:30.510Z,1552959570.510 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.510Z,1552959570.510 [logger](INFO): Join timeout helper Thread ID is 1596 2019-03-19T01:39:30.553Z,1552959570.553 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-19T01:39:30.553Z,1552959570.553 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.569Z,1552959570.569 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-03-19T01:39:30.570Z,1552959570.570 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.570Z,1552959570.570 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-03-19T01:39:30.570Z,1552959570.570 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.570Z,1552959570.570 [controlThread](INFO): Join timeout helper Thread ID is 1597 2019-03-19T01:39:30.729Z,1552959570.729 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-19T01:39:30.729Z,1552959570.729 [controlThread](DEBUG): Uninitializing ControlThread 2019-03-19T01:39:30.730Z,1552959570.730 [NAL9602](INFO): Powering down 2019-03-19T01:39:30.731Z,1552959570.731 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-03-19T01:39:30.732Z,1552959570.732 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-03-19T01:39:30.732Z,1552959570.732 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-03-19T01:39:30.732Z,1552959570.732 [MissionManager](INFO): Uninitializing Mission Default 2019-03-19T01:39:30.733Z,1552959570.733 [Default] Stopped 2019-03-19T01:39:30.733Z,1552959570.733 [Default](DEBUG): Aggregate::uninitialize Default 2019-03-19T01:39:30.733Z,1552959570.733 [Default:B.GoToSurface] Stopped 2019-03-19T01:39:30.733Z,1552959570.733 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-19T01:39:30.733Z,1552959570.733 [Default:CheckIn] Stopped 2019-03-19T01:39:30.733Z,1552959570.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-19T01:39:30.733Z,1552959570.733 [Default:CheckIn:Read_GPS] Stopped 2019-03-19T01:39:30.735Z,1552959570.735 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-03-19T01:39:30.736Z,1552959570.736 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-03-19T01:39:30.736Z,1552959570.736 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-03-19T01:39:30.736Z,1552959570.736 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-03-19T01:39:30.736Z,1552959570.736 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-03-19T01:39:30.736Z,1552959570.736 [BuoyancyServo](INFO): Powering down 2019-03-19T01:39:30.749Z,1552959570.749 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-19T01:39:30.749Z,1552959570.749 [ElevatorServo](INFO): Powering down 2019-03-19T01:39:30.750Z,1552959570.750 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-03-19T01:39:30.750Z,1552959570.750 [MassServo](INFO): Powering down 2019-03-19T01:39:30.751Z,1552959570.751 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-19T01:39:30.751Z,1552959570.751 [RudderServo](INFO): Powering down 2019-03-19T01:39:30.752Z,1552959570.752 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-03-19T01:39:30.752Z,1552959570.752 [ThrusterServo](INFO): Powering down 2019-03-19T01:39:30.753Z,1552959570.753 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-03-19T01:39:30.753Z,1552959570.753 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-03-19T01:39:30.753Z,1552959570.753 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-03-19T01:39:30.753Z,1552959570.753 [CBIT](DEBUG): Powering off loads. 2019-03-19T01:39:30.765Z,1552959570.765 [CBIT](DEBUG): Disabling WDT. 2019-03-19T01:39:30.777Z,1552959570.777 [CBIT](DEBUG): Opening all GF detection circuits. 2019-03-19T01:39:30.777Z,1552959570.777 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.850Z,1552959570.850 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.855Z,1552959570.855 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.886Z,1552959570.886 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:30.888Z,1552959570.888 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:31.171Z,1552959571.171 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-19T01:39:31.225Z,1552959571.225 [logger ThreadHandler](INFO): Thread cancelled.