2017-12-13T16:47:31.250Z,1513183651.250 [Supervisor](DEBUG): Initializing supervisor.
2017-12-13T16:47:31.261Z,1513183651.261 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2017-12-13T16:47:31.261Z,1513183651.261 [SyncHandler](INFO): Protected caller Thread ID is 799
2017-12-13T16:47:31.262Z,1513183651.262 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-12-13T16:47:31.273Z,1513183651.273 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2017-12-13T16:47:31.273Z,1513183651.273 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2017-12-13T16:47:31.276Z,1513183651.276 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-12-13T16:47:31.298Z,1513183651.298 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-12-13T16:47:31.309Z,1513183651.309 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2017-12-13T16:47:31.309Z,1513183651.309 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2017-12-13T16:47:31.310Z,1513183651.310 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-12-13T16:47:31.321Z,1513183651.321 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2017-12-13T16:47:31.321Z,1513183651.321 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2017-12-13T16:47:31.323Z,1513183651.323 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-12-13T16:47:31.323Z,1513183651.323 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-12-13T16:47:31.326Z,1513183651.326 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-12-13T16:47:31.655Z,1513183651.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-12-13T16:47:31.656Z,1513183651.656 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-12-13T16:47:31.894Z,1513183651.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-12-13T16:47:31.895Z,1513183651.895 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-12-13T16:47:32.508Z,1513183652.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-12-13T16:47:32.509Z,1513183652.509 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-12-13T16:47:32.747Z,1513183652.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-12-13T16:47:32.748Z,1513183652.748 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-12-13T16:47:32.887Z,1513183652.887 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-12-13T16:47:33.244Z,1513183653.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-12-13T16:47:33.245Z,1513183653.245 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-12-13T16:47:33.413Z,1513183653.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-12-13T16:47:33.414Z,1513183653.414 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-12-13T16:47:33.727Z,1513183653.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-12-13T16:47:33.729Z,1513183653.729 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-12-13T16:47:34.121Z,1513183654.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-12-13T16:47:34.121Z,1513183654.121 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-12-13T16:47:34.404Z,1513183654.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-12-13T16:47:34.405Z,1513183654.405 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-12-13T16:47:34.884Z,1513183654.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-12-13T16:47:34.884Z,1513183654.884 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-12-13T16:47:35.070Z,1513183655.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-12-13T16:47:35.070Z,1513183655.070 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-12-13T16:47:35.167Z,1513183655.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-12-13T16:47:35.169Z,1513183655.169 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-12-13T16:47:35.613Z,1513183655.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-12-13T16:47:35.613Z,1513183655.613 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-12-13T16:47:35.717Z,1513183655.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-12-13T16:47:35.719Z,1513183655.719 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-12-13T16:47:35.722Z,1513183655.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-12-13T16:47:35.949Z,1513183655.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-12-13T16:47:35.950Z,1513183655.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-12-13T16:47:36.048Z,1513183656.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-12-13T16:47:36.137Z,1513183656.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-12-13T16:47:36.237Z,1513183656.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-12-13T16:47:36.322Z,1513183656.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-12-13T16:47:36.444Z,1513183656.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-12-13T16:47:36.590Z,1513183656.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-12-13T16:47:36.683Z,1513183656.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-12-13T16:47:36.773Z,1513183656.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-12-13T16:47:36.857Z,1513183656.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-12-13T16:47:37.027Z,1513183657.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-12-13T16:47:37.103Z,1513183657.103 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-12-13T16:47:37.121Z,1513183657.121 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-12-13T16:47:37.275Z,1513183657.275 [SBIT](DEBUG): Construct Startup Built In Test.
2017-12-13T16:47:37.306Z,1513183657.306 [SBIT] Loaded
2017-12-13T16:47:37.306Z,1513183657.306 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-12-13T16:47:37.307Z,1513183657.307 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-12-13T16:47:37.334Z,1513183657.334 [IBIT] Loaded
2017-12-13T16:47:37.334Z,1513183657.334 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-12-13T16:47:37.337Z,1513183657.337 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-12-13T16:47:37.488Z,1513183657.488 [CBIT] Loaded
2017-12-13T16:47:37.489Z,1513183657.489 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-12-13T16:47:37.489Z,1513183657.489 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-12-13T16:47:37.490Z,1513183657.490 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-12-13T16:47:37.578Z,1513183657.578 [VerticalControl](DEBUG): Construct VerticalControl.
2017-12-13T16:47:37.677Z,1513183657.677 [VerticalControl] Loaded
2017-12-13T16:47:37.677Z,1513183657.677 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-12-13T16:47:37.678Z,1513183657.678 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-12-13T16:47:37.737Z,1513183657.737 [HorizontalControl] Loaded
2017-12-13T16:47:37.737Z,1513183657.737 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-12-13T16:47:37.738Z,1513183657.738 [SpeedControl](DEBUG): Construct SpeedControl.
2017-12-13T16:47:37.743Z,1513183657.743 [SpeedControl] Loaded
2017-12-13T16:47:37.744Z,1513183657.744 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-12-13T16:47:37.744Z,1513183657.744 [LoopControl](DEBUG): Construct LoopControl.
2017-12-13T16:47:37.745Z,1513183657.745 [LoopControl] Loaded
2017-12-13T16:47:37.745Z,1513183657.745 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-12-13T16:47:37.746Z,1513183657.746 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-12-13T16:47:37.746Z,1513183657.746 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-12-13T16:47:37.802Z,1513183657.802 [DepthRateCalculator] Loaded
2017-12-13T16:47:37.803Z,1513183657.803 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-12-13T16:47:37.808Z,1513183657.808 [PitchRateCalculator] Loaded
2017-12-13T16:47:37.808Z,1513183657.808 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-12-13T16:47:37.824Z,1513183657.824 [SpeedCalculator] Loaded
2017-12-13T16:47:37.825Z,1513183657.825 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-12-13T16:47:37.846Z,1513183657.846 [TempGradientCalculator] Loaded
2017-12-13T16:47:37.846Z,1513183657.846 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-12-13T16:47:37.862Z,1513183657.862 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-12-13T16:47:37.863Z,1513183657.863 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-12-13T16:47:37.867Z,1513183657.867 [YawRateCalculator] Loaded
2017-12-13T16:47:37.868Z,1513183657.868 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-12-13T16:47:37.868Z,1513183657.868 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-12-13T16:47:37.869Z,1513183657.869 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-12-13T16:47:37.993Z,1513183657.993 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-12-13T16:47:37.993Z,1513183657.993 [StratificationFrontDetector](DEBUG): (re)initializing
2017-12-13T16:47:37.994Z,1513183657.994 [StratificationFrontDetector] Loaded
2017-12-13T16:47:37.994Z,1513183657.994 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-12-13T16:47:37.994Z,1513183657.994 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-12-13T16:47:37.995Z,1513183657.995 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-12-13T16:47:38.277Z,1513183658.277 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-12-13T16:47:38.278Z,1513183658.278 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-12-13T16:47:38.387Z,1513183658.387 [DeadReckonUsingSpeedCalculator] Loaded
2017-12-13T16:47:38.388Z,1513183658.388 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-12-13T16:47:38.441Z,1513183658.441 [DeadReckonWithRespectToSeafloor] Loaded
2017-12-13T16:47:38.441Z,1513183658.441 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2017-12-13T16:47:38.457Z,1513183658.457 [NavChart] Loaded
2017-12-13T16:47:38.457Z,1513183658.457 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-12-13T16:47:38.461Z,1513183658.461 [UniversalFixResidualReporter] Loaded
2017-12-13T16:47:38.461Z,1513183658.461 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-12-13T16:47:38.462Z,1513183658.462 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-12-13T16:47:38.463Z,1513183658.463 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-12-13T16:47:38.487Z,1513183658.487 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-12-13T16:47:38.488Z,1513183658.488 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-12-13T16:47:38.687Z,1513183658.687 [Aanderaa_O2] Loaded
2017-12-13T16:47:38.688Z,1513183658.688 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-12-13T16:47:38.700Z,1513183658.700 [CANONSampler] Loaded
2017-12-13T16:47:38.700Z,1513183658.700 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2017-12-13T16:47:38.776Z,1513183658.776 [CTD_NeilBrown] Loaded
2017-12-13T16:47:38.777Z,1513183658.777 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-12-13T16:47:38.778Z,1513183658.778 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406D14E0
2017-12-13T16:47:38.778Z,1513183658.778 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 884
2017-12-13T16:47:38.810Z,1513183658.810 [CTD_Seabird] Loaded
2017-12-13T16:47:38.811Z,1513183658.811 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-12-13T16:47:38.812Z,1513183658.812 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407014E0
2017-12-13T16:47:38.812Z,1513183658.812 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 885
2017-12-13T16:47:38.827Z,1513183658.827 [PAR_Licor] Loaded
2017-12-13T16:47:38.827Z,1513183658.827 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-12-13T16:47:38.876Z,1513183658.876 [WetLabsBB2FL] Loaded
2017-12-13T16:47:38.876Z,1513183658.876 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-12-13T16:47:38.877Z,1513183658.877 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407314E0
2017-12-13T16:47:38.878Z,1513183658.878 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 886
2017-12-13T16:47:38.920Z,1513183658.920 [WetLabsSeaOWL_UV_A] Loaded
2017-12-13T16:47:38.921Z,1513183658.921 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2017-12-13T16:47:38.922Z,1513183658.922 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407614E0
2017-12-13T16:47:38.922Z,1513183658.922 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 887
2017-12-13T16:47:38.923Z,1513183658.923 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-12-13T16:47:38.923Z,1513183658.923 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-12-13T16:47:39.459Z,1513183659.459 [DataOverHttps] Loaded
2017-12-13T16:47:39.459Z,1513183659.459 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-12-13T16:47:39.472Z,1513183659.472 [Depth_Keller] Loaded
2017-12-13T16:47:39.472Z,1513183659.472 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-12-13T16:47:39.477Z,1513183659.477 [DropWeight] Loaded
2017-12-13T16:47:39.478Z,1513183659.478 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-12-13T16:47:39.567Z,1513183659.567 [NAL9602] Loaded
2017-12-13T16:47:39.568Z,1513183659.568 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-12-13T16:47:39.573Z,1513183659.573 [Onboard] Loaded
2017-12-13T16:47:39.573Z,1513183659.573 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-12-13T16:47:39.580Z,1513183659.580 [Radio_Surface] Loaded
2017-12-13T16:47:39.580Z,1513183659.580 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-12-13T16:47:39.581Z,1513183659.581 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0
2017-12-13T16:47:39.582Z,1513183659.582 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 888
2017-12-13T16:47:39.734Z,1513183659.734 [PNI_TCM] Loaded
2017-12-13T16:47:39.734Z,1513183659.734 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-12-13T16:47:41.501Z,1513183661.501 [BPC1] Loaded
2017-12-13T16:47:41.502Z,1513183661.502 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-12-13T16:47:41.502Z,1513183661.502 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-12-13T16:47:41.503Z,1513183661.503 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-12-13T16:47:45.354Z,1513183665.354 [BuoyancyServo] Loaded
2017-12-13T16:47:45.354Z,1513183665.354 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-12-13T16:47:45.365Z,1513183665.365 [ElevatorServo] Loaded
2017-12-13T16:47:45.366Z,1513183665.366 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-12-13T16:47:45.376Z,1513183665.376 [MassServo] Loaded
2017-12-13T16:47:45.377Z,1513183665.377 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-12-13T16:47:45.388Z,1513183665.388 [RudderServo] Loaded
2017-12-13T16:47:45.388Z,1513183665.388 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-12-13T16:47:45.399Z,1513183665.399 [ThrusterServo] Loaded
2017-12-13T16:47:45.399Z,1513183665.399 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-12-13T16:47:45.400Z,1513183665.400 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-12-13T16:47:45.400Z,1513183665.400 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-12-13T16:47:45.526Z,1513183665.526 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-12-13T16:47:45.527Z,1513183665.527 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-12-13T16:47:45.566Z,1513183665.566 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-12-13T16:47:45.569Z,1513183665.569 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-12-13T16:47:45.570Z,1513183665.570 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-12-13T16:47:45.577Z,1513183665.577 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-12-13T16:47:45.578Z,1513183665.578 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4C4E0
2017-12-13T16:47:45.578Z,1513183665.578 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 889
2017-12-13T16:47:45.583Z,1513183665.583 [Supervisor](INFO): Main Thread ID is 795
2017-12-13T16:47:45.583Z,1513183665.583 [Supervisor](DEBUG): Running supervisor.
2017-12-13T16:47:45.584Z,1513183665.584 [CommandLine ThreadHandler](INFO): Handler Thread ID is 890
2017-12-13T16:47:45.586Z,1513183665.586 [controlThread ThreadHandler](INFO): Handler Thread ID is 891
2017-12-13T16:47:45.587Z,1513183665.587 [controlThread](DEBUG): Initializing ControlThread
2017-12-13T16:47:45.587Z,1513183665.587 [SBIT](INFO): Initialize SBIT Component.
2017-12-13T16:47:45.588Z,1513183665.588 [SBIT](IMPORTANT): git: 2017-12-12
2017-12-13T16:47:45.588Z,1513183665.588 [SBIT](INFO): git hash: 18638d31f033e3fa0cb90b26256f5481d37f00cc
2017-12-13T16:47:45.589Z,1513183665.589 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-12-13T16:47:45.590Z,1513183665.590 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017
2017-12-13T16:47:45.591Z,1513183665.591 [SBIT](INFO): Beginning SBIT in 44.000000 seconds.
2017-12-13T16:47:45.591Z,1513183665.591 [IBIT](INFO): Initialize IBIT Component.
2017-12-13T16:47:45.592Z,1513183665.592 [CBIT](DEBUG): Initialize CBIT Component.
2017-12-13T16:47:45.593Z,1513183665.593 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-12-13T16:47:45.593Z,1513183665.593 [logger ThreadHandler](INFO): Handler Thread ID is 892
2017-12-13T16:47:45.621Z,1513183665.621 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 893
2017-12-13T16:47:45.622Z,1513183665.622 [CTD_NeilBrown](INFO): Powering down
2017-12-13T16:47:45.654Z,1513183665.654 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 894
2017-12-13T16:47:45.655Z,1513183665.655 [CTD_Seabird](INFO): Initializing
2017-12-13T16:47:45.684Z,1513183665.684 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-12-13T16:47:45.686Z,1513183665.686 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-12-13T16:47:45.687Z,1513183665.687 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-12-13T16:47:45.688Z,1513183665.688 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-12-13T16:47:45.688Z,1513183665.688 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-12-13T16:47:45.688Z,1513183665.688 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-12-13T16:47:45.689Z,1513183665.689 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-12-13T16:47:45.689Z,1513183665.689 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-12-13T16:47:45.690Z,1513183665.690 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-12-13T16:47:45.691Z,1513183665.691 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-12-13T16:47:45.691Z,1513183665.691 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-12-13T16:47:45.692Z,1513183665.692 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T16:47:45.693Z,1513183665.693 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T16:47:45.693Z,1513183665.693 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T16:47:45.694Z,1513183665.694 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T16:47:45.694Z,1513183665.694 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T16:47:45.694Z,1513183665.694 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-12-13T16:47:45.695Z,1513183665.695 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-12-13T16:47:45.706Z,1513183665.706 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-12-13T16:47:45.719Z,1513183665.719 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 896
2017-12-13T16:47:45.721Z,1513183665.721 [WetLabsBB2FL](INFO): Powering down
2017-12-13T16:47:45.745Z,1513183665.745 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 897
2017-12-13T16:47:45.755Z,1513183665.755 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-13T16:47:45.760Z,1513183665.760 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 898
2017-12-13T16:47:45.781Z,1513183665.781 [Radio_Surface](INFO): Powering up
2017-12-13T16:47:45.793Z,1513183665.793 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 899
2017-12-13T16:47:45.802Z,1513183665.802 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-12-13T16:47:45.802Z,1513183665.802 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-12-13T16:47:45.802Z,1513183665.802 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-12-13T16:47:45.802Z,1513183665.802 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-12-13T16:47:45.802Z,1513183665.802 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-12-13T16:47:45.803Z,1513183665.803 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-12-13T16:47:45.803Z,1513183665.803 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-12-13T16:47:45.803Z,1513183665.803 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-12-13T16:47:45.803Z,1513183665.803 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-12-13T16:47:45.803Z,1513183665.803 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-12-13T16:47:45.804Z,1513183665.804 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-12-13T16:47:45.804Z,1513183665.804 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-12-13T16:47:45.804Z,1513183665.804 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-12-13T16:47:45.804Z,1513183665.804 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-12-13T16:47:45.804Z,1513183665.804 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-12-13T16:47:45.805Z,1513183665.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-12-13T16:47:45.821Z,1513183665.821 [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>


2017-12-13T16:47:45.825Z,1513183665.825 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-12-13T16:47:45.911Z,1513183665.911 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-12-13T16:47:45.937Z,1513183665.937 [Default:A.Wait](DEBUG): Construct Wait.
2017-12-13T16:47:45.938Z,1513183665.938 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-13T16:47:45.969Z,1513183665.969 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-13T16:47:45.969Z,1513183665.969 [CTD_Seabird](INFO): Powering down
2017-12-13T16:47:45.994Z,1513183665.994 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-12-13T16:47:45.996Z,1513183665.996 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-12-13T16:47:46.018Z,1513183666.018 [Default:E.Execute](DEBUG): Construct Execute.
2017-12-13T16:47:46.033Z,1513183666.033 [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>


2017-12-13T16:47:46.038Z,1513183666.038 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,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,
2017-12-13T16:47:46.104Z,1513183666.104 [CANONSampler](INFO): Powering down
2017-12-13T16:47:46.217Z,1513183666.217 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-12-13T16:47:46.254Z,1513183666.254 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-12-13T16:47:46.478Z,1513183666.478 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-12-13T16:47:46.569Z,1513183666.569 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:46.697Z,1513183666.697 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:46.805Z,1513183666.805 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-13T16:47:46.813Z,1513183666.813 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-13T16:47:46.827Z,1513183666.827 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-12-13T16:47:46.833Z,1513183666.833 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-12-13T16:47:46.847Z,1513183666.847 [MassServo](DEBUG): Initializing EZServoServo.
2017-12-13T16:47:46.853Z,1513183666.853 [MassServo](DEBUG): Initializing MassServo.
2017-12-13T16:47:46.871Z,1513183666.871 [RudderServo](DEBUG): Initializing EZServoServo.
2017-12-13T16:47:46.877Z,1513183666.877 [RudderServo](DEBUG): Initializing RudderServo.
2017-12-13T16:47:46.882Z,1513183666.882 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-12-13T16:47:46.889Z,1513183666.889 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-12-13T16:47:47.284Z,1513183667.284 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:47.293Z,1513183667.293 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:47.472Z,1513183667.472 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:47.485Z,1513183667.485 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:47.963Z,1513183667.963 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:47.964Z,1513183667.964 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:48.366Z,1513183668.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:48.367Z,1513183668.367 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:48.752Z,1513183668.752 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:48.753Z,1513183668.753 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:49.152Z,1513183669.152 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:49.153Z,1513183669.153 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:49.637Z,1513183669.637 [Aanderaa_O2](INFO): Powering down
2017-12-13T16:47:49.759Z,1513183669.759 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:47:49.759Z,1513183669.759 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T16:48:09.582Z,1513183689.582 [NAL9602](INFO): Powering up NAL9602
2017-12-13T16:48:20.794Z,1513183700.794 [NAL9602](INFO): NAL9602 initialized
2017-12-13T16:48:30.071Z,1513183710.071 [SBIT](IMPORTANT): Beginning Startup BIT
2017-12-13T16:48:30.091Z,1513183710.091 [CBIT](IMPORTANT): Beginning ground fault scan
2017-12-13T16:48:36.104Z,1513183716.104 [NAL9602](INFO): SBD MO Status=0, MOMSN=3633, MT Status=0, MTMSN=0
2017-12-13T16:48:36.104Z,1513183716.104 [NAL9602](INFO): No messages in MT queue
2017-12-13T16:48:41.005Z,1513183721.005 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.005065
CHAN A1 (24V): 4.657929
CHAN A2 (12V): -0.002491
CHAN A3 (5V): -0.000586
CHAN B0 (3.3V): 0.000014
CHAN B1 (3.15aV): 0.000311
CHAN B2 (3.15bV): -0.000219
CHAN B3 (GND): 0.000023
OPEN: -0.000309
Full Scale Calc: 4.765 mA, -1.589 mA
2017-12-13T16:49:10.758Z,1513183750.758 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T16:49:10.758Z,1513183750.758 [BPC1] Data Fault, FailCount= 1
2017-12-13T16:49:10.758Z,1513183750.758 [BPC1](ERROR): Data Fault
2017-12-13T16:49:10.812Z,1513183750.812 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T16:49:12.149Z,1513183752.149 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:49:12.149Z,1513183752.149 [BPC1] No Fault, FailCount= 1
2017-12-13T16:49:23.790Z,1513183763.790 [SBIT](IMPORTANT): SBIT PASSED
2017-12-13T16:49:24.181Z,1513183764.181 [MissionManager](IMPORTANT): Started mission Startup
2017-12-13T16:49:24.182Z,1513183764.182 [Startup] Running Loop=1
2017-12-13T16:49:24.182Z,1513183764.182 [Startup](DEBUG): Aggregate::initialize Startup
2017-12-13T16:49:24.182Z,1513183764.182 [Startup:A.GoToSurface] Running Loop=1
2017-12-13T16:49:24.182Z,1513183764.182 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-13T16:49:24.183Z,1513183764.183 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-13T16:49:24.183Z,1513183764.183 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-13T16:49:24.183Z,1513183764.183 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-13T16:49:24.184Z,1513183764.184 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-13T16:49:24.184Z,1513183764.184 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-13T16:49:24.194Z,1513183764.194 [Startup:StartupSatComms] Running Loop=1
2017-12-13T16:49:24.194Z,1513183764.194 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-12-13T16:49:24.194Z,1513183764.194 [Startup:StartupSatComms:A] Running Loop=1
2017-12-13T16:49:24.562Z,1513183764.562 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-12-13T16:49:32.954Z,1513183772.954 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:49:32.954Z,1513183772.954 [BPC1] Communications Fault, FailCount= 2
2017-12-13T16:49:32.954Z,1513183772.954 [BPC1](ERROR): Communications Fault
2017-12-13T16:49:32.972Z,1513183772.972 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:49:34.181Z,1513183774.181 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:49:34.181Z,1513183774.181 [BPC1] No Fault, FailCount= 2
2017-12-13T16:49:54.944Z,1513183794.944 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:49:54.944Z,1513183794.944 [BPC1] Communications Fault, FailCount= 3
2017-12-13T16:49:54.944Z,1513183794.944 [BPC1](ERROR): Communications Fault
2017-12-13T16:49:54.988Z,1513183794.988 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:49:56.190Z,1513183796.190 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:49:56.191Z,1513183796.191 [BPC1] No Fault, FailCount= 3
2017-12-13T16:50:13.394Z,1513183813.394 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003853
2017-12-13T16:50:24.233Z,1513183824.233 [Startup:StartupSatComms:A](INFO): Timed out from 2017-12-13T16:49:24.2Z
2017-12-13T16:50:24.233Z,1513183824.233 [Startup:StartupSatComms:A] Stopped
2017-12-13T16:50:24.233Z,1513183824.233 [Startup:StartupSatComms:B] Running Loop=1
2017-12-13T16:50:24.630Z,1513183824.630 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-12-13T16:50:28.698Z,1513183828.698 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171213T164216/Courier0004.lzma
2017-12-13T16:50:29.467Z,1513183829.467 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164216/Courier0004.lzma.bak
2017-12-13T16:50:29.467Z,1513183829.467 [DataOverHttps](INFO): SBD MOMSN=5435011
2017-12-13T16:50:38.411Z,1513183838.411 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:50:38.411Z,1513183838.411 [BPC1] Communications Fault, FailCount= 1
2017-12-13T16:50:38.411Z,1513183838.411 [BPC1](ERROR): Communications Fault
2017-12-13T16:50:38.475Z,1513183838.475 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:50:38.510Z,1513183838.510 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20171213T164216/Express0005.lzma
2017-12-13T16:50:39.287Z,1513183839.287 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164216/Express0005.lzma.bak
2017-12-13T16:50:39.287Z,1513183839.287 [DataOverHttps](INFO): SBD MOMSN=5435013
2017-12-13T16:50:39.371Z,1513183839.371 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:50:39.371Z,1513183839.371 [BPC1] No Fault, FailCount= 1
2017-12-13T16:50:48.141Z,1513183848.141 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T16:50:48.141Z,1513183848.141 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-12-13T16:50:48.141Z,1513183848.141 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-12-13T16:50:48.143Z,1513183848.143 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T16:50:48.143Z,1513183848.143 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T16:50:48.143Z,1513183848.143 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T16:50:48.181Z,1513183848.181 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-12-13T16:50:48.182Z,1513183848.182 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T16:50:48.242Z,1513183848.242 [DataOverHttps](INFO): Sending 521 bytes from file Logs/20171213T164731/Express0001.lzma
2017-12-13T16:50:48.313Z,1513183848.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-12-13T16:50:48.313Z,1513183848.313 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-12-13T16:50:48.314Z,1513183848.314 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T16:50:48.314Z,1513183848.314 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T16:50:48.654Z,1513183848.654 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-12-13T16:50:48.655Z,1513183848.655 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T16:50:48.655Z,1513183848.655 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T16:50:48.656Z,1513183848.656 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T16:50:48.656Z,1513183848.656 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T16:50:48.656Z,1513183848.656 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T16:50:48.999Z,1513183848.999 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164731/Express0001.lzma.bak
2017-12-13T16:50:48.999Z,1513183848.999 [DataOverHttps](INFO): SBD MOMSN=5435017
2017-12-13T16:50:49.656Z,1513183849.656 [Startup:StartupSatComms:B] Stopped
2017-12-13T16:50:49.656Z,1513183849.656 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-12-13T16:50:49.656Z,1513183849.656 [Startup:StartupSatComms] Stopped
2017-12-13T16:50:49.656Z,1513183849.656 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-12-13T16:50:49.657Z,1513183849.657 [Startup](INFO): Completed Startup
2017-12-13T16:50:49.657Z,1513183849.657 [MissionManager](INFO): Startup is completed.
2017-12-13T16:50:49.658Z,1513183849.658 [MissionManager](INFO): Uninitializing Mission Startup
2017-12-13T16:50:49.658Z,1513183849.658 [Startup] Stopped
2017-12-13T16:50:49.658Z,1513183849.658 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-12-13T16:50:49.658Z,1513183849.658 [Startup:A.GoToSurface] Stopped
2017-12-13T16:50:49.658Z,1513183849.658 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-13T16:50:49.864Z,1513183849.864 [MissionManager](IMPORTANT): Started mission Default
2017-12-13T16:50:49.864Z,1513183849.864 [Default] Running Loop=1
2017-12-13T16:50:49.864Z,1513183849.864 [Default](DEBUG): Aggregate::initialize Default
2017-12-13T16:50:49.864Z,1513183849.864 [Default:B.GoToSurface] Running Loop=1
2017-12-13T16:50:49.864Z,1513183849.864 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-13T16:50:49.869Z,1513183849.869 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-13T16:50:49.869Z,1513183849.869 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-13T16:50:49.869Z,1513183849.869 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-13T16:50:49.870Z,1513183849.870 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-13T16:50:49.870Z,1513183849.870 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-13T16:50:49.870Z,1513183849.870 [Default:A.Wait] Running Loop=1
2017-12-13T16:50:49.870Z,1513183849.870 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-12-13T16:51:03.144Z,1513183863.144 [Default:A.Wait](INFO): Done Waiting.
2017-12-13T16:51:03.144Z,1513183863.144 [Default:A.Wait] Stopped
2017-12-13T16:51:03.144Z,1513183863.144 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T16:51:03.579Z,1513183863.579 [Default:CheckIn] Running Loop=1
2017-12-13T16:51:03.580Z,1513183863.580 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T16:51:03.580Z,1513183863.580 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T16:51:03.974Z,1513183863.974 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-12-13T16:51:53.699Z,1513183913.699 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:51:53.699Z,1513183913.699 [BPC1] Communications Fault, FailCount= 1
2017-12-13T16:51:53.699Z,1513183913.699 [BPC1](ERROR): Communications Fault
2017-12-13T16:51:53.742Z,1513183913.742 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:51:54.635Z,1513183914.635 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:51:54.635Z,1513183914.635 [BPC1] No Fault, FailCount= 1
2017-12-13T16:51:55.794Z,1513183915.794 [PNI_TCM](ERROR): CRC does not match. Expected:0x61595 got:0x16925
2017-12-13T16:51:57.797Z,1513183917.797 [PNI_TCM](ERROR): CRC does not match. Expected:0x50428 got:0x49139
2017-12-13T16:52:11.426Z,1513183931.426 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T16:52:11.426Z,1513183931.426 [BPC1] Data Fault, FailCount= 2
2017-12-13T16:52:11.426Z,1513183931.426 [BPC1](ERROR): Data Fault
2017-12-13T16:52:11.470Z,1513183931.470 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T16:52:12.624Z,1513183932.624 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:52:12.627Z,1513183932.627 [BPC1] No Fault, FailCount= 2
2017-12-13T16:52:33.378Z,1513183953.378 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:52:33.378Z,1513183953.378 [BPC1] Communications Fault, FailCount= 3
2017-12-13T16:52:33.378Z,1513183953.378 [BPC1](ERROR): Communications Fault
2017-12-13T16:52:33.432Z,1513183953.432 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:52:34.622Z,1513183954.622 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:52:34.622Z,1513183954.622 [BPC1] No Fault, FailCount= 3
2017-12-13T16:52:55.871Z,1513183975.871 [PNI_TCM](ERROR): CRC does not match. Expected:0x50449 got:0x62003
2017-12-13T16:53:03.447Z,1513183983.447 [NAL9602](INFO): GPS fix at 20171213T165301: (36.802816, -121.788044)
2017-12-13T16:53:03.521Z,1513183983.521 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T16:53:03.521Z,1513183983.521 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T16:53:03.899Z,1513183983.899 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-12-13T16:53:04.437Z,1513183984.437 [PNI_TCM](ERROR): CRC does not match. Expected:0x22976 got:0x8216
2017-12-13T16:53:28.259Z,1513184008.259 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:53:28.259Z,1513184008.259 [BPC1] Communications Fault, FailCount= 1
2017-12-13T16:53:28.259Z,1513184008.259 [BPC1](ERROR): Communications Fault
2017-12-13T16:53:28.304Z,1513184008.304 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:53:29.492Z,1513184009.492 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:53:29.492Z,1513184009.492 [BPC1] No Fault, FailCount= 1
2017-12-13T16:53:34.634Z,1513184014.634 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T16:53:35.838Z,1513184015.838 [NAL9602](INFO): Powering down
2017-12-13T16:53:48.682Z,1513184028.682 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T16:53:48.682Z,1513184028.682 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T16:53:48.682Z,1513184028.682 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T16:53:48.695Z,1513184028.695 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T16:53:49.102Z,1513184029.102 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T16:53:49.102Z,1513184029.102 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T16:53:49.486Z,1513184029.486 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T16:53:49.487Z,1513184029.487 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T16:53:49.487Z,1513184029.487 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T16:54:05.430Z,1513184045.430 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T16:54:22.267Z,1513184062.267 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:54:22.267Z,1513184062.267 [BPC1] Communications Fault, FailCount= 1
2017-12-13T16:54:22.267Z,1513184062.267 [BPC1](ERROR): Communications Fault
2017-12-13T16:54:22.317Z,1513184062.317 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:54:23.517Z,1513184063.517 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:54:23.517Z,1513184063.517 [BPC1] No Fault, FailCount= 1
2017-12-13T16:54:35.830Z,1513184075.830 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T16:55:06.630Z,1513184106.630 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T16:55:10.847Z,1513184110.847 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20171213T164731/Courier0004.lzma
2017-12-13T16:55:11.583Z,1513184111.583 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164731/Courier0004.lzma.bak
2017-12-13T16:55:11.583Z,1513184111.583 [DataOverHttps](INFO): SBD MOMSN=5435028
2017-12-13T16:55:20.504Z,1513184120.504 [PNI_TCM](ERROR): CRC does not match. Expected:0x21803 got:0x62222
2017-12-13T16:55:20.504Z,1513184120.504 [PNI_TCM](ERROR): CRC does not match. Expected:0x7495 got:0x62158
2017-12-13T16:55:20.505Z,1513184120.505 [PNI_TCM](ERROR): CRC does not match. Expected:0x16160 got:0x62145
2017-12-13T16:55:20.505Z,1513184120.505 [PNI_TCM](ERROR): CRC does not match. Expected:0x14035 got:0x62225
2017-12-13T16:55:20.505Z,1513184120.505 [PNI_TCM](ERROR): CRC does not match. Expected:0x55118 got:0x61992
2017-12-13T16:55:20.506Z,1513184120.506 [PNI_TCM](ERROR): CRC does not match. Expected:0x31788 got:0x61947
2017-12-13T16:55:20.506Z,1513184120.506 [PNI_TCM](ERROR): CRC does not match. Expected:0x47136 got:0x62070
2017-12-13T16:55:20.626Z,1513184120.626 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20171213T164731/Express0005.lzma
2017-12-13T16:55:21.348Z,1513184121.348 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164731/Express0005.lzma.bak
2017-12-13T16:55:21.348Z,1513184121.348 [DataOverHttps](INFO): SBD MOMSN=5435031
2017-12-13T16:55:21.984Z,1513184121.984 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T16:55:21.984Z,1513184121.984 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T16:55:21.984Z,1513184121.984 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T16:55:22.192Z,1513184122.192 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:55:22.192Z,1513184122.192 [BPC1] Communications Fault, FailCount= 1
2017-12-13T16:55:22.192Z,1513184122.192 [BPC1](ERROR): Communications Fault
2017-12-13T16:55:22.240Z,1513184122.240 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:55:26.134Z,1513184126.134 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:55:26.134Z,1513184126.134 [BPC1] No Fault, FailCount= 1
2017-12-13T16:56:08.083Z,1513184168.083 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T16:56:08.083Z,1513184168.083 [BPC1] Data Fault, FailCount= 1
2017-12-13T16:56:08.083Z,1513184168.083 [BPC1](ERROR): Data Fault
2017-12-13T16:56:08.104Z,1513184168.104 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T16:56:09.302Z,1513184169.302 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:56:09.302Z,1513184169.302 [BPC1] No Fault, FailCount= 1
2017-12-13T16:56:30.169Z,1513184190.169 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:56:30.169Z,1513184190.169 [BPC1] Communications Fault, FailCount= 2
2017-12-13T16:56:30.169Z,1513184190.169 [BPC1](ERROR): Communications Fault
2017-12-13T16:56:30.211Z,1513184190.211 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:56:31.442Z,1513184191.442 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:56:31.442Z,1513184191.442 [BPC1] No Fault, FailCount= 2
2017-12-13T16:56:49.850Z,1513184209.850 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T16:56:49.850Z,1513184209.850 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2017-12-13T16:56:49.850Z,1513184209.850 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T16:56:49.883Z,1513184209.883 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T16:56:50.236Z,1513184210.236 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T16:56:50.236Z,1513184210.236 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2017-12-13T16:56:50.587Z,1513184210.587 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T16:56:50.587Z,1513184210.587 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T16:56:50.588Z,1513184210.588 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T16:57:45.719Z,1513184265.719 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T16:58:04.870Z,1513184284.870 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:58:04.870Z,1513184284.870 [BPC1] Communications Fault, FailCount= 1
2017-12-13T16:58:04.870Z,1513184284.870 [BPC1](ERROR): Communications Fault
2017-12-13T16:58:04.942Z,1513184284.942 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:58:06.121Z,1513184286.121 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:58:06.121Z,1513184286.121 [BPC1] No Fault, FailCount= 1
2017-12-13T16:59:00.256Z,1513184340.256 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:59:00.256Z,1513184340.256 [BPC1] Communications Fault, FailCount= 1
2017-12-13T16:59:00.257Z,1513184340.257 [BPC1](ERROR): Communications Fault
2017-12-13T16:59:00.313Z,1513184340.313 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:59:01.470Z,1513184341.470 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:59:01.471Z,1513184341.471 [BPC1] No Fault, FailCount= 1
2017-12-13T16:59:50.836Z,1513184390.836 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T16:59:50.837Z,1513184390.837 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2017-12-13T16:59:50.837Z,1513184390.837 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T16:59:50.849Z,1513184390.849 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T16:59:51.200Z,1513184391.200 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T16:59:51.200Z,1513184391.200 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2017-12-13T16:59:51.588Z,1513184391.588 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T16:59:51.589Z,1513184391.589 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T16:59:51.589Z,1513184391.589 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T16:59:53.977Z,1513184393.977 [BPC1](ERROR): Failed to receive battery data
2017-12-13T16:59:53.978Z,1513184393.977 [BPC1] Communications Fault, FailCount= 1
2017-12-13T16:59:53.978Z,1513184393.978 [BPC1](ERROR): Communications Fault
2017-12-13T16:59:54.028Z,1513184394.028 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T16:59:55.229Z,1513184395.229 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T16:59:55.229Z,1513184395.229 [BPC1] No Fault, FailCount= 1
2017-12-13T17:00:22.418Z,1513184422.418 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T17:00:22.418Z,1513184422.418 [Default:CheckIn:C.Wait] Stopped
2017-12-13T17:00:22.418Z,1513184422.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:00:22.418Z,1513184422.418 [Default:CheckIn:D] Running Loop=1
2017-12-13T17:00:22.809Z,1513184422.809 [Default:CheckIn:D] Stopped
2017-12-13T17:00:22.809Z,1513184422.809 [Default:CheckIn:E] Running Loop=1
2017-12-13T17:00:23.191Z,1513184423.191 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.548782 min 
2017-12-13T17:00:23.193Z,1513184423.193 [Default:CheckIn:E] Stopped
2017-12-13T17:00:23.194Z,1513184423.194 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T17:00:23.194Z,1513184423.194 [Default:CheckIn] Stopped
2017-12-13T17:00:23.194Z,1513184423.194 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T17:00:23.194Z,1513184423.194 [Default:CheckIn](INFO): Running loop #2
2017-12-13T17:00:23.194Z,1513184423.194 [Default:CheckIn] Running Loop=2
2017-12-13T17:00:23.194Z,1513184423.194 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T17:00:23.194Z,1513184423.194 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T17:00:26.530Z,1513184426.530 [NAL9602](INFO): Powering up
2017-12-13T17:00:37.686Z,1513184437.686 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:00:49.321Z,1513184449.321 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:00:49.321Z,1513184449.321 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:00:49.322Z,1513184449.322 [BPC1](ERROR): Communications Fault
2017-12-13T17:00:49.358Z,1513184449.358 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:00:50.582Z,1513184450.582 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:00:50.582Z,1513184450.582 [BPC1] No Fault, FailCount= 1
2017-12-13T17:01:48.393Z,1513184508.393 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:01:48.393Z,1513184508.393 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:01:48.393Z,1513184508.393 [BPC1](ERROR): Communications Fault
2017-12-13T17:01:48.441Z,1513184508.441 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:01:49.605Z,1513184509.605 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:01:49.606Z,1513184509.606 [BPC1] No Fault, FailCount= 1
2017-12-13T17:02:06.763Z,1513184526.763 [NAL9602](INFO): SBD MO Status=2, MOMSN=3634, MT Status=2, MTMSN=0
2017-12-13T17:02:06.763Z,1513184526.763 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:02:15.970Z,1513184535.970 [PNI_TCM](ERROR): CRC does not match. Expected:0x48649 got:0x32142
2017-12-13T17:02:20.793Z,1513184540.793 [PNI_TCM](ERROR): CRC does not match. Expected:0x55220 got:0x16815
2017-12-13T17:02:40.647Z,1513184560.647 [NAL9602](INFO): SBD MO Status=2, MOMSN=3634, MT Status=2, MTMSN=0
2017-12-13T17:02:40.647Z,1513184560.647 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:02:43.462Z,1513184563.462 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:02:43.462Z,1513184563.462 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:02:43.462Z,1513184563.462 [BPC1](ERROR): Communications Fault
2017-12-13T17:02:43.522Z,1513184563.522 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:02:44.693Z,1513184564.693 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:02:44.693Z,1513184564.693 [BPC1] No Fault, FailCount= 1
2017-12-13T17:02:51.884Z,1513184571.884 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:02:51.884Z,1513184571.884 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2017-12-13T17:02:51.884Z,1513184571.884 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T17:02:51.896Z,1513184571.896 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T17:02:52.299Z,1513184572.299 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T17:02:52.299Z,1513184572.299 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2017-12-13T17:02:52.693Z,1513184572.693 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T17:02:52.693Z,1513184572.693 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:02:52.693Z,1513184572.693 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:03:10.627Z,1513184590.627 [NAL9602](INFO): SBD MO Status=2, MOMSN=3634, MT Status=2, MTMSN=0
2017-12-13T17:03:10.627Z,1513184590.627 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:03:18.635Z,1513184598.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=3634, MT Status=0, MTMSN=0
2017-12-13T17:03:18.635Z,1513184598.635 [NAL9602](INFO): No messages in MT queue
2017-12-13T17:03:23.062Z,1513184603.062 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:03:23.062Z,1513184603.062 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:03:23.062Z,1513184603.062 [BPC1](ERROR): Communications Fault
2017-12-13T17:03:23.082Z,1513184603.082 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:03:27.085Z,1513184607.085 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:03:27.085Z,1513184607.085 [BPC1] No Fault, FailCount= 1
2017-12-13T17:05:02.425Z,1513184702.425 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:05:02.425Z,1513184702.425 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:05:02.425Z,1513184702.425 [BPC1](ERROR): Communications Fault
2017-12-13T17:05:02.488Z,1513184702.488 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:05:03.639Z,1513184703.639 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:05:03.639Z,1513184703.639 [BPC1] No Fault, FailCount= 1
2017-12-13T17:05:23.248Z,1513184723.248 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-12-13T17:00:23.2Z
2017-12-13T17:05:23.248Z,1513184723.248 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T17:05:23.261Z,1513184723.261 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T17:05:27.417Z,1513184727.417 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20171213T164731/Courier0008.lzma
2017-12-13T17:05:28.159Z,1513184728.159 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164731/Courier0008.lzma.bak
2017-12-13T17:05:28.159Z,1513184728.159 [DataOverHttps](INFO): SBD MOMSN=5435050
2017-12-13T17:05:37.294Z,1513184737.294 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20171213T164731/Express0009.lzma
2017-12-13T17:05:38.095Z,1513184738.095 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164731/Express0009.lzma.bak
2017-12-13T17:05:38.095Z,1513184738.095 [DataOverHttps](INFO): SBD MOMSN=5435052
2017-12-13T17:05:38.754Z,1513184738.754 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T17:05:38.754Z,1513184738.754 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T17:05:38.754Z,1513184738.754 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T17:05:52.769Z,1513184752.769 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:05:52.769Z,1513184752.769 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2017-12-13T17:05:52.769Z,1513184752.769 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T17:05:52.792Z,1513184752.792 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T17:05:53.189Z,1513184753.189 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T17:05:53.189Z,1513184753.189 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2017-12-13T17:05:53.577Z,1513184753.577 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T17:05:53.577Z,1513184753.577 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:05:53.577Z,1513184753.577 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:05:57.520Z,1513184757.520 [NAL9602](INFO): Powering down
2017-12-13T17:06:16.357Z,1513184776.357 [PNI_TCM](ERROR): CRC does not match. Expected:0x21565 got:0x44059
2017-12-13T17:06:57.846Z,1513184817.846 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:06:57.846Z,1513184817.846 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:06:57.846Z,1513184817.846 [BPC1](ERROR): Communications Fault
2017-12-13T17:06:57.914Z,1513184817.914 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:06:59.090Z,1513184819.090 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:06:59.090Z,1513184819.090 [BPC1] No Fault, FailCount= 1
2017-12-13T17:07:45.998Z,1513184865.998 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T17:07:51.557Z,1513184871.557 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:07:51.557Z,1513184871.557 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:07:51.557Z,1513184871.557 [BPC1](ERROR): Communications Fault
2017-12-13T17:07:51.577Z,1513184871.577 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:07:52.775Z,1513184872.775 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:07:52.775Z,1513184872.775 [BPC1] No Fault, FailCount= 1
2017-12-13T17:08:26.354Z,1513184906.354 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:08:26.354Z,1513184906.354 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:08:26.355Z,1513184906.355 [BPC1](ERROR): Communications Fault
2017-12-13T17:08:26.403Z,1513184906.403 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:08:27.580Z,1513184907.580 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:08:27.580Z,1513184907.580 [BPC1] No Fault, FailCount= 1
2017-12-13T17:08:53.682Z,1513184933.682 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:08:53.682Z,1513184933.682 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2017-12-13T17:08:53.682Z,1513184933.682 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T17:08:53.706Z,1513184933.706 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T17:08:54.071Z,1513184934.071 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T17:08:54.071Z,1513184934.071 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2017-12-13T17:08:54.438Z,1513184934.438 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T17:08:54.438Z,1513184934.438 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:08:54.438Z,1513184934.438 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:09:43.855Z,1513184983.855 [PNI_TCM](ERROR): CRC does not match. Expected:0x4441 got:0x49579
2017-12-13T17:09:44.660Z,1513184984.660 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:09:44.660Z,1513184984.660 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:09:44.661Z,1513184984.661 [BPC1](ERROR): Communications Fault
2017-12-13T17:09:44.684Z,1513184984.684 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:09:45.884Z,1513184985.884 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:09:45.884Z,1513184985.884 [BPC1] No Fault, FailCount= 1
2017-12-13T17:09:47.981Z,1513184987.981 [CommandLine](IMPORTANT): got command get VerticalControl.surfaceThreshold
2017-12-13T17:09:47.982Z,1513184987.982 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold 1.000000 m
2017-12-13T17:10:06.656Z,1513185006.656 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:10:06.656Z,1513185006.656 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:10:06.656Z,1513185006.656 [BPC1](ERROR): Communications Fault
2017-12-13T17:10:06.710Z,1513185006.710 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:10:07.881Z,1513185007.881 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:10:07.882Z,1513185007.882 [BPC1] No Fault, FailCount= 2
2017-12-13T17:10:39.063Z,1513185039.063 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T17:10:39.063Z,1513185039.063 [Default:CheckIn:C.Wait] Stopped
2017-12-13T17:10:39.063Z,1513185039.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:10:39.063Z,1513185039.063 [Default:CheckIn:D] Running Loop=1
2017-12-13T17:10:39.464Z,1513185039.464 [Default:CheckIn:D] Stopped
2017-12-13T17:10:39.464Z,1513185039.464 [Default:CheckIn:E] Running Loop=1
2017-12-13T17:10:39.881Z,1513185039.881 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.826656 min 
2017-12-13T17:10:39.881Z,1513185039.881 [Default:CheckIn:E] Stopped
2017-12-13T17:10:39.881Z,1513185039.881 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T17:10:39.881Z,1513185039.881 [Default:CheckIn] Stopped
2017-12-13T17:10:39.881Z,1513185039.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T17:10:39.881Z,1513185039.881 [Default:CheckIn](INFO): Running loop #3
2017-12-13T17:10:39.882Z,1513185039.882 [Default:CheckIn] Running Loop=3
2017-12-13T17:10:39.882Z,1513185039.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T17:10:39.882Z,1513185039.882 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T17:10:43.196Z,1513185043.196 [NAL9602](INFO): Powering up
2017-12-13T17:10:43.966Z,1513185043.966 [PNI_TCM](ERROR): CRC does not match. Expected:0x32734 got:0x16742
2017-12-13T17:10:47.169Z,1513185047.169 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:10:47.169Z,1513185047.169 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:10:47.169Z,1513185047.169 [BPC1](ERROR): Communications Fault
2017-12-13T17:10:47.195Z,1513185047.195 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:10:48.398Z,1513185048.398 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:10:48.398Z,1513185048.398 [BPC1] No Fault, FailCount= 1
2017-12-13T17:10:54.330Z,1513185054.330 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:10:55.130Z,1513185055.130 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-12-13T17:10:55.130Z,1513185055.130 [NAL9602] Data Fault, FailCount= 1
2017-12-13T17:10:55.130Z,1513185055.130 [NAL9602](ERROR): Data Fault
2017-12-13T17:10:55.220Z,1513185055.220 [CBIT](ERROR): Data Fault in component: NAL9602
2017-12-13T17:10:55.530Z,1513185055.530 [NAL9602](INFO): Powering down
2017-12-13T17:10:56.437Z,1513185056.437 [CBIT](INFO): Clearing failed state for component NAL9602
2017-12-13T17:10:56.437Z,1513185056.437 [NAL9602] No Fault, FailCount= 1
2017-12-13T17:11:25.534Z,1513185085.534 [NAL9602](INFO): Powering up NAL9602
2017-12-13T17:11:36.330Z,1513185096.330 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:11:43.154Z,1513185103.154 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:11:43.154Z,1513185103.154 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:11:43.154Z,1513185103.154 [BPC1](ERROR): Communications Fault
2017-12-13T17:11:43.176Z,1513185103.176 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:11:44.077Z,1513185104.077 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:11:44.077Z,1513185104.077 [BPC1] No Fault, FailCount= 1
2017-12-13T17:11:54.869Z,1513185114.869 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:11:54.869Z,1513185114.869 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2017-12-13T17:11:54.869Z,1513185114.869 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T17:11:54.881Z,1513185114.881 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T17:11:55.321Z,1513185115.321 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T17:11:55.321Z,1513185115.321 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2017-12-13T17:11:55.636Z,1513185115.636 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T17:11:55.637Z,1513185115.637 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:11:55.637Z,1513185115.637 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:12:12.845Z,1513185132.845 [CommandLine](IMPORTANT): got command get platform_battery_charge
2017-12-13T17:12:12.846Z,1513185132.846 [CommandLine](IMPORTANT): platform_battery_charge 399.947014 Ah
2017-12-13T17:12:37.236Z,1513185157.236 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:12:37.236Z,1513185157.236 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:12:37.236Z,1513185157.236 [BPC1](ERROR): Communications Fault
2017-12-13T17:12:37.258Z,1513185157.258 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:12:38.466Z,1513185158.466 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:12:38.466Z,1513185158.466 [BPC1] No Fault, FailCount= 1
2017-12-13T17:13:36.519Z,1513185216.519 [NAL9602](INFO): SBD MO Status=2, MOMSN=3635, MT Status=2, MTMSN=0
2017-12-13T17:13:36.519Z,1513185216.519 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:13:38.541Z,1513185218.541 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:13:38.541Z,1513185218.541 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:13:38.541Z,1513185218.541 [BPC1](ERROR): Communications Fault
2017-12-13T17:13:38.591Z,1513185218.591 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:13:39.783Z,1513185219.783 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:13:39.783Z,1513185219.783 [BPC1] No Fault, FailCount= 1
2017-12-13T17:14:32.019Z,1513185272.019 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:14:32.019Z,1513185272.019 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:14:32.019Z,1513185272.019 [BPC1](ERROR): Communications Fault
2017-12-13T17:14:32.039Z,1513185272.039 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:14:33.247Z,1513185273.247 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:14:33.248Z,1513185273.248 [BPC1] No Fault, FailCount= 1
2017-12-13T17:14:54.098Z,1513185294.098 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:14:54.098Z,1513185294.098 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:14:54.098Z,1513185294.098 [BPC1](ERROR): Communications Fault
2017-12-13T17:14:54.179Z,1513185294.179 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:14:55.360Z,1513185295.360 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:14:55.360Z,1513185295.360 [BPC1] No Fault, FailCount= 2
2017-12-13T17:14:55.768Z,1513185295.768 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:14:55.768Z,1513185295.768 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2017-12-13T17:14:55.785Z,1513185295.785 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T17:14:55.822Z,1513185295.822 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T17:14:56.136Z,1513185296.136 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T17:14:56.137Z,1513185296.137 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2017-12-13T17:14:56.516Z,1513185296.516 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T17:14:56.521Z,1513185296.521 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:14:56.521Z,1513185296.521 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:15:35.294Z,1513185335.294 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:15:35.294Z,1513185335.294 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:15:35.294Z,1513185335.294 [BPC1](ERROR): Communications Fault
2017-12-13T17:15:35.360Z,1513185335.360 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:15:36.547Z,1513185336.547 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:15:36.547Z,1513185336.547 [BPC1] No Fault, FailCount= 1
2017-12-13T17:15:40.123Z,1513185340.123 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-12-13T17:10:39.9Z
2017-12-13T17:15:40.124Z,1513185340.124 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T17:15:40.124Z,1513185340.124 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T17:15:43.929Z,1513185343.929 [DataOverHttps](INFO): Sending 46 bytes from file Logs/20171213T164731/Courier0012.lzma
2017-12-13T17:15:44.703Z,1513185344.703 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164731/Courier0012.lzma.bak
2017-12-13T17:15:44.704Z,1513185344.704 [DataOverHttps](INFO): SBD MOMSN=5435071
2017-12-13T17:15:55.210Z,1513185355.210 [DataOverHttps](INFO): Sending 310 bytes from file Logs/20171213T164731/Express0013.lzma
2017-12-13T17:15:55.951Z,1513185355.951 [DataOverHttps](INFO): Moved sent file to Logs/20171213T164731/Express0013.lzma.bak
2017-12-13T17:15:55.951Z,1513185355.951 [DataOverHttps](INFO): SBD MOMSN=5435073
2017-12-13T17:15:56.593Z,1513185356.593 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T17:15:56.593Z,1513185356.593 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T17:15:56.593Z,1513185356.593 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T17:16:07.674Z,1513185367.674 [PNI_TCM](ERROR): CRC does not match. Expected:0x46411 got:0x45339
2017-12-13T17:16:12.874Z,1513185372.874 [PNI_TCM](ERROR): CRC does not match. Expected:0x51316 got:0x6465
2017-12-13T17:16:14.078Z,1513185374.078 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2017-12-13T17:16:14.478Z,1513185374.478 [PNI_TCM](ERROR): CRC does not match. Expected:0x34860 got:0x2790
2017-12-13T17:16:39.646Z,1513185399.646 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-12-13T17:16:54.868Z,1513185414.868 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:16:54.868Z,1513185414.868 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:16:54.868Z,1513185414.868 [BPC1](ERROR): Communications Fault
2017-12-13T17:16:54.905Z,1513185414.905 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:16:56.095Z,1513185416.095 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:16:56.095Z,1513185416.095 [BPC1] No Fault, FailCount= 1
2017-12-13T17:17:10.258Z,1513185430.258 [NAL9602](INFO): Powering down
2017-12-13T17:17:46.329Z,1513185466.329 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T17:17:56.701Z,1513185476.701 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:17:56.701Z,1513185476.701 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2017-12-13T17:17:56.702Z,1513185476.702 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T17:17:56.714Z,1513185476.714 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T17:17:57.120Z,1513185477.120 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T17:17:57.120Z,1513185477.120 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2017-12-13T17:17:57.526Z,1513185477.526 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T17:17:57.526Z,1513185477.526 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:17:57.526Z,1513185477.526 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:19:03.489Z,1513185543.489 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:19:03.489Z,1513185543.489 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:19:03.489Z,1513185543.489 [BPC1](ERROR): Communications Fault
2017-12-13T17:19:03.518Z,1513185543.518 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:19:04.712Z,1513185544.712 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:19:04.712Z,1513185544.712 [BPC1] No Fault, FailCount= 1
2017-12-13T17:19:37.475Z,1513185577.475 [CommandLine](IMPORTANT): got command configSet list
2017-12-13T17:19:37.476Z,1513185577.476 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): CBIT.gf24Offset=107 microampere;

2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): CBIT.gfScanTimeout=1 hour;

