2018-03-14T22:43:11.594Z,1521067391.594 [Supervisor](DEBUG): Initializing supervisor.
2018-03-14T22:43:11.597Z,1521067391.597 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-03-14T22:43:11.597Z,1521067391.597 [SyncHandler](INFO): Protected caller Thread ID is 796
2018-03-14T22:43:11.598Z,1521067391.598 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-03-14T22:43:11.599Z,1521067391.599 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-03-14T22:43:11.599Z,1521067391.599 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 797
2018-03-14T22:43:11.602Z,1521067391.602 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-03-14T22:43:11.614Z,1521067391.614 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-03-14T22:43:11.615Z,1521067391.615 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-03-14T22:43:11.615Z,1521067391.615 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 799
2018-03-14T22:43:11.616Z,1521067391.616 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-03-14T22:43:11.617Z,1521067391.617 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-03-14T22:43:11.617Z,1521067391.617 [logger ThreadHandler](INFO): Protected caller Thread ID is 800
2018-03-14T22:43:11.619Z,1521067391.619 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-03-14T22:43:11.619Z,1521067391.619 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-03-14T22:43:11.621Z,1521067391.621 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-03-14T22:43:11.917Z,1521067391.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-03-14T22:43:11.917Z,1521067391.917 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-03-14T22:43:12.100Z,1521067392.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-03-14T22:43:12.101Z,1521067392.101 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-03-14T22:43:12.358Z,1521067392.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-03-14T22:43:12.359Z,1521067392.359 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-03-14T22:43:12.495Z,1521067392.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-03-14T22:43:12.495Z,1521067392.495 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-03-14T22:43:12.590Z,1521067392.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-03-14T22:43:12.591Z,1521067392.591 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-03-14T22:43:13.015Z,1521067393.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-03-14T22:43:13.015Z,1521067393.015 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-03-14T22:43:13.332Z,1521067393.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-03-14T22:43:13.333Z,1521067393.333 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-03-14T22:43:13.736Z,1521067393.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-03-14T22:43:13.737Z,1521067393.737 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-03-14T22:43:13.836Z,1521067393.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-03-14T22:43:13.837Z,1521067393.837 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-03-14T22:43:13.931Z,1521067393.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-03-14T22:43:13.932Z,1521067393.932 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-03-14T22:43:14.126Z,1521067394.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-03-14T22:43:14.126Z,1521067394.126 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-03-14T22:43:14.320Z,1521067394.320 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-03-14T22:43:14.320Z,1521067394.320 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-03-14T22:43:14.634Z,1521067394.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-03-14T22:43:14.634Z,1521067394.634 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-03-14T22:43:14.768Z,1521067394.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-03-14T22:43:14.769Z,1521067394.769 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-03-14T22:43:14.849Z,1521067394.849 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-03-14T22:43:14.849Z,1521067394.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-03-14T22:43:14.947Z,1521067394.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-03-14T22:43:15.035Z,1521067395.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-03-14T22:43:15.125Z,1521067395.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-03-14T22:43:15.207Z,1521067395.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-03-14T22:43:15.347Z,1521067395.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-03-14T22:43:15.509Z,1521067395.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-03-14T22:43:15.584Z,1521067395.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-03-14T22:43:15.801Z,1521067395.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-03-14T22:43:15.802Z,1521067395.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-03-14T22:43:15.883Z,1521067395.883 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-03-14T22:43:15.971Z,1521067395.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-03-14T22:43:16.068Z,1521067396.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-03-14T22:43:16.190Z,1521067396.190 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-03-14T22:43:16.191Z,1521067396.191 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-03-14T22:43:16.196Z,1521067396.196 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-03-14T22:43:16.747Z,1521067396.747 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-03-14T22:43:16.748Z,1521067396.748 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-03-14T22:43:16.810Z,1521067396.810 [DepthRateCalculator] Loaded
2018-03-14T22:43:16.810Z,1521067396.810 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-03-14T22:43:16.816Z,1521067396.816 [PitchRateCalculator] Loaded
2018-03-14T22:43:16.817Z,1521067396.817 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-03-14T22:43:16.832Z,1521067396.832 [SpeedCalculator] Loaded
2018-03-14T22:43:16.832Z,1521067396.832 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-03-14T22:43:16.868Z,1521067396.868 [TempGradientCalculator] Loaded
2018-03-14T22:43:16.868Z,1521067396.868 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-03-14T22:43:16.902Z,1521067396.902 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-03-14T22:43:16.902Z,1521067396.902 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-03-14T22:43:16.916Z,1521067396.916 [YawRateCalculator] Loaded
2018-03-14T22:43:16.916Z,1521067396.916 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-03-14T22:43:16.916Z,1521067396.916 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-03-14T22:43:16.917Z,1521067396.917 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-03-14T22:43:17.007Z,1521067397.007 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-03-14T22:43:17.007Z,1521067397.007 [StratificationFrontDetector](DEBUG): (re)initializing
2018-03-14T22:43:17.007Z,1521067397.007 [StratificationFrontDetector] Loaded
2018-03-14T22:43:17.007Z,1521067397.007 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-03-14T22:43:17.008Z,1521067397.008 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-03-14T22:43:17.008Z,1521067397.008 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-03-14T22:43:17.123Z,1521067397.123 [BuoyancyServo] Loaded
2018-03-14T22:43:17.123Z,1521067397.123 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-03-14T22:43:17.138Z,1521067397.138 [ElevatorServo] Loaded
2018-03-14T22:43:17.138Z,1521067397.138 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-03-14T22:43:17.153Z,1521067397.153 [MassServo] Loaded
2018-03-14T22:43:17.153Z,1521067397.153 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-03-14T22:43:17.168Z,1521067397.168 [RudderServo] Loaded
2018-03-14T22:43:17.168Z,1521067397.168 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-03-14T22:43:17.208Z,1521067397.208 [ThrusterServo] Loaded
2018-03-14T22:43:17.209Z,1521067397.209 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-03-14T22:43:17.209Z,1521067397.209 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-03-14T22:43:17.210Z,1521067397.210 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-03-14T22:43:17.263Z,1521067397.263 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-03-14T22:43:17.263Z,1521067397.263 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-03-14T22:43:17.415Z,1521067397.415 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-03-14T22:43:17.415Z,1521067397.415 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-03-14T22:43:17.673Z,1521067397.673 [DataOverHttps] Loaded
2018-03-14T22:43:17.674Z,1521067397.674 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-03-14T22:43:17.687Z,1521067397.687 [Depth_Keller] Loaded
2018-03-14T22:43:17.687Z,1521067397.687 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-03-14T22:43:17.692Z,1521067397.692 [DropWeight] Loaded
2018-03-14T22:43:17.692Z,1521067397.692 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-03-14T22:43:17.789Z,1521067397.789 [NAL9602] Loaded
2018-03-14T22:43:17.789Z,1521067397.789 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-03-14T22:43:17.804Z,1521067397.804 [Onboard] Loaded
2018-03-14T22:43:17.805Z,1521067397.805 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-03-14T22:43:17.815Z,1521067397.815 [Radio_Surface] Loaded
2018-03-14T22:43:17.815Z,1521067397.815 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-03-14T22:43:17.816Z,1521067397.816 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408714E0
2018-03-14T22:43:17.816Z,1521067397.816 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-03-14T22:43:17.863Z,1521067397.863 [PNI_TCM] Loaded
2018-03-14T22:43:17.864Z,1521067397.864 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-03-14T22:43:19.590Z,1521067399.590 [BPC1] Loaded
2018-03-14T22:43:19.591Z,1521067399.591 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-03-14T22:43:19.591Z,1521067399.591 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-03-14T22:43:19.592Z,1521067399.592 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-03-14T22:43:19.719Z,1521067399.719 [SBIT](DEBUG): Construct Startup Built In Test.
2018-03-14T22:43:19.744Z,1521067399.744 [SBIT] Loaded
2018-03-14T22:43:19.744Z,1521067399.744 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-03-14T22:43:19.745Z,1521067399.745 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-03-14T22:43:19.756Z,1521067399.756 [IBIT] Loaded
2018-03-14T22:43:19.756Z,1521067399.756 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-03-14T22:43:19.759Z,1521067399.759 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-03-14T22:43:19.909Z,1521067399.909 [CBIT] Loaded
2018-03-14T22:43:19.910Z,1521067399.910 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-03-14T22:43:19.910Z,1521067399.910 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-03-14T22:43:19.911Z,1521067399.911 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-03-14T22:43:20.051Z,1521067400.051 [Aanderaa_O2] Loaded
2018-03-14T22:43:20.051Z,1521067400.051 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-03-14T22:43:20.062Z,1521067400.062 [CANONSampler] Loaded
2018-03-14T22:43:20.063Z,1521067400.063 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-03-14T22:43:20.077Z,1521067400.077 [PAR_Licor] Loaded
2018-03-14T22:43:20.077Z,1521067400.077 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-03-14T22:43:20.125Z,1521067400.125 [WetLabsBB2FL] Loaded
2018-03-14T22:43:20.126Z,1521067400.126 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-03-14T22:43:20.127Z,1521067400.127 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2018-03-14T22:43:20.127Z,1521067400.127 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 883
2018-03-14T22:43:20.128Z,1521067400.128 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-03-14T22:43:20.128Z,1521067400.128 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-03-14T22:43:20.194Z,1521067400.194 [VerticalControl](DEBUG): Construct VerticalControl.
2018-03-14T22:43:20.281Z,1521067400.281 [VerticalControl] Loaded
2018-03-14T22:43:20.281Z,1521067400.281 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-03-14T22:43:20.282Z,1521067400.282 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-03-14T22:43:20.337Z,1521067400.337 [HorizontalControl] Loaded
2018-03-14T22:43:20.338Z,1521067400.338 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-03-14T22:43:20.338Z,1521067400.338 [SpeedControl](DEBUG): Construct SpeedControl.
2018-03-14T22:43:20.340Z,1521067400.340 [SpeedControl] Loaded
2018-03-14T22:43:20.340Z,1521067400.340 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-03-14T22:43:20.341Z,1521067400.341 [LoopControl](DEBUG): Construct LoopControl.
2018-03-14T22:43:20.341Z,1521067400.341 [LoopControl] Loaded
2018-03-14T22:43:20.342Z,1521067400.342 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-03-14T22:43:20.342Z,1521067400.342 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-03-14T22:43:20.343Z,1521067400.343 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-03-14T22:43:20.367Z,1521067400.367 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-03-14T22:43:20.367Z,1521067400.367 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-03-14T22:43:20.457Z,1521067400.457 [DeadReckonUsingSpeedCalculator] Loaded
2018-03-14T22:43:20.458Z,1521067400.458 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-03-14T22:43:20.511Z,1521067400.511 [DeadReckonWithRespectToSeafloor] Loaded
2018-03-14T22:43:20.512Z,1521067400.512 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-03-14T22:43:20.528Z,1521067400.528 [NavChart] Loaded
2018-03-14T22:43:20.528Z,1521067400.528 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-03-14T22:43:20.532Z,1521067400.532 [UniversalFixResidualReporter] Loaded
2018-03-14T22:43:20.533Z,1521067400.533 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-03-14T22:43:20.533Z,1521067400.533 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-03-14T22:43:20.537Z,1521067400.537 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-03-14T22:43:20.537Z,1521067400.537 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-03-14T22:43:20.544Z,1521067400.544 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-03-14T22:43:20.545Z,1521067400.545 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409BD4E0
2018-03-14T22:43:20.545Z,1521067400.545 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 884
2018-03-14T22:43:20.549Z,1521067400.549 [Supervisor](INFO): Main Thread ID is 9710
2018-03-14T22:43:20.550Z,1521067400.550 [Supervisor](DEBUG): Running supervisor.
2018-03-14T22:43:20.550Z,1521067400.550 [CommandLine ThreadHandler](INFO): Handler Thread ID is 885
2018-03-14T22:43:20.553Z,1521067400.553 [controlThread ThreadHandler](INFO): Handler Thread ID is 886
2018-03-14T22:43:20.553Z,1521067400.553 [controlThread](DEBUG): Initializing ControlThread
2018-03-14T22:43:20.554Z,1521067400.554 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-03-14T22:43:20.555Z,1521067400.555 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-03-14T22:43:20.555Z,1521067400.555 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-03-14T22:43:20.555Z,1521067400.555 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-03-14T22:43:20.556Z,1521067400.556 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-03-14T22:43:20.556Z,1521067400.556 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-03-14T22:43:20.561Z,1521067400.561 [SBIT](INFO): Initialize SBIT Component.
2018-03-14T22:43:20.561Z,1521067400.561 [SBIT](IMPORTANT): git: 2018-03-10-3-g1088291
2018-03-14T22:43:20.562Z,1521067400.562 [SBIT](INFO): git hash: 10882917f95cfba58cc6008bb2c6cc253db9d857
2018-03-14T22:43:20.562Z,1521067400.562 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-03-14T22:43:20.563Z,1521067400.563 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-03-14T22:43:20.564Z,1521067400.564 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-03-14T22:43:20.564Z,1521067400.564 [IBIT](INFO): Initialize IBIT Component.
2018-03-14T22:43:20.565Z,1521067400.565 [CBIT](DEBUG): Initialize CBIT Component.
2018-03-14T22:43:20.565Z,1521067400.565 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-03-14T22:43:20.566Z,1521067400.566 [logger ThreadHandler](INFO): Handler Thread ID is 887
2018-03-14T22:43:20.583Z,1521067400.583 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 888
2018-03-14T22:43:20.589Z,1521067400.589 [Radio_Surface](INFO): Powering up
2018-03-14T22:43:20.595Z,1521067400.595 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 889
2018-03-14T22:43:20.596Z,1521067400.596 [WetLabsBB2FL](INFO): Powering down
2018-03-14T22:43:20.623Z,1521067400.623 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 890
2018-03-14T22:43:20.626Z,1521067400.626 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-03-14T22:43:20.626Z,1521067400.626 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-03-14T22:43:20.627Z,1521067400.627 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-03-14T22:43:20.627Z,1521067400.627 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-03-14T22:43:20.627Z,1521067400.627 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-03-14T22:43:20.627Z,1521067400.627 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-03-14T22:43:20.627Z,1521067400.627 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-03-14T22:43:20.627Z,1521067400.627 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-03-14T22:43:20.628Z,1521067400.628 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-03-14T22:43:20.628Z,1521067400.628 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-03-14T22:43:20.628Z,1521067400.628 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-03-14T22:43:20.628Z,1521067400.628 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-03-14T22:43:20.628Z,1521067400.628 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-03-14T22:43:20.628Z,1521067400.628 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-03-14T22:43:20.629Z,1521067400.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-03-14T22:43:20.629Z,1521067400.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-03-14T22:43:20.675Z,1521067400.675 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-03-14T22:43:20.677Z,1521067400.677 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-03-14T22:43:20.677Z,1521067400.677 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-03-14T22:43:20.678Z,1521067400.678 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-03-14T22:43:20.678Z,1521067400.678 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-03-14T22:43:20.679Z,1521067400.679 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T22:43:20.679Z,1521067400.679 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T22:43:20.680Z,1521067400.680 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-03-14T22:43:20.680Z,1521067400.680 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T22:43:20.680Z,1521067400.680 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T22:43:20.681Z,1521067400.681 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-03-14T22:43:20.681Z,1521067400.681 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-03-14T22:43:20.682Z,1521067400.682 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-03-14T22:43:20.708Z,1521067400.708 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-03-14T22:43:20.747Z,1521067400.747 [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-03-14T22:43:20.748Z,1521067400.748 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-03-14T22:43:20.821Z,1521067400.821 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-03-14T22:43:20.822Z,1521067400.822 [Default:A.Wait](DEBUG): Construct Wait.
2018-03-14T22:43:20.840Z,1521067400.840 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-03-14T22:43:20.866Z,1521067400.866 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-03-14T22:43:20.869Z,1521067400.869 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-03-14T22:43:20.894Z,1521067400.894 [Default:E.Execute](DEBUG): Construct Execute.
2018-03-14T22:43:20.898Z,1521067400.898 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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-03-14T22:43:20.911Z,1521067400.911 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-03-14T22:43:20.923Z,1521067400.923 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-03-14T22:43:20.934Z,1521067400.934 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-03-14T22:43:21.038Z,1521067401.038 [BPC1](INFO): Start
2018-03-14T22:43:21.176Z,1521067401.176 [CANONSampler](INFO): Powering down
2018-03-14T22:43:21.304Z,1521067401.304 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-03-14T22:43:21.397Z,1521067401.397 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:21.420Z,1521067401.420 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:21.515Z,1521067401.515 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:43:21.523Z,1521067401.523 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-03-14T22:43:21.545Z,1521067401.545 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:43:21.551Z,1521067401.551 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-03-14T22:43:21.565Z,1521067401.565 [MassServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:43:21.571Z,1521067401.571 [MassServo](DEBUG): Initializing MassServo.
2018-03-14T22:43:21.584Z,1521067401.584 [RudderServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:43:21.591Z,1521067401.591 [RudderServo](DEBUG): Initializing RudderServo.
2018-03-14T22:43:21.596Z,1521067401.596 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:43:21.603Z,1521067401.603 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-03-14T22:43:21.839Z,1521067401.839 [BPC1](INFO): Starting
2018-03-14T22:43:21.893Z,1521067401.893 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:21.894Z,1521067401.894 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:22.092Z,1521067402.092 [BPC1](INFO): Starting
2018-03-14T22:43:22.112Z,1521067402.112 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:22.112Z,1521067402.112 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:22.451Z,1521067402.451 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-03-14T22:43:22.451Z,1521067402.451 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-03-14T22:43:22.451Z,1521067402.451 [BuoyancyServo] Communications Fault, FailCount= 1
2018-03-14T22:43:22.451Z,1521067402.451 [BuoyancyServo](ERROR): Communications Fault
2018-03-14T22:43:22.658Z,1521067402.658 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-03-14T22:43:22.695Z,1521067402.695 [BPC1](INFO): Starting
2018-03-14T22:43:22.721Z,1521067402.721 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:22.722Z,1521067402.722 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:22.743Z,1521067402.743 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-03-14T22:43:22.743Z,1521067402.743 [BuoyancyServo](INFO): Powering down
2018-03-14T22:43:23.099Z,1521067403.099 [BPC1](INFO): Starting
2018-03-14T22:43:23.112Z,1521067403.112 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:23.112Z,1521067403.112 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:23.489Z,1521067403.489 [BPC1](INFO): Starting
2018-03-14T22:43:23.538Z,1521067403.538 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:23.539Z,1521067403.539 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:23.889Z,1521067403.889 [BPC1](INFO): Starting
2018-03-14T22:43:23.926Z,1521067403.926 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:23.943Z,1521067403.943 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:24.299Z,1521067404.299 [BPC1](INFO): Starting
2018-03-14T22:43:24.419Z,1521067404.419 [Aanderaa_O2](INFO): Powering down
2018-03-14T22:43:24.499Z,1521067404.499 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:24.500Z,1521067404.500 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:43:24.700Z,1521067404.700 [BPC1](INFO): Starting
2018-03-14T22:43:24.843Z,1521067404.843 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-03-14T22:43:24.843Z,1521067404.843 [BuoyancyServo] No Fault, FailCount= 1
2018-03-14T22:43:25.096Z,1521067405.096 [BPC1](INFO): Starting
2018-03-14T22:43:25.179Z,1521067405.179 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:43:25.299Z,1521067405.299 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-03-14T22:43:25.599Z,1521067405.599 [BPC1](INFO): Starting
2018-03-14T22:43:25.950Z,1521067405.950 [BPC1](INFO): Starting
2018-03-14T22:43:26.348Z,1521067406.348 [BPC1](INFO): Starting
2018-03-14T22:43:26.766Z,1521067406.766 [BPC1](INFO): Starting
2018-03-14T22:43:27.163Z,1521067407.163 [BPC1](INFO): Starting
2018-03-14T22:43:27.559Z,1521067407.559 [BPC1](INFO): Starting
2018-03-14T22:43:27.964Z,1521067407.964 [BPC1](INFO): Starting
2018-03-14T22:43:28.356Z,1521067408.356 [BPC1](INFO): Starting
2018-03-14T22:43:28.769Z,1521067408.769 [BPC1](INFO): Starting
2018-03-14T22:43:29.148Z,1521067409.148 [BPC1](INFO): Starting
2018-03-14T22:43:29.547Z,1521067409.547 [BPC1](INFO): Starting
2018-03-14T22:43:29.975Z,1521067409.975 [BPC1](INFO): Starting
2018-03-14T22:43:30.355Z,1521067410.355 [BPC1](INFO): Starting
2018-03-14T22:43:30.759Z,1521067410.759 [BPC1](INFO): Starting
2018-03-14T22:43:31.148Z,1521067411.148 [BPC1](INFO): Starting
2018-03-14T22:43:31.555Z,1521067411.555 [BPC1](INFO): Starting
2018-03-14T22:43:31.947Z,1521067411.947 [BPC1](INFO): Starting
2018-03-14T22:43:32.348Z,1521067412.348 [BPC1](INFO): Starting
2018-03-14T22:43:32.750Z,1521067412.750 [BPC1](INFO): Starting
2018-03-14T22:43:33.155Z,1521067413.155 [BPC1](INFO): Starting
2018-03-14T22:43:33.542Z,1521067413.542 [BPC1](INFO): Starting
2018-03-14T22:43:33.948Z,1521067413.948 [BPC1](INFO): Starting
2018-03-14T22:43:34.348Z,1521067414.348 [BPC1](INFO): Starting
2018-03-14T22:43:34.755Z,1521067414.755 [BPC1](INFO): Starting
2018-03-14T22:43:35.148Z,1521067415.148 [BPC1](INFO): Starting
2018-03-14T22:43:35.547Z,1521067415.547 [BPC1](INFO): Starting
2018-03-14T22:43:35.945Z,1521067415.945 [BPC1](INFO): Starting
2018-03-14T22:43:36.355Z,1521067416.355 [BPC1](INFO): Starting
2018-03-14T22:43:36.756Z,1521067416.756 [BPC1](INFO): Starting
2018-03-14T22:43:37.151Z,1521067417.151 [BPC1](INFO): Starting
2018-03-14T22:43:37.547Z,1521067417.547 [BPC1](INFO): Starting
2018-03-14T22:43:37.955Z,1521067417.955 [BPC1](INFO): Starting
2018-03-14T22:43:38.347Z,1521067418.347 [BPC1](INFO): Starting
2018-03-14T22:43:38.747Z,1521067418.747 [BPC1](INFO): Starting
2018-03-14T22:43:39.155Z,1521067419.155 [BPC1](INFO): Starting
2018-03-14T22:43:39.555Z,1521067419.555 [BPC1](INFO): Starting
2018-03-14T22:43:39.947Z,1521067419.947 [BPC1](INFO): Starting
2018-03-14T22:43:40.347Z,1521067420.347 [BPC1](INFO): Starting
2018-03-14T22:43:40.748Z,1521067420.748 [BPC1](INFO): Starting
2018-03-14T22:43:41.155Z,1521067421.155 [BPC1](INFO): Starting
2018-03-14T22:43:41.559Z,1521067421.559 [BPC1](INFO): Starting
2018-03-14T22:43:41.947Z,1521067421.947 [BPC1](INFO): Starting
2018-03-14T22:43:42.346Z,1521067422.346 [BPC1](INFO): Starting
2018-03-14T22:43:42.755Z,1521067422.755 [BPC1](INFO): Starting
2018-03-14T22:43:43.145Z,1521067423.145 [BPC1](INFO): Starting
2018-03-14T22:43:43.544Z,1521067423.544 [BPC1](INFO): Starting
2018-03-14T22:43:43.946Z,1521067423.946 [BPC1](INFO): Starting
2018-03-14T22:43:43.993Z,1521067423.993 [SBIT](IMPORTANT): Beginning Startup BIT
2018-03-14T22:43:43.997Z,1521067423.997 [CBIT](IMPORTANT): Beginning ground fault scan
2018-03-14T22:43:44.355Z,1521067424.355 [BPC1](INFO): Starting
2018-03-14T22:43:44.748Z,1521067424.748 [BPC1](INFO): Starting
2018-03-14T22:43:45.148Z,1521067425.148 [BPC1](INFO): Starting
2018-03-14T22:43:45.548Z,1521067425.548 [BPC1](INFO): Starting
2018-03-14T22:43:45.960Z,1521067425.960 [BPC1](INFO): Starting
2018-03-14T22:43:46.491Z,1521067426.491 [BPC1](INFO): Starting
2018-03-14T22:43:46.889Z,1521067426.889 [BPC1](INFO): Starting
2018-03-14T22:43:47.278Z,1521067427.278 [BPC1](INFO): Starting
2018-03-14T22:43:47.677Z,1521067427.677 [BPC1](INFO): Starting
2018-03-14T22:43:48.052Z,1521067428.052 [NAL9602](INFO): Powering up NAL9602
2018-03-14T22:43:48.078Z,1521067428.078 [BPC1](INFO): Starting
2018-03-14T22:43:48.476Z,1521067428.476 [BPC1](INFO): Starting
2018-03-14T22:43:48.880Z,1521067428.880 [BPC1](INFO): Starting
2018-03-14T22:43:49.275Z,1521067429.275 [BPC1](INFO): Starting
2018-03-14T22:43:49.676Z,1521067429.676 [BPC1](INFO): Starting
2018-03-14T22:43:50.076Z,1521067430.076 [BPC1](INFO): Starting
2018-03-14T22:43:50.477Z,1521067430.477 [BPC1](INFO): Starting
2018-03-14T22:43:50.877Z,1521067430.877 [BPC1](INFO): Starting
2018-03-14T22:43:51.278Z,1521067431.278 [BPC1](INFO): Starting
2018-03-14T22:43:51.676Z,1521067431.676 [BPC1](INFO): Starting
2018-03-14T22:43:52.076Z,1521067432.076 [BPC1](INFO): Starting
2018-03-14T22:43:52.476Z,1521067432.476 [BPC1](INFO): Starting
2018-03-14T22:43:52.876Z,1521067432.876 [BPC1](INFO): Starting
2018-03-14T22:43:53.285Z,1521067433.285 [BPC1](INFO): Starting
2018-03-14T22:43:53.677Z,1521067433.677 [BPC1](INFO): Starting
2018-03-14T22:43:54.078Z,1521067434.078 [BPC1](INFO): Starting
2018-03-14T22:43:54.477Z,1521067434.477 [BPC1](INFO): Starting
2018-03-14T22:43:54.876Z,1521067434.876 [BPC1](INFO): Starting
2018-03-14T22:43:54.968Z,1521067434.968 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): -0.005525
CHAN A2 (12V): -0.002047
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001562
CHAN B1 (3.15aV): -0.000072
CHAN B2 (3.15bV): 0.000160
CHAN B3 (GND): 0.000009
OPEN: -0.000155
Full Scale Calc: 4.765 mA, -1.589 mA
2018-03-14T22:43:55.276Z,1521067435.276 [BPC1](INFO): Starting
2018-03-14T22:43:55.677Z,1521067435.677 [BPC1](INFO): Starting
2018-03-14T22:43:56.080Z,1521067436.080 [BPC1](INFO): Starting
2018-03-14T22:43:56.479Z,1521067436.479 [BPC1](INFO): Starting
2018-03-14T22:43:56.880Z,1521067436.880 [BPC1](INFO): Starting
2018-03-14T22:43:57.272Z,1521067437.272 [BPC1](INFO): Starting
2018-03-14T22:43:57.676Z,1521067437.676 [BPC1](INFO): Starting
2018-03-14T22:43:58.080Z,1521067438.080 [BPC1](INFO): Starting
2018-03-14T22:43:58.484Z,1521067438.484 [BPC1](INFO): Starting
2018-03-14T22:43:58.894Z,1521067438.894 [BPC1](INFO): Starting
2018-03-14T22:43:59.252Z,1521067439.252 [NAL9602](INFO): NAL9602 initialized
2018-03-14T22:43:59.293Z,1521067439.293 [BPC1](INFO): Starting
2018-03-14T22:43:59.958Z,1521067439.958 [BPC1](INFO): Starting
2018-03-14T22:44:00.327Z,1521067440.327 [BPC1](INFO): Starting
2018-03-14T22:44:00.717Z,1521067440.717 [BPC1](INFO): Starting
2018-03-14T22:44:01.118Z,1521067441.118 [BPC1](INFO): Starting
2018-03-14T22:44:01.500Z,1521067441.500 [BPC1](INFO): Starting
2018-03-14T22:44:01.901Z,1521067441.901 [BPC1](INFO): Starting
2018-03-14T22:44:02.319Z,1521067442.319 [BPC1](INFO): Starting
2018-03-14T22:44:02.715Z,1521067442.715 [BPC1](INFO): Starting
2018-03-14T22:44:03.112Z,1521067443.112 [BPC1](INFO): Starting
2018-03-14T22:44:03.530Z,1521067443.530 [BPC1](INFO): Starting
2018-03-14T22:44:03.910Z,1521067443.910 [BPC1](INFO): Starting
2018-03-14T22:44:04.300Z,1521067444.300 [BPC1](INFO): Starting
2018-03-14T22:44:04.700Z,1521067444.700 [BPC1](INFO): Starting
2018-03-14T22:44:05.100Z,1521067445.100 [BPC1](INFO): Starting
2018-03-14T22:44:05.505Z,1521067445.505 [BPC1](INFO): Starting
2018-03-14T22:44:05.906Z,1521067445.906 [BPC1](INFO): Starting
2018-03-14T22:44:06.310Z,1521067446.310 [BPC1](INFO): Starting
2018-03-14T22:44:06.709Z,1521067446.709 [BPC1](INFO): Starting
2018-03-14T22:44:07.126Z,1521067447.126 [BPC1](INFO): Starting
2018-03-14T22:44:07.507Z,1521067447.507 [BPC1](INFO): Starting
2018-03-14T22:44:07.915Z,1521067447.915 [BPC1](INFO): Starting
2018-03-14T22:44:08.312Z,1521067448.312 [BPC1](INFO): Starting
2018-03-14T22:44:08.705Z,1521067448.705 [BPC1](INFO): Starting
2018-03-14T22:44:09.110Z,1521067449.110 [BPC1](INFO): Starting
2018-03-14T22:44:09.509Z,1521067449.509 [BPC1](INFO): Starting
2018-03-14T22:44:09.901Z,1521067449.901 [BPC1](INFO): Starting
2018-03-14T22:44:10.302Z,1521067450.302 [BPC1](INFO): Starting
2018-03-14T22:44:10.704Z,1521067450.704 [BPC1](INFO): Starting
2018-03-14T22:44:11.105Z,1521067451.105 [BPC1](INFO): Starting
2018-03-14T22:44:11.508Z,1521067451.508 [BPC1](INFO): Starting
2018-03-14T22:44:11.907Z,1521067451.907 [BPC1](INFO): Starting
2018-03-14T22:44:12.335Z,1521067452.335 [BPC1](INFO): Starting
2018-03-14T22:44:12.719Z,1521067452.719 [BPC1](INFO): Starting
2018-03-14T22:44:13.126Z,1521067453.126 [BPC1](INFO): Starting
2018-03-14T22:44:13.523Z,1521067453.523 [BPC1](INFO): Starting
2018-03-14T22:44:13.901Z,1521067453.901 [BPC1](INFO): Starting
2018-03-14T22:44:14.304Z,1521067454.304 [BPC1](INFO): Starting
2018-03-14T22:44:14.704Z,1521067454.704 [BPC1](INFO): Starting
2018-03-14T22:44:15.110Z,1521067455.110 [BPC1](INFO): Starting
2018-03-14T22:44:15.497Z,1521067455.497 [BPC1](INFO): Starting
2018-03-14T22:44:15.905Z,1521067455.905 [BPC1](INFO): Starting
2018-03-14T22:44:16.308Z,1521067456.308 [BPC1](INFO): Starting
2018-03-14T22:44:16.703Z,1521067456.703 [BPC1](INFO): Starting
2018-03-14T22:44:17.105Z,1521067457.105 [BPC1](INFO): Starting
2018-03-14T22:44:17.519Z,1521067457.519 [BPC1](INFO): Starting
2018-03-14T22:44:17.923Z,1521067457.923 [BPC1](INFO): Starting
2018-03-14T22:44:18.319Z,1521067458.319 [BPC1](INFO): Starting
2018-03-14T22:44:18.722Z,1521067458.722 [BPC1](INFO): Starting
2018-03-14T22:44:19.107Z,1521067459.107 [BPC1](INFO): Starting
2018-03-14T22:44:19.500Z,1521067459.500 [BPC1](INFO): Starting
2018-03-14T22:44:19.909Z,1521067459.909 [BPC1](INFO): Starting
2018-03-14T22:44:20.305Z,1521067460.305 [BPC1](INFO): Starting
2018-03-14T22:44:20.707Z,1521067460.707 [BPC1](INFO): Starting
2018-03-14T22:44:21.110Z,1521067461.110 [BPC1](INFO): Starting
2018-03-14T22:44:21.496Z,1521067461.496 [BPC1](INFO): Starting
2018-03-14T22:44:21.497Z,1521067461.497 [BPC1](INFO): Initialized.
2018-03-14T22:44:21.897Z,1521067461.897 [BPC1](INFO): Runnable
2018-03-14T22:44:21.911Z,1521067461.911 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:44:22.296Z,1521067462.296 [BPC1](INFO): Runnable
2018-03-14T22:44:22.723Z,1521067462.723 [BPC1](INFO): Runnable
2018-03-14T22:44:23.111Z,1521067463.111 [BPC1](INFO): Runnable
2018-03-14T22:44:23.514Z,1521067463.514 [BPC1](INFO): Runnable
2018-03-14T22:44:23.916Z,1521067463.916 [BPC1](INFO): Runnable
2018-03-14T22:44:23.916Z,1521067463.916 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:44:24.300Z,1521067464.300 [BPC1](INFO): Runnable
2018-03-14T22:44:24.708Z,1521067464.708 [BPC1](INFO): Runnable
2018-03-14T22:44:25.103Z,1521067465.103 [BPC1](INFO): Runnable
2018-03-14T22:44:25.511Z,1521067465.511 [BPC1](INFO): Runnable
2018-03-14T22:44:25.911Z,1521067465.911 [BPC1](INFO): Runnable
2018-03-14T22:44:26.317Z,1521067466.317 [BPC1](INFO): Runnable
2018-03-14T22:44:26.824Z,1521067466.824 [BPC1](INFO): Runnable
2018-03-14T22:44:27.209Z,1521067467.209 [BPC1](INFO): Runnable
2018-03-14T22:44:27.604Z,1521067467.604 [BPC1](INFO): Runnable
2018-03-14T22:44:28.039Z,1521067468.039 [BPC1](INFO): Runnable
2018-03-14T22:44:28.039Z,1521067468.039 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:44:28.047Z,1521067468.047 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:44:28.423Z,1521067468.423 [BPC1](INFO): Runnable
2018-03-14T22:44:28.448Z,1521067468.448 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:44:28.827Z,1521067468.827 [BPC1](INFO): Runnable
2018-03-14T22:44:29.215Z,1521067469.215 [BPC1](INFO): Runnable
2018-03-14T22:44:29.612Z,1521067469.612 [BPC1](INFO): Runnable
2018-03-14T22:44:30.017Z,1521067470.017 [BPC1](INFO): Runnable
2018-03-14T22:44:30.412Z,1521067470.412 [BPC1](INFO): Runnable
2018-03-14T22:44:30.813Z,1521067470.813 [BPC1](INFO): Runnable
2018-03-14T22:44:31.217Z,1521067471.217 [BPC1](INFO): Runnable
2018-03-14T22:44:31.612Z,1521067471.612 [BPC1](INFO): Runnable
2018-03-14T22:44:32.013Z,1521067472.013 [BPC1](INFO): Runnable
2018-03-14T22:44:32.405Z,1521067472.405 [BPC1](INFO): Runnable
2018-03-14T22:44:32.805Z,1521067472.805 [BPC1](INFO): Runnable
2018-03-14T22:44:33.228Z,1521067473.228 [BPC1](INFO): Runnable
2018-03-14T22:44:33.628Z,1521067473.628 [BPC1](INFO): Runnable
2018-03-14T22:44:34.031Z,1521067474.031 [BPC1](INFO): Runnable
2018-03-14T22:44:34.427Z,1521067474.427 [BPC1](INFO): Runnable
2018-03-14T22:44:34.805Z,1521067474.805 [BPC1](INFO): Runnable
2018-03-14T22:44:35.205Z,1521067475.205 [BPC1](INFO): Runnable
2018-03-14T22:44:35.604Z,1521067475.604 [BPC1](INFO): Runnable
2018-03-14T22:44:36.012Z,1521067476.012 [BPC1](INFO): Runnable
2018-03-14T22:44:36.413Z,1521067476.413 [BPC1](INFO): Runnable
2018-03-14T22:44:36.413Z,1521067476.413 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:44:36.811Z,1521067476.811 [BPC1](INFO): Runnable
2018-03-14T22:44:37.212Z,1521067477.212 [BPC1](INFO): Runnable
2018-03-14T22:44:37.611Z,1521067477.611 [BPC1](INFO): Runnable
2018-03-14T22:44:38.015Z,1521067478.015 [BPC1](INFO): Runnable
2018-03-14T22:44:38.031Z,1521067478.031 [SBIT](IMPORTANT): SBIT PASSED
2018-03-14T22:44:38.423Z,1521067478.423 [BPC1](INFO): Runnable
2018-03-14T22:44:38.443Z,1521067478.443 [MissionManager](IMPORTANT): Started mission Startup
2018-03-14T22:44:38.443Z,1521067478.443 [Startup] Running Loop=1
2018-03-14T22:44:38.443Z,1521067478.443 [Startup](DEBUG): Aggregate::initialize Startup
2018-03-14T22:44:38.443Z,1521067478.443 [Startup:A.GoToSurface] Running Loop=1
2018-03-14T22:44:38.443Z,1521067478.443 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-03-14T22:44:38.444Z,1521067478.444 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-03-14T22:44:38.444Z,1521067478.444 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-03-14T22:44:38.445Z,1521067478.445 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-03-14T22:44:38.445Z,1521067478.445 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 30.000000 seconds.
2018-03-14T22:44:38.445Z,1521067478.445 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-03-14T22:44:38.451Z,1521067478.451 [Startup:StartupSatComms] Running Loop=1
2018-03-14T22:44:38.451Z,1521067478.451 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-03-14T22:44:38.451Z,1521067478.451 [Startup:StartupSatComms:A] Running Loop=1
2018-03-14T22:44:38.827Z,1521067478.827 [BPC1](INFO): Runnable
2018-03-14T22:44:38.838Z,1521067478.838 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-03-14T22:44:39.223Z,1521067479.223 [BPC1](INFO): Runnable
2018-03-14T22:44:39.628Z,1521067479.628 [BPC1](INFO): Runnable
2018-03-14T22:44:40.004Z,1521067480.004 [BPC1](INFO): Runnable
2018-03-14T22:44:40.408Z,1521067480.408 [BPC1](INFO): Runnable
2018-03-14T22:44:40.808Z,1521067480.808 [BPC1](INFO): Runnable
2018-03-14T22:44:40.808Z,1521067480.808 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:44:40.815Z,1521067480.815 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:44:41.205Z,1521067481.205 [BPC1](INFO): Runnable
2018-03-14T22:44:41.218Z,1521067481.218 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:44:41.615Z,1521067481.615 [BPC1](INFO): Runnable
2018-03-14T22:44:42.013Z,1521067482.013 [BPC1](INFO): Runnable
2018-03-14T22:44:42.415Z,1521067482.415 [BPC1](INFO): Runnable
2018-03-14T22:44:42.820Z,1521067482.820 [BPC1](INFO): Runnable
2018-03-14T22:44:43.215Z,1521067483.215 [BPC1](INFO): Runnable
2018-03-14T22:44:43.635Z,1521067483.635 [BPC1](INFO): Runnable
2018-03-14T22:44:44.023Z,1521067484.023 [BPC1](INFO): Runnable
2018-03-14T22:44:44.436Z,1521067484.436 [BPC1](INFO): Runnable
2018-03-14T22:44:44.827Z,1521067484.827 [BPC1](INFO): Runnable
2018-03-14T22:44:45.208Z,1521067485.208 [BPC1](INFO): Runnable
2018-03-14T22:44:45.604Z,1521067485.604 [BPC1](INFO): Runnable
2018-03-14T22:44:46.008Z,1521067486.008 [BPC1](INFO): Runnable
2018-03-14T22:44:46.413Z,1521067486.413 [BPC1](INFO): Runnable
2018-03-14T22:44:46.811Z,1521067486.811 [BPC1](INFO): Runnable
2018-03-14T22:44:47.211Z,1521067487.211 [BPC1](INFO): Runnable
2018-03-14T22:44:47.615Z,1521067487.615 [BPC1](INFO): Runnable
2018-03-14T22:44:48.015Z,1521067488.015 [BPC1](INFO): Runnable
2018-03-14T22:44:48.413Z,1521067488.413 [BPC1](INFO): Runnable
2018-03-14T22:44:48.828Z,1521067488.828 [BPC1](INFO): Runnable
2018-03-14T22:44:48.828Z,1521067488.828 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:44:49.229Z,1521067489.229 [BPC1](INFO): Runnable
2018-03-14T22:44:49.631Z,1521067489.631 [BPC1](INFO): Runnable
2018-03-14T22:44:50.027Z,1521067490.027 [BPC1](INFO): Runnable
2018-03-14T22:44:50.404Z,1521067490.404 [BPC1](INFO): Runnable
2018-03-14T22:44:50.804Z,1521067490.804 [BPC1](INFO): Runnable
2018-03-14T22:44:51.209Z,1521067491.209 [BPC1](INFO): Runnable
2018-03-14T22:44:51.608Z,1521067491.608 [BPC1](INFO): Runnable
2018-03-14T22:44:52.012Z,1521067492.012 [BPC1](INFO): Runnable
2018-03-14T22:44:52.408Z,1521067492.408 [BPC1](INFO): Runnable
2018-03-14T22:44:52.820Z,1521067492.820 [BPC1](INFO): Runnable
2018-03-14T22:44:53.215Z,1521067493.215 [BPC1](INFO): Runnable
2018-03-14T22:44:53.215Z,1521067493.215 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:44:53.234Z,1521067493.234 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:44:53.616Z,1521067493.616 [BPC1](INFO): Runnable
2018-03-14T22:44:53.631Z,1521067493.631 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:44:54.032Z,1521067494.032 [BPC1](INFO): Runnable
2018-03-14T22:44:54.427Z,1521067494.427 [BPC1](INFO): Runnable
2018-03-14T22:44:54.831Z,1521067494.831 [BPC1](INFO): Runnable
2018-03-14T22:44:55.227Z,1521067495.227 [BPC1](INFO): Runnable
2018-03-14T22:44:55.604Z,1521067495.604 [BPC1](INFO): Runnable
2018-03-14T22:44:56.005Z,1521067496.005 [BPC1](INFO): Runnable
2018-03-14T22:44:56.404Z,1521067496.404 [BPC1](INFO): Runnable
2018-03-14T22:44:56.808Z,1521067496.808 [BPC1](INFO): Runnable
2018-03-14T22:44:57.213Z,1521067497.213 [BPC1](INFO): Runnable
2018-03-14T22:44:57.608Z,1521067497.608 [BPC1](INFO): Runnable
2018-03-14T22:44:58.012Z,1521067498.012 [BPC1](INFO): Runnable
2018-03-14T22:44:58.412Z,1521067498.412 [BPC1](INFO): Runnable
2018-03-14T22:44:58.819Z,1521067498.819 [BPC1](INFO): Runnable
2018-03-14T22:44:59.231Z,1521067499.231 [BPC1](INFO): Runnable
2018-03-14T22:44:59.623Z,1521067499.623 [BPC1](INFO): Runnable
2018-03-14T22:45:00.030Z,1521067500.030 [BPC1](INFO): Runnable
2018-03-14T22:45:00.427Z,1521067500.427 [BPC1](INFO): Runnable
2018-03-14T22:45:00.805Z,1521067500.805 [BPC1](INFO): Runnable
2018-03-14T22:45:01.204Z,1521067501.204 [BPC1](INFO): Runnable
2018-03-14T22:45:01.204Z,1521067501.204 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:45:01.612Z,1521067501.612 [BPC1](INFO): Runnable
2018-03-14T22:45:02.012Z,1521067502.012 [BPC1](INFO): Runnable
2018-03-14T22:45:02.408Z,1521067502.408 [BPC1](INFO): Runnable
2018-03-14T22:45:02.817Z,1521067502.817 [BPC1](INFO): Runnable
2018-03-14T22:45:03.212Z,1521067503.212 [BPC1](INFO): Runnable
2018-03-14T22:45:03.615Z,1521067503.615 [BPC1](INFO): Runnable
2018-03-14T22:45:04.013Z,1521067504.013 [BPC1](INFO): Runnable
2018-03-14T22:45:04.427Z,1521067504.427 [BPC1](INFO): Runnable
2018-03-14T22:45:04.823Z,1521067504.823 [BPC1](INFO): Runnable
2018-03-14T22:45:05.229Z,1521067505.229 [BPC1](INFO): Runnable
2018-03-14T22:45:05.230Z,1521067505.230 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-03-14T22:45:05.236Z,1521067505.236 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:45:05.627Z,1521067505.627 [BPC1](INFO): Runnable
2018-03-14T22:45:05.638Z,1521067505.638 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:45:06.004Z,1521067506.004 [BPC1](INFO): Runnable
2018-03-14T22:45:06.420Z,1521067506.420 [BPC1](INFO): Runnable
2018-03-14T22:45:06.804Z,1521067506.804 [BPC1](INFO): Runnable
2018-03-14T22:45:07.204Z,1521067507.204 [BPC1](INFO): Runnable
2018-03-14T22:45:07.604Z,1521067507.604 [BPC1](INFO): Runnable
2018-03-14T22:45:07.605Z,1521067507.605 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:45:08.004Z,1521067508.004 [BPC1](INFO): Runnable
2018-03-14T22:45:08.413Z,1521067508.413 [BPC1](INFO): Runnable
2018-03-14T22:45:08.808Z,1521067508.808 [BPC1](INFO): Runnable
2018-03-14T22:45:09.215Z,1521067509.215 [BPC1](INFO): Runnable
2018-03-14T22:45:09.622Z,1521067509.622 [BPC1](INFO): Runnable
2018-03-14T22:45:10.022Z,1521067510.022 [BPC1](INFO): Runnable
2018-03-14T22:45:10.428Z,1521067510.428 [BPC1](INFO): Runnable
2018-03-14T22:45:10.831Z,1521067510.831 [BPC1](INFO): Runnable
2018-03-14T22:45:10.831Z,1521067510.831 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-03-14T22:45:10.837Z,1521067510.837 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:45:11.204Z,1521067511.204 [BPC1](INFO): Runnable
2018-03-14T22:45:11.219Z,1521067511.219 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:45:11.604Z,1521067511.604 [BPC1](INFO): Runnable
2018-03-14T22:45:12.004Z,1521067512.004 [BPC1](INFO): Runnable
2018-03-14T22:45:12.412Z,1521067512.412 [BPC1](INFO): Runnable
2018-03-14T22:45:12.808Z,1521067512.808 [BPC1](INFO): Runnable
2018-03-14T22:45:13.213Z,1521067513.213 [BPC1](INFO): Runnable
2018-03-14T22:45:13.611Z,1521067513.611 [BPC1](INFO): Runnable
2018-03-14T22:45:14.011Z,1521067514.011 [BPC1](INFO): Runnable
2018-03-14T22:45:14.415Z,1521067514.415 [BPC1](INFO): Runnable
2018-03-14T22:45:14.827Z,1521067514.827 [BPC1](INFO): Runnable
2018-03-14T22:45:15.223Z,1521067515.223 [BPC1](INFO): Runnable
2018-03-14T22:45:15.627Z,1521067515.627 [BPC1](INFO): Runnable
2018-03-14T22:45:16.027Z,1521067516.027 [BPC1](INFO): Runnable
2018-03-14T22:45:16.404Z,1521067516.404 [BPC1](INFO): Runnable
2018-03-14T22:45:16.808Z,1521067516.808 [BPC1](INFO): Runnable
2018-03-14T22:45:17.204Z,1521067517.204 [BPC1](INFO): Runnable
2018-03-14T22:45:17.604Z,1521067517.604 [BPC1](INFO): Runnable
2018-03-14T22:45:18.009Z,1521067518.009 [BPC1](INFO): Runnable
2018-03-14T22:45:18.009Z,1521067518.009 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:45:18.405Z,1521067518.405 [BPC1](INFO): Runnable
2018-03-14T22:45:18.808Z,1521067518.808 [BPC1](INFO): Runnable
2018-03-14T22:45:19.212Z,1521067519.212 [BPC1](INFO): Runnable
2018-03-14T22:45:19.608Z,1521067519.608 [BPC1](INFO): Runnable
2018-03-14T22:45:20.023Z,1521067520.023 [BPC1](INFO): Runnable
2018-03-14T22:45:20.427Z,1521067520.427 [BPC1](INFO): Runnable
2018-03-14T22:45:20.828Z,1521067520.828 [BPC1](INFO): Runnable
2018-03-14T22:45:21.227Z,1521067521.227 [BPC1](INFO): Runnable
2018-03-14T22:45:21.608Z,1521067521.608 [BPC1](INFO): Runnable
2018-03-14T22:45:22.004Z,1521067522.004 [BPC1](INFO): Runnable
2018-03-14T22:45:22.005Z,1521067522.005 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-03-14T22:45:22.011Z,1521067522.011 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:45:22.408Z,1521067522.408 [BPC1](INFO): Runnable
2018-03-14T22:45:22.426Z,1521067522.426 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:45:22.809Z,1521067522.809 [BPC1](INFO): Runnable
2018-03-14T22:45:23.213Z,1521067523.213 [BPC1](INFO): Runnable
2018-03-14T22:45:23.611Z,1521067523.611 [BPC1](INFO): Runnable
2018-03-14T22:45:24.025Z,1521067524.025 [BPC1](INFO): Runnable
2018-03-14T22:45:24.026Z,1521067524.026 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:45:24.412Z,1521067524.412 [BPC1](INFO): Runnable
2018-03-14T22:45:24.813Z,1521067524.813 [BPC1](INFO): Runnable
2018-03-14T22:45:25.233Z,1521067525.233 [BPC1](INFO): Runnable
2018-03-14T22:45:25.629Z,1521067525.629 [BPC1](INFO): Runnable
2018-03-14T22:45:26.030Z,1521067526.030 [BPC1](INFO): Runnable
2018-03-14T22:45:26.440Z,1521067526.440 [BPC1](INFO): Runnable
2018-03-14T22:45:26.804Z,1521067526.804 [BPC1](INFO): Runnable
2018-03-14T22:45:27.204Z,1521067527.204 [BPC1](INFO): Runnable
2018-03-14T22:45:27.613Z,1521067527.613 [BPC1](INFO): Runnable
2018-03-14T22:45:28.008Z,1521067528.008 [BPC1](INFO): Runnable
2018-03-14T22:45:28.412Z,1521067528.412 [BPC1](INFO): Runnable
2018-03-14T22:45:28.412Z,1521067528.412 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:45:28.419Z,1521067528.419 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:45:28.811Z,1521067528.811 [BPC1](INFO): Runnable
2018-03-14T22:45:28.822Z,1521067528.822 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:45:29.212Z,1521067529.212 [BPC1](INFO): Runnable
2018-03-14T22:45:29.605Z,1521067529.605 [BPC1](INFO): Runnable
2018-03-14T22:45:30.009Z,1521067530.009 [BPC1](INFO): Runnable
2018-03-14T22:45:30.427Z,1521067530.427 [BPC1](INFO): Runnable
2018-03-14T22:45:30.815Z,1521067530.815 [BPC1](INFO): Runnable
2018-03-14T22:45:31.235Z,1521067531.235 [BPC1](INFO): Runnable
2018-03-14T22:45:31.620Z,1521067531.620 [BPC1](INFO): Runnable
2018-03-14T22:45:32.016Z,1521067532.016 [BPC1](INFO): Runnable
2018-03-14T22:45:32.418Z,1521067532.418 [BPC1](INFO): Runnable
2018-03-14T22:45:32.815Z,1521067532.815 [BPC1](INFO): Runnable
2018-03-14T22:45:33.215Z,1521067533.215 [BPC1](INFO): Runnable
2018-03-14T22:45:33.615Z,1521067533.615 [BPC1](INFO): Runnable
2018-03-14T22:45:34.012Z,1521067534.012 [BPC1](INFO): Runnable
2018-03-14T22:45:34.416Z,1521067534.416 [BPC1](INFO): Runnable
2018-03-14T22:45:34.804Z,1521067534.804 [BPC1](INFO): Runnable
2018-03-14T22:45:35.227Z,1521067535.227 [BPC1](INFO): Runnable
2018-03-14T22:45:35.633Z,1521067535.633 [BPC1](INFO): Runnable
2018-03-14T22:45:36.005Z,1521067536.005 [BPC1](INFO): Runnable
2018-03-14T22:45:36.405Z,1521067536.405 [BPC1](INFO): Runnable
2018-03-14T22:45:36.406Z,1521067536.406 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:45:36.809Z,1521067536.809 [BPC1](INFO): Runnable
2018-03-14T22:45:37.218Z,1521067537.218 [BPC1](INFO): Runnable
2018-03-14T22:45:37.608Z,1521067537.608 [BPC1](INFO): Runnable
2018-03-14T22:45:38.012Z,1521067538.012 [BPC1](INFO): Runnable
2018-03-14T22:45:38.413Z,1521067538.413 [BPC1](INFO): Runnable
2018-03-14T22:45:38.823Z,1521067538.823 [BPC1](INFO): Runnable
2018-03-14T22:45:38.843Z,1521067538.843 [Startup:StartupSatComms:A](INFO): Timed out from 2018-03-14T22:44:38.5Z
2018-03-14T22:45:38.843Z,1521067538.843 [Startup:StartupSatComms:A] Stopped
2018-03-14T22:45:38.843Z,1521067538.843 [Startup:StartupSatComms:B] Running Loop=1
2018-03-14T22:45:39.227Z,1521067539.227 [BPC1](INFO): Runnable
2018-03-14T22:45:39.237Z,1521067539.237 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-03-14T22:45:39.632Z,1521067539.632 [BPC1](INFO): Runnable
2018-03-14T22:45:40.023Z,1521067540.023 [BPC1](INFO): Runnable
2018-03-14T22:45:40.408Z,1521067540.408 [BPC1](INFO): Runnable
2018-03-14T22:45:40.804Z,1521067540.804 [BPC1](INFO): Runnable
2018-03-14T22:45:40.804Z,1521067540.804 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:45:40.811Z,1521067540.811 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:45:41.208Z,1521067541.208 [BPC1](INFO): Runnable
2018-03-14T22:45:41.227Z,1521067541.227 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:45:41.604Z,1521067541.604 [BPC1](INFO): Runnable
2018-03-14T22:45:42.017Z,1521067542.017 [BPC1](INFO): Runnable
2018-03-14T22:45:42.412Z,1521067542.412 [BPC1](INFO): Runnable
2018-03-14T22:45:42.812Z,1521067542.812 [BPC1](INFO): Runnable
2018-03-14T22:45:43.213Z,1521067543.213 [BPC1](INFO): Runnable
2018-03-14T22:45:43.611Z,1521067543.611 [BPC1](INFO): Runnable
2018-03-14T22:45:44.026Z,1521067544.026 [BPC1](INFO): Runnable
2018-03-14T22:45:44.427Z,1521067544.427 [BPC1](INFO): Runnable
2018-03-14T22:45:44.829Z,1521067544.829 [BPC1](INFO): Runnable
2018-03-14T22:45:45.215Z,1521067545.215 [BPC1](INFO): Runnable
2018-03-14T22:45:45.604Z,1521067545.604 [BPC1](INFO): Runnable
2018-03-14T22:45:46.004Z,1521067546.004 [BPC1](INFO): Runnable
2018-03-14T22:45:46.404Z,1521067546.404 [BPC1](INFO): Runnable
2018-03-14T22:45:46.804Z,1521067546.804 [BPC1](INFO): Runnable
2018-03-14T22:45:47.204Z,1521067547.204 [BPC1](INFO): Runnable
2018-03-14T22:45:47.608Z,1521067547.608 [BPC1](INFO): Runnable
2018-03-14T22:45:48.011Z,1521067548.011 [BPC1](INFO): Runnable
2018-03-14T22:45:48.408Z,1521067548.408 [BPC1](INFO): Runnable
2018-03-14T22:45:48.812Z,1521067548.812 [BPC1](INFO): Runnable
2018-03-14T22:45:48.812Z,1521067548.812 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:45:49.229Z,1521067549.229 [BPC1](INFO): Runnable
2018-03-14T22:45:49.627Z,1521067549.627 [BPC1](INFO): Runnable
2018-03-14T22:45:50.027Z,1521067550.027 [BPC1](INFO): Runnable
2018-03-14T22:45:50.432Z,1521067550.432 [BPC1](INFO): Runnable
2018-03-14T22:45:50.804Z,1521067550.804 [BPC1](INFO): Runnable
2018-03-14T22:45:51.208Z,1521067551.208 [BPC1](INFO): Runnable
2018-03-14T22:45:51.609Z,1521067551.609 [BPC1](INFO): Runnable
2018-03-14T22:45:52.012Z,1521067552.012 [BPC1](INFO): Runnable
2018-03-14T22:45:52.412Z,1521067552.412 [BPC1](INFO): Runnable
2018-03-14T22:45:52.811Z,1521067552.811 [BPC1](INFO): Runnable
2018-03-14T22:45:53.211Z,1521067553.211 [BPC1](INFO): Runnable
2018-03-14T22:45:53.212Z,1521067553.212 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:45:53.228Z,1521067553.228 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:45:53.617Z,1521067553.617 [BPC1](INFO): Runnable
2018-03-14T22:45:53.631Z,1521067553.631 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:45:54.015Z,1521067554.015 [BPC1](INFO): Runnable
2018-03-14T22:45:54.427Z,1521067554.427 [BPC1](INFO): Runnable
2018-03-14T22:45:54.815Z,1521067554.815 [BPC1](INFO): Runnable
2018-03-14T22:45:55.218Z,1521067555.218 [BPC1](INFO): Runnable
2018-03-14T22:45:55.615Z,1521067555.615 [BPC1](INFO): Runnable
2018-03-14T22:45:56.008Z,1521067556.008 [BPC1](INFO): Runnable
2018-03-14T22:45:56.413Z,1521067556.413 [BPC1](INFO): Runnable
2018-03-14T22:45:56.811Z,1521067556.811 [BPC1](INFO): Runnable
2018-03-14T22:45:57.212Z,1521067557.212 [BPC1](INFO): Runnable
2018-03-14T22:45:57.615Z,1521067557.615 [BPC1](INFO): Runnable
2018-03-14T22:45:58.004Z,1521067558.004 [BPC1](INFO): Runnable
2018-03-14T22:45:58.404Z,1521067558.404 [BPC1](INFO): Runnable
2018-03-14T22:45:58.804Z,1521067558.804 [BPC1](INFO): Runnable
2018-03-14T22:45:59.204Z,1521067559.204 [BPC1](INFO): Runnable
2018-03-14T22:45:59.622Z,1521067559.622 [BPC1](INFO): Runnable
2018-03-14T22:46:00.022Z,1521067560.022 [BPC1](INFO): Runnable
2018-03-14T22:46:00.424Z,1521067560.424 [BPC1](INFO): Runnable
2018-03-14T22:46:00.817Z,1521067560.817 [BPC1](INFO): Runnable
2018-03-14T22:46:00.817Z,1521067560.817 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:46:01.218Z,1521067561.218 [BPC1](INFO): Runnable
2018-03-14T22:46:01.615Z,1521067561.615 [BPC1](INFO): Runnable
2018-03-14T22:46:02.017Z,1521067562.017 [BPC1](INFO): Runnable
2018-03-14T22:46:02.414Z,1521067562.414 [BPC1](INFO): Runnable
2018-03-14T22:46:02.819Z,1521067562.819 [BPC1](INFO): Runnable
2018-03-14T22:46:03.211Z,1521067563.211 [BPC1](INFO): Runnable
2018-03-14T22:46:03.604Z,1521067563.604 [BPC1](INFO): Runnable
2018-03-14T22:46:04.004Z,1521067564.004 [BPC1](INFO): Runnable
2018-03-14T22:46:04.404Z,1521067564.404 [BPC1](INFO): Runnable
2018-03-14T22:46:04.827Z,1521067564.827 [BPC1](INFO): Runnable
2018-03-14T22:46:04.827Z,1521067564.827 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-03-14T22:46:04.833Z,1521067564.833 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:46:05.235Z,1521067565.235 [BPC1](INFO): Runnable
2018-03-14T22:46:05.248Z,1521067565.248 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:46:05.630Z,1521067565.630 [BPC1](INFO): Runnable
2018-03-14T22:46:06.026Z,1521067566.026 [BPC1](INFO): Runnable
2018-03-14T22:46:06.412Z,1521067566.412 [BPC1](INFO): Runnable
2018-03-14T22:46:06.812Z,1521067566.812 [BPC1](INFO): Runnable
2018-03-14T22:46:07.212Z,1521067567.212 [BPC1](INFO): Runnable
2018-03-14T22:46:07.611Z,1521067567.611 [BPC1](INFO): Runnable
2018-03-14T22:46:08.014Z,1521067568.014 [BPC1](INFO): Runnable
2018-03-14T22:46:08.414Z,1521067568.414 [BPC1](INFO): Runnable
2018-03-14T22:46:08.812Z,1521067568.812 [BPC1](INFO): Runnable
2018-03-14T22:46:09.204Z,1521067569.204 [BPC1](INFO): Runnable
2018-03-14T22:46:09.604Z,1521067569.604 [BPC1](INFO): Runnable
2018-03-14T22:46:10.032Z,1521067570.032 [BPC1](INFO): Runnable
2018-03-14T22:46:10.427Z,1521067570.427 [BPC1](INFO): Runnable
2018-03-14T22:46:10.831Z,1521067570.831 [BPC1](INFO): Runnable
2018-03-14T22:46:11.223Z,1521067571.223 [BPC1](INFO): Runnable
2018-03-14T22:46:11.615Z,1521067571.615 [BPC1](INFO): Runnable
2018-03-14T22:46:12.013Z,1521067572.013 [BPC1](INFO): Runnable
2018-03-14T22:46:12.013Z,1521067572.013 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:46:12.412Z,1521067572.412 [BPC1](INFO): Runnable
2018-03-14T22:46:12.821Z,1521067572.821 [BPC1](INFO): Runnable
2018-03-14T22:46:13.211Z,1521067573.211 [BPC1](INFO): Runnable
2018-03-14T22:46:13.604Z,1521067573.604 [BPC1](INFO): Runnable
2018-03-14T22:46:14.004Z,1521067574.004 [BPC1](INFO): Runnable
2018-03-14T22:46:14.404Z,1521067574.404 [BPC1](INFO): Runnable
2018-03-14T22:46:14.804Z,1521067574.804 [BPC1](INFO): Runnable
2018-03-14T22:46:15.234Z,1521067575.234 [BPC1](INFO): Runnable
2018-03-14T22:46:15.619Z,1521067575.619 [BPC1](INFO): Runnable
2018-03-14T22:46:16.019Z,1521067576.019 [BPC1](INFO): Runnable
2018-03-14T22:46:16.020Z,1521067576.020 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-03-14T22:46:16.026Z,1521067576.026 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:46:16.419Z,1521067576.419 [BPC1](INFO): Runnable
2018-03-14T22:46:16.430Z,1521067576.430 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:46:16.813Z,1521067576.813 [BPC1](INFO): Runnable
2018-03-14T22:46:17.211Z,1521067577.211 [BPC1](INFO): Runnable
2018-03-14T22:46:17.613Z,1521067577.613 [BPC1](INFO): Runnable
2018-03-14T22:46:18.027Z,1521067578.027 [BPC1](INFO): Runnable
2018-03-14T22:46:18.431Z,1521067578.431 [BPC1](INFO): Runnable
2018-03-14T22:46:18.431Z,1521067578.431 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:46:18.809Z,1521067578.809 [BPC1](INFO): Runnable
2018-03-14T22:46:19.209Z,1521067579.209 [BPC1](INFO): Runnable
2018-03-14T22:46:19.625Z,1521067579.625 [BPC1](INFO): Runnable
2018-03-14T22:46:20.019Z,1521067580.019 [BPC1](INFO): Runnable
2018-03-14T22:46:20.435Z,1521067580.435 [BPC1](INFO): Runnable
2018-03-14T22:46:20.832Z,1521067580.832 [BPC1](INFO): Runnable
2018-03-14T22:46:20.844Z,1521067580.844 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-03-14T22:46:20.844Z,1521067580.844 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-03-14T22:46:20.844Z,1521067580.844 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-03-14T22:46:20.846Z,1521067580.846 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-03-14T22:46:20.846Z,1521067580.846 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-03-14T22:46:20.846Z,1521067580.846 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-03-14T22:46:20.900Z,1521067580.900 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-03-14T22:46:20.900Z,1521067580.900 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-03-14T22:46:21.248Z,1521067581.248 [BPC1](INFO): Runnable
2018-03-14T22:46:21.378Z,1521067581.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-03-14T22:46:21.378Z,1521067581.378 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-03-14T22:46:21.398Z,1521067581.398 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-03-14T22:46:21.398Z,1521067581.398 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-03-14T22:46:21.668Z,1521067581.668 [BPC1](INFO): Runnable
2018-03-14T22:46:21.691Z,1521067581.691 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-03-14T22:46:21.692Z,1521067581.692 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T22:46:21.692Z,1521067581.692 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T22:46:21.692Z,1521067581.692 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-03-14T22:46:21.693Z,1521067581.693 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T22:46:21.693Z,1521067581.693 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T22:46:28.895Z,1521067588.895 [BPC1](INFO): Runnable
2018-03-14T22:46:28.895Z,1521067588.895 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:46:28.902Z,1521067588.902 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:46:29.043Z,1521067589.043 [BPC1](INFO): Runnable
2018-03-14T22:46:29.054Z,1521067589.054 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:46:29.421Z,1521067589.421 [BPC1](INFO): Runnable
2018-03-14T22:46:29.766Z,1521067589.766 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003863
2018-03-14T22:46:29.826Z,1521067589.826 [BPC1](INFO): Runnable
2018-03-14T22:46:30.335Z,1521067590.335 [BPC1](INFO): Runnable
2018-03-14T22:46:30.611Z,1521067590.611 [BPC1](INFO): Runnable
2018-03-14T22:46:31.719Z,1521067591.719 [CommandLine](IMPORTANT): got command restart application
2018-03-14T22:46:32.722Z,1521067592.722 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-03-14T22:46:32.725Z,1521067592.725 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:46:32.725Z,1521067592.725 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:32.838Z,1521067592.838 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-03-14T22:46:32.839Z,1521067592.839 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:32.839Z,1521067592.839 [CommandLine](INFO): Join timeout helper Thread ID is 913
2018-03-14T22:46:32.850Z,1521067592.850 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-03-14T22:46:32.851Z,1521067592.851 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:32.851Z,1521067592.851 [NavChartDb](INFO): Join timeout helper Thread ID is 914
2018-03-14T22:46:32.915Z,1521067592.915 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:46:32.915Z,1521067592.915 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:32.926Z,1521067592.926 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-03-14T22:46:32.927Z,1521067592.927 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:32.927Z,1521067592.927 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 915
2018-03-14T22:46:32.983Z,1521067592.983 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:46:32.983Z,1521067592.983 [WetLabsBB2FL](INFO): Powering down
2018-03-14T22:46:32.984Z,1521067592.984 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:32.991Z,1521067592.991 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-03-14T22:46:32.991Z,1521067592.991 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:32.991Z,1521067592.991 [Radio_Surface](INFO): Join timeout helper Thread ID is 916
2018-03-14T22:46:33.375Z,1521067593.375 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:46:33.375Z,1521067593.375 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:33.379Z,1521067593.379 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-03-14T22:46:33.379Z,1521067593.379 [logger ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:33.379Z,1521067593.379 [logger](INFO): Join timeout helper Thread ID is 917
2018-03-14T22:46:33.379Z,1521067593.379 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:46:33.380Z,1521067593.380 [logger ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:33.381Z,1521067593.381 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-03-14T22:46:33.381Z,1521067593.381 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:33.381Z,1521067593.381 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-03-14T22:46:33.381Z,1521067593.381 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:33.382Z,1521067593.382 [controlThread](INFO): Join timeout helper Thread ID is 918
2018-03-14T22:46:33.887Z,1521067593.887 [BPC1](INFO): Runnable
2018-03-14T22:46:33.907Z,1521067593.907 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:46:33.908Z,1521067593.908 [controlThread](DEBUG): Uninitializing ControlThread
2018-03-14T22:46:33.911Z,1521067593.911 [NAL9602](INFO): Powering down
2018-03-14T22:46:33.912Z,1521067593.912 [PNI_TCM](INFO): Powering down
2018-03-14T22:46:33.999Z,1521067593.999 [BPC1](INFO): uninitialize
2018-03-14T22:46:33.999Z,1521067593.999 [Aanderaa_O2](INFO): Powering down
2018-03-14T22:46:34.001Z,1521067594.001 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-03-14T22:46:34.003Z,1521067594.003 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-03-14T22:46:34.004Z,1521067594.004 [MissionManager](INFO): Uninitializing Mission Default
2018-03-14T22:46:34.006Z,1521067594.006 [MissionManager](INFO): Uninitializing Mission Startup
2018-03-14T22:46:34.006Z,1521067594.006 [Startup] Stopped
2018-03-14T22:46:34.006Z,1521067594.006 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-03-14T22:46:34.006Z,1521067594.006 [Startup:A.GoToSurface] Stopped
2018-03-14T22:46:34.006Z,1521067594.006 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-03-14T22:46:34.006Z,1521067594.006 [Startup:StartupSatComms] Stopped
2018-03-14T22:46:34.007Z,1521067594.007 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-03-14T22:46:34.007Z,1521067594.007 [Startup:StartupSatComms:B] Stopped
2018-03-14T22:46:34.008Z,1521067594.008 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-03-14T22:46:34.008Z,1521067594.008 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-03-14T22:46:34.009Z,1521067594.009 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-03-14T22:46:34.009Z,1521067594.009 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-03-14T22:46:34.009Z,1521067594.009 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-03-14T22:46:34.009Z,1521067594.009 [BuoyancyServo](INFO): Powering down
2018-03-14T22:46:34.023Z,1521067594.023 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-03-14T22:46:34.023Z,1521067594.023 [ElevatorServo](INFO): Powering down
2018-03-14T22:46:34.023Z,1521067594.023 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-03-14T22:46:34.024Z,1521067594.024 [MassServo](INFO): Powering down
2018-03-14T22:46:34.024Z,1521067594.024 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-03-14T22:46:34.024Z,1521067594.024 [RudderServo](INFO): Powering down
2018-03-14T22:46:34.025Z,1521067594.025 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-03-14T22:46:34.025Z,1521067594.025 [ThrusterServo](INFO): Powering down
2018-03-14T22:46:34.026Z,1521067594.026 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-03-14T22:46:34.026Z,1521067594.026 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-03-14T22:46:34.027Z,1521067594.027 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-03-14T22:46:34.027Z,1521067594.027 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:34.064Z,1521067594.064 [BPC1](INFO): uninitialize
2018-03-14T22:46:34.110Z,1521067594.110 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:34.147Z,1521067594.147 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:34.176Z,1521067594.176 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:46:34.224Z,1521067594.224 [logger ThreadHandler](INFO): Thread cancelled.