2018-10-31T23:04:27.795Z,1541027067.795 [Supervisor](DEBUG): Initializing supervisor.
2018-10-31T23:04:27.798Z,1541027067.798 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-31T23:04:27.798Z,1541027067.798 [SyncHandler](INFO): Protected caller Thread ID is 1945
2018-10-31T23:04:27.799Z,1541027067.799 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-31T23:04:27.800Z,1541027067.800 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-31T23:04:27.800Z,1541027067.800 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1946
2018-10-31T23:04:27.803Z,1541027067.803 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-31T23:04:27.814Z,1541027067.814 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-31T23:04:27.815Z,1541027067.815 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-31T23:04:27.816Z,1541027067.816 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1947
2018-10-31T23:04:27.817Z,1541027067.817 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-31T23:04:27.818Z,1541027067.818 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-31T23:04:27.818Z,1541027067.818 [logger ThreadHandler](INFO): Protected caller Thread ID is 1948
2018-10-31T23:04:27.820Z,1541027067.820 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-31T23:04:27.820Z,1541027067.820 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-31T23:04:27.822Z,1541027067.822 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-31T23:04:27.918Z,1541027067.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-31T23:04:27.918Z,1541027067.918 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-31T23:04:28.017Z,1541027068.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-31T23:04:28.018Z,1541027068.018 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-31T23:04:28.214Z,1541027068.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-31T23:04:28.214Z,1541027068.214 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-31T23:04:28.546Z,1541027068.546 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-31T23:04:28.546Z,1541027068.546 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-31T23:04:28.885Z,1541027068.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-31T23:04:28.886Z,1541027068.886 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-31T23:04:29.339Z,1541027069.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-31T23:04:29.340Z,1541027069.340 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-31T23:04:29.800Z,1541027069.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-31T23:04:29.800Z,1541027069.800 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-31T23:04:30.245Z,1541027070.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-31T23:04:30.246Z,1541027070.246 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-31T23:04:30.326Z,1541027070.326 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-31T23:04:30.646Z,1541027070.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-31T23:04:30.646Z,1541027070.646 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-31T23:04:30.793Z,1541027070.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-31T23:04:30.794Z,1541027070.794 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-31T23:04:31.007Z,1541027071.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-31T23:04:31.008Z,1541027071.008 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-31T23:04:31.212Z,1541027071.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-31T23:04:31.212Z,1541027071.212 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-31T23:04:31.310Z,1541027071.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-31T23:04:31.311Z,1541027071.311 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-31T23:04:31.455Z,1541027071.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-31T23:04:31.457Z,1541027071.457 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-10-31T23:04:31.458Z,1541027071.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-10-31T23:04:31.686Z,1541027071.686 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-31T23:04:31.686Z,1541027071.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-10-31T23:04:31.790Z,1541027071.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-10-31T23:04:31.938Z,1541027071.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-10-31T23:04:32.030Z,1541027072.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-10-31T23:04:32.130Z,1541027072.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-10-31T23:04:32.346Z,1541027072.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-10-31T23:04:32.576Z,1541027072.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-10-31T23:04:32.714Z,1541027072.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-10-31T23:04:32.946Z,1541027072.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-10-31T23:04:33.152Z,1541027073.152 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-10-31T23:04:33.271Z,1541027073.271 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-31T23:04:33.519Z,1541027073.519 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-31T23:04:33.748Z,1541027073.748 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-31T23:04:33.748Z,1541027073.748 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-31T23:04:33.761Z,1541027073.761 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-31T23:04:33.762Z,1541027073.762 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-31T23:04:33.804Z,1541027073.804 [DepthRateCalculator] Loaded
2018-10-31T23:04:33.804Z,1541027073.804 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-31T23:04:33.810Z,1541027073.810 [PitchRateCalculator] Loaded
2018-10-31T23:04:33.810Z,1541027073.810 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-31T23:04:33.826Z,1541027073.826 [SpeedCalculator] Loaded
2018-10-31T23:04:33.826Z,1541027073.826 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-31T23:04:33.847Z,1541027073.847 [TempGradientCalculator] Loaded
2018-10-31T23:04:33.847Z,1541027073.847 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-31T23:04:33.853Z,1541027073.853 [YawRateCalculator] Loaded
2018-10-31T23:04:33.853Z,1541027073.853 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-31T23:04:33.893Z,1541027073.893 [ElevatorOffsetCalculator] Loaded
2018-10-31T23:04:33.893Z,1541027073.893 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-31T23:04:33.893Z,1541027073.893 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-31T23:04:33.894Z,1541027073.894 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-31T23:04:33.919Z,1541027073.919 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-31T23:04:33.920Z,1541027073.920 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-31T23:04:34.210Z,1541027074.210 [DataOverHttps] Loaded
2018-10-31T23:04:34.210Z,1541027074.210 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-31T23:04:34.236Z,1541027074.236 [Depth_Keller] Loaded
2018-10-31T23:04:34.236Z,1541027074.236 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-31T23:04:34.335Z,1541027074.335 [NAL9602] Loaded
2018-10-31T23:04:34.335Z,1541027074.335 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-31T23:04:34.352Z,1541027074.352 [Onboard] Loaded
2018-10-31T23:04:34.352Z,1541027074.352 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-31T23:04:34.362Z,1541027074.362 [Radio_Surface] Loaded
2018-10-31T23:04:34.362Z,1541027074.362 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-31T23:04:34.363Z,1541027074.363 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0
2018-10-31T23:04:34.364Z,1541027074.364 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2028
2018-10-31T23:04:34.365Z,1541027074.365 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-31T23:04:34.365Z,1541027074.365 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-31T23:04:34.495Z,1541027074.495 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-31T23:04:34.519Z,1541027074.519 [SBIT] Loaded
2018-10-31T23:04:34.519Z,1541027074.519 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-31T23:04:34.520Z,1541027074.520 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-31T23:04:34.532Z,1541027074.532 [IBIT] Loaded
2018-10-31T23:04:34.533Z,1541027074.533 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-31T23:04:34.536Z,1541027074.536 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-31T23:04:34.680Z,1541027074.680 [CBIT] Loaded
2018-10-31T23:04:34.680Z,1541027074.680 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-31T23:04:34.680Z,1541027074.680 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-31T23:04:34.681Z,1541027074.681 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-31T23:04:34.840Z,1541027074.840 [PAR_Licor] Loaded
2018-10-31T23:04:34.840Z,1541027074.840 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-10-31T23:04:34.841Z,1541027074.841 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-31T23:04:34.842Z,1541027074.842 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-31T23:04:35.087Z,1541027075.087 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-31T23:04:35.088Z,1541027075.088 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-31T23:04:35.156Z,1541027075.156 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-31T23:04:35.250Z,1541027075.250 [VerticalControl] Loaded
2018-10-31T23:04:35.250Z,1541027075.250 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-31T23:04:35.251Z,1541027075.251 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-31T23:04:35.311Z,1541027075.311 [HorizontalControl] Loaded
2018-10-31T23:04:35.311Z,1541027075.311 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-31T23:04:35.311Z,1541027075.311 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-31T23:04:35.317Z,1541027075.317 [SpeedControl] Loaded
2018-10-31T23:04:35.317Z,1541027075.317 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-31T23:04:35.318Z,1541027075.318 [LoopControl](DEBUG): Construct LoopControl.
2018-10-31T23:04:35.318Z,1541027075.318 [LoopControl] Loaded
2018-10-31T23:04:35.318Z,1541027075.318 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-31T23:04:35.319Z,1541027075.319 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-31T23:04:35.319Z,1541027075.319 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-31T23:04:35.385Z,1541027075.385 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-31T23:04:35.386Z,1541027075.386 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-31T23:04:35.486Z,1541027075.486 [BuoyancyServo] Loaded
2018-10-31T23:04:35.486Z,1541027075.486 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-31T23:04:35.497Z,1541027075.497 [ElevatorServo] Loaded
2018-10-31T23:04:35.498Z,1541027075.498 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-31T23:04:35.508Z,1541027075.508 [MassServo] Loaded
2018-10-31T23:04:35.508Z,1541027075.508 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-31T23:04:35.519Z,1541027075.519 [RudderServo] Loaded
2018-10-31T23:04:35.520Z,1541027075.520 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-31T23:04:35.530Z,1541027075.530 [ThrusterServo] Loaded
2018-10-31T23:04:35.531Z,1541027075.531 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-31T23:04:35.531Z,1541027075.531 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-31T23:04:35.531Z,1541027075.531 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-31T23:04:35.620Z,1541027075.620 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-31T23:04:35.621Z,1541027075.621 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-31T23:04:35.625Z,1541027075.625 [UniversalFixResidualReporter] Loaded
2018-10-31T23:04:35.625Z,1541027075.625 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-31T23:04:35.626Z,1541027075.626 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-31T23:04:35.629Z,1541027075.629 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-31T23:04:35.630Z,1541027075.630 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-31T23:04:35.637Z,1541027075.637 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-31T23:04:35.638Z,1541027075.638 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409B64E0
2018-10-31T23:04:35.638Z,1541027075.638 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2029
2018-10-31T23:04:35.643Z,1541027075.643 [Supervisor](INFO): Main Thread ID is 1944
2018-10-31T23:04:35.643Z,1541027075.643 [Supervisor](DEBUG): Running supervisor.
2018-10-31T23:04:35.643Z,1541027075.643 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2030
2018-10-31T23:04:35.646Z,1541027075.646 [controlThread ThreadHandler](INFO): Handler Thread ID is 2031
2018-10-31T23:04:35.646Z,1541027075.646 [controlThread](DEBUG): Initializing ControlThread
2018-10-31T23:04:35.647Z,1541027075.647 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-31T23:04:35.647Z,1541027075.647 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-31T23:04:35.648Z,1541027075.648 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-31T23:04:35.648Z,1541027075.648 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-31T23:04:35.649Z,1541027075.649 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-31T23:04:35.649Z,1541027075.649 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-31T23:04:35.654Z,1541027075.654 [SBIT](INFO): Initialize SBIT Component.
2018-10-31T23:04:35.654Z,1541027075.654 [SBIT](IMPORTANT): git: 2018-10-09-38-g0a819b2
2018-10-31T23:04:35.655Z,1541027075.655 [SBIT](INFO): git hash: 0a819b2ae8942869f942095d460fcd78d0b2894d
2018-10-31T23:04:35.655Z,1541027075.655 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-31T23:04:35.655Z,1541027075.655 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-31T23:04:35.656Z,1541027075.656 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-10-31T23:04:35.657Z,1541027075.657 [IBIT](INFO): Initialize IBIT Component.
2018-10-31T23:04:35.658Z,1541027075.658 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-31T23:04:35.658Z,1541027075.658 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-31T23:04:35.659Z,1541027075.659 [logger ThreadHandler](INFO): Handler Thread ID is 2032
2018-10-31T23:04:35.678Z,1541027075.678 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2033
2018-10-31T23:04:35.683Z,1541027075.683 [Radio_Surface](INFO): Powering up
2018-10-31T23:04:35.690Z,1541027075.690 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2034
2018-10-31T23:04:35.693Z,1541027075.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-31T23:04:35.693Z,1541027075.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-31T23:04:35.693Z,1541027075.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-31T23:04:35.694Z,1541027075.694 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-31T23:04:35.694Z,1541027075.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-31T23:04:35.694Z,1541027075.694 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-31T23:04:35.694Z,1541027075.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-31T23:04:35.694Z,1541027075.694 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-31T23:04:35.695Z,1541027075.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-31T23:04:35.695Z,1541027075.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-31T23:04:35.695Z,1541027075.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-31T23:04:35.695Z,1541027075.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-31T23:04:35.695Z,1541027075.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-31T23:04:35.695Z,1541027075.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-31T23:04:35.696Z,1541027075.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-31T23:04:35.696Z,1541027075.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-31T23:04:35.769Z,1541027075.769 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-31T23:04:35.771Z,1541027075.771 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-31T23:04:35.772Z,1541027075.772 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-31T23:04:35.772Z,1541027075.772 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-31T23:04:35.774Z,1541027075.774 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-31T23:04:35.775Z,1541027075.775 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-31T23:04:35.775Z,1541027075.775 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-31T23:04:35.775Z,1541027075.775 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-31T23:04:35.776Z,1541027075.776 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-31T23:04:35.803Z,1541027075.803 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-31T23:04:35.835Z,1541027075.835 [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-10-31T23:04:35.836Z,1541027075.836 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-31T23:04:35.934Z,1541027075.934 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-31T23:04:35.935Z,1541027075.935 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-31T23:04:35.936Z,1541027075.936 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-31T23:04:35.951Z,1541027075.951 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-31T23:04:35.978Z,1541027075.978 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-31T23:04:35.984Z,1541027075.984 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-31T23:04:36.001Z,1541027076.000 [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-10-31T23:04:36.021Z,1541027076.021 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-31T23:04:36.054Z,1541027076.054 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-31T23:04:36.065Z,1541027076.065 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2018-10-31T23:04:36.217Z,1541027076.217 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-31T23:04:36.393Z,1541027076.393 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-31T23:04:36.402Z,1541027076.402 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-31T23:04:36.423Z,1541027076.423 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-31T23:04:36.429Z,1541027076.429 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-31T23:04:36.435Z,1541027076.435 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-31T23:04:36.441Z,1541027076.441 [MassServo](DEBUG): Initializing MassServo.
2018-10-31T23:04:36.447Z,1541027076.447 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-31T23:04:36.453Z,1541027076.453 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-31T23:04:36.475Z,1541027076.475 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-31T23:04:36.481Z,1541027076.481 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-31T23:04:46.491Z,1541027086.491 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003999
2018-10-31T23:05:00.216Z,1541027100.216 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-31T23:05:00.221Z,1541027100.221 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-31T23:05:04.659Z,1541027104.659 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:05:11.161Z,1541027111.161 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007358
CHAN A1 (24V): -0.011470
CHAN A2 (12V): -0.005070
CHAN A3 (5V): -0.002852
CHAN B0 (3.3V): -0.001395
CHAN B1 (3.15aV): -0.001352
CHAN B2 (3.15bV): -0.001070
CHAN B3 (GND): 0.000046
OPEN: 0.003816
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-31T23:05:25.667Z,1541027125.667 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:05:25.667Z,1541027125.667 [NAL9602] Communications Fault, FailCount= 1
2018-10-31T23:05:25.667Z,1541027125.667 [NAL9602](ERROR): Communications Fault
2018-10-31T23:05:25.843Z,1541027125.843 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:05:26.058Z,1541027126.058 [NAL9602](INFO): Powering down
2018-10-31T23:05:26.998Z,1541027126.998 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:05:26.998Z,1541027126.998 [NAL9602] No Fault, FailCount= 1
2018-10-31T23:05:53.419Z,1541027153.419 [CommandLine](IMPORTANT): got command report touch platform_buoyancy_position
2018-10-31T23:05:56.302Z,1541027156.302 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:06:01.993Z,1541027161.993 [SBIT](IMPORTANT): SBIT PASSED
2018-10-31T23:06:02.130Z,1541027162.130 [CommandLine](IMPORTANT): got command configSet list
2018-10-31T23:06:02.130Z,1541027162.130 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-31T23:06:02.131Z,1541027162.131 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2018-10-31T23:06:02.359Z,1541027162.359 [MissionManager](IMPORTANT): Started mission Startup
2018-10-31T23:06:02.359Z,1541027162.359 [Startup] Running Loop=1
2018-10-31T23:06:02.359Z,1541027162.359 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-31T23:06:02.359Z,1541027162.359 [Startup:A.GoToSurface] Running Loop=1
2018-10-31T23:06:02.359Z,1541027162.359 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-31T23:06:02.360Z,1541027162.360 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-31T23:06:02.360Z,1541027162.360 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-31T23:06:02.361Z,1541027162.361 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-31T23:06:02.361Z,1541027162.361 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-31T23:06:02.362Z,1541027162.362 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-31T23:06:02.399Z,1541027162.399 [Startup:StartupSatComms] Running Loop=1
2018-10-31T23:06:02.399Z,1541027162.399 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-31T23:06:02.399Z,1541027162.399 [Startup:StartupSatComms:A] Running Loop=1
2018-10-31T23:06:02.744Z,1541027162.744 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-31T23:06:17.103Z,1541027177.103 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:06:17.103Z,1541027177.103 [NAL9602] Communications Fault, FailCount= 2
2018-10-31T23:06:17.103Z,1541027177.103 [NAL9602](ERROR): Communications Fault
2018-10-31T23:06:17.276Z,1541027177.276 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:06:17.506Z,1541027177.506 [NAL9602](INFO): Powering down
2018-10-31T23:06:18.517Z,1541027178.517 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:06:18.517Z,1541027178.517 [NAL9602] No Fault, FailCount= 2
2018-10-31T23:06:28.147Z,1541027188.147 [CommandLine](IMPORTANT): got command report touch BuoyancyServo.component_avgCurrent
2018-10-31T23:06:35.937Z,1541027195.937 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-31T23:06:35.957Z,1541027195.957 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-31T23:06:35.958Z,1541027195.958 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-31T23:06:36.000Z,1541027196.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-31T23:06:36.345Z,1541027196.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-31T23:06:36.345Z,1541027196.345 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-31T23:06:36.741Z,1541027196.741 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-31T23:06:36.742Z,1541027196.742 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-31T23:06:36.742Z,1541027196.742 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-31T23:06:47.567Z,1541027207.567 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:06:53.407Z,1541027213.407 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100.000000 cubic_centimeter
2018-10-31T23:06:53.409Z,1541027213.409 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2018-10-31T23:06:53.607Z,1541027213.607 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-31T23:06:58.485Z,1541027218.485 [Reporter](INFO): platform_buoyancy_position 956.608914 cc
2018-10-31T23:06:58.854Z,1541027218.854 [Reporter](INFO): platform_buoyancy_position 956.608914 cc
2018-10-31T23:06:59.255Z,1541027219.255 [Reporter](INFO): platform_buoyancy_position 956.474629 cc
2018-10-31T23:06:59.644Z,1541027219.644 [Reporter](INFO): platform_buoyancy_position 945.864886 cc
2018-10-31T23:07:00.054Z,1541027220.054 [Reporter](INFO): platform_buoyancy_position 943.581806 cc
2018-10-31T23:07:00.455Z,1541027220.455 [Reporter](INFO): platform_buoyancy_position 941.298727 cc
2018-10-31T23:07:00.456Z,1541027220.456 [Reporter](INFO): BuoyancyServo.component_avgCurrent 146.799996 mA
2018-10-31T23:07:00.851Z,1541027220.851 [Reporter](INFO): platform_buoyancy_position 937.269651 cc
2018-10-31T23:07:01.255Z,1541027221.255 [Reporter](INFO): platform_buoyancy_position 932.972063 cc
2018-10-31T23:07:01.666Z,1541027221.666 [Reporter](INFO): platform_buoyancy_position 928.405905 cc
2018-10-31T23:07:02.052Z,1541027222.052 [Reporter](INFO): platform_buoyancy_position 922.228035 cc
2018-10-31T23:07:02.400Z,1541027222.400 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-31T23:06:02.4Z
2018-10-31T23:07:02.400Z,1541027222.400 [Startup:StartupSatComms:A] Stopped
2018-10-31T23:07:02.400Z,1541027222.400 [Startup:StartupSatComms:B] Running Loop=1
2018-10-31T23:07:02.457Z,1541027222.457 [Reporter](INFO): platform_buoyancy_position 917.796162 cc
2018-10-31T23:07:02.803Z,1541027222.803 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-31T23:07:02.877Z,1541027222.877 [Reporter](INFO): platform_buoyancy_position 911.484065 cc
2018-10-31T23:07:02.877Z,1541027222.877 [Reporter](INFO): BuoyancyServo.component_avgCurrent 396.360010 mA
2018-10-31T23:07:04.623Z,1541027224.623 [Reporter](INFO): platform_buoyancy_position 889.861782 cc
2018-10-31T23:07:04.731Z,1541027224.731 [Reporter](INFO): platform_buoyancy_position 887.712929 cc
2018-10-31T23:07:07.827Z,1541027227.827 [Reporter](INFO): platform_buoyancy_position 847.020012 cc
2018-10-31T23:07:07.850Z,1541027227.850 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181024T163849/Courier0118.lzma
2018-10-31T23:07:07.951Z,1541027227.951 [Reporter](INFO): platform_buoyancy_position 846.617098 cc
2018-10-31T23:07:08.334Z,1541027228.334 [Reporter](INFO): platform_buoyancy_position 840.573688 cc
2018-10-31T23:07:08.636Z,1541027228.636 [DataOverHttps](INFO): Moved sent file to Logs/20181024T163849/Courier0118.lzma.bak
2018-10-31T23:07:08.636Z,1541027228.636 [DataOverHttps](INFO): SBD MOMSN=8760134
2018-10-31T23:07:08.643Z,1541027228.643 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:07:08.643Z,1541027228.643 [NAL9602] Communications Fault, FailCount= 3
2018-10-31T23:07:08.643Z,1541027228.643 [NAL9602](ERROR): Communications Fault
2018-10-31T23:07:08.759Z,1541027228.759 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:07:08.761Z,1541027228.761 [Reporter](INFO): platform_buoyancy_position 834.664446 cc
2018-10-31T23:07:08.762Z,1541027228.762 [Reporter](INFO): BuoyancyServo.component_avgCurrent 565.180004 mA
2018-10-31T23:07:09.759Z,1541027229.759 [NAL9602](INFO): Powering down
2018-10-31T23:07:09.843Z,1541027229.843 [Reporter](INFO): platform_buoyancy_position 821.100140 cc
2018-10-31T23:07:09.949Z,1541027229.949 [Reporter](INFO): platform_buoyancy_position 819.085632 cc
2018-10-31T23:07:13.006Z,1541027233.006 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:07:13.006Z,1541027233.006 [NAL9602] No Fault, FailCount= 3
2018-10-31T23:07:13.008Z,1541027233.008 [Reporter](INFO): platform_buoyancy_position 778.124144 cc
2018-10-31T23:07:13.132Z,1541027233.132 [Reporter](INFO): platform_buoyancy_position 778.124144 cc
2018-10-31T23:07:13.518Z,1541027233.518 [Reporter](INFO): platform_buoyancy_position 771.946332 cc
2018-10-31T23:07:13.903Z,1541027233.903 [Reporter](INFO): platform_buoyancy_position 767.648744 cc
2018-10-31T23:07:13.904Z,1541027233.904 [Reporter](INFO): BuoyancyServo.component_avgCurrent 590.870023 mA
2018-10-31T23:07:14.348Z,1541027234.348 [Reporter](INFO): platform_buoyancy_position 761.068077 cc
2018-10-31T23:07:14.718Z,1541027234.718 [Reporter](INFO): platform_buoyancy_position 756.770431 cc
2018-10-31T23:07:15.146Z,1541027235.146 [Reporter](INFO): platform_buoyancy_position 752.069929 cc
2018-10-31T23:07:15.512Z,1541027235.512 [Reporter](INFO): platform_buoyancy_position 745.892175 cc
2018-10-31T23:07:22.594Z,1541027242.594 [Reporter](INFO): platform_buoyancy_position 653.628027 cc
2018-10-31T23:07:22.595Z,1541027242.595 [Reporter](INFO): BuoyancyServo.component_avgCurrent 568.850040 mA
2018-10-31T23:07:22.616Z,1541027242.616 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20181024T232007/Courier0000.lzma
2018-10-31T23:07:22.764Z,1541027242.764 [Reporter](INFO): platform_buoyancy_position 651.344890 cc
2018-10-31T23:07:23.151Z,1541027243.151 [Reporter](INFO): platform_buoyancy_position 647.047302 cc
2018-10-31T23:07:23.407Z,1541027243.407 [DataOverHttps](INFO): Moved sent file to Logs/20181024T232007/Courier0000.lzma.bak
2018-10-31T23:07:23.407Z,1541027243.407 [DataOverHttps](INFO): SBD MOMSN=8760136
2018-10-31T23:07:23.536Z,1541027243.536 [Reporter](INFO): platform_buoyancy_position 640.735205 cc
2018-10-31T23:07:25.728Z,1541027245.728 [Reporter](INFO): platform_buoyancy_position 612.666481 cc
2018-10-31T23:07:25.890Z,1541027245.890 [Reporter](INFO): platform_buoyancy_position 610.517687 cc
2018-10-31T23:07:30.212Z,1541027250.212 [Reporter](INFO): platform_buoyancy_position 554.648926 cc
2018-10-31T23:07:30.212Z,1541027250.212 [Reporter](INFO): BuoyancyServo.component_avgCurrent 572.520018 mA
2018-10-31T23:07:30.357Z,1541027250.357 [Reporter](INFO): platform_buoyancy_position 552.634418 cc
2018-10-31T23:07:30.729Z,1541027250.729 [Reporter](INFO): platform_buoyancy_position 548.202486 cc
2018-10-31T23:07:31.130Z,1541027251.130 [Reporter](INFO): platform_buoyancy_position 541.890447 cc
2018-10-31T23:07:31.519Z,1541027251.519 [Reporter](INFO): platform_buoyancy_position 537.592859 cc
2018-10-31T23:07:31.929Z,1541027251.929 [Reporter](INFO): platform_buoyancy_position 533.295213 cc
2018-10-31T23:07:32.315Z,1541027252.315 [Reporter](INFO): platform_buoyancy_position 528.460427 cc
2018-10-31T23:07:32.316Z,1541027252.316 [Reporter](INFO): BuoyancyServo.component_avgCurrent 565.180004 mA
2018-10-31T23:07:32.715Z,1541027252.715 [Reporter](INFO): platform_buoyancy_position 522.551185 cc
2018-10-31T23:07:39.635Z,1541027259.635 [Reporter](INFO): platform_buoyancy_position 430.421409 cc
2018-10-31T23:07:39.676Z,1541027259.676 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20181024T232007/Courier0004.lzma
2018-10-31T23:07:39.824Z,1541027259.824 [Reporter](INFO): platform_buoyancy_position 428.138330 cc
2018-10-31T23:07:40.046Z,1541027260.046 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:07:40.176Z,1541027260.176 [Reporter](INFO): platform_buoyancy_position 423.572114 cc
2018-10-31T23:07:40.447Z,1541027260.447 [DataOverHttps](INFO): Moved sent file to Logs/20181024T232007/Courier0004.lzma.bak
2018-10-31T23:07:40.447Z,1541027260.447 [DataOverHttps](INFO): SBD MOMSN=8760148
2018-10-31T23:07:40.548Z,1541027260.548 [Reporter](INFO): platform_buoyancy_position 419.274555 cc
2018-10-31T23:07:40.549Z,1541027260.549 [Reporter](INFO): BuoyancyServo.component_avgCurrent 557.840049 mA
2018-10-31T23:07:42.754Z,1541027262.754 [Reporter](INFO): platform_buoyancy_position 389.191351 cc
2018-10-31T23:07:42.925Z,1541027262.925 [Reporter](INFO): platform_buoyancy_position 387.042528 cc
2018-10-31T23:07:43.271Z,1541027263.271 [Reporter](INFO): platform_buoyancy_position 382.879254 cc
2018-10-31T23:07:46.291Z,1541027266.291 [Reporter](INFO): platform_buoyancy_position 340.037543 cc
2018-10-31T23:07:46.396Z,1541027266.396 [Reporter](INFO): platform_buoyancy_position 339.500344 cc
2018-10-31T23:07:46.783Z,1541027266.783 [Reporter](INFO): platform_buoyancy_position 335.471355 cc
2018-10-31T23:07:47.176Z,1541027267.176 [Reporter](INFO): platform_buoyancy_position 330.905110 cc
2018-10-31T23:07:47.177Z,1541027267.177 [Reporter](INFO): BuoyancyServo.component_avgCurrent 539.489985 mA
2018-10-31T23:07:47.415Z,1541027267.415 [CommandLine](IMPORTANT): got command maintain clear
2018-10-31T23:07:47.587Z,1541027267.587 [Reporter](INFO): platform_buoyancy_position 326.607551 cc
2018-10-31T23:07:47.592Z,1541027267.592 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-31T23:07:48.021Z,1541027268.021 [Reporter](INFO): platform_buoyancy_position 320.564024 cc
2018-10-31T23:07:48.423Z,1541027268.423 [Reporter](INFO): platform_buoyancy_position 316.132151 cc
2018-10-31T23:07:48.764Z,1541027268.764 [Reporter](INFO): platform_buoyancy_position 313.983328 cc
2018-10-31T23:07:51.835Z,1541027271.835 [Reporter](INFO): platform_buoyancy_position 337.754434 cc
2018-10-31T23:07:51.836Z,1541027271.836 [Reporter](INFO): BuoyancyServo.component_avgCurrent 422.050029 mA
2018-10-31T23:07:51.877Z,1541027271.877 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20181024T232007/Courier0007.lzma
2018-10-31T23:07:51.984Z,1541027271.984 [Reporter](INFO): platform_buoyancy_position 340.037543 cc
2018-10-31T23:07:52.343Z,1541027272.343 [Reporter](INFO): platform_buoyancy_position 344.200846 cc
2018-10-31T23:07:52.651Z,1541027272.651 [DataOverHttps](INFO): Moved sent file to Logs/20181024T232007/Courier0007.lzma.bak
2018-10-31T23:07:52.651Z,1541027272.651 [DataOverHttps](INFO): SBD MOMSN=8760162
2018-10-31T23:07:52.750Z,1541027272.750 [Reporter](INFO): platform_buoyancy_position 350.647257 cc
2018-10-31T23:07:53.887Z,1541027273.887 [Reporter](INFO): platform_buoyancy_position 365.554530 cc
2018-10-31T23:07:53.996Z,1541027273.996 [Reporter](INFO): platform_buoyancy_position 367.837638 cc
2018-10-31T23:07:57.039Z,1541027277.039 [Reporter](INFO): platform_buoyancy_position 408.664870 cc
2018-10-31T23:07:57.040Z,1541027277.040 [Reporter](INFO): BuoyancyServo.component_avgCurrent 480.770022 mA
2018-10-31T23:07:57.148Z,1541027277.148 [Reporter](INFO): platform_buoyancy_position 410.679349 cc
2018-10-31T23:07:57.554Z,1541027277.554 [Reporter](INFO): platform_buoyancy_position 415.111223 cc
2018-10-31T23:07:57.937Z,1541027277.937 [Reporter](INFO): platform_buoyancy_position 419.274555 cc
2018-10-31T23:07:58.328Z,1541027278.328 [Reporter](INFO): platform_buoyancy_position 425.855251 cc
2018-10-31T23:07:58.755Z,1541027278.755 [Reporter](INFO): platform_buoyancy_position 430.018525 cc
2018-10-31T23:07:59.160Z,1541027279.160 [Reporter](INFO): platform_buoyancy_position 436.599279 cc
2018-10-31T23:07:59.165Z,1541027279.165 [Reporter](INFO): BuoyancyServo.component_avgCurrent 477.100015 mA
2018-10-31T23:07:59.541Z,1541027279.541 [Reporter](INFO): platform_buoyancy_position 440.896809 cc
2018-10-31T23:08:02.514Z,1541027282.514 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:08:02.514Z,1541027282.514 [NAL9602] Communications Fault, FailCount= 4
2018-10-31T23:08:02.514Z,1541027282.514 [NAL9602](ERROR): Communications Fault
2018-10-31T23:08:02.562Z,1541027282.562 [Startup:StartupSatComms:B](INFO): Timed out from 2018-10-31T23:07:02.4Z
2018-10-31T23:08:02.562Z,1541027282.562 [Startup:StartupSatComms:B] Stopped
2018-10-31T23:08:02.563Z,1541027282.563 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-31T23:08:02.563Z,1541027282.563 [Startup:StartupSatComms] Stopped
2018-10-31T23:08:02.563Z,1541027282.563 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-31T23:08:02.564Z,1541027282.564 [Startup](INFO): Completed Startup
2018-10-31T23:08:02.564Z,1541027282.564 [MissionManager](INFO): Startup is completed.
2018-10-31T23:08:02.564Z,1541027282.564 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-31T23:08:02.564Z,1541027282.564 [Startup] Stopped
2018-10-31T23:08:02.564Z,1541027282.564 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-31T23:08:02.564Z,1541027282.564 [Startup:A.GoToSurface] Stopped
2018-10-31T23:08:02.564Z,1541027282.564 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-31T23:08:02.619Z,1541027282.619 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:08:02.620Z,1541027282.620 [Reporter](INFO): platform_buoyancy_position 481.589756 cc
2018-10-31T23:08:02.642Z,1541027282.642 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20181031T225307/Courier0000.lzma
2018-10-31T23:08:02.662Z,1541027282.662 [NAL9602](INFO): Powering down
2018-10-31T23:08:02.699Z,1541027282.699 [MissionManager](IMPORTANT): Started mission Default
2018-10-31T23:08:02.700Z,1541027282.700 [Default] Running Loop=1
2018-10-31T23:08:02.700Z,1541027282.700 [Default](DEBUG): Aggregate::initialize Default
2018-10-31T23:08:02.700Z,1541027282.700 [Default:B.GoToSurface] Running Loop=1
2018-10-31T23:08:02.700Z,1541027282.700 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-31T23:08:02.700Z,1541027282.700 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-31T23:08:02.701Z,1541027282.701 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-31T23:08:02.701Z,1541027282.701 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-31T23:08:02.701Z,1541027282.701 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-31T23:08:02.702Z,1541027282.702 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-31T23:08:02.702Z,1541027282.702 [Default:A.Wait] Running Loop=1
2018-10-31T23:08:02.702Z,1541027282.702 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-31T23:08:02.787Z,1541027282.787 [Reporter](INFO): platform_buoyancy_position 483.738550 cc
2018-10-31T23:08:03.114Z,1541027283.114 [Reporter](INFO): platform_buoyancy_position 488.036167 cc
2018-10-31T23:08:03.431Z,1541027283.431 [DataOverHttps](INFO): Moved sent file to Logs/20181031T225307/Courier0000.lzma.bak
2018-10-31T23:08:03.431Z,1541027283.431 [DataOverHttps](INFO): SBD MOMSN=8760188
2018-10-31T23:08:03.526Z,1541027283.526 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:08:03.526Z,1541027283.526 [NAL9602] No Fault, FailCount= 4
2018-10-31T23:08:03.528Z,1541027283.528 [Reporter](INFO): platform_buoyancy_position 493.273837 cc
2018-10-31T23:08:03.528Z,1541027283.528 [Reporter](INFO): BuoyancyServo.component_avgCurrent 499.119997 mA
2018-10-31T23:08:03.913Z,1541027283.913 [Reporter](INFO): platform_buoyancy_position 498.914451 cc
2018-10-31T23:08:04.331Z,1541027284.331 [Reporter](INFO): platform_buoyancy_position 503.212039 cc
2018-10-31T23:08:07.384Z,1541027287.384 [Reporter](INFO): platform_buoyancy_position 543.904898 cc
2018-10-31T23:08:07.480Z,1541027287.480 [Reporter](INFO): platform_buoyancy_position 546.053692 cc
2018-10-31T23:08:07.879Z,1541027287.879 [Reporter](INFO): platform_buoyancy_position 550.351338 cc
2018-10-31T23:08:08.271Z,1541027288.271 [Reporter](INFO): platform_buoyancy_position 554.648926 cc
2018-10-31T23:08:08.272Z,1541027288.272 [Reporter](INFO): BuoyancyServo.component_avgCurrent 491.780013 mA
2018-10-31T23:08:08.667Z,1541027288.667 [Reporter](INFO): platform_buoyancy_position 561.095309 cc
2018-10-31T23:08:09.089Z,1541027289.089 [Reporter](INFO): platform_buoyancy_position 565.527240 cc
2018-10-31T23:08:09.455Z,1541027289.455 [Reporter](INFO): platform_buoyancy_position 571.839279 cc
2018-10-31T23:08:09.867Z,1541027289.867 [Reporter](INFO): platform_buoyancy_position 576.136925 cc
2018-10-31T23:08:10.288Z,1541027290.288 [Reporter](INFO): platform_buoyancy_position 582.449022 cc
2018-10-31T23:08:10.655Z,1541027290.655 [Reporter](INFO): platform_buoyancy_position 587.015180 cc
2018-10-31T23:08:10.656Z,1541027290.656 [Reporter](INFO): BuoyancyServo.component_avgCurrent 484.439999 mA
2018-10-31T23:08:11.067Z,1541027291.067 [Reporter](INFO): platform_buoyancy_position 591.312826 cc
2018-10-31T23:08:11.484Z,1541027291.484 [Reporter](INFO): platform_buoyancy_position 597.759208 cc
2018-10-31T23:08:11.855Z,1541027291.855 [Reporter](INFO): platform_buoyancy_position 601.922569 cc
2018-10-31T23:08:12.267Z,1541027292.267 [Reporter](INFO): platform_buoyancy_position 608.368893 cc
2018-10-31T23:08:12.688Z,1541027292.688 [Reporter](INFO): platform_buoyancy_position 612.666481 cc
2018-10-31T23:08:13.055Z,1541027293.055 [Reporter](INFO): platform_buoyancy_position 617.769896 cc
2018-10-31T23:08:13.056Z,1541027293.056 [Reporter](INFO): BuoyancyServo.component_avgCurrent 550.499976 mA
2018-10-31T23:08:13.467Z,1541027293.467 [Reporter](INFO): platform_buoyancy_position 623.544795 cc
2018-10-31T23:08:13.888Z,1541027293.888 [Reporter](INFO): platform_buoyancy_position 629.722665 cc
2018-10-31T23:08:14.283Z,1541027294.283 [Reporter](INFO): platform_buoyancy_position 634.154538 cc
2018-10-31T23:08:14.667Z,1541027294.667 [Reporter](INFO): platform_buoyancy_position 638.855039 cc
2018-10-31T23:08:15.088Z,1541027295.088 [Reporter](INFO): platform_buoyancy_position 645.032851 cc
2018-10-31T23:08:15.483Z,1541027295.483 [Reporter](INFO): platform_buoyancy_position 649.330439 cc
2018-10-31T23:08:15.484Z,1541027295.484 [Reporter](INFO): BuoyancyServo.component_avgCurrent 517.470002 mA
2018-10-31T23:08:15.823Z,1541027295.823 [Default:A.Wait](INFO): Done Waiting.
2018-10-31T23:08:15.823Z,1541027295.823 [Default:A.Wait] Stopped
2018-10-31T23:08:15.823Z,1541027295.823 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-10-31T23:08:15.867Z,1541027295.867 [Reporter](INFO): platform_buoyancy_position 655.776879 cc
2018-10-31T23:08:16.240Z,1541027296.240 [Default:CheckIn] Running Loop=1
2018-10-31T23:08:16.240Z,1541027296.240 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-31T23:08:16.240Z,1541027296.240 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-31T23:08:16.280Z,1541027296.280 [Reporter](INFO): platform_buoyancy_position 659.940124 cc
2018-10-31T23:08:16.624Z,1541027296.624 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-10-31T23:08:16.686Z,1541027296.686 [Reporter](INFO): platform_buoyancy_position 666.252279 cc
2018-10-31T23:08:17.160Z,1541027297.160 [Reporter](INFO): platform_buoyancy_position 672.967231 cc
2018-10-31T23:08:17.507Z,1541027297.507 [Reporter](INFO): platform_buoyancy_position 677.130534 cc
2018-10-31T23:08:17.950Z,1541027297.950 [Reporter](INFO): platform_buoyancy_position 681.562466 cc
2018-10-31T23:08:17.950Z,1541027297.950 [Reporter](INFO): BuoyancyServo.component_avgCurrent 480.770022 mA
2018-10-31T23:08:18.279Z,1541027298.279 [Reporter](INFO): platform_buoyancy_position 685.994339 cc
2018-10-31T23:08:18.685Z,1541027298.685 [Reporter](INFO): platform_buoyancy_position 692.172092 cc
2018-10-31T23:08:19.093Z,1541027299.093 [Reporter](INFO): platform_buoyancy_position 696.469739 cc
2018-10-31T23:08:19.463Z,1541027299.463 [Reporter](INFO): platform_buoyancy_position 702.916121 cc
2018-10-31T23:08:19.887Z,1541027299.887 [Reporter](INFO): platform_buoyancy_position 707.347994 cc
2018-10-31T23:08:20.309Z,1541027300.309 [Reporter](INFO): platform_buoyancy_position 713.794434 cc
2018-10-31T23:08:20.309Z,1541027300.309 [Reporter](INFO): BuoyancyServo.component_avgCurrent 565.180004 mA
2018-10-31T23:08:20.664Z,1541027300.664 [Reporter](INFO): platform_buoyancy_position 717.957737 cc
2018-10-31T23:08:21.101Z,1541027301.101 [Reporter](INFO): platform_buoyancy_position 723.598350 cc
2018-10-31T23:08:21.474Z,1541027301.474 [Reporter](INFO): platform_buoyancy_position 728.836050 cc
2018-10-31T23:08:21.868Z,1541027301.868 [Reporter](INFO): platform_buoyancy_position 732.999353 cc
2018-10-31T23:08:22.276Z,1541027302.276 [Reporter](INFO): platform_buoyancy_position 739.445735 cc
2018-10-31T23:08:22.685Z,1541027302.685 [Reporter](INFO): platform_buoyancy_position 743.877608 cc
2018-10-31T23:08:22.686Z,1541027302.686 [Reporter](INFO): BuoyancyServo.component_avgCurrent 550.499976 mA
2018-10-31T23:08:23.056Z,1541027303.056 [Reporter](INFO): platform_buoyancy_position 748.443825 cc
2018-10-31T23:08:23.476Z,1541027303.476 [Reporter](INFO): platform_buoyancy_position 754.487352 cc
2018-10-31T23:08:23.887Z,1541027303.887 [Reporter](INFO): platform_buoyancy_position 758.784940 cc
2018-10-31T23:08:24.286Z,1541027304.286 [Reporter](INFO): platform_buoyancy_position 765.231322 cc
2018-10-31T23:08:24.684Z,1541027304.684 [Reporter](INFO): platform_buoyancy_position 769.797538 cc
2018-10-31T23:08:25.091Z,1541027305.091 [Reporter](INFO): platform_buoyancy_position 776.109635 cc
2018-10-31T23:08:25.092Z,1541027305.092 [Reporter](INFO): BuoyancyServo.component_avgCurrent 480.770022 mA
2018-10-31T23:08:25.456Z,1541027305.456 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 800.000000 cubic_centimeter
2018-10-31T23:08:25.458Z,1541027305.458 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2018-10-31T23:08:25.492Z,1541027305.492 [Reporter](INFO): platform_buoyancy_position 780.407223 cc
2018-10-31T23:08:25.497Z,1541027305.497 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-31T23:08:25.927Z,1541027305.927 [Reporter](INFO): platform_buoyancy_position 786.719378 cc
2018-10-31T23:08:26.327Z,1541027306.327 [Reporter](INFO): platform_buoyancy_position 791.151251 cc
2018-10-31T23:08:26.747Z,1541027306.747 [Reporter](INFO): BuoyancyServo.component_avgCurrent 0.000000 mA
2018-10-31T23:08:32.996Z,1541027312.996 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:08:37.021Z,1541027317.021 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-31T23:08:37.021Z,1541027317.021 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-31T23:08:37.021Z,1541027317.021 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-31T23:08:37.073Z,1541027317.073 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-31T23:08:37.437Z,1541027317.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-31T23:08:37.438Z,1541027317.438 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-31T23:08:37.816Z,1541027317.816 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-31T23:08:37.817Z,1541027317.817 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-31T23:08:37.817Z,1541027317.817 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-31T23:08:53.799Z,1541027333.799 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:08:53.799Z,1541027333.799 [NAL9602] Communications Fault, FailCount= 5
2018-10-31T23:08:53.799Z,1541027333.799 [NAL9602](ERROR): Communications Fault
2018-10-31T23:08:53.839Z,1541027333.839 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:08:53.839Z,1541027333.839 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-10-31T23:08:54.201Z,1541027334.201 [NAL9602](INFO): Powering down
2018-10-31T23:08:54.240Z,1541027334.240 [CommandLine](FAULT): Scheduling is paused
2018-10-31T23:08:54.240Z,1541027334.240 [CBIT](INFO): Critical error at 20181031T230853
2018-10-31T23:08:54.241Z,1541027334.241 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-31T23:10:10.647Z,1541027410.647 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 30.000000 millimeter
2018-10-31T23:10:10.648Z,1541027410.648 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2018-10-31T23:10:10.940Z,1541027410.940 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-31T23:10:38.165Z,1541027438.165 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-31T23:10:38.165Z,1541027438.165 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-10-31T23:10:38.165Z,1541027438.165 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-31T23:10:38.230Z,1541027438.230 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-31T23:10:38.624Z,1541027438.624 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-31T23:10:38.624Z,1541027438.624 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-10-31T23:10:38.944Z,1541027438.944 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-31T23:10:38.945Z,1541027438.945 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-31T23:10:38.945Z,1541027438.945 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-31T23:10:54.191Z,1541027454.191 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:10:54.191Z,1541027454.191 [NAL9602] No Fault, FailCount= 5
2018-10-31T23:10:54.527Z,1541027454.527 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:11:15.246Z,1541027475.246 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:11:15.246Z,1541027475.246 [NAL9602] Communications Fault, FailCount= 1
2018-10-31T23:11:15.247Z,1541027475.247 [NAL9602](ERROR): Communications Fault
2018-10-31T23:11:15.306Z,1541027475.306 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:11:15.646Z,1541027475.646 [NAL9602](INFO): Powering down
2018-10-31T23:11:16.490Z,1541027476.490 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:11:16.491Z,1541027476.491 [NAL9602] No Fault, FailCount= 1
2018-10-31T23:11:46.046Z,1541027506.046 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:12:08.305Z,1541027528.305 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:12:08.305Z,1541027528.305 [NAL9602] Communications Fault, FailCount= 2
2018-10-31T23:12:08.306Z,1541027528.306 [NAL9602](ERROR): Communications Fault
2018-10-31T23:12:08.382Z,1541027528.382 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:12:08.438Z,1541027528.438 [NAL9602](INFO): Powering down
2018-10-31T23:12:09.245Z,1541027529.245 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:12:09.245Z,1541027529.245 [NAL9602] No Fault, FailCount= 2
2018-10-31T23:12:38.792Z,1541027558.792 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:12:39.226Z,1541027559.226 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-31T23:12:39.226Z,1541027559.226 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-10-31T23:12:39.226Z,1541027559.226 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-31T23:12:39.239Z,1541027559.239 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-31T23:12:39.642Z,1541027559.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-31T23:12:39.642Z,1541027559.642 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-10-31T23:12:40.013Z,1541027560.013 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-31T23:12:40.013Z,1541027560.013 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-31T23:12:40.014Z,1541027560.014 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-31T23:12:52.163Z,1541027572.163 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100.000000 cubic_centimeter
2018-10-31T23:12:53.719Z,1541027573.719 [Reporter](INFO): platform_buoyancy_position 801.760936 cc
2018-10-31T23:12:54.081Z,1541027574.081 [Reporter](INFO): platform_buoyancy_position 801.760936 cc
2018-10-31T23:12:54.494Z,1541027574.494 [Reporter](INFO): platform_buoyancy_position 799.746485 cc
2018-10-31T23:12:54.495Z,1541027574.495 [Reporter](INFO): BuoyancyServo.component_avgCurrent 139.460012 mA
2018-10-31T23:12:54.883Z,1541027574.883 [Reporter](INFO): platform_buoyancy_position 799.477857 cc
2018-10-31T23:12:55.283Z,1541027575.283 [Reporter](INFO): platform_buoyancy_position 797.597633 cc
2018-10-31T23:12:55.694Z,1541027575.694 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:12:56.083Z,1541027576.083 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:12:56.483Z,1541027576.483 [Reporter](INFO): platform_buoyancy_position 797.597633 cc
2018-10-31T23:12:56.894Z,1541027576.894 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:12:56.895Z,1541027576.895 [Reporter](INFO): BuoyancyServo.component_avgCurrent 763.359964 mA
2018-10-31T23:12:57.283Z,1541027577.283 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:12:57.683Z,1541027577.683 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:12:58.089Z,1541027578.089 [Reporter](INFO): platform_buoyancy_position 797.597633 cc
2018-10-31T23:12:58.481Z,1541027578.481 [Reporter](INFO): platform_buoyancy_position 797.597633 cc
2018-10-31T23:12:58.888Z,1541027578.888 [Reporter](INFO): platform_buoyancy_position 797.597633 cc
2018-10-31T23:12:59.289Z,1541027579.289 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:12:59.290Z,1541027579.290 [Reporter](INFO): BuoyancyServo.component_avgCurrent 1398.270011 mA
2018-10-31T23:12:59.688Z,1541027579.688 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:12:59.998Z,1541027579.998 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:12:59.999Z,1541027579.999 [NAL9602] Communications Fault, FailCount= 3
2018-10-31T23:12:59.999Z,1541027579.999 [NAL9602](ERROR): Communications Fault
2018-10-31T23:13:00.090Z,1541027580.090 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:13:00.092Z,1541027580.092 [Reporter](INFO): platform_buoyancy_position 797.597633 cc
2018-10-31T23:13:00.394Z,1541027580.394 [NAL9602](INFO): Powering down
2018-10-31T23:13:00.487Z,1541027580.487 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:13:00.852Z,1541027580.852 [Reporter](INFO): platform_buoyancy_position 797.597633 cc
2018-10-31T23:13:01.290Z,1541027581.290 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:13:01.290Z,1541027581.290 [NAL9602] No Fault, FailCount= 3
2018-10-31T23:13:01.292Z,1541027581.292 [Reporter](INFO): platform_buoyancy_position 797.329063 cc
2018-10-31T23:13:01.663Z,1541027581.663 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:13:01.664Z,1541027581.664 [Reporter](INFO): BuoyancyServo.component_avgCurrent 1423.960090 mA
2018-10-31T23:13:02.063Z,1541027582.063 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:13:02.475Z,1541027582.475 [Reporter](INFO): platform_buoyancy_position 797.463290 cc
2018-10-31T23:13:02.859Z,1541027582.859 [Reporter](INFO): platform_buoyancy_position 797.329063 cc
2018-10-31T23:13:03.263Z,1541027583.263 [Reporter](INFO): platform_buoyancy_position 795.448839 cc
2018-10-31T23:13:03.675Z,1541027583.675 [Reporter](INFO): platform_buoyancy_position 782.556017 cc
2018-10-31T23:13:04.063Z,1541027584.063 [Reporter](INFO): platform_buoyancy_position 773.960841 cc
2018-10-31T23:13:04.064Z,1541027584.064 [Reporter](INFO): BuoyancyServo.component_avgCurrent 1449.650049 mA
2018-10-31T23:13:04.463Z,1541027584.463 [Reporter](INFO): platform_buoyancy_position 763.082528 cc
2018-10-31T23:13:04.876Z,1541027584.876 [Reporter](INFO): platform_buoyancy_position 752.472843 cc
2018-10-31T23:13:05.274Z,1541027585.274 [Reporter](INFO): platform_buoyancy_position 741.728814 cc
2018-10-31T23:13:05.664Z,1541027585.664 [Reporter](INFO): platform_buoyancy_position 732.865068 cc
2018-10-31T23:13:06.065Z,1541027586.065 [BuoyancyServo](FAULT): Overload Error
2018-10-31T23:13:06.065Z,1541027586.065 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-10-31T23:13:06.065Z,1541027586.065 [BuoyancyServo](ERROR): Hardware Fault
2018-10-31T23:13:06.107Z,1541027586.107 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-10-31T23:13:06.436Z,1541027586.436 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-31T23:13:06.437Z,1541027586.437 [BuoyancyServo](INFO): Powering down
2018-10-31T23:13:06.459Z,1541027586.459 [Reporter](INFO): BuoyancyServo.component_avgCurrent no_value
2018-10-31T23:13:09.603Z,1541027589.603 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-31T23:13:09.603Z,1541027589.603 [BuoyancyServo] No Fault, FailCount= 1
2018-10-31T23:13:09.938Z,1541027589.938 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-31T23:13:10.054Z,1541027590.054 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-31T23:13:12.743Z,1541027592.743 [Reporter](INFO): platform_buoyancy_position 728.433079 cc
2018-10-31T23:13:13.204Z,1541027593.204 [Reporter](INFO): platform_buoyancy_position 731.253414 cc
2018-10-31T23:13:13.586Z,1541027593.586 [Reporter](INFO): platform_buoyancy_position 732.462155 cc
2018-10-31T23:13:13.987Z,1541027593.987 [Reporter](INFO): platform_buoyancy_position 730.984844 cc
2018-10-31T23:13:14.390Z,1541027594.390 [Reporter](INFO): platform_buoyancy_position 722.389668 cc
2018-10-31T23:13:14.786Z,1541027594.786 [Reporter](INFO): platform_buoyancy_position 720.240816 cc
2018-10-31T23:13:15.187Z,1541027595.187 [Reporter](INFO): platform_buoyancy_position 717.420538 cc
2018-10-31T23:13:15.587Z,1541027595.587 [Reporter](INFO): platform_buoyancy_position 713.257235 cc
2018-10-31T23:13:15.588Z,1541027595.588 [Reporter](INFO): BuoyancyServo.component_avgCurrent 157.810003 mA
2018-10-31T23:13:15.983Z,1541027595.983 [Reporter](INFO): platform_buoyancy_position 707.347994 cc
2018-10-31T23:13:16.332Z,1541027596.332 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-31T23:08:16.2Z
2018-10-31T23:13:16.332Z,1541027596.332 [Default:CheckIn:Read_GPS] Stopped
2018-10-31T23:13:16.332Z,1541027596.332 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-31T23:13:16.395Z,1541027596.395 [Reporter](INFO): platform_buoyancy_position 702.916121 cc
2018-10-31T23:13:16.735Z,1541027596.735 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-31T23:13:16.791Z,1541027596.791 [Reporter](INFO): platform_buoyancy_position 698.618533 cc
2018-10-31T23:13:17.900Z,1541027597.900 [Reporter](INFO): platform_buoyancy_position 683.576916 cc
2018-10-31T23:13:18.019Z,1541027598.019 [Reporter](INFO): platform_buoyancy_position 681.562466 cc
2018-10-31T23:13:21.061Z,1541027601.061 [Reporter](INFO): platform_buoyancy_position 642.615429 cc
2018-10-31T23:13:21.062Z,1541027601.062 [Reporter](INFO): BuoyancyServo.component_avgCurrent 488.110006 mA
2018-10-31T23:13:21.072Z,1541027601.072 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20181031T225446/Courier0000.lzma
2018-10-31T23:13:21.188Z,1541027601.188 [Reporter](INFO): platform_buoyancy_position 640.466635 cc
2018-10-31T23:13:21.543Z,1541027601.543 [Reporter](INFO): platform_buoyancy_position 636.303332 cc
2018-10-31T23:13:21.871Z,1541027601.871 [DataOverHttps](INFO): Moved sent file to Logs/20181031T225446/Courier0000.lzma.bak
2018-10-31T23:13:21.871Z,1541027601.871 [DataOverHttps](INFO): SBD MOMSN=8760282
2018-10-31T23:13:21.971Z,1541027601.971 [Reporter](INFO): platform_buoyancy_position 629.991235 cc
2018-10-31T23:13:23.323Z,1541027603.323 [Reporter](INFO): platform_buoyancy_position 612.666481 cc
2018-10-31T23:13:23.460Z,1541027603.460 [Reporter](INFO): platform_buoyancy_position 610.652030 cc
2018-10-31T23:13:26.480Z,1541027606.480 [Reporter](INFO): platform_buoyancy_position 571.973622 cc
2018-10-31T23:13:26.481Z,1541027606.481 [Reporter](INFO): BuoyancyServo.component_avgCurrent 521.140039 mA
2018-10-31T23:13:26.584Z,1541027606.584 [Reporter](INFO): platform_buoyancy_position 569.690543 cc
2018-10-31T23:13:26.987Z,1541027606.987 [Reporter](INFO): platform_buoyancy_position 563.378446 cc
2018-10-31T23:13:27.379Z,1541027607.379 [Reporter](INFO): platform_buoyancy_position 559.080800 cc
2018-10-31T23:13:27.768Z,1541027607.768 [Reporter](INFO): platform_buoyancy_position 554.514641 cc
2018-10-31T23:13:28.183Z,1541027608.183 [Reporter](INFO): platform_buoyancy_position 548.336771 cc
2018-10-31T23:13:28.574Z,1541027608.574 [Reporter](INFO): platform_buoyancy_position 543.904898 cc
2018-10-31T23:13:28.574Z,1541027608.574 [Reporter](INFO): BuoyancyServo.component_avgCurrent 565.180004 mA
2018-10-31T23:13:28.968Z,1541027608.968 [Reporter](INFO): platform_buoyancy_position 537.458516 cc
2018-10-31T23:13:31.947Z,1541027611.947 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:13:32.035Z,1541027612.035 [Reporter](INFO): platform_buoyancy_position 498.914451 cc
2018-10-31T23:13:32.057Z,1541027612.057 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20181031T225711/Courier0000.lzma
2018-10-31T23:13:32.136Z,1541027612.136 [Reporter](INFO): platform_buoyancy_position 496.765657 cc
2018-10-31T23:13:32.519Z,1541027612.519 [Reporter](INFO): platform_buoyancy_position 492.333784 cc
2018-10-31T23:13:32.844Z,1541027612.844 [DataOverHttps](INFO): Moved sent file to Logs/20181031T225711/Courier0000.lzma.bak
2018-10-31T23:13:32.844Z,1541027612.844 [DataOverHttps](INFO): SBD MOMSN=8760290
2018-10-31T23:13:32.946Z,1541027612.946 [Reporter](INFO): platform_buoyancy_position 486.021629 cc
2018-10-31T23:13:32.946Z,1541027612.946 [Reporter](INFO): BuoyancyServo.component_avgCurrent 510.129988 mA
2018-10-31T23:13:34.052Z,1541027614.052 [Reporter](INFO): platform_buoyancy_position 471.114356 cc
2018-10-31T23:13:34.179Z,1541027614.179 [Reporter](INFO): platform_buoyancy_position 468.965532 cc
2018-10-31T23:13:37.179Z,1541027617.179 [Reporter](INFO): platform_buoyancy_position 432.435947 cc
2018-10-31T23:13:37.302Z,1541027617.302 [Reporter](INFO): platform_buoyancy_position 430.287153 cc
2018-10-31T23:13:37.679Z,1541027617.679 [Reporter](INFO): platform_buoyancy_position 425.720908 cc
2018-10-31T23:13:38.059Z,1541027618.059 [Reporter](INFO): platform_buoyancy_position 419.543125 cc
2018-10-31T23:13:38.060Z,1541027618.060 [Reporter](INFO): BuoyancyServo.component_avgCurrent 579.859972 mA
2018-10-31T23:13:38.460Z,1541027618.460 [Reporter](INFO): platform_buoyancy_position 415.111223 cc
2018-10-31T23:13:38.868Z,1541027618.868 [Reporter](INFO): platform_buoyancy_position 408.799126 cc
2018-10-31T23:13:39.263Z,1541027619.263 [Reporter](INFO): platform_buoyancy_position 404.232938 cc
2018-10-31T23:13:39.664Z,1541027619.664 [Reporter](INFO): platform_buoyancy_position 398.189441 cc
2018-10-31T23:13:40.067Z,1541027620.067 [Reporter](INFO): platform_buoyancy_position 393.354654 cc
2018-10-31T23:13:43.116Z,1541027623.116 [Reporter](INFO): platform_buoyancy_position 352.796051 cc
2018-10-31T23:13:43.117Z,1541027623.117 [Reporter](INFO): BuoyancyServo.component_avgCurrent 546.829998 mA
2018-10-31T23:13:43.136Z,1541027623.136 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181031T225711/Courier0004.lzma
2018-10-31T23:13:43.260Z,1541027623.260 [Reporter](INFO): platform_buoyancy_position 350.647257 cc
2018-10-31T23:13:43.616Z,1541027623.616 [Reporter](INFO): platform_buoyancy_position 346.349640 cc
2018-10-31T23:13:43.927Z,1541027623.927 [DataOverHttps](INFO): Moved sent file to Logs/20181031T225711/Courier0004.lzma.bak
2018-10-31T23:13:43.927Z,1541027623.927 [DataOverHttps](INFO): SBD MOMSN=8760295
2018-10-31T23:13:44.023Z,1541027624.023 [Reporter](INFO): platform_buoyancy_position 341.917737 cc
2018-10-31T23:13:45.168Z,1541027625.168 [Reporter](INFO): platform_buoyancy_position 326.876121 cc
2018-10-31T23:13:45.276Z,1541027625.276 [Reporter](INFO): platform_buoyancy_position 324.861641 cc
2018-10-31T23:13:48.378Z,1541027628.378 [Reporter](INFO): platform_buoyancy_position 284.303038 cc
2018-10-31T23:13:48.379Z,1541027628.379 [Reporter](INFO): BuoyancyServo.component_avgCurrent 506.460011 mA
2018-10-31T23:13:48.480Z,1541027628.480 [Reporter](INFO): platform_buoyancy_position 282.154244 cc
2018-10-31T23:13:48.887Z,1541027628.887 [Reporter](INFO): platform_buoyancy_position 277.453742 cc
2018-10-31T23:13:49.291Z,1541027629.291 [Reporter](INFO): platform_buoyancy_position 272.081728 cc
2018-10-31T23:13:49.683Z,1541027629.683 [Reporter](INFO): platform_buoyancy_position 266.844028 cc
2018-10-31T23:13:50.086Z,1541027630.086 [Reporter](INFO): platform_buoyancy_position 261.069130 cc
2018-10-31T23:13:50.491Z,1541027630.491 [Reporter](INFO): platform_buoyancy_position 256.100029 cc
2018-10-31T23:13:50.492Z,1541027630.492 [Reporter](INFO): BuoyancyServo.component_avgCurrent 517.470002 mA
2018-10-31T23:13:50.875Z,1541027630.875 [Reporter](INFO): platform_buoyancy_position 249.653618 cc
2018-10-31T23:13:53.853Z,1541027633.853 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:13:53.853Z,1541027633.853 [NAL9602] Communications Fault, FailCount= 4
2018-10-31T23:13:53.853Z,1541027633.853 [NAL9602](ERROR): Communications Fault
2018-10-31T23:13:53.938Z,1541027633.938 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:13:53.940Z,1541027633.940 [Reporter](INFO): platform_buoyancy_position 210.975224 cc
2018-10-31T23:13:53.962Z,1541027633.962 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20181031T230045/Courier0000.lzma
2018-10-31T23:13:53.974Z,1541027633.974 [NAL9602](INFO): Powering down
2018-10-31T23:13:54.049Z,1541027634.049 [Reporter](INFO): platform_buoyancy_position 208.826415 cc
2018-10-31T23:13:54.440Z,1541027634.440 [Reporter](INFO): platform_buoyancy_position 204.260228 cc
2018-10-31T23:13:54.747Z,1541027634.747 [DataOverHttps](INFO): Moved sent file to Logs/20181031T230045/Courier0000.lzma.bak
2018-10-31T23:13:54.747Z,1541027634.747 [DataOverHttps](INFO): SBD MOMSN=8760298
2018-10-31T23:13:54.851Z,1541027634.851 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-31T23:13:54.851Z,1541027634.851 [NAL9602] No Fault, FailCount= 4
2018-10-31T23:13:54.852Z,1541027634.852 [Reporter](INFO): platform_buoyancy_position 198.216730 cc
2018-10-31T23:13:54.853Z,1541027634.853 [Reporter](INFO): BuoyancyServo.component_avgCurrent 543.160021 mA
2018-10-31T23:13:55.960Z,1541027635.960 [Reporter](INFO): platform_buoyancy_position 183.175114 cc
2018-10-31T23:13:56.068Z,1541027636.068 [Reporter](INFO): platform_buoyancy_position 182.906530 cc
2018-10-31T23:13:59.111Z,1541027639.111 [Reporter](INFO): platform_buoyancy_position 142.347897 cc
2018-10-31T23:13:59.208Z,1541027639.208 [Reporter](INFO): platform_buoyancy_position 140.333403 cc
2018-10-31T23:13:59.624Z,1541027639.624 [Reporter](INFO): platform_buoyancy_position 135.632901 cc
2018-10-31T23:14:00.013Z,1541027640.013 [Reporter](INFO): platform_buoyancy_position 131.335328 cc
2018-10-31T23:14:00.013Z,1541027640.013 [Reporter](INFO): BuoyancyServo.component_avgCurrent 440.400034 mA
2018-10-31T23:14:00.408Z,1541027640.408 [Reporter](INFO): platform_buoyancy_position 125.023216 cc
2018-10-31T23:14:00.820Z,1541027640.820 [Reporter](INFO): platform_buoyancy_position 120.725606 cc
2018-10-31T23:14:03.891Z,1541027643.891 [Reporter](INFO): platform_buoyancy_position 101.386409 cc
2018-10-31T23:14:03.917Z,1541027643.917 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20181031T230427/Courier0004.lzma
2018-10-31T23:14:04.100Z,1541027644.100 [Reporter](INFO): BuoyancyServo.component_avgCurrent 0.000000 mA
2018-10-31T23:14:04.701Z,1541027644.701 [DataOverHttps](INFO): Moved sent file to Logs/20181031T230427/Courier0004.lzma.bak
2018-10-31T23:14:04.702Z,1541027644.702 [DataOverHttps](INFO): SBD MOMSN=8760305
2018-10-31T23:14:15.288Z,1541027655.288 [DataOverHttps](INFO): Sending 658 bytes from file Logs/20181024T163849/Express0119.lzma
2018-10-31T23:14:16.080Z,1541027656.080 [DataOverHttps](INFO): Moved sent file to Logs/20181024T163849/Express0119.lzma.bak
2018-10-31T23:14:16.080Z,1541027656.080 [DataOverHttps](INFO): SBD MOMSN=8760309
2018-10-31T23:14:25.802Z,1541027665.802 [NAL9602](INFO): Powering up NAL9602
2018-10-31T23:14:25.871Z,1541027665.871 [DataOverHttps](INFO): Sending 1662 bytes from file Logs/20181024T232007/Express0001.lzma
2018-10-31T23:14:26.663Z,1541027666.663 [DataOverHttps](INFO): Moved sent file to Logs/20181024T232007/Express0001.lzma.bak
2018-10-31T23:14:26.663Z,1541027666.663 [DataOverHttps](INFO): SBD MOMSN=8760341
2018-10-31T23:14:37.266Z,1541027677.266 [DataOverHttps](INFO): Sending 1005 bytes from file Logs/20181024T232007/Express0005.lzma
2018-10-31T23:14:38.063Z,1541027678.063 [DataOverHttps](INFO): Moved sent file to Logs/20181024T232007/Express0005.lzma.bak
2018-10-31T23:14:38.063Z,1541027678.063 [DataOverHttps](INFO): SBD MOMSN=8760450
2018-10-31T23:14:40.103Z,1541027680.103 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-31T23:14:40.104Z,1541027680.104 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-10-31T23:14:40.104Z,1541027680.104 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-31T23:14:40.117Z,1541027680.117 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-31T23:14:40.239Z,1541027680.239 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-31T23:14:40.239Z,1541027680.239 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-10-31T23:14:43.213Z,1541027683.213 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-31T23:14:43.213Z,1541027683.213 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-31T23:14:43.213Z,1541027683.213 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-31T23:14:48.699Z,1541027688.699 [NAL9602](ERROR): NAL9602 initialization error.
2018-10-31T23:14:48.699Z,1541027688.699 [NAL9602] Communications Fault, FailCount= 5
2018-10-31T23:14:48.699Z,1541027688.699 [NAL9602](ERROR): Communications Fault
2018-10-31T23:14:48.773Z,1541027688.773 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-10-31T23:14:48.774Z,1541027688.774 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-10-31T23:14:48.789Z,1541027688.789 [DataOverHttps](INFO): Sending 1186 bytes from file Logs/20181024T232007/Express0008.lzma
2018-10-31T23:14:48.821Z,1541027688.821 [NAL9602](INFO): Powering down
2018-10-31T23:14:48.867Z,1541027688.867 [CBIT](INFO): Critical error at 20181031T231448
2018-10-31T23:14:49.583Z,1541027689.583 [DataOverHttps](INFO): Moved sent file to Logs/20181024T232007/Express0008.lzma.bak
2018-10-31T23:14:49.583Z,1541027689.583 [DataOverHttps](INFO): SBD MOMSN=8760551
2018-10-31T23:15:00.242Z,1541027700.242 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181031T224944/Express0001.lzma
2018-10-31T23:15:01.039Z,1541027701.039 [DataOverHttps](INFO): Moved sent file to Logs/20181031T224944/Express0001.lzma.bak
2018-10-31T23:15:01.039Z,1541027701.039 [DataOverHttps](INFO): SBD MOMSN=8760698
2018-10-31T23:15:11.992Z,1541027711.992 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20181031T225307/Express0001.lzma
2018-10-31T23:15:13.068Z,1541027713.068 [DataOverHttps](INFO): Moved sent file to Logs/20181031T225307/Express0001.lzma.bak
2018-10-31T23:15:13.069Z,1541027713.069 [DataOverHttps](INFO): SBD MOMSN=8760701
2018-10-31T23:15:23.457Z,1541027723.457 [DataOverHttps](INFO): Sending 894 bytes from file Logs/20181031T225446/Express0001.lzma
2018-10-31T23:15:24.223Z,1541027724.223 [DataOverHttps](INFO): Moved sent file to Logs/20181031T225446/Express0001.lzma.bak
2018-10-31T23:15:24.223Z,1541027724.223 [DataOverHttps](INFO): SBD MOMSN=8760711
2018-10-31T23:15:35.311Z,1541027735.311 [DataOverHttps](INFO): Sending 875 bytes from file Logs/20181031T225711/Express0001.lzma
2018-10-31T23:15:36.107Z,1541027736.107 [DataOverHttps](INFO): Moved sent file to Logs/20181031T225711/Express0001.lzma.bak
2018-10-31T23:15:36.107Z,1541027736.107 [DataOverHttps](INFO): SBD MOMSN=8760744
2018-10-31T23:15:44.550Z,1541027744.550 [CommandLine](IMPORTANT): got command quit
2018-10-31T23:15:45.556Z,1541027745.556 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-31T23:15:45.556Z,1541027745.556 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:45.721Z,1541027745.721 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-31T23:15:45.721Z,1541027745.721 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:45.722Z,1541027745.722 [CommandLine](INFO): Join timeout helper Thread ID is 2068
2018-10-31T23:15:45.741Z,1541027745.741 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-31T23:15:45.741Z,1541027745.741 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:45.742Z,1541027745.742 [NavChartDb](INFO): Join timeout helper Thread ID is 2069
2018-10-31T23:15:46.041Z,1541027746.041 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-31T23:15:46.042Z,1541027746.042 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.053Z,1541027746.053 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-31T23:15:46.053Z,1541027746.053 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.054Z,1541027746.054 [Radio_Surface](INFO): Join timeout helper Thread ID is 2070
2018-10-31T23:15:46.393Z,1541027746.393 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-31T23:15:46.393Z,1541027746.393 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.405Z,1541027746.405 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-31T23:15:46.405Z,1541027746.405 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.406Z,1541027746.406 [logger](INFO): Join timeout helper Thread ID is 2071
2018-10-31T23:15:46.425Z,1541027746.425 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-31T23:15:46.425Z,1541027746.425 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.437Z,1541027746.437 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-31T23:15:46.437Z,1541027746.437 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.437Z,1541027746.437 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-31T23:15:46.437Z,1541027746.437 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.438Z,1541027746.438 [controlThread](INFO): Join timeout helper Thread ID is 2072
2018-10-31T23:15:46.739Z,1541027746.739 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-31T23:15:46.739Z,1541027746.739 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-31T23:15:46.742Z,1541027746.742 [NAL9602](INFO): Powering down
2018-10-31T23:15:46.744Z,1541027746.744 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-31T23:15:46.744Z,1541027746.744 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-31T23:15:46.745Z,1541027746.745 [MissionManager](INFO): Uninitializing Mission Default
2018-10-31T23:15:46.745Z,1541027746.745 [Default] Stopped
2018-10-31T23:15:46.746Z,1541027746.746 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-31T23:15:46.746Z,1541027746.746 [Default:B.GoToSurface] Stopped
2018-10-31T23:15:46.746Z,1541027746.746 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-31T23:15:46.746Z,1541027746.746 [Default:CheckIn] Stopped
2018-10-31T23:15:46.746Z,1541027746.746 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-31T23:15:46.746Z,1541027746.746 [Default:CheckIn:Read_Iridium] Stopped
2018-10-31T23:15:46.749Z,1541027746.749 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-31T23:15:46.749Z,1541027746.749 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-31T23:15:46.750Z,1541027746.750 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-31T23:15:46.750Z,1541027746.750 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-31T23:15:46.751Z,1541027746.751 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-31T23:15:46.751Z,1541027746.751 [BuoyancyServo](INFO): Powering down
2018-10-31T23:15:46.765Z,1541027746.765 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-31T23:15:46.765Z,1541027746.765 [ElevatorServo](INFO): Powering down
2018-10-31T23:15:46.766Z,1541027746.766 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-31T23:15:46.766Z,1541027746.766 [MassServo](INFO): Powering down
2018-10-31T23:15:46.767Z,1541027746.767 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-31T23:15:46.767Z,1541027746.767 [RudderServo](INFO): Powering down
2018-10-31T23:15:46.768Z,1541027746.768 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-31T23:15:46.768Z,1541027746.768 [ThrusterServo](INFO): Powering down
2018-10-31T23:15:46.769Z,1541027746.769 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-31T23:15:46.769Z,1541027746.769 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-31T23:15:46.770Z,1541027746.770 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-31T23:15:46.770Z,1541027746.770 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.820Z,1541027746.820 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.911Z,1541027746.911 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-31T23:15:46.964Z,1541027746.964 [logger ThreadHandler](INFO): Thread cancelled.