2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): Normal all CTD_Seabird.sea_water_salinity;

2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): Normal all CTD_Seabird.sea_water_temperature;

2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): Normal linearApproximation Rowe_600LCM.height_above_sea_floor 5.000000 meter;

2017-12-13T17:19:37.477Z,1513185577.477 [CommandLine](IMPORTANT): Normal all mass_concentration_of_chlorophyll_in_sea_water;

2017-12-13T17:19:37.478Z,1513185577.478 [CommandLine](IMPORTANT): Normal all sea_water_temperature;

2017-12-13T17:19:37.478Z,1513185577.478 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2017-12-13T17:19:37.478Z,1513185577.478 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=470 milliliter;

2017-12-13T17:19:37.478Z,1513185577.478 [CommandLine](IMPORTANT): VerticalControl.massDefault=-6 millimeter;

2017-12-13T17:19:44.294Z,1513185584.294 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:19:44.294Z,1513185584.294 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:19:44.294Z,1513185584.294 [BPC1](ERROR): Communications Fault
2017-12-13T17:19:44.352Z,1513185584.352 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:19:45.529Z,1513185585.529 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:19:45.529Z,1513185585.529 [BPC1] No Fault, FailCount= 1
2017-12-13T17:20:14.776Z,1513185614.776 [CommandLine](IMPORTANT): got command configSet CBIT.vehicleOpen 0.000000 bool persist
2017-12-13T17:20:21.777Z,1513185621.777 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:20:21.777Z,1513185621.777 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:20:21.777Z,1513185621.777 [BPC1](ERROR): Communications Fault
2017-12-13T17:20:21.846Z,1513185621.846 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:20:23.023Z,1513185623.023 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:20:23.024Z,1513185623.024 [BPC1] No Fault, FailCount= 1
2017-12-13T17:20:56.986Z,1513185656.986 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T17:20:56.987Z,1513185656.987 [Default:CheckIn:C.Wait] Stopped
2017-12-13T17:20:56.987Z,1513185656.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:20:56.987Z,1513185656.987 [Default:CheckIn:D] Running Loop=1
2017-12-13T17:20:57.387Z,1513185657.387 [Default:CheckIn:D] Stopped
2017-12-13T17:20:57.387Z,1513185657.387 [Default:CheckIn:E] Running Loop=1
2017-12-13T17:20:57.812Z,1513185657.812 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:20:57.812Z,1513185657.812 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10
2017-12-13T17:20:57.812Z,1513185657.812 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T17:20:57.820Z,1513185657.820 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.125376 min 
2017-12-13T17:20:57.820Z,1513185657.820 [Default:CheckIn:E] Stopped
2017-12-13T17:20:57.820Z,1513185657.820 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T17:20:57.820Z,1513185657.820 [Default:CheckIn] Stopped
2017-12-13T17:20:57.825Z,1513185657.825 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T17:20:57.825Z,1513185657.825 [Default:CheckIn](INFO): Running loop #4
2017-12-13T17:20:57.825Z,1513185657.825 [Default:CheckIn] Running Loop=4
2017-12-13T17:20:57.825Z,1513185657.825 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T17:20:57.825Z,1513185657.825 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T17:20:57.843Z,1513185657.843 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T17:20:58.205Z,1513185658.205 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T17:20:58.205Z,1513185658.205 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10
2017-12-13T17:21:01.529Z,1513185661.529 [NAL9602](INFO): Powering up
2017-12-13T17:21:01.611Z,1513185661.611 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T17:21:01.612Z,1513185661.612 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:21:01.612Z,1513185661.612 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:21:12.442Z,1513185672.442 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:21:44.438Z,1513185704.438 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2017-12-13T17:21:44.521Z,1513185704.521 [NAL9602](FAULT): received: 
+CSQ:0

