2016-08-26T23:33:28.402Z,1472254408.402 [Supervisor](DEBUG): Initializing supervisor. 2016-08-26T23:33:28.406Z,1472254408.406 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2016-08-26T23:33:28.407Z,1472254408.407 [SyncHandler](INFO): Protected caller Thread ID is 3233 2016-08-26T23:33:28.407Z,1472254408.407 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2016-08-26T23:33:28.409Z,1472254408.409 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2016-08-26T23:33:28.409Z,1472254408.409 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3234 2016-08-26T23:33:28.413Z,1472254408.413 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2016-08-26T23:33:28.424Z,1472254408.424 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2016-08-26T23:33:28.426Z,1472254408.426 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2016-08-26T23:33:28.426Z,1472254408.426 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3235 2016-08-26T23:33:28.427Z,1472254408.427 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2016-08-26T23:33:28.428Z,1472254408.428 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2016-08-26T23:33:28.428Z,1472254408.428 [logger ThreadHandler](INFO): Protected caller Thread ID is 3236 2016-08-26T23:33:28.430Z,1472254408.430 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2016-08-26T23:33:28.431Z,1472254408.431 [Supervisor](INFO): Looking for Config files in directory: Config/ 2016-08-26T23:33:28.433Z,1472254408.433 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2016-08-26T23:33:28.934Z,1472254408.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2016-08-26T23:33:28.935Z,1472254408.935 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2016-08-26T23:33:29.103Z,1472254409.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2016-08-26T23:33:29.104Z,1472254409.104 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2016-08-26T23:33:29.231Z,1472254409.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2016-08-26T23:33:29.231Z,1472254409.231 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2016-08-26T23:33:29.364Z,1472254409.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2016-08-26T23:33:29.365Z,1472254409.365 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2016-08-26T23:33:29.452Z,1472254409.452 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2016-08-26T23:33:29.652Z,1472254409.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2016-08-26T23:33:29.653Z,1472254409.653 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2016-08-26T23:33:29.741Z,1472254409.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2016-08-26T23:33:29.742Z,1472254409.742 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2016-08-26T23:33:30.014Z,1472254410.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2016-08-26T23:33:30.015Z,1472254410.015 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2016-08-26T23:33:30.361Z,1472254410.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2016-08-26T23:33:30.361Z,1472254410.361 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2016-08-26T23:33:30.657Z,1472254410.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2016-08-26T23:33:30.657Z,1472254410.657 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2016-08-26T23:33:31.153Z,1472254411.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2016-08-26T23:33:31.154Z,1472254411.154 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2016-08-26T23:33:31.354Z,1472254411.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2016-08-26T23:33:31.355Z,1472254411.355 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2016-08-26T23:33:31.460Z,1472254411.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2016-08-26T23:33:31.460Z,1472254411.460 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2016-08-26T23:33:31.908Z,1472254411.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2016-08-26T23:33:31.909Z,1472254411.909 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2016-08-26T23:33:32.021Z,1472254412.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2016-08-26T23:33:32.023Z,1472254412.023 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2016-08-26T23:33:32.025Z,1472254412.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2016-08-26T23:33:32.271Z,1472254412.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2016-08-26T23:33:32.272Z,1472254412.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2016-08-26T23:33:32.388Z,1472254412.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2016-08-26T23:33:32.488Z,1472254412.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2016-08-26T23:33:32.586Z,1472254412.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2016-08-26T23:33:32.678Z,1472254412.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2016-08-26T23:33:32.822Z,1472254412.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2016-08-26T23:33:32.969Z,1472254412.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2016-08-26T23:33:33.074Z,1472254413.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2016-08-26T23:33:33.159Z,1472254413.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2016-08-26T23:33:33.250Z,1472254413.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2016-08-26T23:33:33.427Z,1472254413.427 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2016-08-26T23:33:33.427Z,1472254413.427 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2016-08-26T23:33:33.433Z,1472254413.433 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2016-08-26T23:33:33.589Z,1472254413.589 [SBIT](DEBUG): Construct Startup Built In Test. 2016-08-26T23:33:33.622Z,1472254413.622 [SBIT] Loaded 2016-08-26T23:33:33.622Z,1472254413.622 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2016-08-26T23:33:33.623Z,1472254413.623 [IBIT](DEBUG): Construct Initiated Built In Test. 2016-08-26T23:33:33.653Z,1472254413.653 [IBIT] Loaded 2016-08-26T23:33:33.653Z,1472254413.653 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2016-08-26T23:33:33.655Z,1472254413.655 [CBIT](DEBUG): Construct Continuous Built In Test. 2016-08-26T23:33:33.803Z,1472254413.803 [CBIT] Loaded 2016-08-26T23:33:33.803Z,1472254413.803 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2016-08-26T23:33:33.803Z,1472254413.803 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2016-08-26T23:33:33.804Z,1472254413.804 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2016-08-26T23:33:33.875Z,1472254413.875 [VerticalControl](DEBUG): Construct VerticalControl. 2016-08-26T23:33:33.980Z,1472254413.980 [VerticalControl] Loaded 2016-08-26T23:33:33.980Z,1472254413.980 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2016-08-26T23:33:33.981Z,1472254413.981 [HorizontalControl](DEBUG): Construct HorizontalControl. 2016-08-26T23:33:34.047Z,1472254414.047 [HorizontalControl] Loaded 2016-08-26T23:33:34.047Z,1472254414.047 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2016-08-26T23:33:34.048Z,1472254414.048 [SpeedControl](DEBUG): Construct SpeedControl. 2016-08-26T23:33:34.054Z,1472254414.054 [SpeedControl] Loaded 2016-08-26T23:33:34.054Z,1472254414.054 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2016-08-26T23:33:34.055Z,1472254414.055 [LoopControl](DEBUG): Construct LoopControl. 2016-08-26T23:33:34.056Z,1472254414.056 [LoopControl] Loaded 2016-08-26T23:33:34.056Z,1472254414.056 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2016-08-26T23:33:34.056Z,1472254414.056 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2016-08-26T23:33:34.057Z,1472254414.057 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2016-08-26T23:33:34.107Z,1472254414.107 [DepthRateCalculator] Loaded 2016-08-26T23:33:34.109Z,1472254414.109 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2016-08-26T23:33:34.122Z,1472254414.122 [PitchRateCalculator] Loaded 2016-08-26T23:33:34.122Z,1472254414.122 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2016-08-26T23:33:34.138Z,1472254414.138 [SpeedCalculator] Loaded 2016-08-26T23:33:34.138Z,1472254414.138 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2016-08-26T23:33:34.155Z,1472254414.155 [TempGradientCalculator] Loaded 2016-08-26T23:33:34.156Z,1472254414.156 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2016-08-26T23:33:34.172Z,1472254414.172 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2016-08-26T23:33:34.173Z,1472254414.173 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2016-08-26T23:33:34.178Z,1472254414.178 [YawRateCalculator] Loaded 2016-08-26T23:33:34.179Z,1472254414.179 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2016-08-26T23:33:34.179Z,1472254414.179 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2016-08-26T23:33:34.180Z,1472254414.180 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2016-08-26T23:33:34.298Z,1472254414.298 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2016-08-26T23:33:34.298Z,1472254414.298 [StratificationFrontDetector](DEBUG): (re)initializing 2016-08-26T23:33:34.299Z,1472254414.299 [StratificationFrontDetector] Loaded 2016-08-26T23:33:34.299Z,1472254414.299 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2016-08-26T23:33:34.299Z,1472254414.299 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2016-08-26T23:33:34.300Z,1472254414.300 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2016-08-26T23:33:34.584Z,1472254414.584 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2016-08-26T23:33:34.585Z,1472254414.585 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2016-08-26T23:33:34.708Z,1472254414.708 [DeadReckonUsingMultipleVelocitySources] Loaded 2016-08-26T23:33:34.708Z,1472254414.708 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2016-08-26T23:33:34.767Z,1472254414.767 [DeadReckonUsingSpeedCalculator] Loaded 2016-08-26T23:33:34.767Z,1472254414.767 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2016-08-26T23:33:34.785Z,1472254414.785 [NavChart] Loaded 2016-08-26T23:33:34.786Z,1472254414.786 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2016-08-26T23:33:34.790Z,1472254414.790 [UniversalFixResidualReporter] Loaded 2016-08-26T23:33:34.790Z,1472254414.790 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2016-08-26T23:33:34.791Z,1472254414.791 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2016-08-26T23:33:34.791Z,1472254414.791 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2016-08-26T23:33:34.800Z,1472254414.800 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2016-08-26T23:33:34.801Z,1472254414.801 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2016-08-26T23:33:35.031Z,1472254415.031 [Aanderaa_O2] Loaded 2016-08-26T23:33:35.031Z,1472254415.031 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2016-08-26T23:33:35.112Z,1472254415.112 [CTD_NeilBrown] Loaded 2016-08-26T23:33:35.112Z,1472254415.112 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2016-08-26T23:33:35.113Z,1472254415.113 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4076B4E0 2016-08-26T23:33:35.114Z,1472254415.114 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3315 2016-08-26T23:33:35.131Z,1472254415.131 [ESPComponent] Loaded 2016-08-26T23:33:35.132Z,1472254415.132 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2016-08-26T23:33:35.147Z,1472254415.147 [PAR_Licor] Loaded 2016-08-26T23:33:35.147Z,1472254415.147 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2016-08-26T23:33:35.201Z,1472254415.201 [WetLabsBB2FL] Loaded 2016-08-26T23:33:35.201Z,1472254415.201 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2016-08-26T23:33:35.202Z,1472254415.202 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4079B4E0 2016-08-26T23:33:35.202Z,1472254415.202 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3316 2016-08-26T23:33:35.203Z,1472254415.203 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2016-08-26T23:33:35.204Z,1472254415.204 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2016-08-26T23:33:35.590Z,1472254415.590 [AcousticModem_Benthos_ATM900] Loaded 2016-08-26T23:33:35.591Z,1472254415.591 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2016-08-26T23:33:35.674Z,1472254415.674 [DataOverHttps] Loaded 2016-08-26T23:33:35.675Z,1472254415.675 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2016-08-26T23:33:35.690Z,1472254415.690 [Depth_Keller] Loaded 2016-08-26T23:33:35.690Z,1472254415.690 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2016-08-26T23:33:35.695Z,1472254415.695 [DropWeight] Loaded 2016-08-26T23:33:35.696Z,1472254415.696 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2016-08-26T23:33:35.796Z,1472254415.796 [NAL9602] Loaded 2016-08-26T23:33:35.796Z,1472254415.796 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2016-08-26T23:33:35.840Z,1472254415.840 [Onboard] Loaded 2016-08-26T23:33:35.840Z,1472254415.840 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2016-08-26T23:33:35.848Z,1472254415.848 [Radio_Surface] Loaded 2016-08-26T23:33:35.848Z,1472254415.848 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2016-08-26T23:33:35.851Z,1472254415.851 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E74E0 2016-08-26T23:33:35.851Z,1472254415.851 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3317 2016-08-26T23:33:35.902Z,1472254415.902 [PNI_TCM] Loaded 2016-08-26T23:33:35.902Z,1472254415.902 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2016-08-26T23:33:36.483Z,1472254416.483 [Rowe_600LCM] Loaded 2016-08-26T23:33:36.484Z,1472254416.484 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2016-08-26T23:33:36.485Z,1472254416.485 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0 2016-08-26T23:33:36.485Z,1472254416.485 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 3318 2016-08-26T23:33:38.441Z,1472254418.441 [BPC1] Loaded 2016-08-26T23:33:38.441Z,1472254418.441 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2016-08-26T23:33:38.442Z,1472254418.442 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2016-08-26T23:33:38.442Z,1472254418.442 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2016-08-26T23:33:38.574Z,1472254418.574 [BuoyancyServo] Loaded 2016-08-26T23:33:38.574Z,1472254418.574 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2016-08-26T23:33:38.587Z,1472254418.587 [ElevatorServo] Loaded 2016-08-26T23:33:38.587Z,1472254418.587 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2016-08-26T23:33:38.599Z,1472254418.599 [MassServo] Loaded 2016-08-26T23:33:38.600Z,1472254418.600 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2016-08-26T23:33:38.612Z,1472254418.612 [RudderServo] Loaded 2016-08-26T23:33:38.612Z,1472254418.612 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2016-08-26T23:33:38.624Z,1472254418.624 [ThrusterServo] Loaded 2016-08-26T23:33:38.625Z,1472254418.625 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2016-08-26T23:33:38.625Z,1472254418.625 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2016-08-26T23:33:38.626Z,1472254418.626 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2016-08-26T23:33:38.764Z,1472254418.764 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2016-08-26T23:33:38.765Z,1472254418.765 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2016-08-26T23:33:38.808Z,1472254418.808 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2016-08-26T23:33:38.812Z,1472254418.812 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2016-08-26T23:33:38.813Z,1472254418.813 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2016-08-26T23:33:38.820Z,1472254418.820 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2016-08-26T23:33:38.822Z,1472254418.822 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0 2016-08-26T23:33:38.823Z,1472254418.823 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3319 2016-08-26T23:33:38.827Z,1472254418.827 [Supervisor](INFO): Main Thread ID is 1982 2016-08-26T23:33:38.828Z,1472254418.828 [Supervisor](DEBUG): Running supervisor. 2016-08-26T23:33:38.828Z,1472254418.828 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3320 2016-08-26T23:33:38.833Z,1472254418.833 [controlThread ThreadHandler](INFO): Handler Thread ID is 3321 2016-08-26T23:33:38.833Z,1472254418.833 [controlThread](DEBUG): Initializing ControlThread 2016-08-26T23:33:38.834Z,1472254418.834 [SBIT](INFO): Initialize SBIT Component. 2016-08-26T23:33:38.834Z,1472254418.834 [SBIT](IMPORTANT): git: 2016-07-30 2016-08-26T23:33:38.835Z,1472254418.835 [SBIT](INFO): git hash: 923446e9f5739ffa552978ad226432fb4f934ffd 2016-08-26T23:33:38.835Z,1472254418.835 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2016-08-26T23:33:38.835Z,1472254418.835 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2016-08-26T23:33:38.836Z,1472254418.836 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2016-08-26T23:33:38.837Z,1472254418.837 [IBIT](INFO): Initialize IBIT Component. 2016-08-26T23:33:38.837Z,1472254418.837 [CBIT](DEBUG): Initialize CBIT Component. 2016-08-26T23:33:38.838Z,1472254418.838 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2016-08-26T23:33:38.839Z,1472254418.839 [logger ThreadHandler](INFO): Handler Thread ID is 3322 2016-08-26T23:33:38.857Z,1472254418.857 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3323 2016-08-26T23:33:38.858Z,1472254418.858 [CTD_NeilBrown](INFO): Powering down 2016-08-26T23:33:38.862Z,1472254418.862 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2016-08-26T23:33:38.863Z,1472254418.863 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2016-08-26T23:33:38.864Z,1472254418.864 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2016-08-26T23:33:38.869Z,1472254418.869 [LoopControl](DEBUG): Initialize LoopControlComponent. 2016-08-26T23:33:38.869Z,1472254418.869 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2016-08-26T23:33:38.869Z,1472254418.869 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2016-08-26T23:33:38.870Z,1472254418.870 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2016-08-26T23:33:38.870Z,1472254418.870 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2016-08-26T23:33:38.871Z,1472254418.871 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2016-08-26T23:33:38.872Z,1472254418.872 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2016-08-26T23:33:38.872Z,1472254418.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-26T23:33:38.873Z,1472254418.873 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-26T23:33:38.873Z,1472254418.873 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-26T23:33:38.874Z,1472254418.874 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-26T23:33:38.874Z,1472254418.874 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-26T23:33:38.875Z,1472254418.875 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-26T23:33:38.875Z,1472254418.875 [NavChart](DEBUG): Initialize NavChart Navigation. 2016-08-26T23:33:38.875Z,1472254418.875 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2016-08-26T23:33:38.882Z,1472254418.882 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2016-08-26T23:33:38.882Z,1472254418.882 [DropWeight] Hardware Fault, FailCount= 1 2016-08-26T23:33:38.882Z,1472254418.882 [DropWeight](ERROR): Hardware Fault 2016-08-26T23:33:38.885Z,1472254418.885 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2016-08-26T23:33:38.899Z,1472254418.899 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3324 2016-08-26T23:33:38.910Z,1472254418.910 [WetLabsBB2FL](INFO): Powering down 2016-08-26T23:33:38.942Z,1472254418.942 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2016-08-26T23:33:38.945Z,1472254418.945 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3325 2016-08-26T23:33:38.961Z,1472254418.961 [Radio_Surface](INFO): Powering up 2016-08-26T23:33:38.977Z,1472254418.977 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 3326 2016-08-26T23:33:38.978Z,1472254418.978 [Rowe_600LCM](INFO): Initializing 2016-08-26T23:33:38.979Z,1472254418.979 [Rowe_600LCM](INFO): Checking LCM 2016-08-26T23:33:38.979Z,1472254418.979 [Rowe_600LCM](INFO): LCM OK 2016-08-26T23:33:38.979Z,1472254418.979 [Rowe_600LCM](INFO): Powering up 2016-08-26T23:33:38.980Z,1472254418.980 [MissionManager](DEBUG): 2016-08-26T23:33:38.980Z,1472254418.980 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2016-08-26T23:33:38.993Z,1472254418.993 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3327 2016-08-26T23:33:39.000Z,1472254419.000 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2016-08-26T23:33:39.000Z,1472254419.000 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2016-08-26T23:33:39.001Z,1472254419.001 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2016-08-26T23:33:39.001Z,1472254419.001 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2016-08-26T23:33:39.001Z,1472254419.001 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2016-08-26T23:33:39.001Z,1472254419.001 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2016-08-26T23:33:39.001Z,1472254419.001 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2016-08-26T23:33:39.002Z,1472254419.002 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2016-08-26T23:33:39.002Z,1472254419.002 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2016-08-26T23:33:39.002Z,1472254419.002 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2016-08-26T23:33:39.002Z,1472254419.002 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2016-08-26T23:33:39.002Z,1472254419.002 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2016-08-26T23:33:39.003Z,1472254419.003 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2016-08-26T23:33:39.003Z,1472254419.003 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2016-08-26T23:33:39.003Z,1472254419.003 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2016-08-26T23:33:39.003Z,1472254419.003 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2016-08-26T23:33:39.077Z,1472254419.077 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2016-08-26T23:33:39.078Z,1472254419.078 [Default:A.Wait](DEBUG): Construct Wait. 2016-08-26T23:33:39.080Z,1472254419.080 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2016-08-26T23:33:39.131Z,1472254419.131 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2016-08-26T23:33:39.133Z,1472254419.133 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2016-08-26T23:33:39.155Z,1472254419.155 [Default:E.Execute](DEBUG): Construct Execute. 2016-08-26T23:33:39.159Z,1472254419.159 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2016-08-26T23:33:39.165Z,1472254419.165 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2016-08-26T23:33:39.256Z,1472254419.256 [AcousticModem_Benthos_ATM900](INFO): Powering up 2016-08-26T23:33:39.256Z,1472254419.256 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2016-08-26T23:33:39.735Z,1472254419.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:39.758Z,1472254419.758 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:39.895Z,1472254419.895 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2016-08-26T23:33:39.902Z,1472254419.902 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2016-08-26T23:33:39.909Z,1472254419.909 [ElevatorServo](DEBUG): Initializing EZServoServo. 2016-08-26T23:33:39.914Z,1472254419.914 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2016-08-26T23:33:39.937Z,1472254419.937 [MassServo](DEBUG): Initializing EZServoServo. 2016-08-26T23:33:39.943Z,1472254419.943 [MassServo](DEBUG): Initializing MassServo. 2016-08-26T23:33:39.964Z,1472254419.964 [RudderServo](DEBUG): Initializing EZServoServo. 2016-08-26T23:33:39.971Z,1472254419.971 [RudderServo](DEBUG): Initializing RudderServo. 2016-08-26T23:33:39.976Z,1472254419.976 [ThrusterServo](DEBUG): Initializing EZServoServo. 2016-08-26T23:33:39.995Z,1472254419.995 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2016-08-26T23:33:40.043Z,1472254420.043 [CommandLine](FAULT): Scheduling is paused 2016-08-26T23:33:40.046Z,1472254420.046 [CBIT](ERROR): Hardware Fault in component: DropWeight 2016-08-26T23:33:40.046Z,1472254420.046 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2016-08-26T23:33:40.244Z,1472254420.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:40.245Z,1472254420.245 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:40.557Z,1472254420.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:40.558Z,1472254420.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:40.961Z,1472254420.961 [NAL9602](INFO): Powering up NAL9602 2016-08-26T23:33:41.184Z,1472254421.184 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:41.185Z,1472254421.185 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:41.413Z,1472254421.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:41.414Z,1472254421.414 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:41.782Z,1472254421.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:41.783Z,1472254421.783 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:42.376Z,1472254422.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:42.377Z,1472254422.377 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:42.679Z,1472254422.679 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:42.683Z,1472254422.683 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2016-08-26T23:33:43.460Z,1472254423.460 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2016-08-26T23:33:43.611Z,1472254423.611 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyA1 -b 9600 >& /dev/null & 2016-08-26T23:33:44.573Z,1472254424.573 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2016-08-26T23:33:44.575Z,1472254424.575 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2016-08-26T23:33:46.446Z,1472254426.446 [AcousticModem_Benthos_ATM900](DEBUG): 2016-08-26T23:33:48.061Z,1472254428.061 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2016-08-26T23:33:48.061Z,1472254428.061 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2016-08-26T23:33:48.062Z,1472254428.062 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2016-08-26T23:33:48.445Z,1472254428.445 [AcousticModem_Benthos_ATM900](DEBUG): Aug 26 2016 23:24:20 2016-08-26T23:33:49.240Z,1472254429.240 [Aanderaa_O2](FAULT): Timed out starting 2016-08-26T23:33:49.240Z,1472254429.240 [Aanderaa_O2] Communications Fault, FailCount= 1 2016-08-26T23:33:49.241Z,1472254429.241 [Aanderaa_O2](ERROR): Communications Fault 2016-08-26T23:33:49.246Z,1472254429.246 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2016-08-26T23:33:49.373Z,1472254429.373 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2016-08-26T23:33:49.642Z,1472254429.642 [Aanderaa_O2](INFO): Powering down 2016-08-26T23:33:49.688Z,1472254429.688 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2016-08-26T23:33:49.698Z,1472254429.698 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2016-08-26T23:33:49.698Z,1472254429.698 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2016-08-26T23:33:50.048Z,1472254430.048 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2016-08-26T23:33:50.448Z,1472254430.448 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2016-08-26T23:33:50.449Z,1472254430.449 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2016-08-26T23:33:50.624Z,1472254430.624 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2016-08-26T23:33:50.624Z,1472254430.624 [Aanderaa_O2] No Fault, FailCount= 1 2016-08-26T23:33:50.850Z,1472254430.850 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2016-08-26T23:33:52.051Z,1472254432.051 [NAL9602](INFO): NAL9602 initialized 2016-08-26T23:34:00.852Z,1472254440.852 [Aanderaa_O2](FAULT): Timed out starting 2016-08-26T23:34:00.852Z,1472254440.852 [Aanderaa_O2] Communications Fault, FailCount= 2 2016-08-26T23:34:00.852Z,1472254440.852 [Aanderaa_O2](ERROR): Communications Fault 2016-08-26T23:34:01.061Z,1472254441.061 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2016-08-26T23:34:01.268Z,1472254441.268 [Aanderaa_O2](INFO): Powering down 2016-08-26T23:34:01.875Z,1472254441.875 [CommandLine](IMPORTANT): got command show best platform_pitch_angle 2016-08-26T23:34:01.879Z,1472254441.879 [CommandLine](IMPORTANT): platform_pitch_angle best is PNI_TCM.platform_pitch_angle 2016-08-26T23:34:02.259Z,1472254442.259 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2016-08-26T23:34:02.259Z,1472254442.259 [Aanderaa_O2] No Fault, FailCount= 2 2016-08-26T23:34:12.486Z,1472254452.486 [Aanderaa_O2](FAULT): Timed out starting 2016-08-26T23:34:12.486Z,1472254452.486 [Aanderaa_O2] Communications Fault, FailCount= 3 2016-08-26T23:34:12.486Z,1472254452.486 [Aanderaa_O2](ERROR): Communications Fault 2016-08-26T23:34:12.615Z,1472254452.615 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2016-08-26T23:34:12.616Z,1472254452.616 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2016-08-26T23:34:12.887Z,1472254452.887 [Aanderaa_O2](INFO): Powering down 2016-08-26T23:34:15.549Z,1472254455.549 [CommandLine](IMPORTANT): got command show best platform_pitch_angle 2016-08-26T23:34:15.550Z,1472254455.550 [CommandLine](IMPORTANT): platform_pitch_angle best is PNI_TCM.platform_pitch_angle 2016-08-26T23:34:22.554Z,1472254462.554 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree 2016-08-26T23:34:22.555Z,1472254462.555 [CommandLine](IMPORTANT): platform_pitch_angle 12.118251 arcdeg 2016-08-26T23:34:23.752Z,1472254463.752 [BPC1](ERROR): Failed to receive battery data 2016-08-26T23:34:23.752Z,1472254463.752 [BPC1] Communications Fault, FailCount= 1 2016-08-26T23:34:23.752Z,1472254463.752 [BPC1](ERROR): Communications Fault 2016-08-26T23:34:23.821Z,1472254463.821 [CBIT](ERROR): Communications Fault in component: BPC1 2016-08-26T23:34:25.029Z,1472254465.029 [CBIT](INFO): Clearing failed state for component BPC1 2016-08-26T23:34:25.029Z,1472254465.029 [BPC1] No Fault, FailCount= 1 2016-08-26T23:34:37.529Z,1472254477.529 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree 2016-08-26T23:34:37.530Z,1472254477.530 [CommandLine](IMPORTANT): platform_pitch_angle -5.714505 arcdeg 2016-08-26T23:34:42.300Z,1472254482.300 [SBIT](IMPORTANT): Beginning Startup BIT 2016-08-26T23:34:42.317Z,1472254482.317 [CBIT](IMPORTANT): Beginning GF scan 2016-08-26T23:34:43.342Z,1472254483.342 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree 2016-08-26T23:34:43.343Z,1472254483.343 [CommandLine](IMPORTANT): platform_pitch_angle -4.145722 arcdeg 2016-08-26T23:34:49.130Z,1472254489.130 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree 2016-08-26T23:34:49.131Z,1472254489.131 [CommandLine](IMPORTANT): platform_pitch_angle -26.199907 arcdeg 2016-08-26T23:34:53.643Z,1472254493.643 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree 2016-08-26T23:34:53.644Z,1472254493.644 [CommandLine](IMPORTANT): platform_pitch_angle 14.652535 arcdeg 2016-08-26T23:34:57.712Z,1472254497.712 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree 2016-08-26T23:34:57.713Z,1472254497.713 [CommandLine](IMPORTANT): platform_pitch_angle 40.668469 arcdeg 2016-08-26T23:35:08.444Z,1472254508.444 [CBIT](IMPORTANT): No ground fault detected 2016-08-26T23:35:12.359Z,1472254512.359 [CommandLine](IMPORTANT): got command get platform_magnetic_orientation degree 2016-08-26T23:35:12.360Z,1472254512.360 [CommandLine](IMPORTANT): platform_magnetic_orientation 219.670468 arcdeg 2016-08-26T23:35:18.962Z,1472254518.962 [CommandLine](IMPORTANT): got command get platform_magnetic_orientation degree 2016-08-26T23:35:18.963Z,1472254518.963 [CommandLine](IMPORTANT): platform_magnetic_orientation 306.713715 arcdeg 2016-08-26T23:35:25.608Z,1472254525.608 [CommandLine](IMPORTANT): got command get platform_magnetic_orientation degree 2016-08-26T23:35:25.609Z,1472254525.609 [CommandLine](IMPORTANT): platform_magnetic_orientation 142.525913 arcdeg 2016-08-26T23:35:30.849Z,1472254530.849 [CommandLine](IMPORTANT): got command get platform_magnetic_orientation degree 2016-08-26T23:35:30.850Z,1472254530.850 [CommandLine](IMPORTANT): platform_magnetic_orientation 69.868641 arcdeg 2016-08-26T23:35:35.883Z,1472254535.883 [SBIT](IMPORTANT): SBIT PASSED 2016-08-26T23:35:36.257Z,1472254536.257 [MissionManager](IMPORTANT): Started mission Startup 2016-08-26T23:35:36.257Z,1472254536.257 [Startup] Running Loop=1 2016-08-26T23:35:36.257Z,1472254536.257 [Startup](DEBUG): Aggregate::initialize Startup 2016-08-26T23:35:36.257Z,1472254536.257 [Startup:A.GoToSurface] Running Loop=1 2016-08-26T23:35:36.258Z,1472254536.258 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2016-08-26T23:35:36.259Z,1472254536.259 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2016-08-26T23:35:36.259Z,1472254536.259 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2016-08-26T23:35:36.260Z,1472254536.260 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2016-08-26T23:35:36.261Z,1472254536.261 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2016-08-26T23:35:36.261Z,1472254536.261 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2016-08-26T23:35:36.295Z,1472254536.295 [Startup:StartupSatComms] Running Loop=1 2016-08-26T23:35:36.296Z,1472254536.296 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2016-08-26T23:35:36.296Z,1472254536.296 [Startup:StartupSatComms:A] Running Loop=1 2016-08-26T23:35:36.671Z,1472254536.671 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2016-08-26T23:35:45.347Z,1472254545.347 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005512 2016-08-26T23:36:18.981Z,1472254578.981 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2016-08-26T23:36:18.981Z,1472254578.981 [PNI_TCM] Data Fault, FailCount= 1 2016-08-26T23:36:18.981Z,1472254578.981 [PNI_TCM](ERROR): Data Fault 2016-08-26T23:36:18.994Z,1472254578.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.399921 seconds old. 2016-08-26T23:36:18.995Z,1472254578.995 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.400421 seconds old. 2016-08-26T23:36:19.031Z,1472254579.031 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-08-26T23:36:19.352Z,1472254579.352 [PNI_TCM](INFO): Powering down 2016-08-26T23:36:19.379Z,1472254579.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.785117 seconds old. 2016-08-26T23:36:19.380Z,1472254579.380 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.785617 seconds old. 2016-08-26T23:36:19.759Z,1472254579.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.165207 seconds old. 2016-08-26T23:36:19.760Z,1472254579.760 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.165707 seconds old. 2016-08-26T23:36:20.152Z,1472254580.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.558416 seconds old. 2016-08-26T23:36:20.153Z,1472254580.153 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.558916 seconds old. 2016-08-26T23:36:20.588Z,1472254580.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.994293 seconds old. 2016-08-26T23:36:20.589Z,1472254580.589 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.994793 seconds old. 2016-08-26T23:36:20.601Z,1472254580.601 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-08-26T23:36:20.601Z,1472254580.601 [PNI_TCM] No Fault, FailCount= 1 2016-08-26T23:36:20.982Z,1472254580.982 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.388302 seconds old. 2016-08-26T23:36:20.983Z,1472254580.983 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.388802 seconds old. 2016-08-26T23:36:21.381Z,1472254581.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.787623 seconds old. 2016-08-26T23:36:21.383Z,1472254581.383 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.788123 seconds old. 2016-08-26T23:36:21.826Z,1472254581.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.232012 seconds old. 2016-08-26T23:36:21.827Z,1472254581.827 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.232512 seconds old. 2016-08-26T23:36:22.161Z,1472254582.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.567328 seconds old. 2016-08-26T23:36:22.183Z,1472254582.183 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.588365 seconds old. 2016-08-26T23:36:22.561Z,1472254582.561 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.967449 seconds old. 2016-08-26T23:36:22.579Z,1472254582.579 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.984080 seconds old. 2016-08-26T23:36:22.999Z,1472254582.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.405326 seconds old. 2016-08-26T23:36:23.001Z,1472254583.001 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.405932 seconds old. 2016-08-26T23:36:23.360Z,1472254583.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.766191 seconds old. 2016-08-26T23:36:23.361Z,1472254583.361 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.766691 seconds old. 2016-08-26T23:36:23.761Z,1472254583.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.167012 seconds old. 2016-08-26T23:36:23.762Z,1472254583.762 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.167512 seconds old. 2016-08-26T23:36:24.154Z,1472254584.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.560021 seconds old. 2016-08-26T23:36:24.155Z,1472254584.155 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.560521 seconds old. 2016-08-26T23:36:24.558Z,1472254584.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.964548 seconds old. 2016-08-26T23:36:24.560Z,1472254584.560 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.965048 seconds old. 2016-08-26T23:36:24.955Z,1472254584.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.361063 seconds old. 2016-08-26T23:36:24.956Z,1472254584.956 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.361563 seconds old. 2016-08-26T23:36:25.352Z,1472254585.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.758078 seconds old. 2016-08-26T23:36:25.353Z,1472254585.353 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.758578 seconds old. 2016-08-26T23:36:25.786Z,1472254585.786 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.192049 seconds old. 2016-08-26T23:36:25.787Z,1472254585.787 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.192549 seconds old. 2016-08-26T23:36:26.185Z,1472254586.185 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.590870 seconds old. 2016-08-26T23:36:26.186Z,1472254586.186 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.591370 seconds old. 2016-08-26T23:36:26.597Z,1472254586.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 8.003810 seconds old. 2016-08-26T23:36:26.599Z,1472254586.599 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.004310 seconds old. 2016-08-26T23:36:26.966Z,1472254586.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 8.371781 seconds old. 2016-08-26T23:36:26.967Z,1472254586.967 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.372281 seconds old. 2016-08-26T23:36:27.356Z,1472254587.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 8.761784 seconds old. 2016-08-26T23:36:27.357Z,1472254587.357 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.762284 seconds old. 2016-08-26T23:36:27.811Z,1472254587.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 9.212779 seconds old. 2016-08-26T23:36:27.812Z,1472254587.812 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.217492 seconds old. 2016-08-26T23:36:28.150Z,1472254588.150 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2016-08-26T23:36:28.150Z,1472254588.150 [PNI_TCM] Data Fault, FailCount= 2 2016-08-26T23:36:28.150Z,1472254588.150 [PNI_TCM](ERROR): Data Fault 2016-08-26T23:36:28.156Z,1472254588.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 9.562326 seconds old. 2016-08-26T23:36:28.157Z,1472254588.157 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.562826 seconds old. 2016-08-26T23:36:28.168Z,1472254588.168 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-08-26T23:36:28.558Z,1472254588.558 [PNI_TCM](INFO): Powering down 2016-08-26T23:36:28.565Z,1472254588.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 9.971360 seconds old. 2016-08-26T23:36:28.566Z,1472254588.566 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.971760 seconds old. 2016-08-26T23:36:29.033Z,1472254589.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 10.439286 seconds old. 2016-08-26T23:36:29.035Z,1472254589.035 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.439886 seconds old. 2016-08-26T23:36:29.362Z,1472254589.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 10.768696 seconds old. 2016-08-26T23:36:29.364Z,1472254589.364 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.769196 seconds old. 2016-08-26T23:36:29.787Z,1472254589.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 11.193054 seconds old. 2016-08-26T23:36:29.788Z,1472254589.788 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.193554 seconds old. 2016-08-26T23:36:29.819Z,1472254589.819 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-08-26T23:36:29.819Z,1472254589.819 [PNI_TCM] No Fault, FailCount= 2 2016-08-26T23:36:30.205Z,1472254590.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 11.611000 seconds old. 2016-08-26T23:36:30.206Z,1472254590.206 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.611600 seconds old. 2016-08-26T23:36:30.564Z,1472254590.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 11.969759 seconds old. 2016-08-26T23:36:30.565Z,1472254590.565 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.970259 seconds old. 2016-08-26T23:36:30.985Z,1472254590.985 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 12.391211 seconds old. 2016-08-26T23:36:30.986Z,1472254590.986 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.391711 seconds old. 2016-08-26T23:36:31.375Z,1472254591.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 12.780914 seconds old. 2016-08-26T23:36:31.376Z,1472254591.376 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.781414 seconds old. 2016-08-26T23:36:31.779Z,1472254591.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.185441 seconds old. 2016-08-26T23:36:31.781Z,1472254591.781 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.185941 seconds old. 2016-08-26T23:36:32.210Z,1472254592.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.616212 seconds old. 2016-08-26T23:36:32.211Z,1472254592.211 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.616712 seconds old. 2016-08-26T23:36:32.556Z,1472254592.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.961746 seconds old. 2016-08-26T23:36:32.557Z,1472254592.557 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.962246 seconds old. 2016-08-26T23:36:32.960Z,1472254592.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.366073 seconds old. 2016-08-26T23:36:32.961Z,1472254592.961 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.366573 seconds old. 2016-08-26T23:36:33.365Z,1472254593.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.771401 seconds old. 2016-08-26T23:36:33.367Z,1472254593.367 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.771901 seconds old. 2016-08-26T23:36:33.782Z,1472254593.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.187846 seconds old. 2016-08-26T23:36:33.783Z,1472254593.783 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.188446 seconds old. 2016-08-26T23:36:34.158Z,1472254594.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.564330 seconds old. 2016-08-26T23:36:34.159Z,1472254594.159 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.564830 seconds old. 2016-08-26T23:36:34.564Z,1472254594.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.969858 seconds old. 2016-08-26T23:36:34.565Z,1472254594.565 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.970358 seconds old. 2016-08-26T23:36:34.959Z,1472254594.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 16.365473 seconds old. 2016-08-26T23:36:34.961Z,1472254594.961 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 16.365973 seconds old. 2016-08-26T23:36:35.363Z,1472254595.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 16.769300 seconds old. 2016-08-26T23:36:35.364Z,1472254595.364 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 16.769800 seconds old. 2016-08-26T23:36:35.765Z,1472254595.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 17.171321 seconds old. 2016-08-26T23:36:35.766Z,1472254595.766 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 17.171821 seconds old. 2016-08-26T23:36:36.180Z,1472254596.180 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 17.586467 seconds old. 2016-08-26T23:36:36.182Z,1472254596.182 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 17.586967 seconds old. 2016-08-26T23:36:36.575Z,1472254596.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 17.981376 seconds old. 2016-08-26T23:36:36.577Z,1472254596.577 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 17.981882 seconds old. 2016-08-26T23:36:36.579Z,1472254596.579 [Startup:StartupSatComms:A](INFO): Timed out from 2016-08-26T23:35:36.3Z 2016-08-26T23:36:36.579Z,1472254596.579 [Startup:StartupSatComms:A] Stopped 2016-08-26T23:36:36.579Z,1472254596.579 [Startup:StartupSatComms:B] Running Loop=1 2016-08-26T23:36:36.976Z,1472254596.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 18.382097 seconds old. 2016-08-26T23:36:36.977Z,1472254596.977 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 18.382603 seconds old. 2016-08-26T23:36:36.980Z,1472254596.980 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2016-08-26T23:36:38.426Z,1472254598.426 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2016-08-26T23:36:38.426Z,1472254598.426 [PNI_TCM] Data Fault, FailCount= 3 2016-08-26T23:36:38.426Z,1472254598.426 [PNI_TCM](ERROR): Data Fault 2016-08-26T23:36:38.432Z,1472254598.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 19.838558 seconds old. 2016-08-26T23:36:38.434Z,1472254598.434 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 19.839158 seconds old. 2016-08-26T23:36:38.445Z,1472254598.445 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-08-26T23:36:38.523Z,1472254598.523 [PNI_TCM](INFO): Powering down 2016-08-26T23:36:38.530Z,1472254598.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 19.936307 seconds old. 2016-08-26T23:36:38.532Z,1472254598.532 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 19.937013 seconds old. 2016-08-26T23:36:41.832Z,1472254601.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 23.238125 seconds old. 2016-08-26T23:36:41.833Z,1472254601.833 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-26T23:36:41.833Z,1472254601.833 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2016-08-26T23:36:41.833Z,1472254601.833 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2016-08-26T23:36:41.835Z,1472254601.835 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 23.239931 seconds old. 2016-08-26T23:36:41.835Z,1472254601.835 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2016-08-26T23:36:41.836Z,1472254601.836 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2016-08-26T23:36:41.836Z,1472254601.836 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2016-08-26T23:36:41.847Z,1472254601.847 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2016-08-26T23:36:41.847Z,1472254601.847 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2016-08-26T23:36:41.918Z,1472254601.918 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20160826T231022/Courier0012.lzma 2016-08-26T23:36:41.975Z,1472254601.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2016-08-26T23:36:41.976Z,1472254601.976 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2016-08-26T23:36:41.976Z,1472254601.976 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2016-08-26T23:36:41.976Z,1472254601.976 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2016-08-26T23:36:41.981Z,1472254601.981 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-08-26T23:36:41.981Z,1472254601.981 [PNI_TCM] No Fault, FailCount= 3 2016-08-26T23:36:42.341Z,1472254602.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2016-08-26T23:36:42.345Z,1472254602.345 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2016-08-26T23:36:42.346Z,1472254602.346 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2016-08-26T23:36:42.346Z,1472254602.346 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2016-08-26T23:36:42.347Z,1472254602.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2016-08-26T23:36:42.347Z,1472254602.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2016-08-26T23:36:42.656Z,1472254602.656 [DataOverHttps](INFO): Moved sent file to Logs/20160826T231022/Courier0012.lzma.bak 2016-08-26T23:36:42.657Z,1472254602.657 [DataOverHttps](INFO): SBD MOMSN=4425364 2016-08-26T23:36:42.700Z,1472254602.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 24.106173 seconds old. 2016-08-26T23:36:42.701Z,1472254602.701 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 24.106673 seconds old. 2016-08-26T23:36:42.713Z,1472254602.713 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2016-08-26T23:36:42.713Z,1472254602.713 [Aanderaa_O2] No Fault, FailCount= 3 2016-08-26T23:36:43.695Z,1472254603.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 25.101413 seconds old. 2016-08-26T23:36:43.697Z,1472254603.697 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 25.102119 seconds old. 2016-08-26T23:36:43.791Z,1472254603.791 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 25.197162 seconds old. 2016-08-26T23:36:43.793Z,1472254603.793 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 25.197868 seconds old. 2016-08-26T23:36:47.029Z,1472254607.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 28.434281 seconds old. 2016-08-26T23:36:47.030Z,1472254607.030 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 28.435787 seconds old. 2016-08-26T23:36:47.161Z,1472254607.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 28.567692 seconds old. 2016-08-26T23:36:47.163Z,1472254607.163 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 28.568192 seconds old. 2016-08-26T23:36:47.541Z,1472254607.541 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 28.947376 seconds old. 2016-08-26T23:36:47.543Z,1472254607.543 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 28.948082 seconds old. 2016-08-26T23:36:47.889Z,1472254607.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 29.294916 seconds old. 2016-08-26T23:36:47.890Z,1472254607.890 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 29.295422 seconds old. 2016-08-26T23:36:48.289Z,1472254608.289 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 29.694737 seconds old. 2016-08-26T23:36:48.290Z,1472254608.290 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 29.695337 seconds old. 2016-08-26T23:36:48.693Z,1472254608.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 30.098864 seconds old. 2016-08-26T23:36:48.694Z,1472254608.694 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 30.099364 seconds old. 2016-08-26T23:36:51.916Z,1472254611.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 33.321758 seconds old. 2016-08-26T23:36:51.917Z,1472254611.917 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 33.322364 seconds old. 2016-08-26T23:36:52.005Z,1472254612.005 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20160826T233328/Courier0000.lzma 2016-08-26T23:36:52.089Z,1472254612.089 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 33.494425 seconds old. 2016-08-26T23:36:52.093Z,1472254612.093 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 33.498138 seconds old. 2016-08-26T23:36:52.407Z,1472254612.407 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2016-08-26T23:36:52.407Z,1472254612.407 [PNI_TCM] Data Fault, FailCount= 4 2016-08-26T23:36:52.408Z,1472254612.408 [PNI_TCM](ERROR): Data Fault 2016-08-26T23:36:52.465Z,1472254612.465 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 33.871215 seconds old. 2016-08-26T23:36:52.466Z,1472254612.466 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 33.871815 seconds old. 2016-08-26T23:36:52.486Z,1472254612.486 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-08-26T23:36:52.772Z,1472254612.772 [DataOverHttps](INFO): Moved sent file to Logs/20160826T233328/Courier0000.lzma.bak 2016-08-26T23:36:52.772Z,1472254612.772 [DataOverHttps](INFO): SBD MOMSN=4425366 2016-08-26T23:36:52.816Z,1472254612.816 [PNI_TCM](INFO): Powering down 2016-08-26T23:36:52.823Z,1472254612.823 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 34.228868 seconds old. 2016-08-26T23:36:52.824Z,1472254612.824 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 34.229368 seconds old. 2016-08-26T23:36:53.161Z,1472254613.161 [Aanderaa_O2](FAULT): Timed out starting 2016-08-26T23:36:53.161Z,1472254613.161 [Aanderaa_O2] Communications Fault, FailCount= 1 2016-08-26T23:36:53.161Z,1472254613.161 [Aanderaa_O2](ERROR): Communications Fault 2016-08-26T23:36:54.278Z,1472254614.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 35.684723 seconds old. 2016-08-26T23:36:54.280Z,1472254614.280 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 35.685429 seconds old. 2016-08-26T23:36:54.316Z,1472254614.316 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2016-08-26T23:36:54.326Z,1472254614.326 [Aanderaa_O2](INFO): Powering down 2016-08-26T23:36:54.385Z,1472254614.385 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 35.791491 seconds old. 2016-08-26T23:36:54.387Z,1472254614.387 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 35.791991 seconds old. 2016-08-26T23:36:54.422Z,1472254614.422 [CBIT](INFO): Clearing failed state for component PNI_TCM 2016-08-26T23:36:54.422Z,1472254614.422 [PNI_TCM] No Fault, FailCount= 4 2016-08-26T23:36:57.608Z,1472254617.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 39.013884 seconds old. 2016-08-26T23:36:57.609Z,1472254617.609 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 39.014591 seconds old. 2016-08-26T23:36:57.717Z,1472254617.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 39.123758 seconds old. 2016-08-26T23:36:57.719Z,1472254617.719 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 39.124258 seconds old. 2016-08-26T23:36:57.731Z,1472254617.731 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2016-08-26T23:36:57.731Z,1472254617.731 [Aanderaa_O2] No Fault, FailCount= 1 2016-08-26T23:36:58.078Z,1472254618.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 39.484317 seconds old. 2016-08-26T23:36:58.079Z,1472254618.079 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 39.484817 seconds old. 2016-08-26T23:36:58.509Z,1472254618.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 39.915182 seconds old. 2016-08-26T23:36:58.510Z,1472254618.510 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 39.915788 seconds old. 2016-08-26T23:36:58.864Z,1472254618.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 40.270035 seconds old. 2016-08-26T23:36:58.865Z,1472254618.865 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 40.270535 seconds old. 2016-08-26T23:36:59.308Z,1472254619.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 40.714424 seconds old. 2016-08-26T23:36:59.310Z,1472254619.310 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 40.714924 seconds old. 2016-08-26T23:37:02.512Z,1472254622.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 43.917987 seconds old. 2016-08-26T23:37:02.513Z,1472254622.513 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 43.918493 seconds old. 2016-08-26T23:37:02.579Z,1472254622.579 [DataOverHttps](INFO): Sending 384 bytes from file Logs/20160826T231022/Express0013.lzma 2016-08-26T23:37:02.629Z,1472254622.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 44.035573 seconds old. 2016-08-26T23:37:02.631Z,1472254622.631 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 44.036073 seconds old. 2016-08-26T23:37:03.014Z,1472254623.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 44.420369 seconds old. 2016-08-26T23:37:03.015Z,1472254623.015 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 44.420869 seconds old. 2016-08-26T23:37:03.340Z,1472254623.340 [DataOverHttps](INFO): Moved sent file to Logs/20160826T231022/Express0013.lzma.bak 2016-08-26T23:37:03.341Z,1472254623.341 [DataOverHttps](INFO): SBD MOMSN=4425372 2016-08-26T23:37:03.409Z,1472254623.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 44.814778 seconds old. 2016-08-26T23:37:03.410Z,1472254623.410 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 44.815484 seconds old. 2016-08-26T23:37:04.929Z,1472254624.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 46.335138 seconds old. 2016-08-26T23:37:04.930Z,1472254624.930 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 46.335738 seconds old. 2016-08-26T23:37:05.030Z,1472254625.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 46.436194 seconds old. 2016-08-26T23:37:05.031Z,1472254625.031 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 46.436694 seconds old. 2016-08-26T23:37:11.418Z,1472254631.418 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2016-08-26T23:37:11.419Z,1472254631.419 [PNI_TCM] Data Fault, FailCount= 5 2016-08-26T23:37:11.419Z,1472254631.419 [PNI_TCM](ERROR): Data Fault 2016-08-26T23:37:11.479Z,1472254631.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 52.885694 seconds old. 2016-08-26T23:37:11.481Z,1472254631.481 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 52.886194 seconds old. 2016-08-26T23:37:11.492Z,1472254631.492 [CBIT](ERROR): Data Fault in component: PNI_TCM 2016-08-26T23:37:11.492Z,1472254631.492 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2016-08-26T23:37:11.538Z,1472254631.538 [Aanderaa_O2](FAULT): Timed out starting 2016-08-26T23:37:11.538Z,1472254631.538 [Aanderaa_O2] Communications Fault, FailCount= 2 2016-08-26T23:37:11.538Z,1472254631.538 [Aanderaa_O2](ERROR): Communications Fault 2016-08-26T23:37:11.625Z,1472254631.625 [PNI_TCM](INFO): Powering down 2016-08-26T23:37:11.636Z,1472254631.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 53.042136 seconds old. 2016-08-26T23:37:11.637Z,1472254631.637 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 53.042636 seconds old. 2016-08-26T23:37:11.689Z,1472254631.689 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2016-08-26T23:37:11.915Z,1472254631.915 [Aanderaa_O2](INFO): Powering down 2016-08-26T23:37:12.017Z,1472254632.017 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 53.422926 seconds old. 2016-08-26T23:37:12.018Z,1472254632.018 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 53.423426 seconds old. 2016-08-26T23:37:12.415Z,1472254632.415 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 53.821341 seconds old. 2016-08-26T23:37:12.421Z,1472254632.421 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 53.826253 seconds old. 2016-08-26T23:37:12.762Z,1472254632.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 54.168081 seconds old. 2016-08-26T23:37:12.763Z,1472254632.763 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 54.168581 seconds old. 2016-08-26T23:37:12.787Z,1472254632.787 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2016-08-26T23:37:12.787Z,1472254632.787 [Aanderaa_O2] No Fault, FailCount= 2 2016-08-26T23:37:13.176Z,1472254633.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 54.582221 seconds old. 2016-08-26T23:37:13.181Z,1472254633.181 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 54.586733 seconds old. 2016-08-26T23:37:21.346Z,1472254641.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 62.747679 seconds old. 2016-08-26T23:37:21.347Z,1472254641.347 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 62.752592 seconds old. 2016-08-26T23:37:21.429Z,1472254641.429 [DataOverHttps](INFO): Sending 916 bytes from file Logs/20160826T233328/Express0001.lzma 2016-08-26T23:37:21.508Z,1472254641.508 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 62.913940 seconds old. 2016-08-26T23:37:21.511Z,1472254641.511 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 62.915846 seconds old. 2016-08-26T23:37:21.894Z,1472254641.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 63.300336 seconds old. 2016-08-26T23:37:21.904Z,1472254641.904 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 63.309655 seconds old. 2016-08-26T23:37:22.226Z,1472254642.226 [DataOverHttps](INFO): Moved sent file to Logs/20160826T233328/Express0001.lzma.bak 2016-08-26T23:37:22.226Z,1472254642.226 [DataOverHttps](INFO): SBD MOMSN=4425392 2016-08-26T23:37:22.299Z,1472254642.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 63.705164 seconds old. 2016-08-26T23:37:22.300Z,1472254642.300 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 63.705670 seconds old. 2016-08-26T23:37:24.338Z,1472254644.338 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 65.740419 seconds old. 2016-08-26T23:37:24.340Z,1472254644.340 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 65.745231 seconds old. 2016-08-26T23:37:24.342Z,1472254644.342 [Startup:StartupSatComms:B] Stopped 2016-08-26T23:37:24.346Z,1472254644.346 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2016-08-26T23:37:24.346Z,1472254644.346 [Startup:StartupSatComms] Stopped 2016-08-26T23:37:24.346Z,1472254644.346 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2016-08-26T23:37:24.347Z,1472254644.347 [Startup](INFO): Completed Startup 2016-08-26T23:37:24.347Z,1472254644.347 [Startup] Stopped 2016-08-26T23:37:24.348Z,1472254644.348 [Startup](DEBUG): Aggregate::uninitialize Startup 2016-08-26T23:37:24.348Z,1472254644.348 [Startup:A.GoToSurface] Stopped 2016-08-26T23:37:24.348Z,1472254644.348 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2016-08-26T23:37:24.379Z,1472254644.379 [Aanderaa_O2](FAULT): Timed out starting 2016-08-26T23:37:24.379Z,1472254644.379 [Aanderaa_O2] Communications Fault, FailCount= 3 2016-08-26T23:37:24.379Z,1472254644.379 [Aanderaa_O2](ERROR): Communications Fault 2016-08-26T23:37:24.455Z,1472254644.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 65.861412 seconds old. 2016-08-26T23:37:24.456Z,1472254644.456 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 65.861912 seconds old. 2016-08-26T23:37:24.462Z,1472254644.462 [MissionManager](IMPORTANT): Started mission Default 2016-08-26T23:37:24.463Z,1472254644.463 [Default] Running Loop=1 2016-08-26T23:37:24.463Z,1472254644.463 [Default](DEBUG): Aggregate::initialize Default 2016-08-26T23:37: