2018-11-12T22:02:48.763Z,1542060168.763 [Supervisor](DEBUG): Initializing supervisor. 2018-11-12T22:02:48.766Z,1542060168.766 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-12T22:02:48.766Z,1542060168.766 [SyncHandler](INFO): Protected caller Thread ID is 3144 2018-11-12T22:02:48.767Z,1542060168.767 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-11-12T22:02:48.768Z,1542060168.768 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-11-12T22:02:48.768Z,1542060168.768 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3145 2018-11-12T22:02:48.771Z,1542060168.771 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-11-12T22:02:48.782Z,1542060168.782 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-11-12T22:02:48.783Z,1542060168.783 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-11-12T22:02:48.784Z,1542060168.784 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3146 2018-11-12T22:02:48.784Z,1542060168.784 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-11-12T22:02:48.785Z,1542060168.785 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-11-12T22:02:48.786Z,1542060168.786 [logger ThreadHandler](INFO): Protected caller Thread ID is 3147 2018-11-12T22:02:48.788Z,1542060168.788 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-11-12T22:02:48.788Z,1542060168.788 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-11-12T22:02:48.789Z,1542060168.789 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-11-12T22:02:49.108Z,1542060169.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-11-12T22:02:49.109Z,1542060169.109 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-11-12T22:02:49.551Z,1542060169.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-11-12T22:02:49.551Z,1542060169.551 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-11-12T22:02:49.895Z,1542060169.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-11-12T22:02:49.896Z,1542060169.896 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-11-12T22:02:50.089Z,1542060170.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-11-12T22:02:50.090Z,1542060170.090 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-11-12T22:02:50.542Z,1542060170.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-11-12T22:02:50.542Z,1542060170.542 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-11-12T22:02:51.019Z,1542060171.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-11-12T22:02:51.020Z,1542060171.020 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-11-12T22:02:51.122Z,1542060171.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-11-12T22:02:51.123Z,1542060171.123 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-11-12T22:02:51.392Z,1542060171.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-11-12T22:02:51.393Z,1542060171.393 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-11-12T22:02:51.535Z,1542060171.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-11-12T22:02:51.536Z,1542060171.536 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-11-12T22:02:51.616Z,1542060171.616 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-11-12T22:02:51.832Z,1542060171.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-11-12T22:02:51.833Z,1542060171.833 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-11-12T22:02:51.932Z,1542060171.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-11-12T22:02:51.933Z,1542060171.933 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-11-12T22:02:52.028Z,1542060172.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-11-12T22:02:52.028Z,1542060172.028 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-11-12T22:02:52.176Z,1542060172.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-11-12T22:02:52.177Z,1542060172.177 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-11-12T22:02:52.491Z,1542060172.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-11-12T22:02:52.492Z,1542060172.492 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-11-12T22:02:52.493Z,1542060172.493 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-11-12T22:02:52.624Z,1542060172.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-11-12T22:02:52.847Z,1542060172.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-11-12T22:02:52.847Z,1542060172.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-11-12T22:02:53.018Z,1542060173.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-11-12T22:02:53.118Z,1542060173.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-11-12T22:02:53.216Z,1542060173.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-11-12T22:02:53.408Z,1542060173.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-11-12T22:02:53.500Z,1542060173.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-11-12T22:02:53.819Z,1542060173.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-11-12T22:02:54.001Z,1542060174.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-11-12T22:02:54.144Z,1542060174.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-11-12T22:02:54.545Z,1542060174.545 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2018-11-12T22:02:54.545Z,1542060174.545 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-11-12T22:02:54.555Z,1542060174.555 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-11-12T22:02:54.684Z,1542060174.684 [SBIT](DEBUG): Construct Startup Built In Test. 2018-11-12T22:02:54.714Z,1542060174.714 [SBIT] Loaded 2018-11-12T22:02:54.714Z,1542060174.714 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-11-12T22:02:54.715Z,1542060174.715 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-11-12T22:02:54.742Z,1542060174.742 [IBIT] Loaded 2018-11-12T22:02:54.742Z,1542060174.742 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-11-12T22:02:54.745Z,1542060174.745 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-11-12T22:02:54.896Z,1542060174.896 [CBIT] Loaded 2018-11-12T22:02:54.896Z,1542060174.896 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-11-12T22:02:54.897Z,1542060174.897 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-11-12T22:02:54.897Z,1542060174.897 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-11-12T22:02:55.012Z,1542060175.012 [BuoyancyServo] Loaded 2018-11-12T22:02:55.013Z,1542060175.013 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-11-12T22:02:55.027Z,1542060175.027 [ElevatorServo] Loaded 2018-11-12T22:02:55.027Z,1542060175.027 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-11-12T22:02:55.041Z,1542060175.041 [MassServo] Loaded 2018-11-12T22:02:55.042Z,1542060175.042 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-11-12T22:02:55.056Z,1542060175.056 [RudderServo] Loaded 2018-11-12T22:02:55.057Z,1542060175.057 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-11-12T22:02:55.071Z,1542060175.071 [ThrusterServo] Loaded 2018-11-12T22:02:55.071Z,1542060175.071 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-11-12T22:02:55.072Z,1542060175.072 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-11-12T22:02:55.072Z,1542060175.072 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-11-12T22:02:55.170Z,1542060175.170 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-11-12T22:02:55.170Z,1542060175.170 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-11-12T22:02:55.223Z,1542060175.223 [DeadReckonUsingSpeedCalculator] Loaded 2018-11-12T22:02:55.223Z,1542060175.223 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-11-12T22:02:55.239Z,1542060175.239 [NavChart] Loaded 2018-11-12T22:02:55.239Z,1542060175.239 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-11-12T22:02:55.243Z,1542060175.243 [UniversalFixResidualReporter] Loaded 2018-11-12T22:02:55.243Z,1542060175.243 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-11-12T22:02:55.244Z,1542060175.244 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-11-12T22:02:55.244Z,1542060175.244 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-11-12T22:02:55.258Z,1542060175.258 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-11-12T22:02:55.258Z,1542060175.258 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-11-12T22:02:55.500Z,1542060175.500 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-11-12T22:02:55.500Z,1542060175.500 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-11-12T22:02:55.793Z,1542060175.793 [DataOverHttps] Loaded 2018-11-12T22:02:55.793Z,1542060175.793 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-11-12T22:02:55.806Z,1542060175.806 [Depth_Keller] Loaded 2018-11-12T22:02:55.806Z,1542060175.806 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-11-12T22:02:55.811Z,1542060175.811 [DropWeight] Loaded 2018-11-12T22:02:55.811Z,1542060175.811 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-11-12T22:02:55.900Z,1542060175.900 [NAL9602] Loaded 2018-11-12T22:02:55.901Z,1542060175.901 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-11-12T22:02:55.906Z,1542060175.906 [Onboard] Loaded 2018-11-12T22:02:55.906Z,1542060175.906 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-11-12T22:02:55.913Z,1542060175.913 [Radio_Surface] Loaded 2018-11-12T22:02:55.913Z,1542060175.913 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-11-12T22:02:55.914Z,1542060175.914 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408364E0 2018-11-12T22:02:55.914Z,1542060175.914 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3227 2018-11-12T22:02:55.942Z,1542060175.942 [RDI_Pathfinder] Loaded 2018-11-12T22:02:55.942Z,1542060175.942 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-11-12T22:02:55.964Z,1542060175.964 [RDI_PathfinderUp] Loaded 2018-11-12T22:02:55.964Z,1542060175.964 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2018-11-12T22:02:57.722Z,1542060177.722 [BPC1] Loaded 2018-11-12T22:02:57.723Z,1542060177.723 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-11-12T22:02:57.723Z,1542060177.723 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-11-12T22:02:57.723Z,1542060177.723 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-11-12T22:02:57.793Z,1542060177.793 [VerticalControl](DEBUG): Construct VerticalControl. 2018-11-12T22:02:57.879Z,1542060177.879 [VerticalControl] Loaded 2018-11-12T22:02:57.879Z,1542060177.879 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-11-12T22:02:57.880Z,1542060177.880 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-11-12T22:02:57.937Z,1542060177.937 [HorizontalControl] Loaded 2018-11-12T22:02:57.937Z,1542060177.937 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-11-12T22:02:57.938Z,1542060177.938 [SpeedControl](DEBUG): Construct SpeedControl. 2018-11-12T22:02:57.940Z,1542060177.940 [SpeedControl] Loaded 2018-11-12T22:02:57.940Z,1542060177.940 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-11-12T22:02:57.941Z,1542060177.941 [LoopControl](DEBUG): Construct LoopControl. 2018-11-12T22:02:57.941Z,1542060177.941 [LoopControl] Loaded 2018-11-12T22:02:57.942Z,1542060177.942 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-11-12T22:02:57.942Z,1542060177.942 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-11-12T22:02:57.943Z,1542060177.943 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-11-12T22:02:58.041Z,1542060178.041 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-11-12T22:02:58.041Z,1542060178.041 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-11-12T22:02:58.199Z,1542060178.199 [Aanderaa_O2] Loaded 2018-11-12T22:02:58.199Z,1542060178.199 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-11-12T22:02:58.201Z,1542060178.201 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-11-12T22:02:58.201Z,1542060178.201 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-11-12T22:02:58.265Z,1542060178.265 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-11-12T22:02:58.265Z,1542060178.265 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-11-12T22:02:58.307Z,1542060178.307 [DepthRateCalculator] Loaded 2018-11-12T22:02:58.307Z,1542060178.307 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-11-12T22:02:58.312Z,1542060178.312 [PitchRateCalculator] Loaded 2018-11-12T22:02:58.313Z,1542060178.313 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-11-12T22:02:58.324Z,1542060178.324 [SpeedCalculator] Loaded 2018-11-12T22:02:58.325Z,1542060178.325 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-11-12T22:02:58.346Z,1542060178.346 [TempGradientCalculator] Loaded 2018-11-12T22:02:58.346Z,1542060178.346 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-11-12T22:02:58.351Z,1542060178.351 [YawRateCalculator] Loaded 2018-11-12T22:02:58.351Z,1542060178.351 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-11-12T22:02:58.381Z,1542060178.381 [ElevatorOffsetCalculator] Loaded 2018-11-12T22:02:58.381Z,1542060178.381 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-11-12T22:02:58.381Z,1542060178.381 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-11-12T22:02:58.382Z,1542060178.382 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-11-12T22:02:58.407Z,1542060178.407 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-11-12T22:02:58.411Z,1542060178.411 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-11-12T22:02:58.412Z,1542060178.412 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-11-12T22:02:58.418Z,1542060178.418 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-11-12T22:02:58.419Z,1542060178.419 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409B74E0 2018-11-12T22:02:58.420Z,1542060178.420 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3228 2018-11-12T22:02:58.424Z,1542060178.424 [Supervisor](INFO): Main Thread ID is 3143 2018-11-12T22:02:58.424Z,1542060178.424 [Supervisor](DEBUG): Running supervisor. 2018-11-12T22:02:58.425Z,1542060178.425 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3229 2018-11-12T22:02:58.427Z,1542060178.427 [controlThread ThreadHandler](INFO): Handler Thread ID is 3230 2018-11-12T22:02:58.428Z,1542060178.428 [controlThread](DEBUG): Initializing ControlThread 2018-11-12T22:02:58.429Z,1542060178.429 [SBIT](INFO): Initialize SBIT Component. 2018-11-12T22:02:58.429Z,1542060178.429 [SBIT](IMPORTANT): git: 2018-11-05 2018-11-12T22:02:58.429Z,1542060178.429 [SBIT](INFO): git hash: 17f8e4440ddc7f0910aa911cb821ed1a114d9cee 2018-11-12T22:02:58.430Z,1542060178.430 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-11-12T22:02:58.430Z,1542060178.430 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-11-12T22:02:58.431Z,1542060178.431 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-11-12T22:02:58.432Z,1542060178.432 [IBIT](INFO): Initialize IBIT Component. 2018-11-12T22:02:58.432Z,1542060178.432 [CBIT](DEBUG): Initialize CBIT Component. 2018-11-12T22:02:58.433Z,1542060178.433 [CBIT](DEBUG): Initializing mux pins. 2018-11-12T22:02:58.433Z,1542060178.433 [CBIT](DEBUG): Initializing the watchdog timer. 2018-11-12T22:02:58.433Z,1542060178.433 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-11-12T22:02:58.434Z,1542060178.434 [CBIT](INFO): Reporting lpc3250 register adresses: LPC3Reg::TimClkCtrl_ 00000001 LPC3Reg::WDTimCtrl_: 00000001 LPC3Reg::WDTimMCtrl_: 00000011 LPC3Reg::WDTimEMR_: 00000020 2018-11-12T22:02:58.434Z,1542060178.434 [CBIT](DEBUG): Initializing heartbeat. 2018-11-12T22:02:58.435Z,1542060178.435 [logger ThreadHandler](INFO): Handler Thread ID is 3231 2018-11-12T22:02:58.463Z,1542060178.463 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3232 2018-11-12T22:02:58.468Z,1542060178.468 [Radio_Surface](INFO): Powering up 2018-11-12T22:02:58.479Z,1542060178.479 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3233 2018-11-12T22:02:58.482Z,1542060178.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-11-12T22:02:58.482Z,1542060178.482 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-11-12T22:02:58.482Z,1542060178.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-11-12T22:02:58.483Z,1542060178.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-11-12T22:02:58.483Z,1542060178.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-11-12T22:02:58.483Z,1542060178.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-11-12T22:02:58.483Z,1542060178.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-11-12T22:02:58.483Z,1542060178.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-11-12T22:02:58.483Z,1542060178.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-11-12T22:02:58.484Z,1542060178.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-11-12T22:02:58.484Z,1542060178.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-11-12T22:02:58.484Z,1542060178.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-11-12T22:02:58.484Z,1542060178.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-11-12T22:02:58.484Z,1542060178.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-11-12T22:02:58.485Z,1542060178.485 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-12T22:02:58.485Z,1542060178.485 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-11-12T22:02:58.506Z,1542060178.506 [CBIT](DEBUG): Deactivating GF circuits. 2018-11-12T22:02:58.506Z,1542060178.506 [CBIT](DEBUG): Deactivating emergency mode. 2018-11-12T22:02:58.543Z,1542060178.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-12T22:02:58.544Z,1542060178.544 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:02:58.544Z,1542060178.544 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:02:58.545Z,1542060178.545 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-12T22:02:58.545Z,1542060178.545 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:02:58.546Z,1542060178.546 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:02:58.546Z,1542060178.546 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-11-12T22:02:58.547Z,1542060178.547 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-11-12T22:02:58.553Z,1542060178.553 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-11-12T22:02:58.555Z,1542060178.555 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-11-12T22:02:58.556Z,1542060178.556 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-11-12T22:02:58.556Z,1542060178.556 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-11-12T22:02:58.557Z,1542060178.557 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-12T22:02:58.557Z,1542060178.557 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-11-12T22:02:58.557Z,1542060178.557 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-11-12T22:02:58.558Z,1542060178.558 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-11-12T22:02:58.559Z,1542060178.559 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-11-12T22:02:58.559Z,1542060178.559 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-11-12T22:02:58.561Z,1542060178.561 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-11-12T22:02:58.659Z,1542060178.659 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-12T22:02:58.736Z,1542060178.736 [MissionManager](DEBUG): 2018-11-12T22:02:58.736Z,1542060178.736 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-11-12T22:02:58.822Z,1542060178.822 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-11-12T22:02:58.823Z,1542060178.823 [Default:A.Wait](DEBUG): Construct Wait. 2018-11-12T22:02:58.825Z,1542060178.825 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-12T22:02:58.860Z,1542060178.860 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-11-12T22:02:58.863Z,1542060178.863 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-11-12T22:02:58.888Z,1542060178.888 [Default:E.Execute](DEBUG): Construct Execute. 2018-11-12T22:02:58.892Z,1542060178.892 [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-12T22:02:58.897Z,1542060178.897 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,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, 2018-11-12T22:02:58.922Z,1542060178.922 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-11-12T22:02:58.950Z,1542060178.950 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-11-12T22:02:59.166Z,1542060179.166 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-12T22:02:59.415Z,1542060179.415 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-12T22:02:59.423Z,1542060179.423 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-12T22:02:59.455Z,1542060179.455 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-12T22:02:59.462Z,1542060179.462 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-12T22:02:59.468Z,1542060179.468 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-12T22:02:59.474Z,1542060179.474 [MassServo](DEBUG): Initializing MassServo. 2018-11-12T22:02:59.488Z,1542060179.488 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-12T22:02:59.495Z,1542060179.495 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-12T22:02:59.508Z,1542060179.508 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-11-12T22:02:59.514Z,1542060179.514 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-11-12T22:02:59.768Z,1542060179.768 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-12T22:02:59.768Z,1542060179.768 [DropWeight] Hardware Fault, FailCount= 1 2018-11-12T22:02:59.768Z,1542060179.768 [DropWeight](ERROR): Hardware Fault 2018-11-12T22:02:59.862Z,1542060179.862 [RDI_PathfinderUp](INFO): Powering down 2018-11-12T22:02:59.951Z,1542060179.951 [CommandLine](FAULT): Scheduling is paused 2018-11-12T22:02:59.951Z,1542060179.951 [CBIT](INFO): Critical error at 20181112T220259 2018-11-12T22:02:59.951Z,1542060179.951 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-11-12T22:02:59.954Z,1542060179.954 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-11-12T22:02:59.955Z,1542060179.955 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-11-12T22:03:08.429Z,1542060188.429 [CBIT](INFO): Critical error at 20181112T220259 2018-11-12T22:03:08.506Z,1542060188.506 [CBIT](CRITICAL): Environmental Failure. Press:14.890171 PSI. Humidity:27%. Temp:26 C. ABORTING MISSION 2018-11-12T22:03:08.875Z,1542060188.875 [CBIT](INFO): Critical error at 20181112T220308 2018-11-12T22:03:09.244Z,1542060189.244 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004276 2018-11-12T22:03:09.263Z,1542060189.263 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:03:09.263Z,1542060189.263 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-12T22:03:09.263Z,1542060189.263 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:03:09.281Z,1542060189.281 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:03:09.778Z,1542060189.778 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:03:10.541Z,1542060190.541 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:03:10.541Z,1542060190.541 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-12T22:03:21.259Z,1542060201.259 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-12T22:03:21.259Z,1542060201.259 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-11-12T22:03:21.259Z,1542060201.259 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-12T22:03:21.260Z,1542060201.260 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:03:21.260Z,1542060201.260 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-12T22:03:21.260Z,1542060201.260 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:03:21.299Z,1542060201.299 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:03:21.299Z,1542060201.299 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:03:21.730Z,1542060201.730 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:03:21.850Z,1542060201.850 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:03:22.506Z,1542060202.506 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-12T22:03:22.506Z,1542060202.506 [RDI_Pathfinder] No Fault, FailCount= 1 2018-11-12T22:03:22.507Z,1542060202.507 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:03:22.507Z,1542060202.507 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-12T22:03:26.044Z,1542060206.044 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:03:26.891Z,1542060206.891 [SBIT](IMPORTANT): Beginning Startup BIT 2018-11-12T22:03:26.895Z,1542060206.895 [CBIT](IMPORTANT): Beginning ground fault scan 2018-11-12T22:03:33.107Z,1542060213.107 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:03:33.107Z,1542060213.107 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-12T22:03:33.107Z,1542060213.107 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:03:33.143Z,1542060213.143 [CommandLine](IMPORTANT): got command failComponent 2018-11-12T22:03:33.143Z,1542060213.143 [CommandLine](IMPORTANT): Failed components: 2018-11-12T22:03:33.144Z,1542060213.144 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2018-11-12T22:03:33.144Z,1542060213.144 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault 2018-11-12T22:03:33.240Z,1542060213.240 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:03:33.240Z,1542060213.240 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-12T22:03:33.626Z,1542060213.626 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:03:43.376Z,1542060223.376 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008458 CHAN A1 (24V): 0.003354 CHAN A2 (12V): -0.006885 CHAN A3 (5V): -0.001271 CHAN B0 (3.3V): -0.000025 CHAN B1 (3.15aV): 0.000078 CHAN B2 (3.15bV): 0.000111 CHAN B3 (GND): 0.001541 OPEN: 0.004879 Full Scale Calc: 4.765 mA, -1.589 mA 2018-11-12T22:03:50.828Z,1542060230.828 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:03:50.828Z,1542060230.828 [NAL9602] Communications Fault, FailCount= 1 2018-11-12T22:03:50.828Z,1542060230.828 [NAL9602](ERROR): Communications Fault 2018-11-12T22:03:50.843Z,1542060230.843 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-12T22:03:50.843Z,1542060230.843 [RDI_Pathfinder] Communications Fault, FailCount= 2 2018-11-12T22:03:50.843Z,1542060230.843 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-12T22:03:50.920Z,1542060230.920 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:03:50.920Z,1542060230.920 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:03:51.251Z,1542060231.251 [NAL9602](INFO): Powering down 2018-11-12T22:03:51.338Z,1542060231.338 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:03:52.104Z,1542060232.104 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:03:52.104Z,1542060232.104 [NAL9602] No Fault, FailCount= 1 2018-11-12T22:03:52.104Z,1542060232.104 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-12T22:03:52.104Z,1542060232.104 [RDI_Pathfinder] No Fault, FailCount= 2 2018-11-12T22:04:15.078Z,1542060255.078 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-12T22:04:15.078Z,1542060255.078 [RDI_Pathfinder] Communications Fault, FailCount= 3 2018-11-12T22:04:15.079Z,1542060255.079 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-12T22:04:15.168Z,1542060255.168 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:04:15.168Z,1542060255.168 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:04:15.550Z,1542060255.550 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:04:15.614Z,1542060255.614 [CBIT](INFO): Critical error at 20181112T220415 2018-11-12T22:04:20.731Z,1542060260.731 [SBIT](IMPORTANT): SBIT PASSED 2018-11-12T22:04:20.774Z,1542060260.774 [CommandLine](IMPORTANT): got command configSet list 2018-11-12T22:04:20.774Z,1542060260.774 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-11-12T22:04:20.775Z,1542060260.775 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2018-11-12T22:04:20.775Z,1542060260.775 [CommandLine](IMPORTANT): BuoyancyServo.powerOnTimeout=1 second; 2018-11-12T22:04:20.775Z,1542060260.775 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2018-11-12T22:04:20.775Z,1542060260.775 [CommandLine](IMPORTANT): ElevatorServo.powerOnTimeout=0.8 second; 2018-11-12T22:04:20.775Z,1542060260.775 [CommandLine](IMPORTANT): MassServo.powerOnTimeout=0.8 second; 2018-11-12T22:04:20.775Z,1542060260.775 [CommandLine](IMPORTANT): RudderServo.powerOnTimeout=0.8 second; 2018-11-12T22:04:20.776Z,1542060260.776 [CommandLine](IMPORTANT): SCPI.loadAtStartup=0 bool; 2018-11-12T22:04:20.776Z,1542060260.776 [CommandLine](IMPORTANT): ThrusterServo.powerOnTimeout=0.8 second; 2018-11-12T22:04:20.776Z,1542060260.776 [CommandLine](IMPORTANT): WetLabsSeaOWL_UV_A.loadAtStartup=0 bool; 2018-11-12T22:04:21.090Z,1542060261.090 [MissionManager](IMPORTANT): Started mission Startup 2018-11-12T22:04:21.091Z,1542060261.091 [Startup] Running Loop=1 2018-11-12T22:04:21.091Z,1542060261.091 [Startup](DEBUG): Aggregate::initialize Startup 2018-11-12T22:04:21.091Z,1542060261.091 [Startup:A.GoToSurface] Running Loop=1 2018-11-12T22:04:21.091Z,1542060261.091 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-12T22:04:21.092Z,1542060261.092 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-12T22:04:21.092Z,1542060261.092 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-12T22:04:21.092Z,1542060261.092 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-12T22:04:21.093Z,1542060261.093 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-12T22:04:21.093Z,1542060261.093 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-12T22:04:21.099Z,1542060261.099 [Startup:StartupSatComms] Running Loop=1 2018-11-12T22:04:21.099Z,1542060261.099 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-11-12T22:04:21.099Z,1542060261.099 [Startup:StartupSatComms:A] Running Loop=1 2018-11-12T22:04:21.471Z,1542060261.471 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:04:21.501Z,1542060261.501 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-11-12T22:04:42.664Z,1542060282.664 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:04:42.664Z,1542060282.664 [NAL9602] Communications Fault, FailCount= 2 2018-11-12T22:04:42.664Z,1542060282.664 [NAL9602](ERROR): Communications Fault 2018-11-12T22:04:42.733Z,1542060282.733 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:04:43.063Z,1542060283.063 [NAL9602](INFO): Powering down 2018-11-12T22:04:43.913Z,1542060283.913 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:04:43.914Z,1542060283.914 [NAL9602] No Fault, FailCount= 2 2018-11-12T22:04:58.786Z,1542060298.786 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:04:58.786Z,1542060298.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-11-12T22:04:58.787Z,1542060298.787 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-12T22:04:58.788Z,1542060298.788 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:04:58.788Z,1542060298.788 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-11-12T22:04:58.788Z,1542060298.788 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-12T22:04:58.831Z,1542060298.831 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-12T22:04:58.831Z,1542060298.831 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-12T22:04:59.223Z,1542060299.223 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-12T22:04:59.223Z,1542060299.223 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-11-12T22:04:59.224Z,1542060299.224 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-12T22:04:59.224Z,1542060299.224 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-11-12T22:04:59.586Z,1542060299.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-12T22:04:59.586Z,1542060299.586 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:04:59.587Z,1542060299.587 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:04:59.587Z,1542060299.587 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-12T22:04:59.588Z,1542060299.588 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:04:59.588Z,1542060299.588 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:05:13.160Z,1542060313.160 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:05:23.851Z,1542060323.851 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-12T22:04:21.1Z 2018-11-12T22:05:23.851Z,1542060323.851 [Startup:StartupSatComms:A] Stopped 2018-11-12T22:05:23.851Z,1542060323.851 [Startup:StartupSatComms:B] Running Loop=1 2018-11-12T22:05:23.965Z,1542060323.965 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-12T22:05:28.757Z,1542060328.757 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20181112T215003/Courier0004.lzma 2018-11-12T22:05:29.528Z,1542060329.528 [DataOverHttps](INFO): Moved sent file to Logs/20181112T215003/Courier0004.lzma.bak 2018-11-12T22:05:29.529Z,1542060329.529 [DataOverHttps](INFO): SBD MOMSN=8856424 2018-11-12T22:05:34.249Z,1542060334.249 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:05:34.249Z,1542060334.249 [NAL9602] Communications Fault, FailCount= 3 2018-11-12T22:05:34.249Z,1542060334.249 [NAL9602](ERROR): Communications Fault 2018-11-12T22:05:34.315Z,1542060334.315 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:05:34.647Z,1542060334.647 [NAL9602](INFO): Powering down 2018-11-12T22:05:38.160Z,1542060338.160 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:05:38.160Z,1542060338.160 [NAL9602] No Fault, FailCount= 3 2018-11-12T22:05:38.191Z,1542060338.191 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20181112T215635/Courier0000.lzma 2018-11-12T22:05:38.968Z,1542060338.968 [DataOverHttps](INFO): Moved sent file to Logs/20181112T215635/Courier0000.lzma.bak 2018-11-12T22:05:38.968Z,1542060338.968 [DataOverHttps](INFO): SBD MOMSN=8856428 2018-11-12T22:05:47.721Z,1542060347.721 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20181112T220248/Courier0000.lzma 2018-11-12T22:05:48.520Z,1542060348.520 [DataOverHttps](INFO): Moved sent file to Logs/20181112T220248/Courier0000.lzma.bak 2018-11-12T22:05:48.520Z,1542060348.520 [DataOverHttps](INFO): SBD MOMSN=8856436 2018-11-12T22:05:57.321Z,1542060357.321 [DataOverHttps](INFO): Sending 305 bytes from file Logs/20181112T190652/Express0032.lzma 2018-11-12T22:05:58.120Z,1542060358.120 [DataOverHttps](INFO): Moved sent file to Logs/20181112T190652/Express0032.lzma.bak 2018-11-12T22:05:58.121Z,1542060358.121 [DataOverHttps](INFO): SBD MOMSN=8856443 2018-11-12T22:06:03.311Z,1542060363.311 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:06:03.311Z,1542060363.311 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-12T22:06:06.746Z,1542060366.746 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:06:06.825Z,1542060366.825 [DataOverHttps](INFO): Sending 562 bytes from file Logs/20181112T204746/Express0001.lzma 2018-11-12T22:06:07.620Z,1542060367.620 [DataOverHttps](INFO): Moved sent file to Logs/20181112T204746/Express0001.lzma.bak 2018-11-12T22:06:07.621Z,1542060367.621 [DataOverHttps](INFO): SBD MOMSN=8856457 2018-11-12T22:06:16.343Z,1542060376.343 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:06:16.343Z,1542060376.343 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-12T22:06:16.343Z,1542060376.343 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:06:16.393Z,1542060376.393 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:06:16.421Z,1542060376.421 [DataOverHttps](INFO): Sending 621 bytes from file Logs/20181112T205928/Express0001.lzma 2018-11-12T22:06:16.562Z,1542060376.562 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:06:17.204Z,1542060377.204 [DataOverHttps](INFO): Moved sent file to Logs/20181112T205928/Express0001.lzma.bak 2018-11-12T22:06:17.205Z,1542060377.205 [DataOverHttps](INFO): SBD MOMSN=8856474 2018-11-12T22:06:17.278Z,1542060377.278 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:06:17.278Z,1542060377.278 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-12T22:06:26.065Z,1542060386.065 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-12T22:05:23.9Z 2018-11-12T22:06:26.065Z,1542060386.065 [Startup:StartupSatComms:B] Stopped 2018-11-12T22:06:26.065Z,1542060386.065 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-11-12T22:06:26.065Z,1542060386.065 [Startup:StartupSatComms] Stopped 2018-11-12T22:06:26.066Z,1542060386.066 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-11-12T22:06:26.067Z,1542060386.067 [Startup](INFO): Completed Startup 2018-11-12T22:06:26.067Z,1542060386.067 [MissionManager](INFO): Startup is completed. 2018-11-12T22:06:26.067Z,1542060386.067 [MissionManager](INFO): Uninitializing Mission Startup 2018-11-12T22:06:26.067Z,1542060386.067 [Startup] Stopped 2018-11-12T22:06:26.067Z,1542060386.067 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-11-12T22:06:26.067Z,1542060386.067 [Startup:A.GoToSurface] Stopped 2018-11-12T22:06:26.067Z,1542060386.067 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-12T22:06:26.092Z,1542060386.092 [DataOverHttps](INFO): Sending 771 bytes from file Logs/20181112T210013/Express0001.lzma 2018-11-12T22:06:26.195Z,1542060386.195 [MissionManager](IMPORTANT): Started mission Default 2018-11-12T22:06:26.196Z,1542060386.196 [Default] Running Loop=1 2018-11-12T22:06:26.196Z,1542060386.196 [Default](DEBUG): Aggregate::initialize Default 2018-11-12T22:06:26.196Z,1542060386.196 [Default:B.GoToSurface] Running Loop=1 2018-11-12T22:06:26.196Z,1542060386.196 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-12T22:06:26.196Z,1542060386.196 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-12T22:06:26.197Z,1542060386.197 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-12T22:06:26.197Z,1542060386.197 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-12T22:06:26.197Z,1542060386.197 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-12T22:06:26.198Z,1542060386.198 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-12T22:06:26.206Z,1542060386.206 [Default:A.Wait] Running Loop=1 2018-11-12T22:06:26.206Z,1542060386.206 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-11-12T22:06:26.888Z,1542060386.888 [DataOverHttps](INFO): Moved sent file to Logs/20181112T210013/Express0001.lzma.bak 2018-11-12T22:06:26.888Z,1542060386.888 [DataOverHttps](INFO): SBD MOMSN=8856490 2018-11-12T22:06:27.292Z,1542060387.292 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:06:27.292Z,1542060387.292 [NAL9602] Communications Fault, FailCount= 4 2018-11-12T22:06:27.292Z,1542060387.292 [NAL9602](ERROR): Communications Fault 2018-11-12T22:06:27.381Z,1542060387.381 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:06:27.711Z,1542060387.711 [NAL9602](INFO): Powering down 2018-11-12T22:06:30.770Z,1542060390.770 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:06:30.770Z,1542060390.770 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-12T22:06:30.770Z,1542060390.770 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:06:30.822Z,1542060390.822 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:06:30.983Z,1542060390.983 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:06:31.002Z,1542060391.002 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:06:31.002Z,1542060391.002 [NAL9602] No Fault, FailCount= 4 2018-11-12T22:06:31.675Z,1542060391.675 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:06:31.675Z,1542060391.675 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-12T22:06:39.275Z,1542060399.275 [Default:A.Wait](INFO): Done Waiting. 2018-11-12T22:06:39.275Z,1542060399.275 [Default:A.Wait] Stopped 2018-11-12T22:06:39.275Z,1542060399.275 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-11-12T22:06:39.698Z,1542060399.698 [Default:CheckIn] Running Loop=1 2018-11-12T22:06:39.699Z,1542060399.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-11-12T22:06:39.699Z,1542060399.699 [Default:CheckIn:Read_GPS] Running Loop=1 2018-11-12T22:06:40.089Z,1542060400.089 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-11-12T22:06:42.061Z,1542060402.061 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:06:42.061Z,1542060402.061 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-12T22:06:42.061Z,1542060402.061 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:06:42.079Z,1542060402.079 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:06:42.079Z,1542060402.079 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-12T22:06:42.578Z,1542060402.578 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:06:58.036Z,1542060418.036 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:06:59.670Z,1542060419.670 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:06:59.670Z,1542060419.670 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2018-11-12T22:06:59.670Z,1542060419.670 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-12T22:06:59.672Z,1542060419.672 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:06:59.672Z,1542060419.672 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-11-12T22:06:59.672Z,1542060419.672 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-12T22:06:59.684Z,1542060419.684 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-12T22:06:59.685Z,1542060419.685 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-12T22:07:00.078Z,1542060420.078 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-12T22:07:00.078Z,1542060420.078 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2018-11-12T22:07:00.079Z,1542060420.079 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-12T22:07:00.079Z,1542060420.079 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-11-12T22:07:00.470Z,1542060420.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-12T22:07:00.470Z,1542060420.470 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:07:00.471Z,1542060420.471 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:07:00.471Z,1542060420.471 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-12T22:07:00.472Z,1542060420.472 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:07:00.472Z,1542060420.472 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:07:15.334Z,1542060435.334 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-12T22:07:15.334Z,1542060435.334 [RDI_Pathfinder] No Fault, FailCount= 3 2018-11-12T22:07:18.888Z,1542060438.888 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:07:18.888Z,1542060438.888 [NAL9602] Communications Fault, FailCount= 5 2018-11-12T22:07:18.888Z,1542060438.888 [NAL9602](ERROR): Communications Fault 2018-11-12T22:07:18.998Z,1542060438.998 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:07:18.998Z,1542060438.998 [CBIT](CRITICAL): Communications Fault in component: NAL9602 2018-11-12T22:07:19.287Z,1542060439.287 [NAL9602](INFO): Powering down 2018-11-12T22:07:19.398Z,1542060439.398 [CBIT](INFO): Critical error at 20181112T220718 2018-11-12T22:07:28.502Z,1542060448.502 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2018-11-12T22:07:28.502Z,1542060448.502 [RDI_Pathfinder] Data Fault, FailCount= 1 2018-11-12T22:07:28.502Z,1542060448.502 [RDI_Pathfinder](ERROR): Data Fault 2018-11-12T22:07:28.548Z,1542060448.548 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2018-11-12T22:07:28.970Z,1542060448.970 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:07:29.735Z,1542060449.735 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-12T22:07:29.735Z,1542060449.735 [RDI_Pathfinder] No Fault, FailCount= 1 2018-11-12T22:07:52.826Z,1542060472.826 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-12T22:07:52.826Z,1542060472.826 [RDI_Pathfinder] Communications Fault, FailCount= 2 2018-11-12T22:07:52.826Z,1542060472.826 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-12T22:07:52.847Z,1542060472.847 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:07:53.294Z,1542060473.294 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:07:54.048Z,1542060474.048 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-12T22:07:54.048Z,1542060474.048 [RDI_Pathfinder] No Fault, FailCount= 2 2018-11-12T22:08:17.157Z,1542060497.157 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-12T22:08:17.157Z,1542060497.157 [RDI_Pathfinder] Communications Fault, FailCount= 3 2018-11-12T22:08:17.157Z,1542060497.157 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-12T22:08:17.176Z,1542060497.176 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:08:17.176Z,1542060497.176 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:08:17.622Z,1542060497.622 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:08:17.669Z,1542060497.669 [CBIT](INFO): Critical error at 20181112T220817 2018-11-12T22:09:00.680Z,1542060540.680 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:09:00.680Z,1542060540.680 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2018-11-12T22:09:00.680Z,1542060540.680 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-12T22:09:00.682Z,1542060540.682 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:09:00.682Z,1542060540.682 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-11-12T22:09:00.682Z,1542060540.682 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-12T22:09:00.695Z,1542060540.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-12T22:09:00.695Z,1542060540.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-12T22:09:01.097Z,1542060541.097 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-12T22:09:01.097Z,1542060541.097 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2018-11-12T22:09:01.098Z,1542060541.098 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-12T22:09:01.098Z,1542060541.098 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-11-12T22:09:01.486Z,1542060541.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-12T22:09:01.486Z,1542060541.486 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:09:01.487Z,1542060541.487 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:09:01.487Z,1542060541.487 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-12T22:09:01.487Z,1542060541.487 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:09:01.488Z,1542060541.488 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:09:12.297Z,1542060552.297 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:09:12.297Z,1542060552.297 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-12T22:09:19.072Z,1542060559.072 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2018-11-12T22:09:19.109Z,1542060559.109 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:09:19.109Z,1542060559.109 [NAL9602] No Fault, FailCount= 5 2018-11-12T22:09:19.456Z,1542060559.456 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:09:22.691Z,1542060562.691 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:09:22.691Z,1542060562.691 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-12T22:09:22.691Z,1542060562.691 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:09:22.715Z,1542060562.715 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:09:23.210Z,1542060563.210 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:09:26.553Z,1542060566.553 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:09:26.553Z,1542060566.553 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-12T22:09:36.994Z,1542060576.994 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:09:36.994Z,1542060576.994 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-12T22:09:36.994Z,1542060576.994 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:09:37.019Z,1542060577.019 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:09:37.510Z,1542060577.510 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:09:38.215Z,1542060578.215 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:09:38.215Z,1542060578.215 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-12T22:09:40.568Z,1542060580.568 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:09:40.568Z,1542060580.568 [NAL9602] Communications Fault, FailCount= 1 2018-11-12T22:09:40.568Z,1542060580.568 [NAL9602](ERROR): Communications Fault 2018-11-12T22:09:40.625Z,1542060580.625 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:09:40.967Z,1542060580.967 [NAL9602](INFO): Powering down 2018-11-12T22:09:41.815Z,1542060581.815 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:09:41.815Z,1542060581.815 [NAL9602] No Fault, FailCount= 1 2018-11-12T22:09:48.598Z,1542060588.598 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:09:48.598Z,1542060588.598 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-12T22:09:48.598Z,1542060588.598 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:09:48.640Z,1542060588.640 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:09:48.641Z,1542060588.641 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-12T22:09:49.110Z,1542060589.110 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:10:11.363Z,1542060611.363 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:10:27.785Z,1542060627.785 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-11-12T22:10:32.160Z,1542060632.160 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:10:32.160Z,1542060632.160 [NAL9602] Communications Fault, FailCount= 2 2018-11-12T22:10:32.160Z,1542060632.160 [NAL9602](ERROR): Communications Fault 2018-11-12T22:10:32.230Z,1542060632.230 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:10:32.557Z,1542060632.557 [NAL9602](INFO): Powering down 2018-11-12T22:10:33.394Z,1542060633.394 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:10:33.394Z,1542060633.394 [NAL9602] No Fault, FailCount= 2 2018-11-12T22:11:01.689Z,1542060661.689 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:11:01.689Z,1542060661.689 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2018-11-12T22:11:01.689Z,1542060661.689 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-12T22:11:01.691Z,1542060661.691 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:11:01.691Z,1542060661.691 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-11-12T22:11:01.691Z,1542060661.691 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-12T22:11:01.703Z,1542060661.703 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-12T22:11:01.703Z,1542060661.703 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-12T22:11:02.105Z,1542060662.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-12T22:11:02.106Z,1542060662.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2018-11-12T22:11:02.106Z,1542060662.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-12T22:11:02.106Z,1542060662.106 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-11-12T22:11:02.488Z,1542060662.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-12T22:11:02.489Z,1542060662.489 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:11:02.489Z,1542060662.489 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:11:02.489Z,1542060662.489 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-12T22:11:02.490Z,1542060662.490 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:11:02.490Z,1542060662.490 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:11:02.864Z,1542060662.864 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:11:17.208Z,1542060677.208 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-12T22:11:17.208Z,1542060677.208 [RDI_Pathfinder] No Fault, FailCount= 3 2018-11-12T22:11:23.972Z,1542060683.972 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:11:23.972Z,1542060683.972 [NAL9602] Communications Fault, FailCount= 3 2018-11-12T22:11:23.972Z,1542060683.972 [NAL9602](ERROR): Communications Fault 2018-11-12T22:11:24.024Z,1542060684.024 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:11:24.371Z,1542060684.371 [NAL9602](INFO): Powering down 2018-11-12T22:11:25.214Z,1542060685.214 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:11:25.214Z,1542060685.214 [NAL9602] No Fault, FailCount= 3 2018-11-12T22:11:30.383Z,1542060690.383 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2018-11-12T22:11:30.384Z,1542060690.384 [RDI_Pathfinder] Data Fault, FailCount= 1 2018-11-12T22:11:30.384Z,1542060690.384 [RDI_Pathfinder](ERROR): Data Fault 2018-11-12T22:11:30.423Z,1542060690.423 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2018-11-12T22:11:30.854Z,1542060690.854 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:11:31.613Z,1542060691.613 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-12T22:11:31.613Z,1542060691.613 [RDI_Pathfinder] No Fault, FailCount= 1 2018-11-12T22:11:40.009Z,1542060700.009 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-12T22:06:39.7Z 2018-11-12T22:11:40.009Z,1542060700.009 [Default:CheckIn:Read_GPS] Stopped 2018-11-12T22:11:40.009Z,1542060700.009 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-11-12T22:11:40.391Z,1542060700.391 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-12T22:11:44.375Z,1542060704.375 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20181112T220248/Courier0004.lzma 2018-11-12T22:11:45.156Z,1542060705.156 [DataOverHttps](INFO): Moved sent file to Logs/20181112T220248/Courier0004.lzma.bak 2018-11-12T22:11:45.156Z,1542060705.156 [DataOverHttps](INFO): SBD MOMSN=8856552 2018-11-12T22:11:53.803Z,1542060713.803 [DataOverHttps](INFO): Sending 740 bytes from file Logs/20181112T211925/Express0001.lzma 2018-11-12T22:11:54.577Z,1542060714.577 [DataOverHttps](INFO): Moved sent file to Logs/20181112T211925/Express0001.lzma.bak 2018-11-12T22:11:54.577Z,1542060714.577 [DataOverHttps](INFO): SBD MOMSN=8856556 2018-11-12T22:11:54.583Z,1542060714.583 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:11:54.599Z,1542060714.599 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-12T22:11:54.599Z,1542060714.599 [RDI_Pathfinder] Communications Fault, FailCount= 2 2018-11-12T22:11:54.599Z,1542060714.599 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-12T22:11:54.659Z,1542060714.659 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:11:55.291Z,1542060715.291 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:12:00.473Z,1542060720.473 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-11-12T22:12:00.473Z,1542060720.473 [RDI_Pathfinder] No Fault, FailCount= 2 2018-11-12T22:12:05.258Z,1542060725.258 [DataOverHttps](INFO): Sending 610 bytes from file Logs/20181112T212129/Express0001.lzma 2018-11-12T22:12:06.016Z,1542060726.016 [DataOverHttps](INFO): Moved sent file to Logs/20181112T212129/Express0001.lzma.bak 2018-11-12T22:12:06.016Z,1542060726.016 [DataOverHttps](INFO): SBD MOMSN=8856584 2018-11-12T22:12:14.696Z,1542060734.696 [DataOverHttps](INFO): Sending 1031 bytes from file Logs/20181112T215003/Express0001.lzma 2018-11-12T22:12:15.472Z,1542060735.472 [DataOverHttps](INFO): Moved sent file to Logs/20181112T215003/Express0001.lzma.bak 2018-11-12T22:12:15.473Z,1542060735.473 [DataOverHttps](INFO): SBD MOMSN=8856600 2018-11-12T22:12:15.484Z,1542060735.484 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-12T22:12:15.484Z,1542060735.484 [NAL9602] Communications Fault, FailCount= 4 2018-11-12T22:12:15.484Z,1542060735.484 [NAL9602](ERROR): Communications Fault 2018-11-12T22:12:15.550Z,1542060735.550 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-12T22:12:16.110Z,1542060736.110 [NAL9602](INFO): Powering down 2018-11-12T22:12:19.385Z,1542060739.385 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-12T22:12:19.385Z,1542060739.385 [NAL9602] No Fault, FailCount= 4 2018-11-12T22:12:19.386Z,1542060739.386 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:12:19.417Z,1542060739.417 [Aanderaa_O2] No Fault, FailCount= 3 2018-11-12T22:12:24.230Z,1542060744.230 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20181112T215003/Express0005.lzma 2018-11-12T22:12:24.656Z,1542060744.656 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-11-12T22:12:24.656Z,1542060744.656 [RDI_Pathfinder] Communications Fault, FailCount= 3 2018-11-12T22:12:24.656Z,1542060744.656 [RDI_Pathfinder](ERROR): Communications Fault 2018-11-12T22:12:24.675Z,1542060744.675 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:12:24.676Z,1542060744.676 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2018-11-12T22:12:25.028Z,1542060745.028 [DataOverHttps](INFO): Moved sent file to Logs/20181112T215003/Express0005.lzma.bak 2018-11-12T22:12:25.028Z,1542060745.028 [DataOverHttps](INFO): SBD MOMSN=8856632 2018-11-12T22:12:25.134Z,1542060745.134 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:12:25.164Z,1542060745.164 [CBIT](INFO): Critical error at 20181112T221224 2018-11-12T22:12:29.474Z,1542060749.474 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:12:29.474Z,1542060749.474 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-11-12T22:12:29.474Z,1542060749.474 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:12:29.534Z,1542060749.534 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:12:30.002Z,1542060750.002 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:12:30.722Z,1542060750.722 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:12:30.722Z,1542060750.722 [Aanderaa_O2] No Fault, FailCount= 1 2018-11-12T22:12:33.882Z,1542060753.882 [DataOverHttps](INFO): Sending 640 bytes from file Logs/20181112T215635/Express0001.lzma 2018-11-12T22:12:34.680Z,1542060754.680 [DataOverHttps](INFO): Moved sent file to Logs/20181112T215635/Express0001.lzma.bak 2018-11-12T22:12:34.680Z,1542060754.680 [DataOverHttps](INFO): SBD MOMSN=8856645 2018-11-12T22:12:43.332Z,1542060763.332 [DataOverHttps](INFO): Sending 1032 bytes from file Logs/20181112T220248/Express0001.lzma 2018-11-12T22:12:44.096Z,1542060764.096 [DataOverHttps](INFO): Moved sent file to Logs/20181112T220248/Express0001.lzma.bak 2018-11-12T22:12:44.097Z,1542060764.097 [DataOverHttps](INFO): SBD MOMSN=8856662 2018-11-12T22:12:44.134Z,1542060764.134 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:12:44.134Z,1542060764.134 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-11-12T22:12:44.134Z,1542060764.134 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:12:44.190Z,1542060764.190 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:12:44.979Z,1542060764.979 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:12:50.086Z,1542060770.086 [NAL9602](INFO): Powering up NAL9602 2018-11-12T22:12:50.144Z,1542060770.144 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-11-12T22:12:50.144Z,1542060770.144 [Aanderaa_O2] No Fault, FailCount= 2 2018-11-12T22:12:54.912Z,1542060774.912 [DataOverHttps](INFO): Sending 363 bytes from file Logs/20181112T220248/Express0005.lzma 2018-11-12T22:12:55.712Z,1542060775.712 [DataOverHttps](INFO): Moved sent file to Logs/20181112T220248/Express0005.lzma.bak 2018-11-12T22:12:55.712Z,1542060775.712 [DataOverHttps](INFO): SBD MOMSN=8856695 2018-11-12T22:12:56.365Z,1542060776.365 [Default:CheckIn:Read_Iridium] Stopped 2018-11-12T22:12:56.365Z,1542060776.365 [Default:CheckIn:C.Wait] Running Loop=1 2018-11-12T22:12:56.365Z,1542060776.365 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-11-12T22:13:00.455Z,1542060780.455 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2018-11-12T22:13:00.455Z,1542060780.455 [Aanderaa_O2] Communications Fault, FailCount= 3 2018-11-12T22:13:00.455Z,1542060780.455 [Aanderaa_O2](ERROR): Communications Fault 2018-11-12T22:13:00.472Z,1542060780.472 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-11-12T22:13:00.473Z,1542060780.473 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2018-11-12T22:13:01.002Z,1542060781.002 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:13:02.854Z,1542060782.854 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:13:02.854Z,1542060782.854 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2018-11-12T22:13:02.854Z,1542060782.854 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-11-12T22:13:02.856Z,1542060782.856 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-11-12T22:13:02.856Z,1542060782.856 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-11-12T22:13:02.856Z,1542060782.856 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-12T22:13:02.869Z,1542060782.869 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-11-12T22:13:02.869Z,1542060782.869 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-12T22:13:03.270Z,1542060783.270 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-11-12T22:13:03.270Z,1542060783.270 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2018-11-12T22:13:03.271Z,1542060783.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-12T22:13:03.271Z,1542060783.271 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-11-12T22:13:03.658Z,1542060783.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-12T22:13:03.658Z,1542060783.658 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:13:03.659Z,1542060783.659 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:13:03.659Z,1542060783.659 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-12T22:13:03.660Z,1542060783.660 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-12T22:13:03.660Z,1542060783.660 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-12T22:13:04.844Z,1542060784.844 [CommandLine](IMPORTANT): got command restart application 2018-11-12T22:13:05.853Z,1542060785.853 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-12T22:13:05.853Z,1542060785.853 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.028Z,1542060786.028 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-11-12T22:13:06.028Z,1542060786.028 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.034Z,1542060786.034 [CommandLine](INFO): Join timeout helper Thread ID is 3287 2018-11-12T22:13:06.036Z,1542060786.036 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-11-12T22:13:06.038Z,1542060786.038 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.040Z,1542060786.040 [NavChartDb](INFO): Join timeout helper Thread ID is 3288 2018-11-12T22:13:06.234Z,1542060786.234 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-12T22:13:06.235Z,1542060786.235 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.238Z,1542060786.238 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-11-12T22:13:06.238Z,1542060786.238 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.239Z,1542060786.239 [Radio_Surface](INFO): Join timeout helper Thread ID is 3289 2018-11-12T22:13:06.338Z,1542060786.338 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-12T22:13:06.339Z,1542060786.339 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.358Z,1542060786.358 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-11-12T22:13:06.359Z,1542060786.359 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.359Z,1542060786.359 [logger](INFO): Join timeout helper Thread ID is 3290 2018-11-12T22:13:06.359Z,1542060786.359 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-12T22:13:06.359Z,1542060786.359 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.360Z,1542060786.360 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-11-12T22:13:06.360Z,1542060786.360 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.360Z,1542060786.360 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-11-12T22:13:06.360Z,1542060786.360 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.361Z,1542060786.361 [controlThread](INFO): Join timeout helper Thread ID is 3291 2018-11-12T22:13:06.418Z,1542060786.418 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-12T22:13:06.419Z,1542060786.419 [controlThread](DEBUG): Uninitializing ControlThread 2018-11-12T22:13:06.419Z,1542060786.419 [NAL9602](INFO): Powering down 2018-11-12T22:13:06.421Z,1542060786.421 [RDI_Pathfinder](INFO): Powering down 2018-11-12T22:13:06.422Z,1542060786.422 [RDI_PathfinderUp](INFO): Powering down 2018-11-12T22:13:06.423Z,1542060786.423 [Aanderaa_O2](INFO): Powering down 2018-11-12T22:13:06.424Z,1542060786.424 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-12T22:13:06.424Z,1542060786.424 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-11-12T22:13:06.425Z,1542060786.425 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-11-12T22:13:06.425Z,1542060786.425 [MissionManager](INFO): Uninitializing Mission Default 2018-11-12T22:13:06.425Z,1542060786.425 [Default] Stopped 2018-11-12T22:13:06.425Z,1542060786.425 [Default](DEBUG): Aggregate::uninitialize Default 2018-11-12T22:13:06.425Z,1542060786.425 [Default:B.GoToSurface] Stopped 2018-11-12T22:13:06.425Z,1542060786.425 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-12T22:13:06.426Z,1542060786.426 [Default:CheckIn] Stopped 2018-11-12T22:13:06.426Z,1542060786.426 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-11-12T22:13:06.426Z,1542060786.426 [Default:CheckIn:C.Wait] Stopped 2018-11-12T22:13:06.426Z,1542060786.426 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-11-12T22:13:06.429Z,1542060786.429 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-11-12T22:13:06.429Z,1542060786.429 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-11-12T22:13:06.429Z,1542060786.429 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-11-12T22:13:06.430Z,1542060786.430 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-11-12T22:13:06.430Z,1542060786.430 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-12T22:13:06.430Z,1542060786.430 [BuoyancyServo](INFO): Powering down 2018-11-12T22:13:06.442Z,1542060786.442 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-12T22:13:06.443Z,1542060786.443 [ElevatorServo](INFO): Powering down 2018-11-12T22:13:06.443Z,1542060786.443 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-12T22:13:06.443Z,1542060786.443 [MassServo](INFO): Powering down 2018-11-12T22:13:06.444Z,1542060786.444 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-12T22:13:06.444Z,1542060786.444 [RudderServo](INFO): Powering down 2018-11-12T22:13:06.445Z,1542060786.445 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-12T22:13:06.445Z,1542060786.445 [ThrusterServo](INFO): Powering down 2018-11-12T22:13:06.446Z,1542060786.446 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-11-12T22:13:06.447Z,1542060786.447 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-11-12T22:13:06.447Z,1542060786.447 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-12T22:13:06.447Z,1542060786.447 [CBIT](DEBUG): Powering off loads. 2018-11-12T22:13:06.447Z,1542060786.447 [CBIT](DEBUG): Disabling WDT. 2018-11-12T22:13:06.447Z,1542060786.447 [CBIT](DEBUG): Opening all GF detection circuits. 2018-11-12T22:13:06.448Z,1542060786.448 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.555Z,1542060786.555 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.613Z,1542060786.613 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-12T22:13:06.667Z,1542060786.667 [logger ThreadHandler](INFO): Thread cancelled.