2018-12-18T05:57:42.448Z,1545112662.448 [Supervisor](DEBUG): Initializing supervisor.
2018-12-18T05:57:42.454Z,1545112662.454 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-12-18T05:57:42.455Z,1545112662.455 [SyncHandler](INFO): Protected caller Thread ID is 805
2018-12-18T05:57:42.456Z,1545112662.456 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-12-18T05:57:42.459Z,1545112662.459 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-12-18T05:57:42.459Z,1545112662.459 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 806
2018-12-18T05:57:42.463Z,1545112662.463 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-12-18T05:57:42.474Z,1545112662.474 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-12-18T05:57:42.476Z,1545112662.476 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-12-18T05:57:42.476Z,1545112662.476 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 807
2018-12-18T05:57:42.477Z,1545112662.477 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-12-18T05:57:42.478Z,1545112662.478 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-12-18T05:57:42.479Z,1545112662.479 [logger ThreadHandler](INFO): Protected caller Thread ID is 808
2018-12-18T05:57:42.482Z,1545112662.482 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-12-18T05:57:42.482Z,1545112662.482 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-12-18T05:57:42.486Z,1545112662.486 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-12-18T05:57:42.596Z,1545112662.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-12-18T05:57:42.598Z,1545112662.598 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-12-18T05:57:42.716Z,1545112662.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-12-18T05:57:42.718Z,1545112662.718 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-12-18T05:57:42.943Z,1545112662.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-12-18T05:57:42.945Z,1545112662.945 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-12-18T05:57:43.370Z,1545112663.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-12-18T05:57:43.370Z,1545112663.370 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-12-18T05:57:43.755Z,1545112663.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-12-18T05:57:43.755Z,1545112663.755 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-12-18T05:57:44.267Z,1545112664.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-12-18T05:57:44.269Z,1545112664.269 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-12-18T05:57:44.797Z,1545112664.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-12-18T05:57:44.798Z,1545112664.798 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-12-18T05:57:45.289Z,1545112665.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-12-18T05:57:45.291Z,1545112665.291 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-12-18T05:57:45.386Z,1545112665.386 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-12-18T05:57:45.893Z,1545112665.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-12-18T05:57:45.896Z,1545112665.896 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-12-18T05:57:46.072Z,1545112666.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-12-18T05:57:46.073Z,1545112666.073 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-12-18T05:57:46.285Z,1545112666.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-12-18T05:57:46.286Z,1545112666.286 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-12-18T05:57:46.486Z,1545112666.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-12-18T05:57:46.487Z,1545112666.487 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-12-18T05:57:46.583Z,1545112666.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-12-18T05:57:46.584Z,1545112666.584 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-12-18T05:57:46.726Z,1545112666.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-12-18T05:57:46.728Z,1545112666.728 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-12-18T05:57:46.731Z,1545112666.731 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-12-18T05:57:46.957Z,1545112666.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-12-18T05:57:46.959Z,1545112666.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-12-18T05:57:47.062Z,1545112667.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-12-18T05:57:47.210Z,1545112667.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-12-18T05:57:47.303Z,1545112667.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-12-18T05:57:47.387Z,1545112667.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-12-18T05:57:47.543Z,1545112667.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-12-18T05:57:47.741Z,1545112667.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-12-18T05:57:47.838Z,1545112667.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-12-18T05:57:47.932Z,1545112667.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-12-18T05:57:48.029Z,1545112668.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-12-18T05:57:48.111Z,1545112668.111 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-12-18T05:57:48.121Z,1545112668.121 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-12-18T05:57:48.246Z,1545112668.246 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-12-18T05:57:48.247Z,1545112668.247 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-12-18T05:57:48.271Z,1545112668.271 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-12-18T05:57:48.271Z,1545112668.271 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-12-18T05:57:48.326Z,1545112668.326 [DepthRateCalculator] Loaded
2018-12-18T05:57:48.326Z,1545112668.326 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-12-18T05:57:48.332Z,1545112668.332 [PitchRateCalculator] Loaded
2018-12-18T05:57:48.332Z,1545112668.332 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-12-18T05:57:48.347Z,1545112668.347 [SpeedCalculator] Loaded
2018-12-18T05:57:48.348Z,1545112668.348 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-12-18T05:57:48.368Z,1545112668.368 [TempGradientCalculator] Loaded
2018-12-18T05:57:48.368Z,1545112668.368 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-12-18T05:57:48.374Z,1545112668.374 [YawRateCalculator] Loaded
2018-12-18T05:57:48.374Z,1545112668.374 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-12-18T05:57:48.412Z,1545112668.412 [ElevatorOffsetCalculator] Loaded
2018-12-18T05:57:48.412Z,1545112668.412 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-12-18T05:57:48.413Z,1545112668.413 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-12-18T05:57:48.413Z,1545112668.413 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-12-18T05:57:48.454Z,1545112668.454 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-12-18T05:57:48.454Z,1545112668.454 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-12-18T05:57:48.627Z,1545112668.627 [Module Loader](ERROR): Cannot load library: libboost_system.so.1.63.0: cannot open shared object file: No such file or directory
2018-12-18T05:57:48.627Z,1545112668.627 [Module Loader](ERROR): Could not load the module at Modules/Sensor.so
2018-12-18T05:57:48.629Z,1545112668.629 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-12-18T05:57:48.786Z,1545112668.786 [SBIT](DEBUG): Construct Startup Built In Test.
2018-12-18T05:57:48.811Z,1545112668.811 [SBIT] Loaded
2018-12-18T05:57:48.812Z,1545112668.812 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-12-18T05:57:48.812Z,1545112668.812 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-12-18T05:57:48.839Z,1545112668.839 [IBIT] Loaded
2018-12-18T05:57:48.840Z,1545112668.840 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-12-18T05:57:48.843Z,1545112668.843 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-12-18T05:57:49.218Z,1545112669.218 [CBIT] Loaded
2018-12-18T05:57:49.218Z,1545112669.218 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-12-18T05:57:49.218Z,1545112669.218 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-12-18T05:57:49.219Z,1545112669.219 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-12-18T05:57:49.532Z,1545112669.532 [CTD_Seabird] Loaded
2018-12-18T05:57:49.532Z,1545112669.532 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-12-18T05:57:49.533Z,1545112669.533 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4064D4E0
2018-12-18T05:57:49.534Z,1545112669.534 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 887
2018-12-18T05:57:49.550Z,1545112669.550 [ESPComponent] Loaded
2018-12-18T05:57:49.551Z,1545112669.551 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-12-18T05:57:49.564Z,1545112669.564 [PAR_Licor] Loaded
2018-12-18T05:57:49.564Z,1545112669.564 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-12-18T05:57:49.611Z,1545112669.611 [WetLabsBB2FL] Loaded
2018-12-18T05:57:49.611Z,1545112669.611 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-12-18T05:57:49.612Z,1545112669.612 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4067D4E0
2018-12-18T05:57:49.612Z,1545112669.612 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 888
2018-12-18T05:57:49.613Z,1545112669.613 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-12-18T05:57:49.614Z,1545112669.614 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-12-18T05:57:49.889Z,1545112669.889 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-12-18T05:57:49.890Z,1545112669.890 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-12-18T05:57:49.975Z,1545112669.975 [VerticalControl](DEBUG): Construct VerticalControl.
2018-12-18T05:57:50.065Z,1545112670.065 [VerticalControl] Loaded
2018-12-18T05:57:50.065Z,1545112670.065 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-12-18T05:57:50.066Z,1545112670.066 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-12-18T05:57:50.123Z,1545112670.123 [HorizontalControl] Loaded
2018-12-18T05:57:50.124Z,1545112670.124 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-12-18T05:57:50.124Z,1545112670.124 [SpeedControl](DEBUG): Construct SpeedControl.
2018-12-18T05:57:50.130Z,1545112670.130 [SpeedControl] Loaded
2018-12-18T05:57:50.130Z,1545112670.130 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-12-18T05:57:50.131Z,1545112670.131 [LoopControl](DEBUG): Construct LoopControl.
2018-12-18T05:57:50.131Z,1545112670.131 [LoopControl] Loaded
2018-12-18T05:57:50.131Z,1545112670.131 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-12-18T05:57:50.132Z,1545112670.132 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-12-18T05:57:50.132Z,1545112670.132 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-12-18T05:57:50.233Z,1545112670.233 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-12-18T05:57:50.234Z,1545112670.234 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-12-18T05:57:50.348Z,1545112670.348 [BuoyancyServo] Loaded
2018-12-18T05:57:50.349Z,1545112670.349 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-12-18T05:57:50.360Z,1545112670.360 [ElevatorServo] Loaded
2018-12-18T05:57:50.360Z,1545112670.360 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-12-18T05:57:50.370Z,1545112670.370 [MassServo] Loaded
2018-12-18T05:57:50.371Z,1545112670.371 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-12-18T05:57:50.381Z,1545112670.381 [RudderServo] Loaded
2018-12-18T05:57:50.382Z,1545112670.382 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-12-18T05:57:50.392Z,1545112670.392 [ThrusterServo] Loaded
2018-12-18T05:57:50.392Z,1545112670.392 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-12-18T05:57:50.393Z,1545112670.393 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-12-18T05:57:50.393Z,1545112670.393 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-12-18T05:57:50.504Z,1545112670.504 [DeadReckonUsingSpeedCalculator] Loaded
2018-12-18T05:57:50.504Z,1545112670.504 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-12-18T05:57:50.520Z,1545112670.520 [NavChart] Loaded
2018-12-18T05:57:50.521Z,1545112670.521 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-12-18T05:57:50.525Z,1545112670.525 [UniversalFixResidualReporter] Loaded
2018-12-18T05:57:50.525Z,1545112670.525 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-12-18T05:57:50.525Z,1545112670.525 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-12-18T05:57:50.529Z,1545112670.529 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-12-18T05:57:50.530Z,1545112670.530 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-12-18T05:57:50.536Z,1545112670.536 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-12-18T05:57:50.537Z,1545112670.537 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407D74E0
2018-12-18T05:57:50.537Z,1545112670.537 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 889
2018-12-18T05:57:50.542Z,1545112670.542 [Supervisor](INFO): Main Thread ID is 801
2018-12-18T05:57:50.542Z,1545112670.542 [Supervisor](DEBUG): Running supervisor.
2018-12-18T05:57:50.542Z,1545112670.542 [CommandLine ThreadHandler](INFO): Handler Thread ID is 890
2018-12-18T05:57:50.545Z,1545112670.545 [controlThread ThreadHandler](INFO): Handler Thread ID is 891
2018-12-18T05:57:50.545Z,1545112670.545 [controlThread](DEBUG): Initializing ControlThread
2018-12-18T05:57:50.546Z,1545112670.546 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-18T05:57:50.546Z,1545112670.546 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-12-18T05:57:50.547Z,1545112670.547 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-12-18T05:57:50.547Z,1545112670.547 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-12-18T05:57:50.547Z,1545112670.547 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-12-18T05:57:50.548Z,1545112670.548 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-12-18T05:57:50.548Z,1545112670.548 [SBIT](INFO): Initialize SBIT Component.
2018-12-18T05:57:50.549Z,1545112670.549 [SBIT](IMPORTANT): git: 2018-12-13
2018-12-18T05:57:50.549Z,1545112670.549 [SBIT](INFO): git hash: 8200dc43e6ef01082c531ff0fab84136a371dc2d
2018-12-18T05:57:50.549Z,1545112670.549 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-12-18T05:57:50.551Z,1545112670.551 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #7 PREEMPT Mon Dec 17 14:09:40 PST 2018
2018-12-18T05:57:50.552Z,1545112670.552 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-12-18T05:57:50.552Z,1545112670.552 [IBIT](INFO): Initialize IBIT Component.
2018-12-18T05:57:50.553Z,1545112670.553 [CBIT](DEBUG): Initialize CBIT Component.
2018-12-18T05:57:50.554Z,1545112670.554 [logger ThreadHandler](INFO): Handler Thread ID is 892
2018-12-18T05:57:50.565Z,1545112670.565 [CBIT](DEBUG): Initialized mux pins.
2018-12-18T05:57:50.565Z,1545112670.565 [CBIT](DEBUG): Initializing the watchdog timer.
2018-12-18T05:57:50.574Z,1545112670.574 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 893
2018-12-18T05:57:50.574Z,1545112670.574 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-18T05:57:50.578Z,1545112670.578 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-18T05:57:50.579Z,1545112670.579 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 895
2018-12-18T05:57:50.580Z,1545112670.580 [WetLabsBB2FL](INFO): Powering down
2018-12-18T05:57:50.593Z,1545112670.593 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-12-18T05:57:50.593Z,1545112670.593 [CBIT](DEBUG): Initializing heartbeat.
2018-12-18T05:57:50.630Z,1545112670.630 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896
2018-12-18T05:57:50.637Z,1545112670.637 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-12-18T05:57:50.637Z,1545112670.637 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-12-18T05:57:50.637Z,1545112670.637 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-12-18T05:57:50.637Z,1545112670.637 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-12-18T05:57:50.638Z,1545112670.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-12-18T05:57:50.638Z,1545112670.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-12-18T05:57:50.638Z,1545112670.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-12-18T05:57:50.638Z,1545112670.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-12-18T05:57:50.638Z,1545112670.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-12-18T05:57:50.639Z,1545112670.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-12-18T05:57:50.639Z,1545112670.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-12-18T05:57:50.639Z,1545112670.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-12-18T05:57:50.639Z,1545112670.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-12-18T05:57:50.639Z,1545112670.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-12-18T05:57:50.640Z,1545112670.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-12-18T05:57:50.640Z,1545112670.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-12-18T05:57:50.665Z,1545112670.665 [CBIT](DEBUG): Deactivating GF circuits.
2018-12-18T05:57:50.665Z,1545112670.665 [CBIT](DEBUG): Deactivating emergency mode.
2018-12-18T05:57:50.705Z,1545112670.705 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-12-18T05:57:50.707Z,1545112670.707 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-12-18T05:57:50.708Z,1545112670.708 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-12-18T05:57:50.708Z,1545112670.708 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-12-18T05:57:50.710Z,1545112670.710 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-18T05:57:50.711Z,1545112670.711 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-18T05:57:50.711Z,1545112670.711 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-18T05:57:50.711Z,1545112670.711 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-12-18T05:57:50.712Z,1545112670.712 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-12-18T05:57:50.713Z,1545112670.713 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-12-18T05:57:50.758Z,1545112670.758 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-18T05:57:50.801Z,1545112670.801 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2018-12-18T05:57:50.813Z,1545112670.813 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-12-18T05:57:51.052Z,1545112671.052 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-12-18T05:57:51.057Z,1545112671.057 [Default:A.Wait](DEBUG): Construct Wait.
2018-12-18T05:57:51.059Z,1545112671.059 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-18T05:57:51.139Z,1545112671.139 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-12-18T05:57:51.161Z,1545112671.161 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-12-18T05:57:51.167Z,1545112671.167 [Default:E.Execute](DEBUG): Construct Execute.
2018-12-18T05:57:51.180Z,1545112671.180 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2018-12-18T05:57:51.184Z,1545112671.184 [controlThread](DEBUG): Component order: CycleStarter,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-18T05:57:51.247Z,1545112671.247 [ESPComponent](INFO): powering down ESP
2018-12-18T05:57:51.690Z,1545112671.690 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:52.098Z,1545112672.098 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-18T05:57:52.109Z,1545112672.109 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-18T05:57:52.127Z,1545112672.127 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-12-18T05:57:52.133Z,1545112672.133 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-12-18T05:57:52.146Z,1545112672.146 [MassServo](DEBUG): Initializing EZServoServo.
2018-12-18T05:57:52.153Z,1545112672.153 [MassServo](DEBUG): Initializing MassServo.
2018-12-18T05:57:52.175Z,1545112672.175 [RudderServo](DEBUG): Initializing EZServoServo.
2018-12-18T05:57:52.185Z,1545112672.185 [RudderServo](DEBUG): Initializing RudderServo.
2018-12-18T05:57:52.197Z,1545112672.197 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-12-18T05:57:52.217Z,1545112672.217 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-12-18T05:57:52.427Z,1545112672.427 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:52.745Z,1545112672.745 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:53.097Z,1545112673.097 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-12-18T05:57:53.097Z,1545112673.097 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-12-18T05:57:53.097Z,1545112673.097 [BuoyancyServo] Communications Fault, FailCount= 1
2018-12-18T05:57:53.097Z,1545112673.097 [BuoyancyServo](ERROR): Communications Fault
2018-12-18T05:57:53.305Z,1545112673.305 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-12-18T05:57:53.338Z,1545112673.338 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:53.721Z,1545112673.721 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:54.121Z,1545112674.121 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:54.521Z,1545112674.521 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:54.921Z,1545112674.921 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:55.321Z,1545112675.321 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:55.351Z,1545112675.351 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-12-18T05:57:55.351Z,1545112675.351 [BuoyancyServo] No Fault, FailCount= 1
2018-12-18T05:57:55.721Z,1545112675.721 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:56.129Z,1545112676.129 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:56.562Z,1545112676.562 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:56.957Z,1545112676.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:57.357Z,1545112677.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:57.374Z,1545112677.374 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-18T05:57:57.494Z,1545112677.494 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-18T05:57:57.757Z,1545112677.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:58.157Z,1545112678.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:58.557Z,1545112678.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:58.957Z,1545112678.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:59.357Z,1545112679.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:57:59.757Z,1545112679.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:00.157Z,1545112680.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:00.557Z,1545112680.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:00.571Z,1545112680.571 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2143304%. Temp:3538824 C. ABORTING MISSION
2018-12-18T05:58:00.571Z,1545112680.571 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2018-12-18T05:58:00.957Z,1545112680.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:00.971Z,1545112680.971 [CommandLine](FAULT): Scheduling is paused
2018-12-18T05:58:00.972Z,1545112680.972 [CBIT](INFO): Critical error at 20181218T055800
2018-12-18T05:58:01.357Z,1545112681.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:01.757Z,1545112681.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:02.157Z,1545112682.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:02.557Z,1545112682.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:02.957Z,1545112682.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:03.357Z,1545112683.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:03.757Z,1545112683.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:04.157Z,1545112684.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:04.557Z,1545112684.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:04.617Z,1545112684.617 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-18T05:58:04.617Z,1545112684.617 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-18T05:58:04.617Z,1545112684.617 [CTD_Seabird](ERROR): Communications Fault
2018-12-18T05:58:04.957Z,1545112684.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:04.970Z,1545112684.970 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-18T05:58:05.021Z,1545112685.021 [CTD_Seabird](INFO): Powering down
2018-12-18T05:58:05.357Z,1545112685.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:05.757Z,1545112685.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:06.157Z,1545112686.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:06.557Z,1545112686.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:06.957Z,1545112686.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:07.357Z,1545112687.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:07.369Z,1545112687.369 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-18T05:58:07.369Z,1545112687.369 [CTD_Seabird] No Fault, FailCount= 1
2018-12-18T05:58:07.757Z,1545112687.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:07.985Z,1545112687.985 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-18T05:58:07.987Z,1545112687.987 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-18T05:58:08.157Z,1545112688.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:08.557Z,1545112688.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:08.957Z,1545112688.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:09.357Z,1545112689.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:09.757Z,1545112689.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:10.157Z,1545112690.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:10.557Z,1545112690.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:10.957Z,1545112690.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:11.357Z,1545112691.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:11.757Z,1545112691.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:12.178Z,1545112692.178 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:12.557Z,1545112692.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:12.957Z,1545112692.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:13.357Z,1545112693.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:13.757Z,1545112693.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:14.157Z,1545112694.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:14.557Z,1545112694.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:14.957Z,1545112694.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:14.968Z,1545112694.968 [SBIT](IMPORTANT): Beginning Startup BIT
2018-12-18T05:58:14.973Z,1545112694.973 [CBIT](IMPORTANT): Beginning ground fault scan
2018-12-18T05:58:15.357Z,1545112695.357 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:15.757Z,1545112695.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:16.157Z,1545112696.157 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:16.557Z,1545112696.557 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:16.957Z,1545112696.957 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:17.418Z,1545112697.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:17.817Z,1545112697.817 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:18.217Z,1545112698.217 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:18.617Z,1545112698.617 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:19.017Z,1545112699.017 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:19.417Z,1545112699.417 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:19.817Z,1545112699.817 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:20.229Z,1545112700.229 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:20.626Z,1545112700.626 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:21.017Z,1545112701.017 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:21.418Z,1545112701.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:21.817Z,1545112701.817 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:22.033Z,1545112702.033 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-18T05:58:22.033Z,1545112702.033 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-18T05:58:22.033Z,1545112702.033 [CTD_Seabird](ERROR): Communications Fault
2018-12-18T05:58:22.217Z,1545112702.217 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:22.266Z,1545112702.266 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-18T05:58:22.437Z,1545112702.437 [CTD_Seabird](INFO): Powering down
2018-12-18T05:58:22.626Z,1545112702.626 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:23.017Z,1545112703.017 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:23.417Z,1545112703.417 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:23.817Z,1545112703.817 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:24.217Z,1545112704.217 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:24.617Z,1545112704.617 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:24.666Z,1545112704.666 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-18T05:58:24.667Z,1545112704.667 [CTD_Seabird] No Fault, FailCount= 2
2018-12-18T05:58:25.017Z,1545112705.017 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:25.385Z,1545112705.385 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-18T05:58:25.393Z,1545112705.393 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-18T05:58:25.457Z,1545112705.457 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:25.814Z,1545112705.814 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:25.890Z,1545112705.890 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005424
CHAN A1 (24V): -0.010063
CHAN A2 (12V): -0.005108
CHAN A3 (5V): -0.002690
CHAN B0 (3.3V): -0.000364
CHAN B1 (3.15aV): -0.001246
CHAN B2 (3.15bV): -0.001105
CHAN B3 (GND): -0.000009
OPEN: 0.003711
Full Scale Calc: 4.765 mA, -1.589 mA
2018-12-18T05:58:26.241Z,1545112706.241 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:26.617Z,1545112706.617 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:27.018Z,1545112707.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:27.430Z,1545112707.430 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:27.817Z,1545112707.817 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:28.217Z,1545112708.217 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:28.617Z,1545112708.617 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:29.017Z,1545112709.017 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:29.417Z,1545112709.417 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:29.817Z,1545112709.817 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:30.217Z,1545112710.217 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:30.782Z,1545112710.782 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:31.177Z,1545112711.177 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:31.569Z,1545112711.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:31.969Z,1545112711.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:32.369Z,1545112712.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:32.769Z,1545112712.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:33.169Z,1545112713.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:33.569Z,1545112713.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:33.969Z,1545112713.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:34.369Z,1545112714.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:34.774Z,1545112714.774 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:35.170Z,1545112715.170 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:35.569Z,1545112715.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:35.969Z,1545112715.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:36.369Z,1545112716.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:36.769Z,1545112716.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:37.169Z,1545112717.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:37.569Z,1545112717.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:37.969Z,1545112717.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:38.369Z,1545112718.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:38.769Z,1545112718.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:39.169Z,1545112719.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:39.445Z,1545112719.445 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-18T05:58:39.445Z,1545112719.445 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-18T05:58:39.445Z,1545112719.445 [CTD_Seabird](ERROR): Communications Fault
2018-12-18T05:58:39.565Z,1545112719.565 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:39.632Z,1545112719.632 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-18T05:58:39.633Z,1545112719.633 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-18T05:58:39.849Z,1545112719.849 [CTD_Seabird](INFO): Powering down
2018-12-18T05:58:39.970Z,1545112719.970 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:40.369Z,1545112720.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:40.769Z,1545112720.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:41.169Z,1545112721.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:41.569Z,1545112721.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:41.973Z,1545112721.973 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:42.374Z,1545112722.374 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:42.769Z,1545112722.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:43.169Z,1545112723.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:43.569Z,1545112723.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:43.969Z,1545112723.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:44.369Z,1545112724.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:44.769Z,1545112724.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:45.169Z,1545112725.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:45.569Z,1545112725.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:45.969Z,1545112725.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:46.369Z,1545112726.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:46.769Z,1545112726.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:47.169Z,1545112727.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:47.569Z,1545112727.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:47.969Z,1545112727.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:48.369Z,1545112728.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:48.769Z,1545112728.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:49.169Z,1545112729.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:49.569Z,1545112729.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:49.969Z,1545112729.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:50.369Z,1545112730.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:50.726Z,1545112730.726 [CommandLine](IMPORTANT): got command quit
2018-12-18T05:58:50.769Z,1545112730.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:51.169Z,1545112731.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:51.569Z,1545112731.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:51.732Z,1545112731.732 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T05:58:51.732Z,1545112731.732 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:51.740Z,1545112731.740 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-12-18T05:58:51.740Z,1545112731.740 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:51.741Z,1545112731.741 [CommandLine](INFO): Join timeout helper Thread ID is 912
2018-12-18T05:58:51.753Z,1545112731.753 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-12-18T05:58:51.753Z,1545112731.753 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:51.754Z,1545112731.754 [NavChartDb](INFO): Join timeout helper Thread ID is 913
2018-12-18T05:58:51.969Z,1545112731.969 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:52.369Z,1545112732.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:52.769Z,1545112732.769 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:53.170Z,1545112733.170 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:53.470Z,1545112733.470 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T05:58:53.470Z,1545112733.470 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.489Z,1545112733.489 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-12-18T05:58:53.489Z,1545112733.489 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.489Z,1545112733.489 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 914
2018-12-18T05:58:53.569Z,1545112733.569 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T05:58:53.673Z,1545112733.673 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T05:58:53.673Z,1545112733.673 [WetLabsBB2FL](INFO): Powering down
2018-12-18T05:58:53.674Z,1545112733.674 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.677Z,1545112733.677 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-12-18T05:58:53.677Z,1545112733.677 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.678Z,1545112733.678 [CTD_Seabird](INFO): Join timeout helper Thread ID is 915
2018-12-18T05:58:53.713Z,1545112733.713 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T05:58:53.713Z,1545112733.713 [CTD_Seabird](INFO): Powering down
2018-12-18T05:58:53.725Z,1545112733.725 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.737Z,1545112733.737 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-12-18T05:58:53.737Z,1545112733.737 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.738Z,1545112733.738 [logger](INFO): Join timeout helper Thread ID is 916
2018-12-18T05:58:53.781Z,1545112733.781 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T05:58:53.781Z,1545112733.781 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.798Z,1545112733.798 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-12-18T05:58:53.798Z,1545112733.798 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.798Z,1545112733.798 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-12-18T05:58:53.798Z,1545112733.798 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:53.799Z,1545112733.799 [controlThread](INFO): Join timeout helper Thread ID is 917
2018-12-18T05:58:53.957Z,1545112733.957 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T05:58:53.957Z,1545112733.957 [controlThread](DEBUG): Uninitializing ControlThread
2018-12-18T05:58:53.959Z,1545112733.959 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2018-12-18T05:58:53.960Z,1545112733.960 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-12-18T05:58:53.961Z,1545112733.961 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-12-18T05:58:53.961Z,1545112733.961 [MissionManager](INFO): Uninitializing Mission Default
2018-12-18T05:58:53.964Z,1545112733.964 [MissionManager](INFO): Uninitializing Mission Startup
2018-12-18T05:58:53.965Z,1545112733.965 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-12-18T05:58:53.965Z,1545112733.965 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-12-18T05:58:53.965Z,1545112733.965 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-12-18T05:58:53.965Z,1545112733.965 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-12-18T05:58:53.966Z,1545112733.966 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-12-18T05:58:53.966Z,1545112733.966 [BuoyancyServo](INFO): Powering down
2018-12-18T05:58:53.977Z,1545112733.977 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-12-18T05:58:53.977Z,1545112733.977 [ElevatorServo](INFO): Powering down
2018-12-18T05:58:53.978Z,1545112733.978 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-12-18T05:58:53.978Z,1545112733.978 [MassServo](INFO): Powering down
2018-12-18T05:58:53.979Z,1545112733.979 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-12-18T05:58:53.979Z,1545112733.979 [RudderServo](INFO): Powering down
2018-12-18T05:58:53.980Z,1545112733.980 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-12-18T05:58:53.980Z,1545112733.980 [ThrusterServo](INFO): Powering down
2018-12-18T05:58:53.981Z,1545112733.981 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-12-18T05:58:53.981Z,1545112733.981 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-12-18T05:58:53.981Z,1545112733.981 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-12-18T05:58:53.982Z,1545112733.982 [CBIT](DEBUG): Powering off loads.
2018-12-18T05:58:53.993Z,1545112733.993 [CBIT](DEBUG): Disabling WDT.
2018-12-18T05:58:54.005Z,1545112734.005 [CBIT](DEBUG): Opening all GF detection circuits.
2018-12-18T05:58:54.006Z,1545112734.006 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:54.046Z,1545112734.046 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:54.051Z,1545112734.051 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:54.094Z,1545112734.094 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-18T05:58:54.148Z,1545112734.148 [logger ThreadHandler](INFO): Thread cancelled.