2018-11-13T23:16:50.884Z,1542151010.884 [Supervisor](DEBUG): Initializing supervisor. 2018-11-13T23:16:50.887Z,1542151010.887 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-13T23:16:50.888Z,1542151010.888 [SyncHandler](INFO): Protected caller Thread ID is 9698 2018-11-13T23:16:50.888Z,1542151010.888 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-11-13T23:16:50.889Z,1542151010.889 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-11-13T23:16:50.889Z,1542151010.889 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9699 2018-11-13T23:16:50.892Z,1542151010.892 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-11-13T23:16:50.903Z,1542151010.903 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-11-13T23:16:50.904Z,1542151010.904 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-11-13T23:16:50.905Z,1542151010.905 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9700 2018-11-13T23:16:50.906Z,1542151010.906 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-11-13T23:16:50.907Z,1542151010.907 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-11-13T23:16:50.907Z,1542151010.907 [logger ThreadHandler](INFO): Protected caller Thread ID is 9701 2018-11-13T23:16:50.909Z,1542151010.909 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-11-13T23:16:50.909Z,1542151010.909 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-11-13T23:16:50.911Z,1542151010.911 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-11-13T23:16:51.229Z,1542151011.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-11-13T23:16:51.230Z,1542151011.230 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-11-13T23:16:51.673Z,1542151011.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-11-13T23:16:51.674Z,1542151011.674 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-11-13T23:16:52.071Z,1542151012.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-11-13T23:16:52.072Z,1542151012.072 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-11-13T23:16:52.281Z,1542151012.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-11-13T23:16:52.281Z,1542151012.281 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-11-13T23:16:52.728Z,1542151012.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-11-13T23:16:52.729Z,1542151012.729 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-11-13T23:16:53.222Z,1542151013.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-11-13T23:16:53.223Z,1542151013.223 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-11-13T23:16:53.325Z,1542151013.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-11-13T23:16:53.326Z,1542151013.326 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-11-13T23:16:53.521Z,1542151013.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-11-13T23:16:53.522Z,1542151013.522 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-11-13T23:16:53.663Z,1542151013.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-11-13T23:16:53.664Z,1542151013.664 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-11-13T23:16:53.743Z,1542151013.743 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-11-13T23:16:53.960Z,1542151013.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-11-13T23:16:53.960Z,1542151013.960 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-11-13T23:16:54.060Z,1542151014.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-11-13T23:16:54.060Z,1542151014.060 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-11-13T23:16:54.155Z,1542151014.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-11-13T23:16:54.156Z,1542151014.156 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-11-13T23:16:54.304Z,1542151014.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-11-13T23:16:54.304Z,1542151014.304 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-11-13T23:16:54.619Z,1542151014.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-11-13T23:16:54.621Z,1542151014.621 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-11-13T23:16:54.622Z,1542151014.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-11-13T23:16:54.753Z,1542151014.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-11-13T23:16:54.975Z,1542151014.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-11-13T23:16:54.975Z,1542151014.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-11-13T23:16:55.146Z,1542151015.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-11-13T23:16:55.245Z,1542151015.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-11-13T23:16:55.343Z,1542151015.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-11-13T23:16:55.534Z,1542151015.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-11-13T23:16:55.618Z,1542151015.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-11-13T23:16:55.716Z,1542151015.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-11-13T23:16:55.808Z,1542151015.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-11-13T23:16:55.889Z,1542151015.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-11-13T23:16:55.998Z,1542151015.998 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-11-13T23:16:56.007Z,1542151016.007 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-11-13T23:16:56.140Z,1542151016.140 [SBIT](DEBUG): Construct Startup Built In Test. 2018-11-13T23:16:56.170Z,1542151016.170 [SBIT] Loaded 2018-11-13T23:16:56.170Z,1542151016.170 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-11-13T23:16:56.171Z,1542151016.171 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-11-13T23:16:56.199Z,1542151016.199 [IBIT] Loaded 2018-11-13T23:16:56.199Z,1542151016.199 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-11-13T23:16:56.202Z,1542151016.202 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-11-13T23:16:56.357Z,1542151016.357 [CBIT] Loaded 2018-11-13T23:16:56.357Z,1542151016.357 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-11-13T23:16:56.358Z,1542151016.358 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-11-13T23:16:56.358Z,1542151016.358 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-11-13T23:16:56.475Z,1542151016.475 [BuoyancyServo] Loaded 2018-11-13T23:16:56.476Z,1542151016.476 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-11-13T23:16:56.490Z,1542151016.490 [ElevatorServo] Loaded 2018-11-13T23:16:56.490Z,1542151016.490 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-11-13T23:16:56.505Z,1542151016.505 [MassServo] Loaded 2018-11-13T23:16:56.505Z,1542151016.505 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-11-13T23:16:56.520Z,1542151016.520 [RudderServo] Loaded 2018-11-13T23:16:56.520Z,1542151016.520 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-11-13T23:16:56.535Z,1542151016.535 [ThrusterServo] Loaded 2018-11-13T23:16:56.535Z,1542151016.535 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-11-13T23:16:56.535Z,1542151016.535 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-11-13T23:16:56.536Z,1542151016.536 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-11-13T23:16:56.636Z,1542151016.636 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-11-13T23:16:56.636Z,1542151016.636 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-11-13T23:16:56.690Z,1542151016.690 [DeadReckonUsingSpeedCalculator] Loaded 2018-11-13T23:16:56.690Z,1542151016.690 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-11-13T23:16:56.706Z,1542151016.706 [NavChart] Loaded 2018-11-13T23:16:56.707Z,1542151016.707 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-11-13T23:16:56.711Z,1542151016.711 [UniversalFixResidualReporter] Loaded 2018-11-13T23:16:56.711Z,1542151016.711 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-11-13T23:16:56.711Z,1542151016.711 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-11-13T23:16:56.712Z,1542151016.712 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-11-13T23:16:56.725Z,1542151016.725 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-11-13T23:16:56.726Z,1542151016.726 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-11-13T23:16:56.971Z,1542151016.971 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-11-13T23:16:56.972Z,1542151016.972 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-11-13T23:16:57.133Z,1542151017.133 [DataOverHttps] Loaded 2018-11-13T23:16:57.133Z,1542151017.133 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-11-13T23:16:57.146Z,1542151017.146 [Depth_Keller] Loaded 2018-11-13T23:16:57.147Z,1542151017.147 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-11-13T23:16:57.151Z,1542151017.151 [DropWeight] Loaded 2018-11-13T23:16:57.152Z,1542151017.152 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-11-13T23:16:57.242Z,1542151017.242 [NAL9602] Loaded 2018-11-13T23:16:57.243Z,1542151017.243 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-11-13T23:16:57.248Z,1542151017.248 [Onboard] Loaded 2018-11-13T23:16:57.248Z,1542151017.248 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-11-13T23:16:57.255Z,1542151017.255 [Radio_Surface] Loaded 2018-11-13T23:16:57.255Z,1542151017.255 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-11-13T23:16:57.256Z,1542151017.256 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407D04E0 2018-11-13T23:16:57.257Z,1542151017.257 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9781 2018-11-13T23:16:59.835Z,1542151019.835 [BPC1] Loaded 2018-11-13T23:16:59.835Z,1542151019.835 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-11-13T23:16:59.835Z,1542151019.835 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-11-13T23:16:59.836Z,1542151019.836 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-11-13T23:16:59.904Z,1542151019.904 [VerticalControl](DEBUG): Construct VerticalControl. 2018-11-13T23:16:59.992Z,1542151019.992 [VerticalControl] Loaded 2018-11-13T23:16:59.993Z,1542151019.993 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-11-13T23:16:59.993Z,1542151019.993 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-11-13T23:17:00.051Z,1542151020.051 [HorizontalControl] Loaded 2018-11-13T23:17:00.051Z,1542151020.051 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-11-13T23:17:00.052Z,1542151020.052 [SpeedControl](DEBUG): Construct SpeedControl. 2018-11-13T23:17:00.054Z,1542151020.054 [SpeedControl] Loaded 2018-11-13T23:17:00.054Z,1542151020.054 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-11-13T23:17:00.055Z,1542151020.055 [LoopControl](DEBUG): Construct LoopControl. 2018-11-13T23:17:00.056Z,1542151020.056 [LoopControl] Loaded 2018-11-13T23:17:00.056Z,1542151020.056 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-11-13T23:17:00.056Z,1542151020.056 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-11-13T23:17:00.057Z,1542151020.057 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-11-13T23:17:00.159Z,1542151020.159 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-11-13T23:17:00.160Z,1542151020.160 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-11-13T23:17:00.180Z,1542151020.180 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-11-13T23:17:00.181Z,1542151020.181 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-11-13T23:17:00.246Z,1542151020.246 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-11-13T23:17:00.247Z,1542151020.247 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-11-13T23:17:00.287Z,1542151020.287 [DepthRateCalculator] Loaded 2018-11-13T23:17:00.288Z,1542151020.288 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-11-13T23:17:00.293Z,1542151020.293 [PitchRateCalculator] Loaded 2018-11-13T23:17:00.293Z,1542151020.293 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-11-13T23:17:00.305Z,1542151020.305 [SpeedCalculator] Loaded 2018-11-13T23:17:00.306Z,1542151020.306 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-11-13T23:17:00.327Z,1542151020.327 [TempGradientCalculator] Loaded 2018-11-13T23:17:00.327Z,1542151020.327 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-11-13T23:17:00.333Z,1542151020.333 [YawRateCalculator] Loaded 2018-11-13T23:17:00.333Z,1542151020.333 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-11-13T23:17:00.363Z,1542151020.363 [ElevatorOffsetCalculator] Loaded 2018-11-13T23:17:00.363Z,1542151020.363 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-11-13T23:17:00.363Z,1542151020.363 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-11-13T23:17:00.364Z,1542151020.364 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-11-13T23:17:00.389Z,1542151020.389 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-11-13T23:17:00.392Z,1542151020.392 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-11-13T23:17:00.393Z,1542151020.393 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-11-13T23:17:00.399Z,1542151020.399 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-11-13T23:17:00.401Z,1542151020.401 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0 2018-11-13T23:17:00.401Z,1542151020.401 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9783 2018-11-13T23:17:00.406Z,1542151020.406 [Supervisor](INFO): Main Thread ID is 9697 2018-11-13T23:17:00.406Z,1542151020.406 [Supervisor](DEBUG): Running supervisor. 2018-11-13T23:17:00.406Z,1542151020.406 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9784 2018-11-13T23:17:00.409Z,1542151020.409 [controlThread ThreadHandler](INFO): Handler Thread ID is 9785 2018-11-13T23:17:00.409Z,1542151020.409 [controlThread](DEBUG): Initializing ControlThread 2018-11-13T23:17:00.410Z,1542151020.410 [SBIT](INFO): Initialize SBIT Component. 2018-11-13T23:17:00.411Z,1542151020.411 [SBIT](IMPORTANT): git: 2018-11-05 2018-11-13T23:17:00.411Z,1542151020.411 [SBIT](INFO): git hash: 17f8e4440ddc7f0910aa911cb821ed1a114d9cee 2018-11-13T23:17:00.411Z,1542151020.411 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-11-13T23:17:00.411Z,1542151020.411 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-11-13T23:17:00.413Z,1542151020.413 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-11-13T23:17:00.413Z,1542151020.413 [IBIT](INFO): Initialize IBIT Component. 2018-11-13T23:17:00.414Z,1542151020.414 [CBIT](DEBUG): Initialize CBIT Component. 2018-11-13T23:17:00.414Z,1542151020.414 [CBIT](DEBUG): Initializing mux pins. 2018-11-13T23:17:00.414Z,1542151020.414 [CBIT](DEBUG): Initializing the watchdog timer. 2018-11-13T23:17:00.414Z,1542151020.414 [CBIT](CRITICAL): Watchdog Timer failed to initialize. 2018-11-13T23:17:00.415Z,1542151020.415 [CBIT] Hardware Fault, FailCount= 1 2018-11-13T23:17:00.415Z,1542151020.415 [CBIT](ERROR): Hardware Fault 2018-11-13T23:17:00.416Z,1542151020.416 [CBIT](INFO): Reporting lpc3250 register adresses: LPC3Reg::TimClkCtrl_ 0x400200bc LPC3Reg::WDTimCtrl_: 0x4041e004 LPC3Reg::WDTimMCtrl_: 0x4041e00c LPC3Reg::WDTimEMR_: 0x4041e014 2018-11-13T23:17:00.416Z,1542151020.416 [CBIT](DEBUG): Initializing heartbeat. 2018-11-13T23:17:00.417Z,1542151020.417 [logger ThreadHandler](INFO): Handler Thread ID is 9786 2018-11-13T23:17:00.441Z,1542151020.441 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9787 2018-11-13T23:17:00.447Z,1542151020.447 [Radio_Surface](INFO): Powering up 2018-11-13T23:17:00.465Z,1542151020.465 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9788 2018-11-13T23:17:00.468Z,1542151020.468 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2018-11-13T23:17:00.477Z,1542151020.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-11-13T23:17:00.478Z,1542151020.478 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2018-11-13T23:17:00.478Z,1542151020.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-11-13T23:17:00.480Z,1542151020.480 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2018-11-13T23:17:00.480Z,1542151020.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-11-13T23:17:00.482Z,1542151020.482 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2018-11-13T23:17:00.482Z,1542151020.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-11-13T23:17:00.483Z,1542151020.483 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2018-11-13T23:17:00.483Z,1542151020.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-11-13T23:17:00.485Z,1542151020.485 [CBIT](DEBUG): Deactivating GF circuits. 2018-11-13T23:17:00.485Z,1542151020.485 [CBIT](DEBUG): Deactivating emergency mode. 2018-11-13T23:17:00.485Z,1542151020.485 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2018-11-13T23:17:00.486Z,1542151020.486 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-11-13T23:17:00.487Z,1542151020.487 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2018-11-13T23:17:00.487Z,1542151020.487 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-11-13T23:17:00.489Z,1542151020.489 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2018-11-13T23:17:00.489Z,1542151020.489 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-13T23:17:00.491Z,1542151020.491 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2018-11-13T23:17:00.522Z,1542151020.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-13T23:17:00.522Z,1542151020.522 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-13T23:17:00.523Z,1542151020.523 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-13T23:17:00.523Z,1542151020.523 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-13T23:17:00.523Z,1542151020.523 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-13T23:17:00.524Z,1542151020.524 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-13T23:17:00.524Z,1542151020.524 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-11-13T23:17:00.524Z,1542151020.524 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-11-13T23:17:00.531Z,1542151020.531 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-11-13T23:17:00.533Z,1542151020.533 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-11-13T23:17:00.534Z,1542151020.534 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-11-13T23:17:00.534Z,1542151020.534 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-11-13T23:17:00.535Z,1542151020.535 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-13T23:17:00.535Z,1542151020.535 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-11-13T23:17:00.535Z,1542151020.535 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-11-13T23:17:00.536Z,1542151020.536 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-11-13T23:17:00.536Z,1542151020.536 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-11-13T23:17:00.537Z,1542151020.537 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-11-13T23:17:00.538Z,1542151020.538 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-11-13T23:17:00.561Z,1542151020.561 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-13T23:17:00.593Z,1542151020.593 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-13T23:17:00.613Z,1542151020.613 [MissionManager](DEBUG): 2018-11-13T23:17:00.614Z,1542151020.614 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-11-13T23:17:00.655Z,1542151020.655 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-11-13T23:17:00.665Z,1542151020.665 [Default:A.Wait](DEBUG): Construct Wait. 2018-11-13T23:17:00.667Z,1542151020.667 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-13T23:17:00.702Z,1542151020.702 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-11-13T23:17:00.717Z,1542151020.717 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-11-13T23:17:00.723Z,1542151020.723 [Default:E.Execute](DEBUG): Construct Execute. 2018-11-13T23:17:00.739Z,1542151020.739 [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-13T23:17:00.744Z,1542151020.744 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,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, 2018-11-13T23:17:00.765Z,1542151020.765 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-11-13T23:17:00.806Z,1542151020.806 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-11-13T23:17:00.938Z,1542151020.938 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-13T23:17:01.135Z,1542151021.135 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-13T23:17:01.141Z,1542151021.141 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-13T23:17:01.147Z,1542151021.147 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-13T23:17:01.153Z,1542151021.153 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-13T23:17:01.159Z,1542151021.159 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-13T23:17:01.165Z,1542151021.165 [MassServo](DEBUG): Initializing MassServo. 2018-11-13T23:17:01.171Z,1542151021.171 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-13T23:17:01.201Z,1542151021.201 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-13T23:17:01.211Z,1542151021.211 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-11-13T23:17:01.217Z,1542151021.217 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-11-13T23:17:01.253Z,1542151021.253 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-13T23:17:01.253Z,1542151021.253 [CBIT](DEBUG): Powering off loads. 2018-11-13T23:17:01.254Z,1542151021.254 [CBIT](DEBUG): Disabling WDT. 2018-11-13T23:17:01.254Z,1542151021.254 [CBIT](DEBUG): Opening all GF detection circuits. 2018-11-13T23:17:01.366Z,1542151021.366 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-13T23:17:01.366Z,1542151021.366 [DropWeight] Hardware Fault, FailCount= 1 2018-11-13T23:17:01.366Z,1542151021.366 [DropWeight](ERROR): Hardware Fault 2018-11-13T23:17:01.417Z,1542151021.417 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-13T23:17:02.445Z,1542151022.445 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-11-13T23:17:02.445Z,1542151022.445 [ElevatorServo](FAULT): Elevator failed to initialize 2018-11-13T23:17:02.445Z,1542151022.445 [ElevatorServo] Communications Fault, FailCount= 1 2018-11-13T23:17:02.445Z,1542151022.445 [ElevatorServo](ERROR): Communications Fault 2018-11-13T23:17:02.757Z,1542151022.757 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-11-13T23:17:02.757Z,1542151022.757 [RudderServo](FAULT): Rudder failed to initialize 2018-11-13T23:17:02.757Z,1542151022.757 [RudderServo] Communications Fault, FailCount= 1 2018-11-13T23:17:02.757Z,1542151022.757 [RudderServo](ERROR): Communications Fault 2018-11-13T23:17:03.085Z,1542151023.085 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-11-13T23:17:03.409Z,1542151023.409 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2018-11-13T23:17:03.409Z,1542151023.409 [ThrusterServo](FAULT): Thruster failed to initialize 2018-11-13T23:17:03.409Z,1542151023.409 [ThrusterServo] Communications Fault, FailCount= 1 2018-11-13T23:17:03.409Z,1542151023.409 [ThrusterServo](ERROR): Communications Fault 2018-11-13T23:17:03.769Z,1542151023.769 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-11-13T23:17:03.769Z,1542151023.769 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-11-13T23:17:03.769Z,1542151023.769 [BuoyancyServo] Communications Fault, FailCount= 1 2018-11-13T23:17:03.769Z,1542151023.769 [BuoyancyServo](ERROR): Communications Fault 2018-11-13T23:17:03.770Z,1542151023.770 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-13T23:17:03.770Z,1542151023.770 [ElevatorServo](INFO): Powering down 2018-11-13T23:17:04.109Z,1542151024.109 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2018-11-13T23:17:04.109Z,1542151024.109 [MassServo] Communications Fault, FailCount= 1 2018-11-13T23:17:04.109Z,1542151024.109 [MassServo](ERROR): Communications Fault 2018-11-13T23:17:04.110Z,1542151024.110 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-13T23:17:04.110Z,1542151024.110 [RudderServo](INFO): Powering down 2018-11-13T23:17:04.145Z,1542151024.145 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-13T23:17:04.145Z,1542151024.145 [ThrusterServo](INFO): Powering down 2018-11-13T23:17:04.275Z,1542151024.275 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-13T23:17:04.276Z,1542151024.276 [MassServo](INFO): Powering down 2018-11-13T23:17:04.656Z,1542151024.656 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-13T23:17:04.774Z,1542151024.774 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-13T23:17:04.775Z,1542151024.775 [MassServo](FAULT): LCB fault: Current Limiter Activated. 2018-11-13T23:17:04.775Z,1542151024.775 [MassServo] Hardware Fault, FailCount= 1 2018-11-13T23:17:04.775Z,1542151024.775 [MassServo](ERROR): Hardware Fault 2018-11-13T23:17:04.776Z,1542151024.776 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-13T23:17:04.909Z,1542151024.909 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-13T23:17:05.075Z,1542151025.075 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-13T23:17:05.190Z,1542151025.190 [MassServo](DEBUG): Initializing MassServo. 2018-11-13T23:17:12.396Z,1542151032.396 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-13T23:17:20.141Z,1542151040.141 [CommandLine](IMPORTANT): got command restart application 2018-11-13T23:17:21.145Z,1542151041.145 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2018-11-13T23:17:21.148Z,1542151041.148 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-13T23:17:21.148Z,1542151041.148 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.157Z,1542151041.157 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-11-13T23:17:21.157Z,1542151041.157 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.158Z,1542151041.158 [CommandLine](INFO): Join timeout helper Thread ID is 9797 2018-11-13T23:17:21.170Z,1542151041.170 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-11-13T23:17:21.171Z,1542151041.171 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.171Z,1542151041.171 [NavChartDb](INFO): Join timeout helper Thread ID is 9798 2018-11-13T23:17:21.172Z,1542151041.172 [NavChartDb](INFO): # of records loaded: 1603 2018-11-13T23:17:21.179Z,1542151041.179 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000 2018-11-13T23:17:21.180Z,1542151041.180 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-13T23:17:21.180Z,1542151041.180 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.197Z,1542151041.197 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-11-13T23:17:21.197Z,1542151041.197 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.197Z,1542151041.197 [Radio_Surface](INFO): Join timeout helper Thread ID is 9799 2018-11-13T23:17:21.505Z,1542151041.505 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-13T23:17:21.505Z,1542151041.505 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.517Z,1542151041.517 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-11-13T23:17:21.517Z,1542151041.517 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.517Z,1542151041.517 [logger](INFO): Join timeout helper Thread ID is 9800 2018-11-13T23:17:21.527Z,1542151041.527 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-13T23:17:21.528Z,1542151041.528 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.528Z,1542151041.528 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-11-13T23:17:21.528Z,1542151041.528 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.528Z,1542151041.528 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-11-13T23:17:21.529Z,1542151041.529 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:21.529Z,1542151041.529 [controlThread](INFO): Join timeout helper Thread ID is 9801 2018-11-13T23:17:21.833Z,1542151041.833 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-13T23:17:21.833Z,1542151041.833 [controlThread](DEBUG): Uninitializing ControlThread 2018-11-13T23:17:21.836Z,1542151041.836 [NAL9602](INFO): Powering down 2018-11-13T23:17:21.856Z,1542151041.856 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-13T23:17:21.857Z,1542151041.857 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-11-13T23:17:21.858Z,1542151041.858 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-11-13T23:17:21.858Z,1542151041.858 [MissionManager](INFO): Uninitializing Mission Default 2018-11-13T23:17:21.860Z,1542151041.860 [MissionManager](INFO): Uninitializing Mission Startup 2018-11-13T23:17:21.862Z,1542151041.862 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-11-13T23:17:21.862Z,1542151041.862 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-11-13T23:17:21.862Z,1542151041.862 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-11-13T23:17:21.863Z,1542151041.863 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-11-13T23:17:21.863Z,1542151041.863 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-13T23:17:21.863Z,1542151041.863 [BuoyancyServo](INFO): Powering down 2018-11-13T23:17:22.013Z,1542151042.013 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-13T23:17:22.013Z,1542151042.013 [ElevatorServo](INFO): Powering down 2018-11-13T23:17:22.014Z,1542151042.014 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-13T23:17:22.014Z,1542151042.014 [MassServo](INFO): Powering down 2018-11-13T23:17:22.015Z,1542151042.015 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-13T23:17:22.015Z,1542151042.015 [RudderServo](INFO): Powering down 2018-11-13T23:17:22.016Z,1542151042.016 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-13T23:17:22.016Z,1542151042.016 [ThrusterServo](INFO): Powering down 2018-11-13T23:17:22.017Z,1542151042.017 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-11-13T23:17:22.017Z,1542151042.017 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-11-13T23:17:22.017Z,1542151042.017 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-13T23:17:22.017Z,1542151042.017 [CBIT](DEBUG): Powering off loads. 2018-11-13T23:17:22.018Z,1542151042.018 [CBIT](DEBUG): Disabling WDT. 2018-11-13T23:17:22.032Z,1542151042.032 [CBIT](CRITICAL): run-time exception #7 2018-11-13T23:17:22.032Z,1542151042.032 [CBIT](CRITICAL): Bad address: 0x4041E004 2018-11-13T23:17:22.032Z,1542151042.032 [CBIT](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x169f00] 2018-11-13T23:17:22.032Z,1542151042.032 [CBIT] Software Fault, FailCount= 1 2018-11-13T23:17:22.032Z,1542151042.032 [CBIT](ERROR): Software Fault 2018-11-13T23:17:22.034Z,1542151042.034 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-13T23:17:22.034Z,1542151042.034 [SyncHandler](INFO): Protected caller Thread ID is 9802 2018-11-13T23:17:22.035Z,1542151042.035 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:22.490Z,1542151042.490 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:22.539Z,1542151042.539 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-13T23:17:22.581Z,1542151042.581 [logger ThreadHandler](INFO): Thread cancelled.