2018-12-18T00:51:00.628Z,1545094260.628 [Supervisor](DEBUG): Initializing supervisor.
2018-12-18T00:51:00.631Z,1545094260.631 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-12-18T00:51:00.631Z,1545094260.631 [SyncHandler](INFO): Protected caller Thread ID is 613
2018-12-18T00:51:00.632Z,1545094260.632 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-12-18T00:51:00.633Z,1545094260.633 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-12-18T00:51:00.633Z,1545094260.633 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 614
2018-12-18T00:51:00.637Z,1545094260.637 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-12-18T00:51:00.649Z,1545094260.649 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-12-18T00:51:00.650Z,1545094260.650 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-12-18T00:51:00.651Z,1545094260.651 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 615
2018-12-18T00:51:00.652Z,1545094260.652 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-12-18T00:51:00.652Z,1545094260.652 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-12-18T00:51:00.653Z,1545094260.653 [logger ThreadHandler](INFO): Protected caller Thread ID is 616
2018-12-18T00:51:00.655Z,1545094260.655 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-12-18T00:51:00.655Z,1545094260.655 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-12-18T00:51:00.657Z,1545094260.657 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-12-18T00:51:00.753Z,1545094260.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-12-18T00:51:00.754Z,1545094260.754 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-12-18T00:51:00.855Z,1545094260.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-12-18T00:51:00.855Z,1545094260.855 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-12-18T00:51:01.055Z,1545094261.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-12-18T00:51:01.056Z,1545094261.056 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-12-18T00:51:01.423Z,1545094261.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-12-18T00:51:01.423Z,1545094261.423 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-12-18T00:51:01.822Z,1545094261.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-12-18T00:51:01.823Z,1545094261.823 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-12-18T00:51:02.289Z,1545094262.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-12-18T00:51:02.289Z,1545094262.289 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-12-18T00:51:02.773Z,1545094262.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-12-18T00:51:02.774Z,1545094262.774 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-12-18T00:51:03.232Z,1545094263.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-12-18T00:51:03.233Z,1545094263.233 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-12-18T00:51:03.312Z,1545094263.312 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-12-18T00:51:03.626Z,1545094263.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-12-18T00:51:03.626Z,1545094263.626 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-12-18T00:51:03.774Z,1545094263.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-12-18T00:51:03.775Z,1545094263.775 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-12-18T00:51:03.995Z,1545094263.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-12-18T00:51:03.995Z,1545094263.995 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-12-18T00:51:04.202Z,1545094264.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-12-18T00:51:04.203Z,1545094264.203 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-12-18T00:51:04.300Z,1545094264.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-12-18T00:51:04.301Z,1545094264.301 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-12-18T00:51:04.445Z,1545094264.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-12-18T00:51:04.447Z,1545094264.447 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-12-18T00:51:04.448Z,1545094264.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-12-18T00:51:04.679Z,1545094264.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-12-18T00:51:04.680Z,1545094264.680 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-12-18T00:51:04.784Z,1545094264.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-12-18T00:51:04.937Z,1545094264.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-12-18T00:51:05.030Z,1545094265.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-12-18T00:51:05.114Z,1545094265.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-12-18T00:51:05.276Z,1545094265.276 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-12-18T00:51:05.501Z,1545094265.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-12-18T00:51:05.754Z,1545094265.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-12-18T00:51:05.999Z,1545094265.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-12-18T00:51:06.101Z,1545094266.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-12-18T00:51:06.189Z,1545094266.189 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-12-18T00:51:06.189Z,1545094266.189 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-12-18T00:51:06.190Z,1545094266.190 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-12-18T00:51:06.291Z,1545094266.291 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-12-18T00:51:06.292Z,1545094266.292 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-12-18T00:51:06.306Z,1545094266.306 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-12-18T00:51:06.306Z,1545094266.306 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-12-18T00:51:06.349Z,1545094266.349 [DepthRateCalculator] Loaded
2018-12-18T00:51:06.349Z,1545094266.349 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-12-18T00:51:06.355Z,1545094266.355 [PitchRateCalculator] Loaded
2018-12-18T00:51:06.355Z,1545094266.355 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-12-18T00:51:06.371Z,1545094266.371 [SpeedCalculator] Loaded
2018-12-18T00:51:06.371Z,1545094266.371 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-12-18T00:51:06.393Z,1545094266.393 [TempGradientCalculator] Loaded
2018-12-18T00:51:06.393Z,1545094266.393 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-12-18T00:51:06.399Z,1545094266.399 [YawRateCalculator] Loaded
2018-12-18T00:51:06.399Z,1545094266.399 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-12-18T00:51:06.439Z,1545094266.439 [ElevatorOffsetCalculator] Loaded
2018-12-18T00:51:06.439Z,1545094266.439 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-12-18T00:51:06.440Z,1545094266.440 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-12-18T00:51:06.440Z,1545094266.440 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-12-18T00:51:06.468Z,1545094266.468 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-12-18T00:51:06.468Z,1545094266.468 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-12-18T00:51:06.474Z,1545094266.474 [Module Loader](ERROR): Cannot load library: libboost_system.so.1.63.0: cannot open shared object file: No such file or directory
2018-12-18T00:51:06.474Z,1545094266.474 [Module Loader](ERROR): Could not load the module at Modules/Sensor.so
2018-12-18T00:51:06.475Z,1545094266.475 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-12-18T00:51:06.612Z,1545094266.612 [SBIT](DEBUG): Construct Startup Built In Test.
2018-12-18T00:51:06.638Z,1545094266.638 [SBIT] Loaded
2018-12-18T00:51:06.638Z,1545094266.638 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-12-18T00:51:06.639Z,1545094266.639 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-12-18T00:51:06.667Z,1545094266.667 [IBIT] Loaded
2018-12-18T00:51:06.667Z,1545094266.667 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-12-18T00:51:06.671Z,1545094266.671 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-12-18T00:51:07.061Z,1545094267.061 [CBIT] Loaded
2018-12-18T00:51:07.062Z,1545094267.062 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-12-18T00:51:07.062Z,1545094267.062 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-12-18T00:51:07.063Z,1545094267.063 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-12-18T00:51:07.244Z,1545094267.244 [Aanderaa_O2] Loaded
2018-12-18T00:51:07.244Z,1545094267.244 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-12-18T00:51:07.327Z,1545094267.327 [CTD_Seabird] Loaded
2018-12-18T00:51:07.327Z,1545094267.327 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-12-18T00:51:07.329Z,1545094267.329 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4064D4E0
2018-12-18T00:51:07.329Z,1545094267.329 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 696
2018-12-18T00:51:07.346Z,1545094267.346 [ESPComponent] Loaded
2018-12-18T00:51:07.346Z,1545094267.346 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-12-18T00:51:07.360Z,1545094267.360 [PAR_Licor] Loaded
2018-12-18T00:51:07.360Z,1545094267.360 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-12-18T00:51:07.409Z,1545094267.409 [WetLabsBB2FL] Loaded
2018-12-18T00:51:07.409Z,1545094267.409 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-12-18T00:51:07.410Z,1545094267.410 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4067D4E0
2018-12-18T00:51:07.410Z,1545094267.410 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 697
2018-12-18T00:51:07.411Z,1545094267.411 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-12-18T00:51:07.412Z,1545094267.412 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-12-18T00:51:07.657Z,1545094267.657 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-12-18T00:51:07.658Z,1545094267.658 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-12-18T00:51:07.726Z,1545094267.726 [VerticalControl](DEBUG): Construct VerticalControl.
2018-12-18T00:51:07.820Z,1545094267.820 [VerticalControl] Loaded
2018-12-18T00:51:07.820Z,1545094267.820 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-12-18T00:51:07.821Z,1545094267.821 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-12-18T00:51:07.882Z,1545094267.882 [HorizontalControl] Loaded
2018-12-18T00:51:07.882Z,1545094267.882 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-12-18T00:51:07.882Z,1545094267.882 [SpeedControl](DEBUG): Construct SpeedControl.
2018-12-18T00:51:07.888Z,1545094267.888 [SpeedControl] Loaded
2018-12-18T00:51:07.888Z,1545094267.888 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-12-18T00:51:07.889Z,1545094267.889 [LoopControl](DEBUG): Construct LoopControl.
2018-12-18T00:51:07.889Z,1545094267.889 [LoopControl] Loaded
2018-12-18T00:51:07.889Z,1545094267.889 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-12-18T00:51:07.890Z,1545094267.890 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-12-18T00:51:07.890Z,1545094267.890 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-12-18T00:51:07.959Z,1545094267.959 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-12-18T00:51:07.959Z,1545094267.959 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-12-18T00:51:08.057Z,1545094268.057 [BuoyancyServo] Loaded
2018-12-18T00:51:08.058Z,1545094268.058 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-12-18T00:51:08.069Z,1545094268.069 [ElevatorServo] Loaded
2018-12-18T00:51:08.069Z,1545094268.069 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-12-18T00:51:08.080Z,1545094268.080 [MassServo] Loaded
2018-12-18T00:51:08.080Z,1545094268.080 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-12-18T00:51:08.091Z,1545094268.091 [RudderServo] Loaded
2018-12-18T00:51:08.092Z,1545094268.092 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-12-18T00:51:08.102Z,1545094268.102 [ThrusterServo] Loaded
2018-12-18T00:51:08.102Z,1545094268.102 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-12-18T00:51:08.103Z,1545094268.103 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-12-18T00:51:08.103Z,1545094268.103 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-12-18T00:51:08.201Z,1545094268.201 [DeadReckonUsingSpeedCalculator] Loaded
2018-12-18T00:51:08.202Z,1545094268.202 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-12-18T00:51:08.218Z,1545094268.218 [NavChart] Loaded
2018-12-18T00:51:08.218Z,1545094268.218 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-12-18T00:51:08.222Z,1545094268.222 [UniversalFixResidualReporter] Loaded
2018-12-18T00:51:08.222Z,1545094268.222 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-12-18T00:51:08.223Z,1545094268.223 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-12-18T00:51:08.226Z,1545094268.226 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-12-18T00:51:08.227Z,1545094268.227 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-12-18T00:51:08.234Z,1545094268.234 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-12-18T00:51:08.235Z,1545094268.235 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407D74E0
2018-12-18T00:51:08.235Z,1545094268.235 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 698
2018-12-18T00:51:08.240Z,1545094268.240 [Supervisor](INFO): Main Thread ID is 612
2018-12-18T00:51:08.240Z,1545094268.240 [Supervisor](DEBUG): Running supervisor.
2018-12-18T00:51:08.241Z,1545094268.241 [CommandLine ThreadHandler](INFO): Handler Thread ID is 699
2018-12-18T00:51:08.244Z,1545094268.244 [controlThread ThreadHandler](INFO): Handler Thread ID is 700
2018-12-18T00:51:08.244Z,1545094268.244 [controlThread](DEBUG): Initializing ControlThread
2018-12-18T00:51:08.245Z,1545094268.245 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-18T00:51:08.245Z,1545094268.245 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-12-18T00:51:08.246Z,1545094268.246 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-12-18T00:51:08.246Z,1545094268.246 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-12-18T00:51:08.246Z,1545094268.246 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-12-18T00:51:08.247Z,1545094268.247 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-12-18T00:51:08.247Z,1545094268.247 [SBIT](INFO): Initialize SBIT Component.
2018-12-18T00:51:08.248Z,1545094268.248 [SBIT](IMPORTANT): git: 2018-12-13
2018-12-18T00:51:08.248Z,1545094268.248 [SBIT](INFO): git hash: 8200dc43e6ef01082c531ff0fab84136a371dc2d
2018-12-18T00:51:08.249Z,1545094268.249 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-12-18T00:51:08.249Z,1545094268.249 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-12-18T00:51:08.250Z,1545094268.250 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-12-18T00:51:08.250Z,1545094268.250 [IBIT](INFO): Initialize IBIT Component.
2018-12-18T00:51:08.251Z,1545094268.251 [CBIT](DEBUG): Initialize CBIT Component.
2018-12-18T00:51:08.252Z,1545094268.252 [logger ThreadHandler](INFO): Handler Thread ID is 701
2018-12-18T00:51:08.265Z,1545094268.265 [CBIT](DEBUG): Initialized mux pins.
2018-12-18T00:51:08.265Z,1545094268.265 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-12-18T00:51:08.265Z,1545094268.265 [CBIT](DEBUG): Initializing the watchdog timer.
2018-12-18T00:51:08.277Z,1545094268.277 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 702
2018-12-18T00:51:08.278Z,1545094268.278 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-18T00:51:08.281Z,1545094268.281 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-18T00:51:08.282Z,1545094268.282 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 704
2018-12-18T00:51:08.284Z,1545094268.284 [WetLabsBB2FL](INFO): Powering down
2018-12-18T00:51:08.289Z,1545094268.289 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-12-18T00:51:08.289Z,1545094268.289 [CBIT](DEBUG): Initializing heartbeat.
2018-12-18T00:51:08.341Z,1545094268.341 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 705
2018-12-18T00:51:08.345Z,1545094268.345 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-12-18T00:51:08.345Z,1545094268.345 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-12-18T00:51:08.345Z,1545094268.345 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-12-18T00:51:08.345Z,1545094268.345 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-12-18T00:51:08.345Z,1545094268.345 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-12-18T00:51:08.346Z,1545094268.346 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-12-18T00:51:08.346Z,1545094268.346 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-12-18T00:51:08.346Z,1545094268.346 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-12-18T00:51:08.346Z,1545094268.346 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-12-18T00:51:08.346Z,1545094268.346 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-12-18T00:51:08.346Z,1545094268.346 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-12-18T00:51:08.347Z,1545094268.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-12-18T00:51:08.347Z,1545094268.347 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-12-18T00:51:08.347Z,1545094268.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-12-18T00:51:08.347Z,1545094268.347 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-12-18T00:51:08.347Z,1545094268.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-12-18T00:51:08.361Z,1545094268.361 [CBIT](DEBUG): Deactivating GF circuits.
2018-12-18T00:51:08.361Z,1545094268.361 [CBIT](DEBUG): Deactivating emergency mode.
2018-12-18T00:51:08.405Z,1545094268.405 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-12-18T00:51:08.407Z,1545094268.407 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-12-18T00:51:08.408Z,1545094268.408 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-12-18T00:51:08.408Z,1545094268.408 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-12-18T00:51:08.410Z,1545094268.410 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-18T00:51:08.410Z,1545094268.410 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-18T00:51:08.411Z,1545094268.411 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-18T00:51:08.411Z,1545094268.411 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-12-18T00:51:08.412Z,1545094268.412 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-12-18T00:51:08.413Z,1545094268.413 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-12-18T00:51:08.460Z,1545094268.460 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-18T00:51:08.499Z,1545094268.499 [MissionManager](DEBUG):
2018-12-18T00:51:08.500Z,1545094268.500 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-12-18T00:51:08.626Z,1545094268.626 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-12-18T00:51:08.627Z,1545094268.627 [Default:A.Wait](DEBUG): Construct Wait.
2018-12-18T00:51:08.628Z,1545094268.628 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-18T00:51:08.659Z,1545094268.659 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-12-18T00:51:08.698Z,1545094268.698 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-12-18T00:51:08.703Z,1545094268.703 [Default:E.Execute](DEBUG): Construct Execute.
2018-12-18T00:51:08.757Z,1545094268.757 [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-12-18T00:51:08.761Z,1545094268.761 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-12-18T00:51:08.827Z,1545094268.827 [ESPComponent](INFO): powering down ESP
2018-12-18T00:51:09.259Z,1545094269.259 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:09.561Z,1545094269.561 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-18T00:51:09.567Z,1545094269.567 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-18T00:51:09.582Z,1545094269.582 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-12-18T00:51:09.593Z,1545094269.593 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-12-18T00:51:09.603Z,1545094269.603 [MassServo](DEBUG): Initializing EZServoServo.
2018-12-18T00:51:09.613Z,1545094269.613 [MassServo](DEBUG): Initializing MassServo.
2018-12-18T00:51:09.626Z,1545094269.626 [RudderServo](DEBUG): Initializing EZServoServo.
2018-12-18T00:51:09.633Z,1545094269.633 [RudderServo](DEBUG): Initializing RudderServo.
2018-12-18T00:51:09.646Z,1545094269.646 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-12-18T00:51:09.661Z,1545094269.661 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-12-18T00:51:09.718Z,1545094269.718 [CommandLine](FAULT): Scheduling is paused
2018-12-18T00:51:09.719Z,1545094269.719 [CBIT](INFO): Critical error at 20181218T005108
2018-12-18T00:51:09.719Z,1545094269.719 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-12-18T00:51:09.882Z,1545094269.882 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:10.177Z,1545094270.177 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:10.513Z,1545094270.513 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-12-18T00:51:10.513Z,1545094270.513 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-12-18T00:51:10.513Z,1545094270.513 [BuoyancyServo] Communications Fault, FailCount= 1
2018-12-18T00:51:10.513Z,1545094270.513 [BuoyancyServo](ERROR): Communications Fault
2018-12-18T00:51:10.775Z,1545094270.775 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-12-18T00:51:10.853Z,1545094270.853 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:11.201Z,1545094271.201 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:11.601Z,1545094271.601 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:12.001Z,1545094272.001 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:12.441Z,1545094272.441 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:12.801Z,1545094272.801 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:12.850Z,1545094272.850 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-12-18T00:51:12.850Z,1545094272.850 [BuoyancyServo] No Fault, FailCount= 1
2018-12-18T00:51:13.201Z,1545094273.201 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:13.601Z,1545094273.601 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:14.065Z,1545094274.065 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:14.425Z,1545094274.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:14.830Z,1545094274.830 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:14.851Z,1545094274.851 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-18T00:51:14.970Z,1545094274.970 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-18T00:51:15.225Z,1545094275.225 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:15.625Z,1545094275.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:16.025Z,1545094276.025 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:16.425Z,1545094276.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:16.825Z,1545094276.825 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:17.225Z,1545094277.225 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:17.625Z,1545094277.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:18.025Z,1545094278.025 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:18.425Z,1545094278.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:18.440Z,1545094278.440 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2143304%. Temp:3538368 C. ABORTING MISSION
2018-12-18T00:51:18.818Z,1545094278.818 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-18T00:51:18.818Z,1545094278.818 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-12-18T00:51:18.818Z,1545094278.818 [Aanderaa_O2](ERROR): Communications Fault
2018-12-18T00:51:18.825Z,1545094278.825 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:18.838Z,1545094278.838 [CBIT](INFO): Critical error at 20181218T005118
2018-12-18T00:51:18.841Z,1545094278.841 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-18T00:51:19.337Z,1545094279.337 [Aanderaa_O2](INFO): Powering down
2018-12-18T00:51:19.417Z,1545094279.417 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:19.625Z,1545094279.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:20.025Z,1545094280.025 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:20.194Z,1545094280.194 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-18T00:51:20.194Z,1545094280.194 [Aanderaa_O2] No Fault, FailCount= 1
2018-12-18T00:51:20.425Z,1545094280.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:20.837Z,1545094280.837 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:21.225Z,1545094281.225 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:21.625Z,1545094281.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:22.025Z,1545094282.025 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:22.329Z,1545094282.329 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-18T00:51:22.329Z,1545094282.329 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-18T00:51:22.329Z,1545094282.329 [CTD_Seabird](ERROR): Communications Fault
2018-12-18T00:51:22.425Z,1545094282.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:22.438Z,1545094282.438 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-18T00:51:22.733Z,1545094282.733 [CTD_Seabird](INFO): Powering down
2018-12-18T00:51:22.825Z,1545094282.825 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:23.225Z,1545094283.225 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:23.625Z,1545094283.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:24.025Z,1545094284.025 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:24.425Z,1545094284.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:24.825Z,1545094284.825 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:24.837Z,1545094284.837 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-18T00:51:24.837Z,1545094284.837 [CTD_Seabird] No Fault, FailCount= 1
2018-12-18T00:51:25.225Z,1545094285.225 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:25.633Z,1545094285.633 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:25.701Z,1545094285.701 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-18T00:51:25.709Z,1545094285.709 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-18T00:51:26.037Z,1545094286.037 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:26.425Z,1545094286.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:26.825Z,1545094286.825 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:27.225Z,1545094287.225 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:27.625Z,1545094287.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:28.049Z,1545094288.049 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:28.425Z,1545094288.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:28.873Z,1545094288.873 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:29.225Z,1545094289.225 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:29.625Z,1545094289.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:30.033Z,1545094290.033 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:30.425Z,1545094290.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:30.817Z,1545094290.817 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-18T00:51:30.817Z,1545094290.817 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-12-18T00:51:30.818Z,1545094290.818 [Aanderaa_O2](ERROR): Communications Fault
2018-12-18T00:51:30.825Z,1545094290.825 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:30.839Z,1545094290.839 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-18T00:51:31.337Z,1545094291.337 [Aanderaa_O2](INFO): Powering down
2018-12-18T00:51:31.345Z,1545094291.345 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:31.625Z,1545094291.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:32.025Z,1545094292.025 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:32.051Z,1545094292.051 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-18T00:51:32.051Z,1545094292.051 [Aanderaa_O2] No Fault, FailCount= 2
2018-12-18T00:51:32.441Z,1545094292.441 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:32.837Z,1545094292.837 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:32.849Z,1545094292.849 [SBIT](IMPORTANT): Beginning Startup BIT
2018-12-18T00:51:32.877Z,1545094292.877 [CBIT](IMPORTANT): Beginning ground fault scan
2018-12-18T00:51:33.225Z,1545094293.225 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:33.625Z,1545094293.625 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:34.025Z,1545094294.025 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:34.425Z,1545094294.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:34.825Z,1545094294.825 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:35.291Z,1545094295.291 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:35.697Z,1545094295.697 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:36.097Z,1545094296.097 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:36.497Z,1545094296.497 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:36.897Z,1545094296.897 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:37.297Z,1545094297.297 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:37.697Z,1545094297.697 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:38.097Z,1545094298.097 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:38.497Z,1545094298.497 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:38.897Z,1545094298.897 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:39.297Z,1545094299.297 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:39.697Z,1545094299.697 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:39.749Z,1545094299.749 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-18T00:51:39.749Z,1545094299.749 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-18T00:51:39.749Z,1545094299.749 [CTD_Seabird](ERROR): Communications Fault
2018-12-18T00:51:39.762Z,1545094299.762 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-18T00:51:40.097Z,1545094300.097 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:40.153Z,1545094300.153 [CTD_Seabird](INFO): Powering down
2018-12-18T00:51:40.497Z,1545094300.497 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:40.897Z,1545094300.897 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:41.293Z,1545094301.293 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:41.697Z,1545094301.697 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:42.097Z,1545094302.097 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T00:51:42.150Z,1545094302.150 [CBIT]