2018-03-14T22:05:30.625Z,1521065130.625 [Supervisor](DEBUG): Initializing supervisor.
2018-03-14T22:05:30.628Z,1521065130.628 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-03-14T22:05:30.628Z,1521065130.628 [SyncHandler](INFO): Protected caller Thread ID is 9953
2018-03-14T22:05:30.629Z,1521065130.629 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-03-14T22:05:30.630Z,1521065130.630 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-03-14T22:05:30.631Z,1521065130.631 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9954
2018-03-14T22:05:30.633Z,1521065130.633 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-03-14T22:05:30.645Z,1521065130.645 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-03-14T22:05:30.646Z,1521065130.646 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-03-14T22:05:30.646Z,1521065130.646 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9955
2018-03-14T22:05:30.647Z,1521065130.647 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-03-14T22:05:30.648Z,1521065130.648 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-03-14T22:05:30.648Z,1521065130.648 [logger ThreadHandler](INFO): Protected caller Thread ID is 9956
2018-03-14T22:05:30.650Z,1521065130.650 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-03-14T22:05:30.650Z,1521065130.650 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-03-14T22:05:30.652Z,1521065130.652 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-03-14T22:05:30.946Z,1521065130.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-03-14T22:05:30.946Z,1521065130.946 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-03-14T22:05:31.128Z,1521065131.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-03-14T22:05:31.129Z,1521065131.129 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-03-14T22:05:31.385Z,1521065131.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-03-14T22:05:31.386Z,1521065131.386 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-03-14T22:05:31.521Z,1521065131.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-03-14T22:05:31.522Z,1521065131.522 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-03-14T22:05:31.615Z,1521065131.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-03-14T22:05:31.616Z,1521065131.616 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-03-14T22:05:32.033Z,1521065132.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-03-14T22:05:32.034Z,1521065132.034 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-03-14T22:05:32.347Z,1521065132.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-03-14T22:05:32.348Z,1521065132.348 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-03-14T22:05:32.749Z,1521065132.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-03-14T22:05:32.749Z,1521065132.749 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-03-14T22:05:32.849Z,1521065132.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-03-14T22:05:32.849Z,1521065132.849 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-03-14T22:05:32.994Z,1521065132.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-03-14T22:05:32.995Z,1521065132.995 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-03-14T22:05:33.223Z,1521065133.223 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-03-14T22:05:33.224Z,1521065133.224 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-03-14T22:05:33.539Z,1521065133.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-03-14T22:05:33.540Z,1521065133.540 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-03-14T22:05:33.850Z,1521065133.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-03-14T22:05:33.851Z,1521065133.851 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-03-14T22:05:33.984Z,1521065133.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-03-14T22:05:33.985Z,1521065133.985 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-03-14T22:05:34.065Z,1521065134.065 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-03-14T22:05:34.066Z,1521065134.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-03-14T22:05:34.163Z,1521065134.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-03-14T22:05:34.250Z,1521065134.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-03-14T22:05:34.341Z,1521065134.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-03-14T22:05:34.423Z,1521065134.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-03-14T22:05:34.563Z,1521065134.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-03-14T22:05:34.724Z,1521065134.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-03-14T22:05:34.798Z,1521065134.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-03-14T22:05:35.014Z,1521065135.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-03-14T22:05:35.014Z,1521065135.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-03-14T22:05:35.095Z,1521065135.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-03-14T22:05:35.182Z,1521065135.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-03-14T22:05:35.279Z,1521065135.279 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-03-14T22:05:35.399Z,1521065135.399 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-03-14T22:05:35.399Z,1521065135.399 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-03-14T22:05:35.405Z,1521065135.405 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-03-14T22:05:35.635Z,1521065135.635 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-03-14T22:05:35.635Z,1521065135.635 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-03-14T22:05:35.675Z,1521065135.675 [DepthRateCalculator] Loaded
2018-03-14T22:05:35.676Z,1521065135.676 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-03-14T22:05:35.681Z,1521065135.681 [PitchRateCalculator] Loaded
2018-03-14T22:05:35.682Z,1521065135.682 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-03-14T22:05:35.697Z,1521065135.697 [SpeedCalculator] Loaded
2018-03-14T22:05:35.697Z,1521065135.697 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-03-14T22:05:35.717Z,1521065135.717 [TempGradientCalculator] Loaded
2018-03-14T22:05:35.717Z,1521065135.717 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-03-14T22:05:35.732Z,1521065135.732 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-03-14T22:05:35.732Z,1521065135.732 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-03-14T22:05:35.737Z,1521065135.737 [YawRateCalculator] Loaded
2018-03-14T22:05:35.738Z,1521065135.738 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-03-14T22:05:35.738Z,1521065135.738 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-03-14T22:05:35.739Z,1521065135.739 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-03-14T22:05:35.818Z,1521065135.818 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-03-14T22:05:35.818Z,1521065135.818 [StratificationFrontDetector](DEBUG): (re)initializing
2018-03-14T22:05:35.819Z,1521065135.819 [StratificationFrontDetector] Loaded
2018-03-14T22:05:35.819Z,1521065135.819 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-03-14T22:05:35.819Z,1521065135.819 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-03-14T22:05:35.820Z,1521065135.820 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-03-14T22:05:35.931Z,1521065135.931 [BuoyancyServo] Loaded
2018-03-14T22:05:35.931Z,1521065135.931 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-03-14T22:05:35.945Z,1521065135.945 [ElevatorServo] Loaded
2018-03-14T22:05:35.945Z,1521065135.945 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-03-14T22:05:35.959Z,1521065135.959 [MassServo] Loaded
2018-03-14T22:05:35.960Z,1521065135.960 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-03-14T22:05:35.974Z,1521065135.974 [RudderServo] Loaded
2018-03-14T22:05:35.974Z,1521065135.974 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-03-14T22:05:35.988Z,1521065135.988 [ThrusterServo] Loaded
2018-03-14T22:05:35.988Z,1521065135.988 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-03-14T22:05:35.988Z,1521065135.988 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-03-14T22:05:35.989Z,1521065135.989 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-03-14T22:05:36.002Z,1521065136.002 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-03-14T22:05:36.002Z,1521065136.002 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-03-14T22:05:36.095Z,1521065136.095 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-03-14T22:05:36.095Z,1521065136.095 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-03-14T22:05:36.347Z,1521065136.347 [DataOverHttps] Loaded
2018-03-14T22:05:36.348Z,1521065136.348 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-03-14T22:05:36.360Z,1521065136.360 [Depth_Keller] Loaded
2018-03-14T22:05:36.361Z,1521065136.361 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-03-14T22:05:36.365Z,1521065136.365 [DropWeight] Loaded
2018-03-14T22:05:36.365Z,1521065136.365 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-03-14T22:05:36.459Z,1521065136.459 [NAL9602] Loaded
2018-03-14T22:05:36.460Z,1521065136.460 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-03-14T22:05:36.475Z,1521065136.475 [Onboard] Loaded
2018-03-14T22:05:36.475Z,1521065136.475 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-03-14T22:05:36.485Z,1521065136.485 [Radio_Surface] Loaded
2018-03-14T22:05:36.485Z,1521065136.485 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-03-14T22:05:36.486Z,1521065136.486 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408714E0
2018-03-14T22:05:36.486Z,1521065136.486 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 338
2018-03-14T22:05:36.532Z,1521065136.532 [PNI_TCM] Loaded
2018-03-14T22:05:36.532Z,1521065136.532 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-03-14T22:05:38.193Z,1521065138.193 [BPC1] Loaded
2018-03-14T22:05:38.193Z,1521065138.193 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-03-14T22:05:38.194Z,1521065138.194 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-03-14T22:05:38.194Z,1521065138.194 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-03-14T22:05:38.548Z,1521065138.548 [SBIT](DEBUG): Construct Startup Built In Test.
2018-03-14T22:05:38.593Z,1521065138.593 [SBIT] Loaded
2018-03-14T22:05:38.593Z,1521065138.593 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-03-14T22:05:38.594Z,1521065138.594 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-03-14T22:05:38.638Z,1521065138.638 [IBIT] Loaded
2018-03-14T22:05:38.638Z,1521065138.638 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-03-14T22:05:38.641Z,1521065138.641 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-03-14T22:05:38.865Z,1521065138.865 [CBIT] Loaded
2018-03-14T22:05:38.865Z,1521065138.865 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-03-14T22:05:38.866Z,1521065138.866 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-03-14T22:05:38.866Z,1521065138.866 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-03-14T22:05:39.001Z,1521065139.001 [Aanderaa_O2] Loaded
2018-03-14T22:05:39.001Z,1521065139.001 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-03-14T22:05:39.012Z,1521065139.012 [CANONSampler] Loaded
2018-03-14T22:05:39.013Z,1521065139.013 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-03-14T22:05:39.026Z,1521065139.026 [PAR_Licor] Loaded
2018-03-14T22:05:39.026Z,1521065139.026 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-03-14T22:05:39.073Z,1521065139.073 [WetLabsBB2FL] Loaded
2018-03-14T22:05:39.073Z,1521065139.073 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-03-14T22:05:39.075Z,1521065139.075 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2018-03-14T22:05:39.075Z,1521065139.075 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 339
2018-03-14T22:05:39.075Z,1521065139.075 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-03-14T22:05:39.076Z,1521065139.076 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-03-14T22:05:39.140Z,1521065139.140 [VerticalControl](DEBUG): Construct VerticalControl.
2018-03-14T22:05:39.224Z,1521065139.224 [VerticalControl] Loaded
2018-03-14T22:05:39.224Z,1521065139.224 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-03-14T22:05:39.225Z,1521065139.225 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-03-14T22:05:39.279Z,1521065139.279 [HorizontalControl] Loaded
2018-03-14T22:05:39.279Z,1521065139.279 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-03-14T22:05:39.279Z,1521065139.279 [SpeedControl](DEBUG): Construct SpeedControl.
2018-03-14T22:05:39.281Z,1521065139.281 [SpeedControl] Loaded
2018-03-14T22:05:39.281Z,1521065139.281 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-03-14T22:05:39.282Z,1521065139.282 [LoopControl](DEBUG): Construct LoopControl.
2018-03-14T22:05:39.282Z,1521065139.282 [LoopControl] Loaded
2018-03-14T22:05:39.283Z,1521065139.283 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-03-14T22:05:39.283Z,1521065139.283 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-03-14T22:05:39.284Z,1521065139.284 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-03-14T22:05:39.307Z,1521065139.307 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-03-14T22:05:39.307Z,1521065139.307 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-03-14T22:05:39.394Z,1521065139.394 [DeadReckonUsingSpeedCalculator] Loaded
2018-03-14T22:05:39.395Z,1521065139.395 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-03-14T22:05:39.447Z,1521065139.447 [DeadReckonWithRespectToSeafloor] Loaded
2018-03-14T22:05:39.447Z,1521065139.447 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-03-14T22:05:39.463Z,1521065139.463 [NavChart] Loaded
2018-03-14T22:05:39.463Z,1521065139.463 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-03-14T22:05:39.467Z,1521065139.467 [UniversalFixResidualReporter] Loaded
2018-03-14T22:05:39.467Z,1521065139.467 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-03-14T22:05:39.468Z,1521065139.468 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-03-14T22:05:39.471Z,1521065139.471 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-03-14T22:05:39.472Z,1521065139.472 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-03-14T22:05:39.478Z,1521065139.478 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-03-14T22:05:39.479Z,1521065139.479 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409BD4E0
2018-03-14T22:05:39.480Z,1521065139.480 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 340
2018-03-14T22:05:39.484Z,1521065139.484 [Supervisor](INFO): Main Thread ID is 9710
2018-03-14T22:05:39.484Z,1521065139.484 [Supervisor](DEBUG): Running supervisor.
2018-03-14T22:05:39.485Z,1521065139.485 [CommandLine ThreadHandler](INFO): Handler Thread ID is 341
2018-03-14T22:05:39.488Z,1521065139.488 [controlThread ThreadHandler](INFO): Handler Thread ID is 342
2018-03-14T22:05:39.488Z,1521065139.488 [controlThread](DEBUG): Initializing ControlThread
2018-03-14T22:05:39.489Z,1521065139.489 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-03-14T22:05:39.489Z,1521065139.489 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-03-14T22:05:39.489Z,1521065139.489 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-03-14T22:05:39.490Z,1521065139.490 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-03-14T22:05:39.490Z,1521065139.490 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-03-14T22:05:39.491Z,1521065139.491 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-03-14T22:05:39.497Z,1521065139.497 [SBIT](INFO): Initialize SBIT Component.
2018-03-14T22:05:39.498Z,1521065139.498 [SBIT](IMPORTANT): git: 2018-03-10-3-g1088291
2018-03-14T22:05:39.498Z,1521065139.498 [SBIT](INFO): git hash: 10882917f95cfba58cc6008bb2c6cc253db9d857
2018-03-14T22:05:39.498Z,1521065139.498 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-03-14T22:05:39.499Z,1521065139.499 [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:05:39.500Z,1521065139.500 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-03-14T22:05:39.501Z,1521065139.501 [IBIT](INFO): Initialize IBIT Component.
2018-03-14T22:05:39.501Z,1521065139.501 [CBIT](DEBUG): Initialize CBIT Component.
2018-03-14T22:05:39.502Z,1521065139.502 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-03-14T22:05:39.503Z,1521065139.503 [logger ThreadHandler](INFO): Handler Thread ID is 343
2018-03-14T22:05:39.523Z,1521065139.523 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 344
2018-03-14T22:05:39.529Z,1521065139.529 [Radio_Surface](INFO): Powering up
2018-03-14T22:05:39.535Z,1521065139.535 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 345
2018-03-14T22:05:39.536Z,1521065139.536 [WetLabsBB2FL](INFO): Powering down
2018-03-14T22:05:39.567Z,1521065139.567 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 346
2018-03-14T22:05:39.570Z,1521065139.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-03-14T22:05:39.570Z,1521065139.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-03-14T22:05:39.571Z,1521065139.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-03-14T22:05:39.571Z,1521065139.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-03-14T22:05:39.571Z,1521065139.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-03-14T22:05:39.571Z,1521065139.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-03-14T22:05:39.571Z,1521065139.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-03-14T22:05:39.572Z,1521065139.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-03-14T22:05:39.572Z,1521065139.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-03-14T22:05:39.572Z,1521065139.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-03-14T22:05:39.572Z,1521065139.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-03-14T22:05:39.572Z,1521065139.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-03-14T22:05:39.573Z,1521065139.573 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-03-14T22:05:39.573Z,1521065139.573 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-03-14T22:05:39.573Z,1521065139.573 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-03-14T22:05:39.573Z,1521065139.573 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-03-14T22:05:39.611Z,1521065139.611 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-03-14T22:05:39.612Z,1521065139.612 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-03-14T22:05:39.613Z,1521065139.613 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-03-14T22:05:39.614Z,1521065139.614 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-03-14T22:05:39.614Z,1521065139.614 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-03-14T22:05:39.615Z,1521065139.615 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T22:05:39.615Z,1521065139.615 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T22:05:39.616Z,1521065139.616 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-03-14T22:05:39.616Z,1521065139.616 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T22:05:39.616Z,1521065139.616 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T22:05:39.617Z,1521065139.617 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-03-14T22:05:39.617Z,1521065139.617 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-03-14T22:05:39.618Z,1521065139.618 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-03-14T22:05:39.652Z,1521065139.652 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-03-14T22:05:39.679Z,1521065139.679 [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:05:39.680Z,1521065139.680 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-03-14T22:05:39.757Z,1521065139.757 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-03-14T22:05:39.758Z,1521065139.758 [Default:A.Wait](DEBUG): Construct Wait.
2018-03-14T22:05:39.760Z,1521065139.760 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-03-14T22:05:39.786Z,1521065139.786 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-03-14T22:05:39.805Z,1521065139.805 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-03-14T22:05:39.816Z,1521065139.816 [Default:E.Execute](DEBUG): Construct Execute.
2018-03-14T22:05:39.835Z,1521065139.835 [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:05:39.840Z,1521065139.840 [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:05:39.863Z,1521065139.863 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-03-14T22:05:39.901Z,1521065139.901 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-03-14T22:05:40.012Z,1521065140.012 [BPC1](INFO): Start
2018-03-14T22:05:40.125Z,1521065140.125 [CANONSampler](INFO): Powering down
2018-03-14T22:05:40.226Z,1521065140.226 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-03-14T22:05:40.331Z,1521065140.331 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:40.346Z,1521065140.346 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:40.447Z,1521065140.447 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:05:40.455Z,1521065140.455 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-03-14T22:05:40.477Z,1521065140.477 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:05:40.483Z,1521065140.483 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-03-14T22:05:40.489Z,1521065140.489 [MassServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:05:40.495Z,1521065140.495 [MassServo](DEBUG): Initializing MassServo.
2018-03-14T22:05:40.500Z,1521065140.500 [RudderServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:05:40.507Z,1521065140.507 [RudderServo](DEBUG): Initializing RudderServo.
2018-03-14T22:05:40.528Z,1521065140.528 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-03-14T22:05:40.535Z,1521065140.535 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-03-14T22:05:40.795Z,1521065140.795 [BPC1](INFO): Starting
2018-03-14T22:05:40.823Z,1521065140.823 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:40.824Z,1521065140.824 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:41.048Z,1521065141.048 [BPC1](INFO): Starting
2018-03-14T22:05:41.090Z,1521065141.090 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:41.099Z,1521065141.099 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:41.519Z,1521065141.519 [BPC1](INFO): Starting
2018-03-14T22:05:41.543Z,1521065141.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:41.544Z,1521065141.544 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:41.939Z,1521065141.939 [BPC1](INFO): Starting
2018-03-14T22:05:41.950Z,1521065141.950 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:41.951Z,1521065141.951 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:42.325Z,1521065142.325 [BPC1](INFO): Starting
2018-03-14T22:05:42.351Z,1521065142.351 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:42.352Z,1521065142.352 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:42.725Z,1521065142.725 [BPC1](INFO): Starting
2018-03-14T22:05:42.742Z,1521065142.742 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:42.751Z,1521065142.751 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:43.139Z,1521065143.139 [BPC1](INFO): Starting
2018-03-14T22:05:43.259Z,1521065143.259 [Aanderaa_O2](INFO): Powering down
2018-03-14T22:05:43.370Z,1521065143.370 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:43.370Z,1521065143.370 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T22:05:43.530Z,1521065143.530 [BPC1](INFO): Starting
2018-03-14T22:05:43.933Z,1521065143.933 [BPC1](INFO): Starting
2018-03-14T22:05:44.430Z,1521065144.430 [BPC1](INFO): Starting
2018-03-14T22:05:44.789Z,1521065144.789 [BPC1](INFO): Starting
2018-03-14T22:05:45.188Z,1521065145.188 [BPC1](INFO): Starting
2018-03-14T22:05:45.611Z,1521065145.611 [BPC1](INFO): Starting
2018-03-14T22:05:46.001Z,1521065146.001 [BPC1](INFO): Starting
2018-03-14T22:05:46.389Z,1521065146.389 [BPC1](INFO): Starting
2018-03-14T22:05:46.787Z,1521065146.787 [BPC1](INFO): Starting
2018-03-14T22:05:47.195Z,1521065147.195 [BPC1](INFO): Starting
2018-03-14T22:05:47.586Z,1521065147.586 [BPC1](INFO): Starting
2018-03-14T22:05:47.989Z,1521065147.989 [BPC1](INFO): Starting
2018-03-14T22:05:48.387Z,1521065148.387 [BPC1](INFO): Starting
2018-03-14T22:05:48.795Z,1521065148.795 [BPC1](INFO): Starting
2018-03-14T22:05:49.188Z,1521065149.188 [BPC1](INFO): Starting
2018-03-14T22:05:49.598Z,1521065149.598 [BPC1](INFO): Starting
2018-03-14T22:05:49.986Z,1521065149.986 [BPC1](INFO): Starting
2018-03-14T22:05:50.395Z,1521065150.395 [BPC1](INFO): Starting
2018-03-14T22:05:50.786Z,1521065150.786 [BPC1](INFO): Starting
2018-03-14T22:05:51.188Z,1521065151.188 [BPC1](INFO): Starting
2018-03-14T22:05:51.586Z,1521065151.586 [BPC1](INFO): Starting
2018-03-14T22:05:51.995Z,1521065151.995 [BPC1](INFO): Starting
2018-03-14T22:05:52.385Z,1521065152.385 [BPC1](INFO): Starting
2018-03-14T22:05:52.788Z,1521065152.788 [BPC1](INFO): Starting
2018-03-14T22:05:53.187Z,1521065153.187 [BPC1](INFO): Starting
2018-03-14T22:05:53.595Z,1521065153.595 [BPC1](INFO): Starting
2018-03-14T22:05:53.986Z,1521065153.986 [BPC1](INFO): Starting
2018-03-14T22:05:54.388Z,1521065154.388 [BPC1](INFO): Starting
2018-03-14T22:05:54.786Z,1521065154.786 [BPC1](INFO): Starting
2018-03-14T22:05:55.195Z,1521065155.195 [BPC1](INFO): Starting
2018-03-14T22:05:55.586Z,1521065155.586 [BPC1](INFO): Starting
2018-03-14T22:05:55.988Z,1521065155.988 [BPC1](INFO): Starting
2018-03-14T22:05:56.387Z,1521065156.387 [BPC1](INFO): Starting
2018-03-14T22:05:56.795Z,1521065156.795 [BPC1](INFO): Starting
2018-03-14T22:05:57.186Z,1521065157.186 [BPC1](INFO): Starting
2018-03-14T22:05:57.587Z,1521065157.587 [BPC1](INFO): Starting
2018-03-14T22:05:57.988Z,1521065157.988 [BPC1](INFO): Starting
2018-03-14T22:05:58.395Z,1521065158.395 [BPC1](INFO): Starting
2018-03-14T22:05:58.786Z,1521065158.786 [BPC1](INFO): Starting
2018-03-14T22:05:59.188Z,1521065159.188 [BPC1](INFO): Starting
2018-03-14T22:05:59.598Z,1521065159.598 [BPC1](INFO): Starting
2018-03-14T22:05:59.995Z,1521065159.995 [BPC1](INFO): Starting
2018-03-14T22:06:00.386Z,1521065160.386 [BPC1](INFO): Starting
2018-03-14T22:06:00.787Z,1521065160.787 [BPC1](INFO): Starting
2018-03-14T22:06:01.186Z,1521065161.186 [BPC1](INFO): Starting
2018-03-14T22:06:01.595Z,1521065161.595 [BPC1](INFO): Starting
2018-03-14T22:06:01.985Z,1521065161.985 [BPC1](INFO): Starting
2018-03-14T22:06:02.387Z,1521065162.387 [BPC1](INFO): Starting
2018-03-14T22:06:02.794Z,1521065162.794 [BPC1](INFO): Starting
2018-03-14T22:06:03.195Z,1521065163.195 [BPC1](INFO): Starting
2018-03-14T22:06:03.231Z,1521065163.231 [SBIT](IMPORTANT): Beginning Startup BIT
2018-03-14T22:06:03.235Z,1521065163.235 [CBIT](IMPORTANT): Beginning ground fault scan
2018-03-14T22:06:03.586Z,1521065163.586 [BPC1](INFO): Starting
2018-03-14T22:06:03.987Z,1521065163.987 [BPC1](INFO): Starting
2018-03-14T22:06:04.388Z,1521065164.388 [BPC1](INFO): Starting
2018-03-14T22:06:04.796Z,1521065164.796 [BPC1](INFO): Starting
2018-03-14T22:06:05.188Z,1521065165.188 [BPC1](INFO): Starting
2018-03-14T22:06:05.728Z,1521065165.728 [BPC1](INFO): Starting
2018-03-14T22:06:06.117Z,1521065166.117 [BPC1](INFO): Starting
2018-03-14T22:06:06.500Z,1521065166.500 [NAL9602](INFO): Powering up NAL9602
2018-03-14T22:06:06.526Z,1521065166.526 [BPC1](INFO): Starting
2018-03-14T22:06:06.916Z,1521065166.916 [BPC1](INFO): Starting
2018-03-14T22:06:07.321Z,1521065167.321 [BPC1](INFO): Starting
2018-03-14T22:06:07.724Z,1521065167.724 [BPC1](INFO): Starting
2018-03-14T22:06:08.116Z,1521065168.116 [BPC1](INFO): Starting
2018-03-14T22:06:08.517Z,1521065168.517 [BPC1](INFO): Starting
2018-03-14T22:06:08.916Z,1521065168.916 [BPC1](INFO): Starting
2018-03-14T22:06:09.316Z,1521065169.316 [BPC1](INFO): Starting
2018-03-14T22:06:09.716Z,1521065169.716 [BPC1](INFO): Starting
2018-03-14T22:06:10.117Z,1521065170.117 [BPC1](INFO): Starting
2018-03-14T22:06:10.517Z,1521065170.517 [BPC1](INFO): Starting
2018-03-14T22:06:10.916Z,1521065170.916 [BPC1](INFO): Starting
2018-03-14T22:06:11.316Z,1521065171.316 [BPC1](INFO): Starting
2018-03-14T22:06:11.728Z,1521065171.728 [BPC1](INFO): Starting
2018-03-14T22:06:12.117Z,1521065172.117 [BPC1](INFO): Starting
2018-03-14T22:06:12.516Z,1521065172.516 [BPC1](INFO): Starting
2018-03-14T22:06:12.916Z,1521065172.916 [BPC1](INFO): Starting
2018-03-14T22:06:13.321Z,1521065173.321 [BPC1](INFO): Starting
2018-03-14T22:06:13.724Z,1521065173.724 [BPC1](INFO): Starting
2018-03-14T22:06:14.111Z,1521065174.111 [BPC1](INFO): Starting
2018-03-14T22:06:14.204Z,1521065174.204 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001449
CHAN A1 (24V): -0.006205
CHAN A2 (12V): -0.002451
CHAN A3 (5V): -0.001459
CHAN B0 (3.3V): -0.000216
CHAN B1 (3.15aV): 0.000873
CHAN B2 (3.15bV): -0.000558
CHAN B3 (GND): -0.000007
OPEN: -0.000509
Full Scale Calc: 4.765 mA, -1.589 mA
2018-03-14T22:06:14.516Z,1521065174.516 [BPC1](INFO): Starting
2018-03-14T22:06:14.916Z,1521065174.916 [BPC1](INFO): Starting
2018-03-14T22:06:15.318Z,1521065175.318 [BPC1](INFO): Starting
2018-03-14T22:06:15.724Z,1521065175.724 [BPC1](INFO): Starting
2018-03-14T22:06:16.119Z,1521065176.119 [BPC1](INFO): Starting
2018-03-14T22:06:16.528Z,1521065176.528 [BPC1](INFO): Starting
2018-03-14T22:06:16.927Z,1521065176.927 [BPC1](INFO): Starting
2018-03-14T22:06:17.335Z,1521065177.335 [BPC1](INFO): Starting
2018-03-14T22:06:17.688Z,1521065177.688 [NAL9602](INFO): NAL9602 initialized
2018-03-14T22:06:17.712Z,1521065177.712 [BPC1](INFO): Starting
2018-03-14T22:06:18.142Z,1521065178.142 [BPC1](INFO): Starting
2018-03-14T22:06:18.531Z,1521065178.531 [BPC1](INFO): Starting
2018-03-14T22:06:19.187Z,1521065179.187 [BPC1](INFO): Starting
2018-03-14T22:06:19.555Z,1521065179.555 [BPC1](INFO): Starting
2018-03-14T22:06:19.945Z,1521065179.945 [BPC1](INFO): Starting
2018-03-14T22:06:20.349Z,1521065180.349 [BPC1](INFO): Starting
2018-03-14T22:06:20.763Z,1521065180.763 [BPC1](INFO): Starting
2018-03-14T22:06:21.163Z,1521065181.163 [BPC1](INFO): Starting
2018-03-14T22:06:21.574Z,1521065181.574 [BPC1](INFO): Starting
2018-03-14T22:06:21.978Z,1521065181.978 [BPC1](INFO): Starting
2018-03-14T22:06:22.360Z,1521065182.360 [BPC1](INFO): Starting
2018-03-14T22:06:22.753Z,1521065182.753 [BPC1](INFO): Starting
2018-03-14T22:06:23.146Z,1521065183.146 [BPC1](INFO): Starting
2018-03-14T22:06:23.550Z,1521065183.550 [BPC1](INFO): Starting
2018-03-14T22:06:23.951Z,1521065183.951 [BPC1](INFO): Starting
2018-03-14T22:06:24.341Z,1521065184.341 [BPC1](INFO): Starting
2018-03-14T22:06:24.741Z,1521065184.741 [BPC1](INFO): Starting
2018-03-14T22:06:25.140Z,1521065185.140 [BPC1](INFO): Starting
2018-03-14T22:06:25.571Z,1521065185.571 [BPC1](INFO): Starting
2018-03-14T22:06:25.951Z,1521065185.951 [BPC1](INFO): Starting
2018-03-14T22:06:26.352Z,1521065186.352 [BPC1](INFO): Starting
2018-03-14T22:06:26.759Z,1521065186.759 [BPC1](INFO): Starting
2018-03-14T22:06:27.160Z,1521065187.160 [BPC1](INFO): Starting
2018-03-14T22:06:27.542Z,1521065187.542 [BPC1](INFO): Starting
2018-03-14T22:06:27.959Z,1521065187.959 [BPC1](INFO): Starting
2018-03-14T22:06:28.351Z,1521065188.351 [BPC1](INFO): Starting
2018-03-14T22:06:28.749Z,1521065188.749 [BPC1](INFO): Starting
2018-03-14T22:06:29.145Z,1521065189.145 [BPC1](INFO): Starting
2018-03-14T22:06:29.550Z,1521065189.550 [BPC1](INFO): Starting
2018-03-14T22:06:29.952Z,1521065189.952 [BPC1](INFO): Starting
2018-03-14T22:06:30.351Z,1521065190.351 [BPC1](INFO): Starting
2018-03-14T22:06:30.765Z,1521065190.765 [BPC1](INFO): Starting
2018-03-14T22:06:31.156Z,1521065191.156 [BPC1](INFO): Starting
2018-03-14T22:06:31.556Z,1521065191.556 [BPC1](INFO): Starting
2018-03-14T22:06:31.952Z,1521065191.952 [BPC1](INFO): Starting
2018-03-14T22:06:32.353Z,1521065192.353 [BPC1](INFO): Starting
2018-03-14T22:06:32.751Z,1521065192.751 [BPC1](INFO): Starting
2018-03-14T22:06:33.161Z,1521065193.161 [BPC1](INFO): Starting
2018-03-14T22:06:33.542Z,1521065193.542 [BPC1](INFO): Starting
2018-03-14T22:06:33.942Z,1521065193.942 [BPC1](INFO): Starting
2018-03-14T22:06:34.364Z,1521065194.364 [BPC1](INFO): Starting
2018-03-14T22:06:34.749Z,1521065194.749 [BPC1](INFO): Starting
2018-03-14T22:06:35.148Z,1521065195.148 [BPC1](INFO): Starting
2018-03-14T22:06:35.565Z,1521065195.565 [BPC1](INFO): Starting
2018-03-14T22:06:35.965Z,1521065195.965 [BPC1](INFO): Starting
2018-03-14T22:06:36.361Z,1521065196.361 [BPC1](INFO): Starting
2018-03-14T22:06:36.763Z,1521065196.763 [BPC1](INFO): Starting
2018-03-14T22:06:37.165Z,1521065197.165 [BPC1](INFO): Starting
2018-03-14T22:06:37.541Z,1521065197.541 [BPC1](INFO): Starting
2018-03-14T22:06:37.941Z,1521065197.941 [BPC1](INFO): Starting
2018-03-14T22:06:38.360Z,1521065198.360 [BPC1](INFO): Starting
2018-03-14T22:06:38.743Z,1521065198.743 [BPC1](INFO): Starting
2018-03-14T22:06:39.141Z,1521065199.141 [BPC1](INFO): Starting
2018-03-14T22:06:39.537Z,1521065199.537 [BPC1](INFO): Starting
2018-03-14T22:06:39.957Z,1521065199.957 [BPC1](INFO): Starting
2018-03-14T22:06:40.342Z,1521065200.342 [BPC1](INFO): Starting
2018-03-14T22:06:40.342Z,1521065200.342 [BPC1](INFO): Initialized.
2018-03-14T22:06:40.741Z,1521065200.741 [BPC1](INFO): Runnable
2018-03-14T22:06:40.758Z,1521065200.758 [BPC1](INFO): Bank A: initialized.
2018-03-14T22:06:40.771Z,1521065200.771 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:06:41.178Z,1521065201.178 [BPC1](INFO): Runnable
2018-03-14T22:06:41.563Z,1521065201.563 [BPC1](INFO): Runnable
2018-03-14T22:06:41.964Z,1521065201.964 [BPC1](INFO): Runnable
2018-03-14T22:06:42.364Z,1521065202.364 [BPC1](INFO): Runnable
2018-03-14T22:06:42.742Z,1521065202.742 [BPC1](INFO): Runnable
2018-03-14T22:06:42.742Z,1521065202.742 [BPC1](INFO): Bank A: sending IBPS break.
2018-03-14T22:06:42.763Z,1521065202.763 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:06:43.154Z,1521065203.154 [BPC1](INFO): Runnable
2018-03-14T22:06:43.560Z,1521065203.560 [BPC1](INFO): Runnable
2018-03-14T22:06:43.941Z,1521065203.941 [BPC1](INFO): Runnable
2018-03-14T22:06:44.342Z,1521065204.342 [BPC1](INFO): Runnable
2018-03-14T22:06:44.779Z,1521065204.779 [BPC1](INFO): Runnable
2018-03-14T22:06:45.152Z,1521065205.152 [BPC1](INFO): Runnable
2018-03-14T22:06:45.541Z,1521065205.541 [BPC1](INFO): Runnable
2018-03-14T22:06:46.129Z,1521065206.129 [BPC1](INFO): Runnable
2018-03-14T22:06:46.539Z,1521065206.539 [BPC1](INFO): Runnable
2018-03-14T22:06:46.539Z,1521065206.539 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-03-14T22:06:46.563Z,1521065206.563 [BPC1](INFO): Bank A: data parsed.
2018-03-14T22:06:46.563Z,1521065206.563 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:06:46.611Z,1521065206.611 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:06:46.921Z,1521065206.921 [BPC1](INFO): Runnable
2018-03-14T22:06:46.935Z,1521065206.935 [BPC1](INFO): Bank A: initialized.
2018-03-14T22:06:46.947Z,1521065206.947 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:06:47.316Z,1521065207.316 [BPC1](INFO): Runnable
2018-03-14T22:06:47.705Z,1521065207.705 [BPC1](INFO): Runnable
2018-03-14T22:06:48.142Z,1521065208.142 [BPC1](INFO): Runnable
2018-03-14T22:06:48.514Z,1521065208.514 [BPC1](INFO): Runnable
2018-03-14T22:06:48.926Z,1521065208.926 [BPC1](INFO): Runnable
2018-03-14T22:06:49.305Z,1521065209.305 [BPC1](INFO): Runnable
2018-03-14T22:06:49.701Z,1521065209.701 [BPC1](INFO): Runnable
2018-03-14T22:06:50.112Z,1521065210.112 [BPC1](INFO): Runnable
2018-03-14T22:06:50.514Z,1521065210.514 [BPC1](INFO): Runnable
2018-03-14T22:06:50.914Z,1521065210.914 [BPC1](INFO): Runnable
2018-03-14T22:06:51.327Z,1521065211.327 [BPC1](INFO): Runnable
2018-03-14T22:06:51.747Z,1521065211.747 [BPC1](INFO): Runnable
2018-03-14T22:06:52.119Z,1521065212.119 [BPC1](INFO): Runnable
2018-03-14T22:06:52.548Z,1521065212.548 [BPC1](INFO): Runnable
2018-03-14T22:06:52.914Z,1521065212.914 [BPC1](INFO): Runnable
2018-03-14T22:06:53.326Z,1521065213.326 [BPC1](INFO): Runnable
2018-03-14T22:06:53.705Z,1521065213.705 [BPC1](INFO): Runnable
2018-03-14T22:06:54.111Z,1521065214.111 [BPC1](INFO): Runnable
2018-03-14T22:06:54.547Z,1521065214.547 [BPC1](INFO): Runnable
2018-03-14T22:06:54.548Z,1521065214.548 [BPC1](INFO): Bank A: sending IBPS break.
2018-03-14T22:06:54.548Z,1521065214.548 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:06:54.914Z,1521065214.914 [BPC1](INFO): Runnable
2018-03-14T22:06:55.314Z,1521065215.314 [BPC1](INFO): Runnable
2018-03-14T22:06:55.717Z,1521065215.717 [BPC1](INFO): Runnable
2018-03-14T22:06:56.103Z,1521065216.103 [BPC1](INFO): Runnable
2018-03-14T22:06:56.519Z,1521065216.519 [BPC1](INFO): Runnable
2018-03-14T22:06:56.923Z,1521065216.923 [BPC1](INFO): Runnable
2018-03-14T22:06:57.324Z,1521065217.324 [BPC1](INFO): Runnable
2018-03-14T22:06:57.340Z,1521065217.340 [SBIT](IMPORTANT): SBIT PASSED
2018-03-14T22:06:57.723Z,1521065217.723 [BPC1](INFO): Runnable
2018-03-14T22:06:57.739Z,1521065217.739 [MissionManager](IMPORTANT): Started mission Startup
2018-03-14T22:06:57.740Z,1521065217.740 [Startup] Running Loop=1
2018-03-14T22:06:57.740Z,1521065217.740 [Startup](DEBUG): Aggregate::initialize Startup
2018-03-14T22:06:57.740Z,1521065217.740 [Startup:A.GoToSurface] Running Loop=1
2018-03-14T22:06:57.740Z,1521065217.740 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-03-14T22:06:57.741Z,1521065217.741 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-03-14T22:06:57.741Z,1521065217.741 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-03-14T22:06:57.741Z,1521065217.741 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-03-14T22:06:57.742Z,1521065217.742 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 30.000000 seconds.
2018-03-14T22:06:57.742Z,1521065217.742 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-03-14T22:06:57.747Z,1521065217.747 [Startup:StartupSatComms] Running Loop=1
2018-03-14T22:06:57.748Z,1521065217.748 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-03-14T22:06:57.748Z,1521065217.748 [Startup:StartupSatComms:A] Running Loop=1
2018-03-14T22:06:58.104Z,1521065218.104 [BPC1](INFO): Runnable
2018-03-14T22:06:58.139Z,1521065218.139 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-03-14T22:06:58.505Z,1521065218.505 [BPC1](INFO): Runnable
2018-03-14T22:06:58.900Z,1521065218.900 [BPC1](INFO): Runnable
2018-03-14T22:06:58.901Z,1521065218.901 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-03-14T22:06:58.908Z,1521065218.908 [BPC1](INFO): Bank A: data parsed.
2018-03-14T22:06:58.908Z,1521065218.908 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:06:58.927Z,1521065218.927 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:06:59.316Z,1521065219.316 [BPC1](INFO): Runnable
2018-03-14T22:06:59.336Z,1521065219.336 [BPC1](INFO): Bank A: initialized.
2018-03-14T22:06:59.346Z,1521065219.346 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:06:59.708Z,1521065219.708 [BPC1](INFO): Runnable
2018-03-14T22:07:00.108Z,1521065220.108 [BPC1](INFO): Runnable
2018-03-14T22:07:00.508Z,1521065220.508 [BPC1](INFO): Runnable
2018-03-14T22:07:00.903Z,1521065220.903 [BPC1](INFO): Runnable
2018-03-14T22:07:01.313Z,1521065221.313 [BPC1](INFO): Runnable
2018-03-14T22:07:01.718Z,1521065221.718 [BPC1](INFO): Runnable
2018-03-14T22:07:02.119Z,1521065222.119 [BPC1](INFO): Runnable
2018-03-14T22:07:02.522Z,1521065222.522 [BPC1](INFO): Runnable
2018-03-14T22:07:02.927Z,1521065222.927 [BPC1](INFO): Runnable
2018-03-14T22:07:03.301Z,1521065223.301 [BPC1](INFO): Runnable
2018-03-14T22:07:03.700Z,1521065223.700 [BPC1](INFO): Runnable
2018-03-14T22:07:04.104Z,1521065224.104 [BPC1](INFO): Runnable
2018-03-14T22:07:04.508Z,1521065224.508 [BPC1](INFO): Runnable
2018-03-14T22:07:04.908Z,1521065224.908 [BPC1](INFO): Runnable
2018-03-14T22:07:05.313Z,1521065225.313 [BPC1](INFO): Runnable
2018-03-14T22:07:05.707Z,1521065225.707 [BPC1](INFO): Runnable
2018-03-14T22:07:06.109Z,1521065226.109 [BPC1](INFO): Runnable
2018-03-14T22:07:06.511Z,1521065226.511 [BPC1](INFO): Runnable
2018-03-14T22:07:06.921Z,1521065226.921 [BPC1](INFO): Runnable
2018-03-14T22:07:06.921Z,1521065226.921 [BPC1](INFO): Bank A: sending IBPS break.
2018-03-14T22:07:07.326Z,1521065227.326 [BPC1](INFO): Runnable
2018-03-14T22:07:07.326Z,1521065227.326 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:07:07.723Z,1521065227.723 [BPC1](INFO): Runnable
2018-03-14T22:07:08.134Z,1521065228.134 [BPC1](INFO): Runnable
2018-03-14T22:07:08.500Z,1521065228.500 [BPC1](INFO): Runnable
2018-03-14T22:07:08.912Z,1521065228.912 [BPC1](INFO): Runnable
2018-03-14T22:07:09.308Z,1521065229.308 [BPC1](INFO): Runnable
2018-03-14T22:07:09.709Z,1521065229.709 [BPC1](INFO): Runnable
2018-03-14T22:07:10.113Z,1521065230.113 [BPC1](INFO): Runnable
2018-03-14T22:07:10.507Z,1521065230.507 [BPC1](INFO): Runnable
2018-03-14T22:07:10.917Z,1521065230.917 [BPC1](INFO): Runnable
2018-03-14T22:07:11.317Z,1521065231.317 [BPC1](INFO): Runnable
2018-03-14T22:07:11.711Z,1521065231.711 [BPC1](INFO): Runnable
2018-03-14T22:07:11.711Z,1521065231.711 [BPC1](INFO): Bank A valid message: 1824 bytes.
2018-03-14T22:07:11.722Z,1521065231.722 [BPC1](INFO): Bank A: data parsed.
2018-03-14T22:07:11.729Z,1521065231.729 [BPC1](INFO): Bank B valid message: 1824 bytes.
2018-03-14T22:07:11.749Z,1521065231.749 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:07:12.126Z,1521065232.126 [BPC1](INFO): Runnable
2018-03-14T22:07:12.138Z,1521065232.138 [BPC1](INFO): Bank A: initialized.
2018-03-14T22:07:12.151Z,1521065232.151 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:07:12.524Z,1521065232.524 [BPC1](INFO): Runnable
2018-03-14T22:07:12.932Z,1521065232.932 [BPC1](INFO): Runnable
2018-03-14T22:07:13.319Z,1521065233.319 [BPC1](INFO): Runnable
2018-03-14T22:07:13.709Z,1521065233.709 [BPC1](INFO): Runnable
2018-03-14T22:07:14.108Z,1521065234.108 [BPC1](INFO): Runnable
2018-03-14T22:07:14.508Z,1521065234.508 [BPC1](INFO): Runnable
2018-03-14T22:07:14.912Z,1521065234.912 [BPC1](INFO): Runnable
2018-03-14T22:07:15.307Z,1521065235.307 [BPC1](INFO): Runnable
2018-03-14T22:07:15.707Z,1521065235.707 [BPC1](INFO): Runnable
2018-03-14T22:07:16.100Z,1521065236.100 [BPC1](INFO): Runnable
2018-03-14T22:07:16.500Z,1521065236.500 [BPC1](INFO): Runnable
2018-03-14T22:07:16.900Z,1521065236.900 [BPC1](INFO): Runnable
2018-03-14T22:07:17.319Z,1521065237.319 [BPC1](INFO): Runnable
2018-03-14T22:07:17.723Z,1521065237.723 [BPC1](INFO): Runnable
2018-03-14T22:07:18.114Z,1521065238.114 [BPC1](INFO): Runnable
2018-03-14T22:07:18.518Z,1521065238.518 [BPC1](INFO): Runnable
2018-03-14T22:07:18.911Z,1521065238.911 [BPC1](INFO): Runnable
2018-03-14T22:07:19.309Z,1521065239.309 [BPC1](INFO): Runnable
2018-03-14T22:07:19.709Z,1521065239.709 [BPC1](INFO): Runnable
2018-03-14T22:07:19.709Z,1521065239.709 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:07:20.104Z,1521065240.104 [BPC1](INFO): Runnable
2018-03-14T22:07:20.104Z,1521065240.104 [BPC1](INFO): Bank A: sending IBPS break.
2018-03-14T22:07:20.500Z,1521065240.500 [BPC1](INFO): Runnable
2018-03-14T22:07:20.908Z,1521065240.908 [BPC1](INFO): Runnable
2018-03-14T22:07:21.308Z,1521065241.308 [BPC1](INFO): Runnable
2018-03-14T22:07:21.708Z,1521065241.708 [BPC1](INFO): Runnable
2018-03-14T22:07:22.108Z,1521065242.108 [BPC1](INFO): Runnable
2018-03-14T22:07:22.515Z,1521065242.515 [BPC1](INFO): Runnable
2018-03-14T22:07:22.916Z,1521065242.916 [BPC1](INFO): Runnable
2018-03-14T22:07:23.324Z,1521065243.324 [BPC1](INFO): Runnable
2018-03-14T22:07:23.727Z,1521065243.727 [BPC1](INFO): Runnable
2018-03-14T22:07:23.727Z,1521065243.727 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-03-14T22:07:23.737Z,1521065243.737 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:07:24.104Z,1521065244.104 [BPC1](INFO): Runnable
2018-03-14T22:07:24.105Z,1521065244.105 [BPC1](INFO): Bank A valid message: 1596 bytes.
2018-03-14T22:07:24.119Z,1521065244.119 [BPC1](INFO): Bank A: data parsed.
2018-03-14T22:07:24.133Z,1521065244.133 [BPC1](INFO): Bank B: initialized.
2018-03-14T22:07:24.509Z,1521065244.509 [BPC1](INFO): Runnable
2018-03-14T22:07:24.525Z,1521065244.525 [BPC1](INFO): Bank A: initialized.
2018-03-14T22:07:24.907Z,1521065244.907 [BPC1](INFO): Runnable
2018-03-14T22:07:25.313Z,1521065245.313 [BPC1](INFO): Runnable
2018-03-14T22:07:25.707Z,1521065245.707 [BPC1](INFO): Runnable
2018-03-14T22:07:26.113Z,1521065246.113 [BPC1](INFO): Runnable
2018-03-14T22:07:26.113Z,1521065246.113 [BPC1](INFO): Bank A: sending IBPS break.
2018-03-14T22:07:26.114Z,1521065246.114 [BPC1](INFO): Bank B: sending IBPS break.
2018-03-14T22:07:26.508Z,1521065246.508 [BPC1](INFO): Runnable
2018-03-14T22:07:26.907Z,1521065246.907 [BPC1](INFO): Runnable
2018-03-14T22:07:27.301Z,1521065247.301 [BPC1](INFO): Runnable
2018-03-14T22:07:27.725Z,1521065247.725 [BPC1](INFO): Runnable
2018-03-14T22:07:28.127Z,1521065248.127 [BPC1](INFO): Runnable
2018-03-14T22:07:28.531Z,1521065248.531 [BPC1](INFO): Runnable
2018-03-14T22:07:28.931Z,1521065248.931 [BPC1](INFO): Runnable
2018-03-14T22:07:29.304Z,1521065249.304 [BPC1](INFO): Runnable
2018-03-14T22:07:29.305Z,1521065249.305 [BPC1](INFO): Bank B valid message: 1596 bytes.
2018-03-14T22:07:29.315Z,1521065249.315 [BPC1](INFO): Bank B: data parsed.
2018-03-14T22:07:29.315Z,1521065249.315 [BPC1](DEBUG): Received data from all battery sticks
2018-03-14T22:07:29.709Z,1521065249.709 [BPC1](INFO): Stop
2018-03-14T22:07:29.709Z,1521065249.709 [BPC1](INFO): uninitialize
2018-03-14T22:07:30.114Z,1521065250.114 [BPC1](INFO): Stopping
2018-03-14T22:07:30.511Z,1521065250.511 [BPC1](INFO): Stopped
2018-03-14T22:07:30.911Z,1521065250.911 [BPC1](INFO): Stopped
2018-03-14T22:07:31.313Z,1521065251.313 [BPC1](INFO): Stopped
2018-03-14T22:07:31.708Z,1521065251.708 [BPC1](INFO): Stopped
2018-03-14T22:07:32.115Z,1521065252.115 [BPC1](INFO): Stopped
2018-03-14T22:07:32.369Z,1521065252.369 [CommandLine](IMPORTANT): got command configSet list
2018-03-14T22:07:32.370Z,1521065252.370 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-03-14T22:07:32.371Z,1521065252.371 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2018-03-14T22:07:32.371Z,1521065252.371 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-03-14T22:07:32.371Z,1521065252.371 [CommandLine](IMPORTANT): IBIT.batteryHibernationTimeout=3 minute;

2018-03-14T22:07:32.372Z,1521065252.372 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-03-14T22:07:32.519Z,1521065252.519 [BPC1](INFO): Stopped
2018-03-14T22:07:32.919Z,1521065252.919 [BPC1](INFO): Stopped
2018-03-14T22:07:33.331Z,1521065253.331 [BPC1](INFO): Stopped
2018-03-14T22:07:33.714Z,1521065253.714 [BPC1](INFO): Stopped
2018-03-14T22:07:34.116Z,1521065254.116 [BPC1](INFO): Stopped
2018-03-14T22:07:34.507Z,1521065254.507 [BPC1](INFO): Stopped
2018-03-14T22:07:34.907Z,1521065254.907 [BPC1](INFO): Stopped
2018-03-14T22:07:35.311Z,1521065255.311 [BPC1](INFO): Stopped
2018-03-14T22:07:35.707Z,1521065255.707 [BPC1](INFO): Stopped
2018-03-14T22:07:36.109Z,1521065256.109 [BPC1](INFO): Stopped
2018-03-14T22:07:36.511Z,1521065256.511 [BPC1](INFO): Stopped
2018-03-14T22:07:36.915Z,1521065256.915 [BPC1](INFO): Stopped
2018-03-14T22:07:37.311Z,1521065257.311 [BPC1](INFO): Stopped
2018-03-14T22:07:37.700Z,1521065257.700 [BPC1](INFO): Stopped
2018-03-14T22:07:38.123Z,1521065258.123 [BPC1](INFO): Stopped
2018-03-14T22:07:38.523Z,1521065258.523 [BPC1](INFO): Stopped
2018-03-14T22:07:38.927Z,1521065258.927 [BPC1](INFO): Stopped
2018-03-14T22:07:39.331Z,1521065259.331 [BPC1](INFO): Stopped
2018-03-14T22:07:39.701Z,1521065259.701 [BPC1](INFO): Stopped
2018-03-14T22:07:40.110Z,1521065260.110 [BPC1](INFO): Stopped
2018-03-14T22:07:40.508Z,1521065260.508 [BPC1](INFO): Stopped
2018-03-14T22:07:40.911Z,1521065260.911 [BPC1](INFO): Stopped
2018-03-14T22:07:41.315Z,1521065261.315 [BPC1](INFO): Stopped
2018-03-14T22:07:41.714Z,1521065261.714 [BPC1](INFO): Stopped
2018-03-14T22:07:42.115Z,1521065262.115 [BPC1](INFO): Stopped
2018-03-14T22:07:42.507Z,1521065262.507 [BPC1](INFO): Stopped
2018-03-14T22:07:42.900Z,1521065262.900 [BPC1](INFO): Stopped
2018-03-14T22:07:43.323Z,1521065263.323 [BPC1](INFO): Stopped
2018-03-14T22:07:43.711Z,1521065263.711 [BPC1](INFO): Stopped
2018-03-14T22:07:44.115Z,1521065264.115 [BPC1](INFO): Stopped
2018-03-14T22:07:44.514Z,1521065264.514 [BPC1](INFO): Stopped
2018-03-14T22:07:44.907Z,1521065264.907 [BPC1](INFO): Stopped
2018-03-14T22:07:45.311Z,1521065265.311 [BPC1](INFO): Stopped
2018-03-14T22:07:45.700Z,1521065265.700 [BPC1](INFO): Stopped
2018-03-14T22:07:46.100Z,1521065266.100 [BPC1](INFO): Stopped
2018-03-14T22:07:46.505Z,1521065266.505 [BPC1](INFO): Stopped
2018-03-14T22:07:46.904Z,1521065266.904 [BPC1](INFO): Stopped
2018-03-14T22:07:47.300Z,1521065267.300 [BPC1](INFO): Stopped
2018-03-14T22:07:47.700Z,1521065267.700 [BPC1](INFO): Stopped
2018-03-14T22:07:48.112Z,1521065268.112 [BPC1](INFO): Stopped
2018-03-14T22:07:48.532Z,1521065268.532 [BPC1](INFO): Stopped
2018-03-14T22:07:48.929Z,1521065268.929 [BPC1](INFO): Stopped
2018-03-14T22:07:49.326Z,1521065269.326 [BPC1](INFO): Stopped
2018-03-14T22:07:49.723Z,1521065269.723 [BPC1](INFO): Stopped
2018-03-14T22:07:50.117Z,1521065270.117 [BPC1](INFO): Stopped
2018-03-14T22:07:50.514Z,1521065270.514 [BPC1](INFO): Stopped
2018-03-14T22:07:50.907Z,1521065270.907 [BPC1](INFO): Stopped
2018-03-14T22:07:51.309Z,1521065271.309 [BPC1](INFO): Stopped
2018-03-14T22:07:51.701Z,1521065271.701 [BPC1](INFO): Stopped
2018-03-14T22:07:52.114Z,1521065272.114 [BPC1](INFO): Stopped
2018-03-14T22:07:52.504Z,1521065272.504 [BPC1](INFO): Stopped
2018-03-14T22:07:52.900Z,1521065272.900 [BPC1](INFO): Stopped
2018-03-14T22:07:53.308Z,1521065273.308 [BPC1](INFO): Stopped
2018-03-14T22:07:53.723Z,1521065273.723 [BPC1](INFO): Stopped
2018-03-14T22:07:54.123Z,1521065274.123 [BPC1](INFO): Stopped
2018-03-14T22:07:54.522Z,1521065274.522 [BPC1](INFO): Stopped
2018-03-14T22:07:54.918Z,1521065274.918 [BPC1](INFO): Stopped
2018-03-14T22:07:55.319Z,1521065275.319 [BPC1](INFO): Stopped
2018-03-14T22:07:55.700Z,1521065275.700 [BPC1](INFO): Stopped
2018-03-14T22:07:56.100Z,1521065276.100 [BPC1](INFO): Stopped
2018-03-14T22:07:56.505Z,1521065276.505 [BPC1](INFO): Stopped
2018-03-14T22:07:56.911Z,1521065276.911 [BPC1](INFO): Stopped
2018-03-14T22:07:57.312Z,1521065277.312 [BPC1](INFO): Stopped
2018-03-14T22:07:57.711Z,1521065277.711 [BPC1](INFO): Stopped
2018-03-14T22:07:58.101Z,1521065278.101 [BPC1](INFO): Stopped
2018-03-14T22:07:58.119Z,1521065278.119 [Startup:StartupSatComms:A](INFO): Timed out from 2018-03-14T22:06:57.7Z
2018-03-14T22:07:58.119Z,1521065278.119 [Startup:StartupSatComms:A] Stopped
2018-03-14T22:07:58.119Z,1521065278.119 [Startup:StartupSatComms:B] Running Loop=1
2018-03-14T22:07:58.504Z,1521065278.504 [BPC1](INFO): Stopped
2018-03-14T22:07:58.535Z,1521065278.535 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-03-14T22:07:58.914Z,1521065278.914 [BPC1](INFO): Stopped
2018-03-14T22:07:59.315Z,1521065279.315 [BPC1](INFO): Stopped
2018-03-14T22:07:59.718Z,1521065279.718 [BPC1](INFO): Stopped
2018-03-14T22:08:00.119Z,1521065280.119 [BPC1](INFO): Stopped
2018-03-14T22:08:00.504Z,1521065280.504 [BPC1](INFO): Stopped
2018-03-14T22:08:00.908Z,1521065280.908 [BPC1](INFO): Stopped
2018-03-14T22:08:01.313Z,1521065281.313 [BPC1](INFO): Stopped
2018-03-14T22:08:01.711Z,1521065281.711 [BPC1](INFO): Stopped
2018-03-14T22:08:02.100Z,1521065282.100 [BPC1](INFO): Stopped
2018-03-14T22:08:02.515Z,1521065282.515 [BPC1](INFO): Stopped
2018-03-14T22:08:02.901Z,1521065282.901 [BPC1](INFO): Stopped
2018-03-14T22:08:03.300Z,1521065283.300 [BPC1](INFO): Stopped
2018-03-14T22:08:03.700Z,1521065283.700 [BPC1](INFO): Stopped
2018-03-14T22:08:04.127Z,1521065284.127 [BPC1](INFO): Stopped
2018-03-14T22:08:04.518Z,1521065284.518 [BPC1](INFO): Stopped
2018-03-14T22:08:04.929Z,1521065284.929 [BPC1](INFO): Stopped
2018-03-14T22:08:05.331Z,1521065285.331 [BPC1](INFO): Stopped
2018-03-14T22:08:05.700Z,1521065285.700 [BPC1](INFO): Stopped
2018-03-14T22:08:06.109Z,1521065286.109 [BPC1](INFO): Stopped
2018-03-14T22:08:06.507Z,1521065286.507 [BPC1](INFO): Stopped
2018-03-14T22:08:06.911Z,1521065286.911 [BPC1](INFO): Stopped
2018-03-14T22:08:07.311Z,1521065287.311 [BPC1](INFO): Stopped
2018-03-14T22:08:07.700Z,1521065287.700 [BPC1](INFO): Stopped
2018-03-14T22:08:08.104Z,1521065288.104 [BPC1](INFO): Stopped
2018-03-14T22:08:08.504Z,1521065288.504 [BPC1](INFO): Stopped
2018-03-14T22:08:08.901Z,1521065288.901 [BPC1](INFO): Stopped
2018-03-14T22:08:09.322Z,1521065289.322 [BPC1](INFO): Stopped
2018-03-14T22:08:09.738Z,1521065289.738 [BPC1](INFO): Stopped
2018-03-14T22:08:10.119Z,1521065290.119 [BPC1](INFO): Stopped
2018-03-14T22:08:10.510Z,1521065290.510 [BPC1](INFO): Stopped
2018-03-14T22:08:10.958Z,1521065290.958 [BPC1](INFO): Stopped
2018-03-14T22:08:11.308Z,1521065291.308 [BPC1](INFO): Stopped
2018-03-14T22:08:11.715Z,1521065291.715 [BPC1](INFO): Stopped
2018-03-14T22:08:12.111Z,1521065292.111 [BPC1](INFO): Stopped
2018-03-14T22:08:12.508Z,1521065292.508 [BPC1](INFO): Stopped
2018-03-14T22:08:12.918Z,1521065292.918 [BPC1](INFO): Stopped
2018-03-14T22:08:13.300Z,1521065293.300 [BPC1](INFO): Stopped
2018-03-14T22:08:13.704Z,1521065293.704 [BPC1](INFO): Stopped
2018-03-14T22:08:14.104Z,1521065294.104 [BPC1](INFO): Stopped
2018-03-14T22:08:14.531Z,1521065294.531 [BPC1](INFO): Stopped
2018-03-14T22:08:14.932Z,1521065294.932 [BPC1](INFO): Stopped
2018-03-14T22:08:15.313Z,1521065295.313 [BPC1](INFO): Stopped
2018-03-14T22:08:15.731Z,1521065295.731 [BPC1](INFO): Stopped
2018-03-14T22:08:16.104Z,1521065296.104 [BPC1](INFO): Stopped
2018-03-14T22:08:16.509Z,1521065296.509 [BPC1](INFO): Stopped
2018-03-14T22:08:16.911Z,1521065296.911 [BPC1](INFO): Stopped
2018-03-14T22:08:17.315Z,1521065297.315 [BPC1](INFO): Stopped
2018-03-14T22:08:17.718Z,1521065297.718 [BPC1](INFO): Stopped
2018-03-14T22:08:18.117Z,1521065298.117 [BPC1](INFO): Stopped
2018-03-14T22:08:18.508Z,1521065298.508 [BPC1](INFO): Stopped
2018-03-14T22:08:18.527Z,1521065298.527 [CommandLine](IMPORTANT): got command get ESPComponent.loadAtStartup
2018-03-14T22:08:18.528Z,1521065298.528 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup 0 bool
2018-03-14T22:08:18.911Z,1521065298.911 [BPC1](INFO): Stopped
2018-03-14T22:08:19.305Z,1521065299.305 [BPC1](INFO): Stopped
2018-03-14T22:08:19.727Z,1521065299.727 [BPC1](INFO): Stopped
2018-03-14T22:08:20.110Z,1521065300.110 [BPC1](INFO): Stopped
2018-03-14T22:08:20.515Z,1521065300.515 [BPC1](INFO): Stopped
2018-03-14T22:08:20.919Z,1521065300.919 [BPC1](INFO): Stopped
2018-03-14T22:08:21.308Z,1521065301.308 [BPC1](INFO): Stopped
2018-03-14T22:08:21.704Z,1521065301.704 [BPC1](INFO): Stopped
2018-03-14T22:08:22.108Z,1521065302.108 [BPC1](INFO): Stopped
2018-03-14T22:08:22.507Z,1521065302.507 [BPC1](INFO): Stopped
2018-03-14T22:08:22.911Z,1521065302.911 [BPC1](INFO): Stopped
2018-03-14T22:08:23.308Z,1521065303.308 [BPC1](INFO): Stopped
2018-03-14T22:08:23.711Z,1521065303.711 [BPC1](INFO): Stopped
2018-03-14T22:08:24.109Z,1521065304.109 [BPC1](INFO): Stopped
2018-03-14T22:08:24.501Z,1521065304.501 [BPC1](INFO): Stopped
2018-03-14T22:08:24.923Z,1521065304.923 [BPC1](INFO): Stopped
2018-03-14T22:08:25.335Z,1521065305.335 [BPC1](INFO): Stopped
2018-03-14T22:08:25.713Z,1521065305.713 [BPC1](INFO): Stopped
2018-03-14T22:08:26.122Z,1521065306.122 [BPC1](INFO): Stopped
2018-03-14T22:08:26.510Z,1521065306.510 [BPC1](INFO): Stopped
2018-03-14T22:08:26.908Z,1521065306.908 [BPC1](INFO): Stopped
2018-03-14T22:08:27.309Z,1521065307.309 [BPC1](INFO): Stopped
2018-03-14T22:08:27.700Z,1521065307.700 [BPC1](INFO): Stopped
2018-03-14T22:08:28.101Z,1521065308.101 [BPC1](INFO): Stopped
2018-03-14T22:08:28.500Z,1521065308.500 [BPC1](INFO): Stopped
2018-03-14T22:08:28.904Z,1521065308.904 [BPC1](INFO): Stopped
2018-03-14T22:08:29.308Z,1521065309.308 [BPC1](INFO): Stopped
2018-03-14T22:08:29.706Z,1521065309.706 [BPC1](INFO): Stopped
2018-03-14T22:08:30.115Z,1521065310.115 [BPC1](INFO): Stopped
2018-03-14T22:08:30.513Z,1521065310.513 [BPC1](INFO): Stopped
2018-03-14T22:08:30.925Z,1521065310.925 [BPC1](INFO): Stopped
2018-03-14T22:08:31.323Z,1521065311.323 [BPC1](INFO): Stopped
2018-03-14T22:08:31.708Z,1521065311.708 [BPC1](INFO): Stopped
2018-03-14T22:08:32.100Z,1521065312.100 [BPC1](INFO): Stopped
2018-03-14T22:08:32.500Z,1521065312.500 [BPC1](INFO): Stopped
2018-03-14T22:08:32.901Z,1521065312.901 [BPC1](INFO): Stopped
2018-03-14T22:08:33.300Z,1521065313.300 [BPC1](INFO): Stopped
2018-03-14T22:08:33.700Z,1521065313.700 [BPC1](INFO): Stopped
2018-03-14T22:08:34.105Z,1521065314.105 [BPC1](INFO): Stopped
2018-03-14T22:08:34.500Z,1521065314.500 [BPC1](INFO): Stopped
2018-03-14T22:08:34.900Z,1521065314.900 [BPC1](INFO): Stopped
2018-03-14T22:08:35.323Z,1521065315.323 [BPC1](INFO): Stopped
2018-03-14T22:08:35.728Z,1521065315.728 [BPC1](INFO): Stopped
2018-03-14T22:08:36.123Z,1521065316.123 [BPC1](INFO): Stopped
2018-03-14T22:08:36.511Z,1521065316.511 [BPC1](INFO): Stopped
2018-03-14T22:08:36.903Z,1521065316.903 [BPC1](INFO): Stopped
2018-03-14T22:08:37.313Z,1521065317.313 [BPC1](INFO): Stopped
2018-03-14T22:08:37.708Z,1521065317.708 [BPC1](INFO): Stopped
2018-03-14T22:08:38.107Z,1521065318.107 [BPC1](INFO): Stopped
2018-03-14T22:08:38.510Z,1521065318.510 [BPC1](INFO): Stopped
2018-03-14T22:08:38.911Z,1521065318.911 [BPC1](INFO): Stopped
2018-03-14T22:08:39.311Z,1521065319.311 [BPC1](INFO): Stopped
2018-03-14T22:08:39.700Z,1521065319.700 [BPC1](INFO): Stopped
2018-03-14T22:08:39.724Z,1521065319.724 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-03-14T22:08:39.724Z,1521065319.724 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-03-14T22:08:39.725Z,1521065319.725 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-03-14T22:08:39.726Z,1521065319.726 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-03-14T22:08:39.726Z,1521065319.726 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-03-14T22:08:39.726Z,1521065319.726 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-03-14T22:08:39.757Z,1521065319.757 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-03-14T22:08:39.757Z,1521065319.757 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-03-14T22:08:40.100Z,1521065320.100 [BPC1](INFO): Stopped
2018-03-14T22:08:40.136Z,1521065320.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-03-14T22:08:40.136Z,1521065320.136 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-03-14T22:08:40.136Z,1521065320.136 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-03-14T22:08:40.136Z,1521065320.136 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-03-14T22:08:40.531Z,1521065320.531 [BPC1](INFO): Stopped
2018-03-14T22:08:40.557Z,1521065320.557 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-03-14T22:08:40.557Z,1521065320.557 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T22:08:40.558Z,1521065320.558 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T22:08:40.558Z,1521065320.558 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-03-14T22:08:40.559Z,1521065320.559 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T22:08:40.559Z,1521065320.559 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T22:08:40.934Z,1521065320.934 [BPC1](INFO): Stopped
2018-03-14T22:08:44.038Z,1521065324.038 [BPC1](INFO): Stopped
2018-03-14T22:08:44.187Z,1521065324.187 [BPC1](INFO): Stopped
2018-03-14T22:08:44.507Z,1521065324.507 [BPC1](INFO): Stopped
2018-03-14T22:08:44.875Z,1521065324.875 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003904
2018-03-14T22:08:44.912Z,1521065324.912 [BPC1](INFO): Stopped
2018-03-14T22:08:45.450Z,1521065325.450 [BPC1](INFO): Stopped
2018-03-14T22:08:45.723Z,1521065325.723 [BPC1](INFO): Stopped
2018-03-14T22:08:48.816Z,1521065328.816 [BPC1](INFO): Stopped
2018-03-14T22:08:48.878Z,1521065328.878 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180314T220100/Courier0004.lzma
2018-03-14T22:08:48.967Z,1521065328.967 [BPC1](INFO): Stopped
2018-03-14T22:08:49.305Z,1521065329.305 [BPC1](INFO): Stopped
2018-03-14T22:08:49.667Z,1521065329.667 [DataOverHttps](INFO): Moved sent file to Logs/20180314T220100/Courier0004.lzma.bak
2018-03-14T22:08:49.667Z,1521065329.667 [DataOverHttps](INFO): SBD MOMSN=8061615
2018-03-14T22:08:49.720Z,1521065329.720 [BPC1](INFO): Stopped
2018-03-14T22:08:50.238Z,1521065330.238 [BPC1](INFO): Stopped
2018-03-14T22:08:50.522Z,1521065330.522 [BPC1](INFO): Stopped
2018-03-14T22:08:53.636Z,1521065333.636 [BPC1](INFO): Stopped
2018-03-14T22:08:53.775Z,1521065333.775 [BPC1](INFO): Stopped
2018-03-14T22:08:54.128Z,1521065334.128 [BPC1](INFO): Stopped
2018-03-14T22:08:54.532Z,1521065334.532 [BPC1](INFO): Stopped
2018-03-14T22:08:54.923Z,1521065334.923 [BPC1](INFO): Stopped
2018-03-14T22:08:55.337Z,1521065335.337 [BPC1](INFO): Stopped
2018-03-14T22:08:59.628Z,1521065339.628 [BPC1](INFO): Stopped
2018-03-14T22:08:59.638Z,1521065339.638 [Startup:StartupSatComms:B](INFO): Timed out from 2018-03-14T22:07:58.1Z
2018-03-14T22:08:59.638Z,1521065339.638 [Startup:StartupSatComms:B] Stopped
2018-03-14T22:08:59.638Z,1521065339.638 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-03-14T22:08:59.638Z,1521065339.638 [Startup:StartupSatComms] Stopped
2018-03-14T22:08:59.638Z,1521065339.638 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-03-14T22:08:59.639Z,1521065339.639 [Startup](INFO): Completed Startup
2018-03-14T22:08:59.639Z,1521065339.639 [MissionManager](INFO): Startup is completed.
2018-03-14T22:08:59.640Z,1521065339.640 [MissionManager](INFO): Uninitializing Mission Startup
2018-03-14T22:08:59.640Z,1521065339.640 [Startup] Stopped
2018-03-14T22:08:59.640Z,1521065339.640 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-03-14T22:08:59.640Z,1521065339.640 [Startup:A.GoToSurface] Stopped
2018-03-14T22:08:59.640Z,1521065339.640 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-03-14T22:08:59.693Z,1521065339.693 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180314T220530/Courier0000.lzma
2018-03-14T22:08:59.779Z,1521065339.779 [BPC1](INFO): Stopped
2018-03-14T22:08:59.799Z,1521065339.799 [MissionManager](IMPORTANT): Started mission Default
2018-03-14T22:08:59.799Z,1521065339.799 [Default] Running Loop=1
2018-03-14T22:08:59.799Z,1521065339.799 [Default](DEBUG): Aggregate::initialize Default
2018-03-14T22:08:59.799Z,1521065339.799 [Default:B.GoToSurface] Running Loop=1
2018-03-14T22:08:59.799Z,1521065339.799 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-03-14T22:08:59.800Z,1521065339.800 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-03-14T22:08:59.800Z,1521065339.800 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-03-14T22:08:59.800Z,1521065339.800 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-03-14T22:08:59.800Z,1521065339.800 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 30.000000 seconds.
2018-03-14T22:08:59.801Z,1521065339.801 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-03-14T22:08:59.801Z,1521065339.801 [Default:A.Wait] Running Loop=1
2018-03-14T22:08:59.801Z,1521065339.801 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-03-14T22:09:00.099Z,1521065340.099 [BPC1](INFO): Stopped
2018-03-14T22:09:00.459Z,1521065340.459 [DataOverHttps](INFO): Moved sent file to Logs/20180314T220530/Courier0000.lzma.bak
2018-03-14T22:09:00.459Z,1521065340.459 [DataOverHttps](INFO): SBD MOMSN=8061617
2018-03-14T22:09:00.514Z,1521065340.514 [BPC1](INFO): Stopped
2018-03-14T22:09:00.895Z,1521065340.895 [BPC1](INFO): Stopped
2018-03-14T22:09:01.298Z,1521065341.298 [BPC1](INFO): Stopped
2018-03-14T22:09:05.010Z,1521065345.010 [BPC1](INFO): Stopped
2018-03-14T22:09:05.155Z,1521065345.155 [BPC1](INFO): Stopped
2018-03-14T22:09:05.514Z,1521065345.514 [BPC1](INFO): Stopped
2018-03-14T22:09:05.911Z,1521065345.911 [BPC1](INFO): Stopped
2018-03-14T22:09:06.285Z,1521065346.285 [BPC1](INFO): Stopped
2018-03-14T22:09:06.684Z,1521065346.684 [BPC1](INFO): Stopped
2018-03-14T22:09:07.084Z,1521065347.084 [BPC1](INFO): Stopped
2018-03-14T22:09:07.485Z,1521065347.485 [BPC1](INFO): Stopped
2018-03-14T22:09:07.884Z,1521065347.884 [BPC1](INFO): Stopped
2018-03-14T22:09:08.297Z,1521065348.297 [BPC1](INFO): Stopped
2018-03-14T22:09:08.692Z,1521065348.692 [BPC1](INFO): Stopped
2018-03-14T22:09:09.092Z,1521065349.092 [BPC1](INFO): Stopped
2018-03-14T22:09:09.493Z,1521065349.493 [BPC1](INFO): Stopped
2018-03-14T22:09:09.899Z,1521065349.899 [BPC1](INFO): Stopped
2018-03-14T22:09:10.297Z,1521065350.297 [BPC1](INFO): Stopped
2018-03-14T22:09:10.708Z,1521065350.708 [BPC1](INFO): Stopped
2018-03-14T22:09:11.103Z,1521065351.103 [BPC1](INFO): Stopped
2018-03-14T22:09:11.497Z,1521065351.497 [BPC1](INFO): Stopped
2018-03-14T22:09:11.884Z,1521065351.884 [BPC1](INFO): Stopped
2018-03-14T22:09:12.285Z,1521065352.285 [BPC1](INFO): Stopped
2018-03-14T22:09:12.684Z,1521065352.684 [BPC1](INFO): Stopped
2018-03-14T22:09:13.084Z,1521065353.084 [BPC1](INFO): Stopped
2018-03-14T22:09:13.127Z,1521065353.127 [Default:A.Wait](INFO): Done Waiting.
2018-03-14T22:09:13.127Z,1521065353.127 [Default:A.Wait] Stopped
2018-03-14T22:09:13.127Z,1521065353.127 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-03-14T22:09:13.488Z,1521065353.488 [BPC1](INFO): Stopped
2018-03-14T22:09:13.503Z,1521065353.503 [Default:CheckIn] Running Loop=1
2018-03-14T22:09:13.503Z,1521065353.503 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-03-14T22:09:13.503Z,1521065353.503 [Default:CheckIn:Read_GPS] Running Loop=1
2018-03-14T22:09:13.892Z,1521065353.892 [BPC1](INFO): Stopped
2018-03-14T22:09:13.907Z,1521065353.907 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-03-14T22:09:14.292Z,1521065354.292 [BPC1](INFO): Stopped
2018-03-14T22:09:14.691Z,1521065354.691 [BPC1](INFO): Stopped
2018-03-14T22:09:15.101Z,1521065355.101 [BPC1](INFO): Stopped
2018-03-14T22:09:15.507Z,1521065355.507 [BPC1](INFO): Stopped
2018-03-14T22:09:15.907Z,1521065355.907 [BPC1](INFO): Stopped
2018-03-14T22:09:16.311Z,1521065356.311 [BPC1](INFO): Stopped
2018-03-14T22:09:16.705Z,1521065356.705 [BPC1](INFO): Stopped
2018-03-14T22:09:17.098Z,1521065357.098 [BPC1](INFO): Stopped
2018-03-14T22:09:17.492Z,1521065357.492 [BPC1](INFO): Stopped
2018-03-14T22:09:17.884Z,1521065357.884 [BPC1](INFO): Stopped
2018-03-14T22:09:18.284Z,1521065358.284 [BPC1](INFO): Stopped
2018-03-14T22:09:18.684Z,1521065358.684 [BPC1](INFO): Stopped
2018-03-14T22:09:19.085Z,1521065359.085 [BPC1](INFO): Stopped
2018-03-14T22:09:19.484Z,1521065359.484 [BPC1](INFO): Stopped
2018-03-14T22:09:19.888Z,1521065359.888 [BPC1](INFO): Stopped
2018-03-14T22:09:20.302Z,1521065360.302 [BPC1](INFO): Stopped
2018-03-14T22:09:20.695Z,1521065360.695 [BPC1](INFO): Stopped
2018-03-14T22:09:21.103Z,1521065361.103 [BPC1](INFO): Stopped
2018-03-14T22:09:21.506Z,1521065361.506 [BPC1](INFO): Stopped
2018-03-14T22:09:21.890Z,1521065361.890 [BPC1](INFO): Stopped
2018-03-14T22:09:22.291Z,1521065362.291 [BPC1](INFO): Stopped
2018-03-14T22:09:22.693Z,1521065362.693 [BPC1](INFO): Stopped
2018-03-14T22:09:23.091Z,1521065363.091 [BPC1](INFO): Stopped
2018-03-14T22:09:23.491Z,1521065363.491 [BPC1](INFO): Stopped
2018-03-14T22:09:23.899Z,1521065363.899 [BPC1](INFO): Stopped
2018-03-14T22:09:24.295Z,1521065364.295 [BPC1](INFO): Stopped
2018-03-14T22:09:24.696Z,1521065364.696 [BPC1](INFO): Stopped
2018-03-14T22:09:25.095Z,1521065365.095 [BPC1](INFO): Stopped
2018-03-14T22:09:25.503Z,1521065365.503 [BPC1](INFO): Stopped
2018-03-14T22:09:25.894Z,1521065365.894 [BPC1](INFO): Stopped
2018-03-14T22:09:26.303Z,1521065366.303 [BPC1](INFO): Stopped
2018-03-14T22:09:26.707Z,1521065366.707 [BPC1](INFO): Stopped
2018-03-14T22:09:27.090Z,1521065367.090 [BPC1](INFO): Stopped
2018-03-14T22:09:27.493Z,1521065367.493 [BPC1](INFO): Stopped
2018-03-14T22:09:27.891Z,1521065367.891 [BPC1](INFO): Stopped
2018-03-14T22:09:28.291Z,1521065368.291 [BPC1](INFO): Stopped
2018-03-14T22:09:28.702Z,1521065368.702 [BPC1](INFO): Stopped
2018-03-14T22:09:29.095Z,1521065369.095 [BPC1](INFO): Stopped
2018-03-14T22:09:29.498Z,1521065369.498 [BPC1](INFO): Stopped
2018-03-14T22:09:29.893Z,1521065369.893 [BPC1](INFO): Stopped
2018-03-14T22:09:30.295Z,1521065370.295 [BPC1](INFO): Stopped
2018-03-14T22:09:30.709Z,1521065370.709 [BPC1](INFO): Stopped
2018-03-14T22:09:31.111Z,1521065371.111 [BPC1](INFO): Stopped
2018-03-14T22:09:31.508Z,1521065371.508 [BPC1](INFO): Stopped
2018-03-14T22:09:31.906Z,1521065371.906 [BPC1](INFO): Stopped
2018-03-14T22:09:32.286Z,1521065372.286 [BPC1](INFO): Stopped
2018-03-14T22:09:32.684Z,1521065372.684 [BPC1](INFO): Stopped
2018-03-14T22:09:33.084Z,1521065373.084 [BPC1](INFO): Stopped
2018-03-14T22:09:33.489Z,1521065373.489 [BPC1](INFO): Stopped
2018-03-14T22:09:33.587Z,1521065373.587 [CommandLine](IMPORTANT): got command restart application
2018-03-14T22:09:33.889Z,1521065373.889 [BPC1](INFO): Stopped
2018-03-14T22:09:34.291Z,1521065374.291 [BPC1](INFO): Stopped
2018-03-14T22:09:34.591Z,1521065374.591 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-03-14T22:09:34.594Z,1521065374.594 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:09:34.594Z,1521065374.594 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:34.625Z,1521065374.625 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-03-14T22:09:34.625Z,1521065374.625 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:34.626Z,1521065374.626 [CommandLine](INFO): Join timeout helper Thread ID is 372
2018-03-14T22:09:34.626Z,1521065374.626 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-03-14T22:09:34.627Z,1521065374.627 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:34.627Z,1521065374.627 [NavChartDb](INFO): Join timeout helper Thread ID is 373
2018-03-14T22:09:34.697Z,1521065374.697 [BPC1](INFO): Stopped
2018-03-14T22:09:34.795Z,1521065374.795 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:09:34.795Z,1521065374.795 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:34.814Z,1521065374.814 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-03-14T22:09:34.815Z,1521065374.815 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:34.815Z,1521065374.815 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 374
2018-03-14T22:09:34.995Z,1521065374.995 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:09:34.995Z,1521065374.995 [WetLabsBB2FL](INFO): Powering down
2018-03-14T22:09:34.996Z,1521065374.996 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.014Z,1521065375.014 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-03-14T22:09:35.015Z,1521065375.015 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.015Z,1521065375.015 [Radio_Surface](INFO): Join timeout helper Thread ID is 375
2018-03-14T22:09:35.095Z,1521065375.095 [BPC1](INFO): Stopped
2018-03-14T22:09:35.215Z,1521065375.215 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:09:35.215Z,1521065375.215 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.234Z,1521065375.234 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-03-14T22:09:35.235Z,1521065375.235 [logger ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.235Z,1521065375.235 [logger](INFO): Join timeout helper Thread ID is 376
2018-03-14T22:09:35.275Z,1521065375.275 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:09:35.275Z,1521065375.275 [logger ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.295Z,1521065375.295 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-03-14T22:09:35.295Z,1521065375.295 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.296Z,1521065375.296 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-03-14T22:09:35.296Z,1521065375.296 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.296Z,1521065375.296 [controlThread](INFO): Join timeout helper Thread ID is 377
2018-03-14T22:09:35.455Z,1521065375.455 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-03-14T22:09:35.455Z,1521065375.455 [controlThread](DEBUG): Uninitializing ControlThread
2018-03-14T22:09:35.456Z,1521065375.456 [NAL9602](INFO): Powering down
2018-03-14T22:09:35.457Z,1521065375.457 [PNI_TCM](INFO): Powering down
2018-03-14T22:09:35.543Z,1521065375.543 [BPC1](INFO): uninitialize
2018-03-14T22:09:35.543Z,1521065375.543 [Aanderaa_O2](INFO): Powering down
2018-03-14T22:09:35.545Z,1521065375.545 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-03-14T22:09:35.547Z,1521065375.547 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-03-14T22:09:35.547Z,1521065375.547 [MissionManager](INFO): Uninitializing Mission Default
2018-03-14T22:09:35.547Z,1521065375.547 [Default] Stopped
2018-03-14T22:09:35.547Z,1521065375.547 [Default](DEBUG): Aggregate::uninitialize Default
2018-03-14T22:09:35.547Z,1521065375.547 [Default:B.GoToSurface] Stopped
2018-03-14T22:09:35.548Z,1521065375.548 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-03-14T22:09:35.548Z,1521065375.548 [Default:CheckIn] Stopped
2018-03-14T22:09:35.548Z,1521065375.548 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-03-14T22:09:35.548Z,1521065375.548 [Default:CheckIn:Read_GPS] Stopped
2018-03-14T22:09:35.550Z,1521065375.550 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-03-14T22:09:35.551Z,1521065375.551 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-03-14T22:09:35.551Z,1521065375.551 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-03-14T22:09:35.551Z,1521065375.551 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-03-14T22:09:35.551Z,1521065375.551 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-03-14T22:09:35.552Z,1521065375.552 [BuoyancyServo](INFO): Powering down
2018-03-14T22:09:35.567Z,1521065375.567 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-03-14T22:09:35.567Z,1521065375.567 [ElevatorServo](INFO): Powering down
2018-03-14T22:09:35.568Z,1521065375.568 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-03-14T22:09:35.568Z,1521065375.568 [MassServo](INFO): Powering down
2018-03-14T22:09:35.568Z,1521065375.568 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-03-14T22:09:35.568Z,1521065375.568 [RudderServo](INFO): Powering down
2018-03-14T22:09:35.569Z,1521065375.569 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-03-14T22:09:35.569Z,1521065375.569 [ThrusterServo](INFO): Powering down
2018-03-14T22:09:35.570Z,1521065375.570 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-03-14T22:09:35.570Z,1521065375.570 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-03-14T22:09:35.571Z,1521065375.571 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-03-14T22:09:35.571Z,1521065375.571 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.607Z,1521065375.607 [BPC1](INFO): uninitialize
2018-03-14T22:09:35.652Z,1521065375.652 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.686Z,1521065375.686 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.711Z,1521065375.711 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-03-14T22:09:35.759Z,1521065375.759 [logger ThreadHandler](INFO): Thread cancelled.