OK35, 2, 0, 0, 0

OK
2017-12-13T17:21:44.521Z,1513185704.521 [NAL9602] Data Fault, FailCount= 2
2017-12-13T17:21:44.521Z,1513185704.521 [NAL9602](ERROR): Data Fault
2017-12-13T17:21:44.586Z,1513185704.586 [CBIT](ERROR): Data Fault in component: NAL9602
2017-12-13T17:21:44.850Z,1513185704.850 [NAL9602](INFO): Powering down
2017-12-13T17:21:45.709Z,1513185705.709 [CBIT](INFO): Clearing failed state for component NAL9602
2017-12-13T17:21:45.710Z,1513185705.710 [NAL9602] No Fault, FailCount= 2
2017-12-13T17:21:54.079Z,1513185714.079 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:21:54.079Z,1513185714.079 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:21:54.079Z,1513185714.079 [BPC1](ERROR): Communications Fault
2017-12-13T17:21:54.100Z,1513185714.100 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:21:55.307Z,1513185715.307 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:21:55.307Z,1513185715.307 [BPC1] No Fault, FailCount= 1
2017-12-13T17:22:14.966Z,1513185734.966 [NAL9602](INFO): Powering up NAL9602
2017-12-13T17:22:26.205Z,1513185746.205 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:22:26.960Z,1513185746.960 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-12-13T17:22:26.960Z,1513185746.960 [NAL9602] Data Fault, FailCount= 3
2017-12-13T17:22:26.960Z,1513185746.960 [NAL9602](ERROR): Data Fault
2017-12-13T17:22:27.033Z,1513185747.033 [CBIT](ERROR): Data Fault in component: NAL9602
2017-12-13T17:22:27.366Z,1513185747.366 [NAL9602](INFO): Powering down
2017-12-13T17:22:28.252Z,1513185748.252 [CBIT](INFO): Clearing failed state for component NAL9602
2017-12-13T17:22:28.257Z,1513185748.257 [NAL9602] No Fault, FailCount= 3
2017-12-13T17:22:57.386Z,1513185777.386 [NAL9602](INFO): Powering up NAL9602
2017-12-13T17:23:02.734Z,1513185782.734 [DataOverHttps](IMPORTANT): SBD MTMSN=20171213T172258
2017-12-13T17:23:06.877Z,1513185786.877 [DataOverHttps](INFO): Received command:restart app
2017-12-13T17:23:06.913Z,1513185786.913 [CommandLine](IMPORTANT): got command restart application
2017-12-13T17:23:07.917Z,1513185787.917 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2017-12-13T17:23:07.930Z,1513185787.930 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:07.931Z,1513185787.931 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:07.953Z,1513185787.953 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-12-13T17:23:07.953Z,1513185787.953 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:07.953Z,1513185787.953 [CommandLine](INFO): Join timeout helper Thread ID is 1000
2017-12-13T17:23:07.954Z,1513185787.954 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-12-13T17:23:07.954Z,1513185787.954 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:07.954Z,1513185787.954 [NavChartDb](INFO): Join timeout helper Thread ID is 1001
2017-12-13T17:23:08.153Z,1513185788.153 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:08.153Z,1513185788.153 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.169Z,1513185788.169 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-12-13T17:23:08.169Z,1513185788.169 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.169Z,1513185788.169 [Radio_Surface](INFO): Join timeout helper Thread ID is 1002
2017-12-13T17:23:08.417Z,1513185788.417 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:08.417Z,1513185788.417 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.437Z,1513185788.437 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2017-12-13T17:23:08.437Z,1513185788.437 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.437Z,1513185788.437 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1003
2017-12-13T17:23:08.467Z,1513185788.467 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:23:08.501Z,1513185788.501 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:08.501Z,1513185788.501 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.505Z,1513185788.505 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-12-13T17:23:08.505Z,1513185788.505 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.505Z,1513185788.505 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1004
2017-12-13T17:23:08.521Z,1513185788.521 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:08.521Z,1513185788.521 [WetLabsBB2FL](INFO): Powering down
2017-12-13T17:23:08.522Z,1513185788.522 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.538Z,1513185788.538 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2017-12-13T17:23:08.538Z,1513185788.538 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.538Z,1513185788.538 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1005
2017-12-13T17:23:08.553Z,1513185788.553 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:08.681Z,1513185788.681 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-13T17:23:08.681Z,1513185788.681 [CTD_Seabird](INFO): Powering down
2017-12-13T17:23:08.682Z,1513185788.682 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.693Z,1513185788.693 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-12-13T17:23:08.694Z,1513185788.694 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:08.694Z,1513185788.694 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1007
2017-12-13T17:23:09.093Z,1513185789.093 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:09.093Z,1513185789.093 [CTD_NeilBrown](INFO): Powering down
2017-12-13T17:23:09.094Z,1513185789.094 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.102Z,1513185789.102 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-12-13T17:23:09.102Z,1513185789.102 [logger ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.102Z,1513185789.102 [logger](INFO): Join timeout helper Thread ID is 1008
2017-12-13T17:23:09.105Z,1513185789.105 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:09.105Z,1513185789.105 [logger ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.121Z,1513185789.121 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-12-13T17:23:09.122Z,1513185789.122 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.122Z,1513185789.122 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-12-13T17:23:09.122Z,1513185789.122 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.122Z,1513185789.122 [controlThread](INFO): Join timeout helper Thread ID is 1009
2017-12-13T17:23:09.249Z,1513185789.249 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T17:23:09.249Z,1513185789.249 [controlThread](DEBUG): Uninitializing ControlThread
2017-12-13T17:23:09.249Z,1513185789.249 [Aanderaa_O2](INFO): Powering down
2017-12-13T17:23:09.252Z,1513185789.252 [NAL9602](INFO): Powering down
2017-12-13T17:23:09.253Z,1513185789.253 [PNI_TCM](INFO): Powering down
2017-12-13T17:23:09.342Z,1513185789.342 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-12-13T17:23:09.343Z,1513185789.343 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-12-13T17:23:09.344Z,1513185789.344 [MissionManager](INFO): Uninitializing Mission Default
2017-12-13T17:23:09.344Z,1513185789.344 [Default] Stopped
2017-12-13T17:23:09.344Z,1513185789.344 [Default](DEBUG): Aggregate::uninitialize Default
2017-12-13T17:23:09.344Z,1513185789.344 [Default:B.GoToSurface] Stopped
2017-12-13T17:23:09.344Z,1513185789.344 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-13T17:23:09.344Z,1513185789.344 [Default:CheckIn] Stopped
2017-12-13T17:23:09.345Z,1513185789.345 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T17:23:09.345Z,1513185789.345 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T17:23:09.348Z,1513185789.348 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-12-13T17:23:09.348Z,1513185789.348 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-12-13T17:23:09.348Z,1513185789.348 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-12-13T17:23:09.349Z,1513185789.349 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-12-13T17:23:09.349Z,1513185789.349 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-12-13T17:23:09.349Z,1513185789.349 [BuoyancyServo](INFO): Powering down
2017-12-13T17:23:09.361Z,1513185789.361 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-12-13T17:23:09.361Z,1513185789.361 [ElevatorServo](INFO): Powering down
2017-12-13T17:23:09.362Z,1513185789.362 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-12-13T17:23:09.362Z,1513185789.362 [MassServo](INFO): Powering down
2017-12-13T17:23:09.363Z,1513185789.363 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-12-13T17:23:09.363Z,1513185789.363 [RudderServo](INFO): Powering down
2017-12-13T17:23:09.364Z,1513185789.364 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-12-13T17:23:09.364Z,1513185789.364 [ThrusterServo](INFO): Powering down
2017-12-13T17:23:09.365Z,1513185789.365 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-12-13T17:23:09.365Z,1513185789.365 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-12-13T17:23:09.366Z,1513185789.366 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-12-13T17:23:09.366Z,1513185789.366 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.430Z,1513185789.430 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.433Z,1513185789.433 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.436Z,1513185789.436 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.438Z,1513185789.438 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.499Z,1513185789.499 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.539Z,1513185789.539 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-13T17:23:09.606Z,1513185789.606 [logger ThreadHandler](INFO): Thread cancelled.