2018-10-24T16:38:58.529Z,1540399138.529 [Supervisor](DEBUG): Initializing supervisor.
2018-10-24T16:38:58.532Z,1540399138.532 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-24T16:38:58.533Z,1540399138.533 [SyncHandler](INFO): Protected caller Thread ID is 2636
2018-10-24T16:38:58.533Z,1540399138.533 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-24T16:38:58.534Z,1540399138.534 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-24T16:38:58.534Z,1540399138.534 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2637
2018-10-24T16:38:58.537Z,1540399138.537 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-24T16:38:58.548Z,1540399138.548 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-24T16:38:58.549Z,1540399138.549 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-24T16:38:58.550Z,1540399138.550 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2638
2018-10-24T16:38:58.551Z,1540399138.551 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-24T16:38:58.551Z,1540399138.551 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-24T16:38:58.552Z,1540399138.552 [logger ThreadHandler](INFO): Protected caller Thread ID is 2639
2018-10-24T16:38:58.554Z,1540399138.554 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-24T16:38:58.554Z,1540399138.554 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-24T16:38:58.556Z,1540399138.556 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-24T16:38:58.988Z,1540399138.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-24T16:38:58.989Z,1540399138.989 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-24T16:38:59.434Z,1540399139.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-24T16:38:59.434Z,1540399139.434 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-24T16:38:59.627Z,1540399139.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-24T16:38:59.628Z,1540399139.628 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-24T16:38:59.772Z,1540399139.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-24T16:38:59.773Z,1540399139.773 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-24T16:39:00.211Z,1540399140.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-24T16:39:00.211Z,1540399140.211 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-24T16:39:00.305Z,1540399140.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-24T16:39:00.306Z,1540399140.306 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-24T16:39:00.447Z,1540399140.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-24T16:39:00.447Z,1540399140.447 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-24T16:39:00.547Z,1540399140.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-24T16:39:00.548Z,1540399140.548 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-24T16:39:00.650Z,1540399140.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-24T16:39:00.651Z,1540399140.651 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-24T16:39:00.993Z,1540399140.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-24T16:39:00.994Z,1540399140.994 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-24T16:39:01.194Z,1540399141.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-24T16:39:01.195Z,1540399141.195 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-24T16:39:01.407Z,1540399141.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-24T16:39:01.407Z,1540399141.407 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-24T16:39:01.487Z,1540399141.487 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-24T16:39:01.836Z,1540399141.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-24T16:39:01.836Z,1540399141.836 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-24T16:39:02.153Z,1540399142.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-24T16:39:02.155Z,1540399142.155 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-10-24T16:39:02.156Z,1540399142.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-10-24T16:39:02.240Z,1540399142.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-10-24T16:39:02.390Z,1540399142.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-10-24T16:39:02.487Z,1540399142.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-10-24T16:39:02.658Z,1540399142.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-10-24T16:39:02.739Z,1540399142.739 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-10-24T16:39:03.037Z,1540399143.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-24T16:39:03.038Z,1540399143.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-10-24T16:39:03.151Z,1540399143.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-10-24T16:39:03.290Z,1540399143.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-10-24T16:39:03.385Z,1540399143.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-10-24T16:39:03.487Z,1540399143.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-10-24T16:39:03.717Z,1540399143.717 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-24T16:39:03.722Z,1540399143.722 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-24T16:39:03.935Z,1540399143.935 [DepthRateCalculator] Loaded
2018-10-24T16:39:03.935Z,1540399143.935 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-24T16:39:03.960Z,1540399143.960 [PitchRateCalculator] Loaded
2018-10-24T16:39:03.960Z,1540399143.960 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-24T16:39:04.058Z,1540399144.058 [SpeedCalculator] Loaded
2018-10-24T16:39:04.059Z,1540399144.059 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-24T16:39:04.079Z,1540399144.079 [TempGradientCalculator] Loaded
2018-10-24T16:39:04.079Z,1540399144.079 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-24T16:39:04.085Z,1540399144.085 [YawRateCalculator] Loaded
2018-10-24T16:39:04.085Z,1540399144.085 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-24T16:39:04.199Z,1540399144.199 [ElevatorOffsetCalculator] Loaded
2018-10-24T16:39:04.199Z,1540399144.199 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-24T16:39:04.200Z,1540399144.200 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-24T16:39:04.200Z,1540399144.200 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-24T16:39:04.548Z,1540399144.548 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-24T16:39:04.548Z,1540399144.548 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-24T16:39:04.842Z,1540399144.842 [DataOverHttps] Loaded
2018-10-24T16:39:04.842Z,1540399144.842 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-24T16:39:04.868Z,1540399144.868 [Depth_Keller] Loaded
2018-10-24T16:39:04.868Z,1540399144.868 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-24T16:39:04.873Z,1540399144.873 [DropWeight] Loaded
2018-10-24T16:39:04.873Z,1540399144.873 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-24T16:39:04.970Z,1540399144.970 [NAL9602] Loaded
2018-10-24T16:39:04.970Z,1540399144.970 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-24T16:39:04.986Z,1540399144.986 [Onboard] Loaded
2018-10-24T16:39:04.987Z,1540399144.987 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-24T16:39:04.997Z,1540399144.997 [Radio_Surface] Loaded
2018-10-24T16:39:04.997Z,1540399144.997 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-24T16:39:04.998Z,1540399144.998 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-10-24T16:39:04.998Z,1540399144.998 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2719
2018-10-24T16:39:05.005Z,1540399145.005 [SCPI] Loaded
2018-10-24T16:39:05.005Z,1540399145.005 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-10-24T16:39:06.757Z,1540399146.757 [BPC1] Loaded
2018-10-24T16:39:06.757Z,1540399146.757 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-10-24T16:39:06.757Z,1540399146.757 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-24T16:39:06.758Z,1540399146.758 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-24T16:39:06.823Z,1540399146.823 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-24T16:39:06.824Z,1540399146.824 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-24T16:39:06.919Z,1540399146.919 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-10-24T16:39:06.919Z,1540399146.919 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-10-24T16:39:06.973Z,1540399146.973 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-24T16:39:06.973Z,1540399146.973 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-24T16:39:06.990Z,1540399146.990 [NavChart] Loaded
2018-10-24T16:39:06.990Z,1540399146.990 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-24T16:39:06.994Z,1540399146.994 [UniversalFixResidualReporter] Loaded
2018-10-24T16:39:06.994Z,1540399146.994 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-24T16:39:06.994Z,1540399146.994 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-24T16:39:06.995Z,1540399146.995 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-24T16:39:07.218Z,1540399147.218 [CTD_NeilBrown] Loaded
2018-10-24T16:39:07.218Z,1540399147.218 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-10-24T16:39:07.220Z,1540399147.220 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-10-24T16:39:07.220Z,1540399147.220 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2720
2018-10-24T16:39:07.269Z,1540399147.269 [WetLabsBB2FL] Loaded
2018-10-24T16:39:07.269Z,1540399147.269 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-24T16:39:07.270Z,1540399147.270 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-10-24T16:39:07.271Z,1540399147.271 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2721
2018-10-24T16:39:07.271Z,1540399147.271 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-24T16:39:07.272Z,1540399147.272 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-24T16:39:07.339Z,1540399147.339 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-24T16:39:07.438Z,1540399147.438 [VerticalControl] Loaded
2018-10-24T16:39:07.438Z,1540399147.438 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-24T16:39:07.439Z,1540399147.439 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-24T16:39:07.508Z,1540399147.508 [HorizontalControl] Loaded
2018-10-24T16:39:07.508Z,1540399147.508 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-24T16:39:07.508Z,1540399147.508 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-24T16:39:07.514Z,1540399147.514 [SpeedControl] Loaded
2018-10-24T16:39:07.514Z,1540399147.514 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-24T16:39:07.515Z,1540399147.515 [LoopControl](DEBUG): Construct LoopControl.
2018-10-24T16:39:07.515Z,1540399147.515 [LoopControl] Loaded
2018-10-24T16:39:07.515Z,1540399147.515 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-24T16:39:07.516Z,1540399147.516 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-24T16:39:07.516Z,1540399147.516 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-24T16:39:07.529Z,1540399147.529 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-24T16:39:07.530Z,1540399147.530 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-24T16:39:07.660Z,1540399147.660 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-24T16:39:07.671Z,1540399147.671 [SBIT] Loaded
2018-10-24T16:39:07.671Z,1540399147.671 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-24T16:39:07.672Z,1540399147.672 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-24T16:39:07.684Z,1540399147.684 [IBIT] Loaded
2018-10-24T16:39:07.684Z,1540399147.684 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-24T16:39:07.687Z,1540399147.687 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-24T16:39:07.836Z,1540399147.836 [CBIT] Loaded
2018-10-24T16:39:07.836Z,1540399147.836 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-24T16:39:07.837Z,1540399147.837 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-24T16:39:07.837Z,1540399147.837 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-24T16:39:07.939Z,1540399147.939 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-24T16:39:07.939Z,1540399147.939 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-24T16:39:07.965Z,1540399147.965 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-24T16:39:07.965Z,1540399147.965 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-24T16:39:08.063Z,1540399148.063 [BuoyancyServo] Loaded
2018-10-24T16:39:08.063Z,1540399148.063 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-24T16:39:08.075Z,1540399148.075 [ElevatorServo] Loaded
2018-10-24T16:39:08.075Z,1540399148.075 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-24T16:39:08.086Z,1540399148.086 [MassServo] Loaded
2018-10-24T16:39:08.086Z,1540399148.086 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-24T16:39:08.097Z,1540399148.097 [RudderServo] Loaded
2018-10-24T16:39:08.098Z,1540399148.098 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-24T16:39:08.108Z,1540399148.108 [ThrusterServo] Loaded
2018-10-24T16:39:08.109Z,1540399148.109 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-24T16:39:08.109Z,1540399148.109 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-24T16:39:08.113Z,1540399148.113 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-24T16:39:08.114Z,1540399148.114 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-24T16:39:08.120Z,1540399148.120 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-24T16:39:08.121Z,1540399148.121 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2018-10-24T16:39:08.122Z,1540399148.122 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2722
2018-10-24T16:39:08.126Z,1540399148.126 [Supervisor](INFO): Main Thread ID is 2635
2018-10-24T16:39:08.127Z,1540399148.127 [Supervisor](DEBUG): Running supervisor.
2018-10-24T16:39:08.127Z,1540399148.127 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2723
2018-10-24T16:39:08.130Z,1540399148.130 [controlThread ThreadHandler](INFO): Handler Thread ID is 2724
2018-10-24T16:39:08.130Z,1540399148.130 [controlThread](DEBUG): Initializing ControlThread
2018-10-24T16:39:08.131Z,1540399148.131 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-24T16:39:08.131Z,1540399148.131 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-24T16:39:08.131Z,1540399148.131 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-24T16:39:08.132Z,1540399148.132 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-24T16:39:08.132Z,1540399148.132 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-24T16:39:08.133Z,1540399148.133 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-24T16:39:08.140Z,1540399148.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:39:08.141Z,1540399148.141 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:39:08.141Z,1540399148.141 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:39:08.142Z,1540399148.142 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:39:08.142Z,1540399148.142 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:39:08.142Z,1540399148.142 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:39:08.143Z,1540399148.143 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-24T16:39:08.143Z,1540399148.143 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-24T16:39:08.143Z,1540399148.143 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-24T16:39:08.146Z,1540399148.146 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-24T16:39:08.146Z,1540399148.146 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-24T16:39:08.147Z,1540399148.147 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-24T16:39:08.147Z,1540399148.147 [SBIT](INFO): Initialize SBIT Component.
2018-10-24T16:39:08.147Z,1540399148.147 [SBIT](IMPORTANT): git: 2018-10-09-31-g8f1ca26
2018-10-24T16:39:08.148Z,1540399148.148 [SBIT](INFO): git hash: 8f1ca2616133f591d84f59109522710795260801
2018-10-24T16:39:08.148Z,1540399148.148 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-24T16:39:08.148Z,1540399148.148 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-24T16:39:08.149Z,1540399148.149 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-10-24T16:39:08.150Z,1540399148.150 [IBIT](INFO): Initialize IBIT Component.
2018-10-24T16:39:08.151Z,1540399148.151 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-24T16:39:08.151Z,1540399148.151 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-24T16:39:08.152Z,1540399148.152 [logger ThreadHandler](INFO): Handler Thread ID is 2725
2018-10-24T16:39:08.169Z,1540399148.169 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2726
2018-10-24T16:39:08.175Z,1540399148.175 [Radio_Surface](INFO): Powering up
2018-10-24T16:39:08.181Z,1540399148.181 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2727
2018-10-24T16:39:08.193Z,1540399148.193 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2728
2018-10-24T16:39:08.195Z,1540399148.195 [WetLabsBB2FL](INFO): Powering down
2018-10-24T16:39:08.221Z,1540399148.221 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2729
2018-10-24T16:39:08.224Z,1540399148.224 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-24T16:39:08.225Z,1540399148.225 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-24T16:39:08.225Z,1540399148.225 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-24T16:39:08.225Z,1540399148.225 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-24T16:39:08.225Z,1540399148.225 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-24T16:39:08.226Z,1540399148.226 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-24T16:39:08.226Z,1540399148.226 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-24T16:39:08.226Z,1540399148.226 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-24T16:39:08.226Z,1540399148.226 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-24T16:39:08.226Z,1540399148.226 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-24T16:39:08.227Z,1540399148.227 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-24T16:39:08.227Z,1540399148.227 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-24T16:39:08.227Z,1540399148.227 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-24T16:39:08.227Z,1540399148.227 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-24T16:39:08.227Z,1540399148.227 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-24T16:39:08.227Z,1540399148.227 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-24T16:39:08.258Z,1540399148.258 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-24T16:39:08.270Z,1540399148.270 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-24T16:39:08.281Z,1540399148.281 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2018-10-24T16:39:08.282Z,1540399148.282 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-24T16:39:08.406Z,1540399148.406 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-24T16:39:08.407Z,1540399148.407 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-24T16:39:08.409Z,1540399148.409 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-24T16:39:08.444Z,1540399148.444 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-24T16:39:08.462Z,1540399148.462 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-24T16:39:08.468Z,1540399148.468 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-24T16:39:08.484Z,1540399148.484 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2018-10-24T16:39:08.488Z,1540399148.488 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-24T16:39:08.505Z,1540399148.505 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-24T16:39:08.564Z,1540399148.564 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-24T16:39:08.564Z,1540399148.564 [Onboard](FAULT): Can't read temperature LSB.
2018-10-24T16:39:08.564Z,1540399148.564 [Onboard] Hardware Fault, FailCount= 1
2018-10-24T16:39:08.564Z,1540399148.564 [Onboard](ERROR): Hardware Fault
2018-10-24T16:39:08.565Z,1540399148.565 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-24T16:39:08.565Z,1540399148.565 [Onboard](FAULT): Can't read temperature MSB.
2018-10-24T16:39:08.566Z,1540399148.566 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-24T16:39:08.566Z,1540399148.566 [Onboard](FAULT): Can't read pressure LSB.
2018-10-24T16:39:08.566Z,1540399148.566 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-24T16:39:08.567Z,1540399148.567 [Onboard](FAULT): Can't read pressure MSB.
2018-10-24T16:39:08.869Z,1540399148.869 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-24T16:39:08.877Z,1540399148.877 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-24T16:39:08.892Z,1540399148.892 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-24T16:39:08.897Z,1540399148.897 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-24T16:39:08.904Z,1540399148.904 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-24T16:39:08.913Z,1540399148.913 [MassServo](DEBUG): Initializing MassServo.
2018-10-24T16:39:08.931Z,1540399148.931 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-24T16:39:08.937Z,1540399148.937 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-24T16:39:08.958Z,1540399148.958 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-24T16:39:08.965Z,1540399148.965 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-24T16:39:09.001Z,1540399149.001 [CBIT](ERROR): Hardware Fault in component: Onboard
2018-10-24T16:39:09.002Z,1540399149.002 [CBIT](CRITICAL): Hardware Fault in component: Onboard
2018-10-24T16:39:09.139Z,1540399149.139 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-10-24T16:39:09.139Z,1540399149.139 [DropWeight] Hardware Fault, FailCount= 1
2018-10-24T16:39:09.139Z,1540399149.139 [DropWeight](ERROR): Hardware Fault
2018-10-24T16:39:09.186Z,1540399149.186 [CommandLine](FAULT): Scheduling is paused
2018-10-24T16:39:09.186Z,1540399149.186 [CBIT](INFO): Critical error at 20181024T163909
2018-10-24T16:39:09.186Z,1540399149.186 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-24T16:39:09.189Z,1540399149.189 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-10-24T16:39:09.189Z,1540399149.189 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-10-24T16:39:13.830Z,1540399153.830 [Radio_Surface](INFO): Powering down
2018-10-24T16:39:17.899Z,1540399157.899 [CBIT](INFO): Critical error at 20181024T163909
2018-10-24T16:39:18.333Z,1540399158.333 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2139112%. Temp:4816376 C. ABORTING MISSION
2018-10-24T16:39:18.714Z,1540399158.714 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004060
2018-10-24T16:39:18.735Z,1540399158.735 [CBIT](INFO): Critical error at 20181024T163918
2018-10-24T16:39:35.117Z,1540399175.117 [NAL9602](INFO): Powering up NAL9602
2018-10-24T16:39:36.747Z,1540399176.747 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-24T16:39:36.752Z,1540399176.752 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-24T16:39:50.386Z,1540399190.386 [NAL9602](INFO): NAL9602 initialized
2018-10-24T16:39:52.726Z,1540399192.726 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007711
CHAN A1 (24V): 0.001848
CHAN A2 (12V): -0.005190
CHAN A3 (5V): -0.002849
CHAN B0 (3.3V): -0.001279
CHAN B1 (3.15aV): -0.000744
CHAN B2 (3.15bV): -0.001185
CHAN B3 (GND): -0.000604
OPEN: -0.001359
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-24T16:40:16.991Z,1540399216.991 [CommandLine](IMPORTANT): got command get 
2018-10-24T16:40:16.991Z,1540399216.991 [CommandLine](FAULT): Incomplete syntax. Try: help get
2018-10-24T16:40:21.214Z,1540399221.214 [CommandLine](IMPORTANT): got command get depth
2018-10-24T16:40:21.215Z,1540399221.215 [CommandLine](IMPORTANT): depth 16.645708 m
2018-10-24T16:40:23.069Z,1540399223.069 [BPC1](ERROR): BPC1A: No match for serial number 0226 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2018-10-24T16:40:23.071Z,1540399223.071 [BPC1](ERROR): BPC1B: No match for serial number 0273 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2018-10-24T16:40:23.071Z,1540399223.071 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-10-24T16:40:23.071Z,1540399223.071 [BPC1] Data Fault, FailCount= 1
2018-10-24T16:40:23.071Z,1540399223.071 [BPC1](ERROR): Data Fault
2018-10-24T16:40:23.370Z,1540399223.370 [CBIT](ERROR): Data Fault in component: BPC1
2018-10-24T16:40:30.313Z,1540399230.313 [SBIT](IMPORTANT): SBIT PASSED
2018-10-24T16:40:30.350Z,1540399230.350 [CommandLine](IMPORTANT): got command configSet list
2018-10-24T16:40:30.350Z,1540399230.350 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-24T16:40:30.351Z,1540399230.351 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-10-24T16:40:30.351Z,1540399230.351 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-10-24T16:40:30.351Z,1540399230.351 [CommandLine](IMPORTANT): Depth_Keller.scale=43.44 micropascal;

2018-10-24T16:40:30.671Z,1540399230.671 [MissionManager](IMPORTANT): Started mission Startup
2018-10-24T16:40:30.671Z,1540399230.671 [Startup] Running Loop=1
2018-10-24T16:40:30.671Z,1540399230.671 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-24T16:40:30.672Z,1540399230.672 [Startup:A.GoToSurface] Running Loop=1
2018-10-24T16:40:30.672Z,1540399230.672 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-24T16:40:30.672Z,1540399230.672 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-24T16:40:30.673Z,1540399230.673 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-24T16:40:30.673Z,1540399230.673 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-24T16:40:30.674Z,1540399230.674 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-24T16:40:30.674Z,1540399230.674 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-24T16:40:30.710Z,1540399230.710 [Radio_Surface](INFO): Powering up
2018-10-24T16:40:48.323Z,1540399248.323 [CommandLine](IMPORTANT): got command get sea_water_pressure 
2018-10-24T16:40:48.323Z,1540399248.323 [CommandLine](IMPORTANT): sea_water_pressure 16.772705 db
2018-10-24T16:41:08.278Z,1540399268.278 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:41:08.278Z,1540399268.278 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-10-24T16:41:08.278Z,1540399268.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:41:08.280Z,1540399268.280 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:41:08.280Z,1540399268.280 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-24T16:41:08.280Z,1540399268.280 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:41:08.308Z,1540399268.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:41:08.308Z,1540399268.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:41:08.721Z,1540399268.721 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:41:08.722Z,1540399268.722 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-10-24T16:41:08.722Z,1540399268.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:41:08.722Z,1540399268.722 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-24T16:41:09.084Z,1540399269.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:41:09.085Z,1540399269.085 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:41:09.085Z,1540399269.085 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:41:09.086Z,1540399269.086 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:41:09.086Z,1540399269.086 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:41:09.086Z,1540399269.086 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:41:32.950Z,1540399292.950 [Radio_Surface](INFO): Powering down
2018-10-24T16:41:39.025Z,1540399299.025 [Radio_Surface](INFO): Powering up
2018-10-24T16:41:59.004Z,1540399319.004 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.346218
2018-10-24T16:42:35.001Z,1540399355.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-24T16:42:53.038Z,1540399373.038 [Radio_Surface](INFO): Powering down
2018-10-24T16:42:59.098Z,1540399379.098 [Radio_Surface](INFO): Powering up
2018-10-24T16:43:09.413Z,1540399389.413 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:43:09.413Z,1540399389.413 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-10-24T16:43:09.413Z,1540399389.413 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:43:09.415Z,1540399389.415 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:43:09.415Z,1540399389.415 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-24T16:43:09.415Z,1540399389.415 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:43:09.428Z,1540399389.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:43:09.429Z,1540399389.429 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:43:09.826Z,1540399389.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:43:09.826Z,1540399389.826 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-10-24T16:43:09.826Z,1540399389.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:43:09.826Z,1540399389.826 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-24T16:43:10.201Z,1540399390.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-24T16:43:10.216Z,1540399390.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:43:10.217Z,1540399390.217 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:43:10.217Z,1540399390.217 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:43:10.218Z,1540399390.218 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:43:10.218Z,1540399390.218 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:43:10.219Z,1540399390.219 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:43:45.401Z,1540399425.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-24T16:43:54.032Z,1540399434.032 [CommandLine](IMPORTANT): got command get Onboard.loadAtStartup
2018-10-24T16:43:54.032Z,1540399434.032 [CommandLine](IMPORTANT): Onboard.loadAtStartup 1 bool
2018-10-24T16:44:03.749Z,1540399443.749 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002875
2018-10-24T16:44:28.846Z,1540399468.846 [Radio_Surface](INFO): Powering down
2018-10-24T16:44:34.918Z,1540399474.918 [Radio_Surface](INFO): Powering up
2018-10-24T16:44:53.270Z,1540399493.270 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-10-24T16:45:10.407Z,1540399510.407 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:45:10.407Z,1540399510.407 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-10-24T16:45:10.407Z,1540399510.407 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:45:10.409Z,1540399510.409 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:45:10.409Z,1540399510.409 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-10-24T16:45:10.409Z,1540399510.409 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:45:10.423Z,1540399510.423 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:45:10.423Z,1540399510.423 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:45:10.833Z,1540399510.833 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:45:10.833Z,1540399510.833 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-10-24T16:45:10.833Z,1540399510.833 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:45:10.834Z,1540399510.834 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-10-24T16:45:11.200Z,1540399511.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:45:11.201Z,1540399511.201 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:45:11.202Z,1540399511.202 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:45:11.202Z,1540399511.202 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:45:11.202Z,1540399511.202 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:45:11.203Z,1540399511.203 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:45:58.159Z,1540399558.159 [CommandLine](IMPORTANT): got command get sea_water_pressure
2018-10-24T16:45:58.159Z,1540399558.159 [CommandLine](IMPORTANT): sea_water_pressure 16.781394 db
2018-10-24T16:47:11.413Z,1540399631.413 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:47:11.413Z,1540399631.413 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-10-24T16:47:11.413Z,1540399631.413 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:47:11.415Z,1540399631.415 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:47:11.415Z,1540399631.415 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-10-24T16:47:11.415Z,1540399631.415 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:47:11.470Z,1540399631.470 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:47:11.470Z,1540399631.470 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:47:11.818Z,1540399631.818 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:47:11.819Z,1540399631.819 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-10-24T16:47:11.819Z,1540399631.819 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:47:11.819Z,1540399631.819 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-10-24T16:47:12.204Z,1540399632.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:47:12.205Z,1540399632.205 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:47:12.205Z,1540399632.205 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:47:12.206Z,1540399632.206 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:47:12.206Z,1540399632.206 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:47:12.207Z,1540399632.207 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:47:30.290Z,1540399650.290 [CommandLine](IMPORTANT): got command get sea_water_pressure
2018-10-24T16:47:30.291Z,1540399650.291 [CommandLine](IMPORTANT): sea_water_pressure 16.781828 db
2018-10-24T16:48:42.222Z,1540399722.222 [CommandLine](IMPORTANT): got command failComponent
2018-10-24T16:48:42.222Z,1540399722.222 [CommandLine](IMPORTANT): Failed components:
2018-10-24T16:48:42.222Z,1540399722.222 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-10-24T16:48:42.222Z,1540399722.222 [CommandLine](IMPORTANT): Onboard: Hardware Fault
2018-10-24T16:48:42.223Z,1540399722.223 [CommandLine](IMPORTANT): BPC1: Data Fault
2018-10-24T16:48:53.698Z,1540399733.698 [CommandLine](IMPORTANT): got command failComponent none Onboard
2018-10-24T16:48:53.698Z,1540399733.698 [Onboard] No Fault, FailCount= 1
2018-10-24T16:48:53.698Z,1540399733.698 [CommandLine](IMPORTANT): Onboard failureMode is No Fault
2018-10-24T16:48:54.494Z,1540399734.494 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-24T16:48:54.494Z,1540399734.494 [Onboard](FAULT): Can't read temperature LSB.
2018-10-24T16:48:54.494Z,1540399734.494 [Onboard] Hardware Fault, FailCount= 2
2018-10-24T16:48:54.494Z,1540399734.494 [Onboard](ERROR): Hardware Fault
2018-10-24T16:48:54.495Z,1540399734.495 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-24T16:48:54.495Z,1540399734.495 [Onboard](FAULT): Can't read temperature MSB.
2018-10-24T16:48:54.496Z,1540399734.496 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-24T16:48:54.496Z,1540399734.496 [Onboard](FAULT): Can't read pressure LSB.
2018-10-24T16:48:54.497Z,1540399734.497 [Onboard](ERROR): I2C read error. Can't read 1 bytes.
2018-10-24T16:48:54.497Z,1540399734.497 [Onboard](FAULT): Can't read pressure MSB.
2018-10-24T16:48:54.571Z,1540399734.571 [CBIT](ERROR): Hardware Fault in component: Onboard
2018-10-24T16:48:54.571Z,1540399734.571 [CBIT](CRITICAL): Hardware Fault in component: Onboard
2018-10-24T16:48:54.906Z,1540399734.906 [CBIT](INFO): Critical error at 20181024T164854
2018-10-24T16:49:12.421Z,1540399752.421 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:49:12.421Z,1540399752.421 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2018-10-24T16:49:12.421Z,1540399752.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:49:12.423Z,1540399752.423 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:49:12.423Z,1540399752.423 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-10-24T16:49:12.423Z,1540399752.423 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:49:12.437Z,1540399752.437 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:49:12.438Z,1540399752.438 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:49:12.832Z,1540399752.832 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:49:12.832Z,1540399752.832 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2018-10-24T16:49:12.833Z,1540399752.833 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:49:12.833Z,1540399752.833 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-10-24T16:49:13.207Z,1540399753.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:49:13.208Z,1540399753.208 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:49:13.208Z,1540399753.208 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:49:13.217Z,1540399753.217 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:49:13.218Z,1540399753.218 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:49:13.218Z,1540399753.218 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:49:26.146Z,1540399766.146 [CommandLine](IMPORTANT): got command failComponent
2018-10-24T16:49:26.147Z,1540399766.147 [CommandLine](IMPORTANT): Failed components:
2018-10-24T16:49:26.147Z,1540399766.147 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-10-24T16:49:26.147Z,1540399766.147 [CommandLine](IMPORTANT): Onboard: Hardware Fault
2018-10-24T16:49:26.147Z,1540399766.147 [CommandLine](IMPORTANT): BPC1: Data Fault
2018-10-24T16:49:53.551Z,1540399793.551 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-24T16:49:53.552Z,1540399793.552 [NAL9602] Data Fault, FailCount= 1
2018-10-24T16:49:53.552Z,1540399793.552 [NAL9602](ERROR): Data Fault
2018-10-24T16:49:53.599Z,1540399793.599 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-24T16:49:53.954Z,1540399793.954 [NAL9602](INFO): Powering down
2018-10-24T16:49:54.785Z,1540399794.785 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-24T16:49:54.785Z,1540399794.785 [NAL9602] No Fault, FailCount= 1
2018-10-24T16:50:24.266Z,1540399824.266 [NAL9602](INFO): Powering up NAL9602
2018-10-24T16:50:24.305Z,1540399824.305 [CBIT](INFO): Clearing failed state for component BPC1
2018-10-24T16:50:24.305Z,1540399824.305 [BPC1] No Fault, FailCount= 1
2018-10-24T16:50:35.466Z,1540399835.466 [NAL9602](INFO): NAL9602 initialized
2018-10-24T16:51:13.394Z,1540399873.394 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:51:13.394Z,1540399873.394 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2018-10-24T16:51:13.394Z,1540399873.394 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:51:13.395Z,1540399873.395 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:51:13.396Z,1540399873.396 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-10-24T16:51:13.396Z,1540399873.396 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:51:13.422Z,1540399873.422 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:51:13.422Z,1540399873.422 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:51:13.837Z,1540399873.837 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:51:13.837Z,1540399873.837 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2018-10-24T16:51:13.838Z,1540399873.838 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:51:13.838Z,1540399873.838 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-10-24T16:51:14.225Z,1540399874.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:51:14.225Z,1540399874.225 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:51:14.226Z,1540399874.226 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:51:14.226Z,1540399874.226 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:51:14.226Z,1540399874.226 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:51:14.227Z,1540399874.227 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:51:32.160Z,1540399892.160 [BPC1](ERROR): BPC1A: No match for serial number 0226 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2018-10-24T16:51:32.162Z,1540399892.162 [BPC1](ERROR): BPC1B: No match for serial number 0273 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2018-10-24T16:51:32.162Z,1540399892.162 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-10-24T16:51:32.162Z,1540399892.162 [BPC1] Data Fault, FailCount= 2
2018-10-24T16:51:32.162Z,1540399892.162 [BPC1](ERROR): Data Fault
2018-10-24T16:51:32.210Z,1540399892.210 [CBIT](ERROR): Data Fault in component: BPC1
2018-10-24T16:52:59.646Z,1540399979.646 [Radio_Surface](INFO): Powering down
2018-10-24T16:53:05.726Z,1540399985.726 [Radio_Surface](INFO): Powering up
2018-10-24T16:53:14.428Z,1540399994.428 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:53:14.428Z,1540399994.428 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2018-10-24T16:53:14.428Z,1540399994.428 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:53:14.430Z,1540399994.430 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:53:14.430Z,1540399994.430 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-10-24T16:53:14.430Z,1540399994.430 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:53:14.475Z,1540399994.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:53:14.475Z,1540399994.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:53:14.838Z,1540399994.838 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:53:14.838Z,1540399994.838 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2018-10-24T16:53:14.839Z,1540399994.839 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:53:14.839Z,1540399994.839 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-10-24T16:53:15.224Z,1540399995.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:53:15.225Z,1540399995.225 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:53:15.225Z,1540399995.225 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:53:15.226Z,1540399995.226 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:53:15.226Z,1540399995.226 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:53:15.226Z,1540399995.226 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:53:36.838Z,1540400016.838 [Radio_Surface](INFO): Powering down
2018-10-24T16:53:42.014Z,1540400022.014 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.399673
2018-10-24T16:53:42.906Z,1540400022.906 [Radio_Surface](INFO): Powering up
2018-10-24T16:54:09.243Z,1540400049.243 [CBIT](INFO): Clearing failed state for component DropWeight
2018-10-24T16:54:09.243Z,1540400049.243 [DropWeight] No Fault, FailCount= 1
2018-10-24T16:54:18.013Z,1540400058.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-24T16:54:35.629Z,1540400075.629 [VerticalControl](CRITICAL): Failure to ascend, stopping mission after 840.108826 seconds, depthRate=-0.000147 m/s, pitch=nan deg.
2018-10-24T16:54:35.635Z,1540400075.635 [CBIT](INFO): Critical error at 20181024T165435
2018-10-24T16:54:53.213Z,1540400093.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-24T16:55:15.229Z,1540400115.229 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:55:15.229Z,1540400115.229 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2018-10-24T16:55:15.229Z,1540400115.229 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:55:15.231Z,1540400115.231 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:55:15.231Z,1540400115.231 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-10-24T16:55:15.231Z,1540400115.231 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:55:15.289Z,1540400115.289 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:55:15.289Z,1540400115.289 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:55:15.678Z,1540400115.678 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:55:15.678Z,1540400115.678 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2018-10-24T16:55:15.678Z,1540400115.678 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:55:15.678Z,1540400115.678 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-10-24T16:55:16.044Z,1540400116.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:55:16.045Z,1540400116.045 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:55:16.045Z,1540400116.045 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:55:16.046Z,1540400116.046 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:55:16.046Z,1540400116.046 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:55:16.047Z,1540400116.047 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:55:28.013Z,1540400128.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-24T16:55:40.003Z,1540400140.003 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.000000 meter
2018-10-24T16:55:40.004Z,1540400140.004 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2018-10-24T16:55:40.093Z,1540400140.093 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,SCPI,BPC1,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-10-24T16:55:40.454Z,1540400140.454 [Startup:StartupSatComms] Running Loop=1
2018-10-24T16:55:40.454Z,1540400140.454 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-24T16:55:40.454Z,1540400140.454 [Startup:StartupSatComms:A] Running Loop=1
2018-10-24T16:55:40.827Z,1540400140.827 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-24T16:56:03.249Z,1540400163.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-10-24T16:56:14.349Z,1540400174.349 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002937
2018-10-24T16:56:15.428Z,1540400175.428 [CommandLine](IMPORTANT): got command get sea_water_pressure
2018-10-24T16:56:15.429Z,1540400175.429 [CommandLine](IMPORTANT): sea_water_pressure 16.802680 db
2018-10-24T16:56:40.768Z,1540400200.768 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-24T16:55:40.5Z
2018-10-24T16:56:40.768Z,1540400200.768 [Startup:StartupSatComms:A] Stopped
2018-10-24T16:56:40.768Z,1540400200.768 [Startup:StartupSatComms:B] Running Loop=1
2018-10-24T16:56:41.159Z,1540400201.159 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-24T16:56:52.537Z,1540400212.537 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20181024T154610/Courier0007.lzma
2018-10-24T16:56:53.308Z,1540400213.308 [DataOverHttps](INFO): Moved sent file to Logs/20181024T154610/Courier0007.lzma.bak
2018-10-24T16:56:53.308Z,1540400213.308 [DataOverHttps](INFO): SBD MOMSN=8749356
2018-10-24T16:57:15.673Z,1540400235.673 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20181024T162941/Courier0000.lzma
2018-10-24T16:57:16.085Z,1540400236.085 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:57:16.085Z,1540400236.085 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2018-10-24T16:57:16.086Z,1540400236.086 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-24T16:57:16.087Z,1540400236.087 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-24T16:57:16.087Z,1540400236.087 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-10-24T16:57:16.087Z,1540400236.087 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-24T16:57:16.104Z,1540400236.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-24T16:57:16.104Z,1540400236.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-24T16:57:16.459Z,1540400236.459 [DataOverHttps](INFO): Moved sent file to Logs/20181024T162941/Courier0000.lzma.bak
2018-10-24T16:57:16.459Z,1540400236.459 [DataOverHttps](INFO): SBD MOMSN=8749359
2018-10-24T16:57:16.547Z,1540400236.547 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-24T16:57:16.547Z,1540400236.547 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2018-10-24T16:57:16.548Z,1540400236.548 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-24T16:57:16.548Z,1540400236.548 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-10-24T16:57:17.068Z,1540400237.068 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-24T16:57:17.074Z,1540400237.074 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:57:17.075Z,1540400237.075 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:57:17.075Z,1540400237.075 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-24T16:57:17.075Z,1540400237.075 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-24T16:57:17.076Z,1540400237.076 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-24T16:57:39.291Z,1540400259.291 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20181024T163352/Courier0000.lzma
2018-10-24T16:57:40.083Z,1540400260.083 [DataOverHttps](INFO): Moved sent file to Logs/20181024T163352/Courier0000.lzma.bak
2018-10-24T16:57:40.083Z,1540400260.083 [DataOverHttps](INFO): SBD MOMSN=8749369
2018-10-24T16:57:40.915Z,1540400260.915 [Startup:StartupSatComms:B](INFO): Timed out from 2018-10-24T16:56:40.8Z
2018-10-24T16:57:40.915Z,1540400260.915 [Startup:StartupSatComms:B] Stopped
2018-10-24T16:57:40.915Z,1540400260.915 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-24T16:57:40.915Z,1540400260.915 [Startup:StartupSatComms] Stopped
2018-10-24T16:57:40.916Z,1540400260.916 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-24T16:57:40.916Z,1540400260.916 [Startup](INFO): Completed Startup
2018-10-24T16:57:40.917Z,1540400260.917 [MissionManager](INFO): Startup is completed.
2018-10-24T16:57:40.917Z,1540400260.917 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-24T16:57:40.917Z,1540400260.917 [Startup] Stopped
2018-10-24T16:57:40.917Z,1540400260.917 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-24T16:57:40.917Z,1540400260.917 [Startup:A.GoToSurface] Stopped
2018-10-24T16:57:40.917Z,1540400260.917 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-24T16:57:48.304Z,1540400268.304 [MissionManager](IMPORTANT): Started mission Default
2018-10-24T16:57:48.304Z,1540400268.304 [Default] Running Loop=1
2018-10-24T16:57:48.304Z,1540400268.304 [Default](DEBUG): Aggregate::initialize Default
2018-10-24T16:57:48.304Z,1540400268.304 [Default:B.GoToSurface] Running Loop=1
2018-10-24T16:57:48.305Z,1540400268.305 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-24T16:57:48.305Z,1540400268.305 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-24T16:57:48.305Z,1540400268.305 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-24T16:57:48.306Z,1540400268.306 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-24T16:57:48.306Z,1540400268.306 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-24T16:57:48.306Z,1540400268.306 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-24T16:57:48.307Z,1540400268.307 [Default:A.Wait] Running Loop=1
2018-10-24T16:57:48.307Z,1540400268.307 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-24T16:57:53.297Z,1540400273.297 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20181024T163858/Courier0000.lzma
2018-10-24T16:57:54.094Z,1540400274.094 [DataOverHttps](INFO): Moved sent file to Logs/20181024T163858/Courier0000.lzma.bak
2018-10-24T16:57:54.095Z,1540400274.095 [DataOverHttps](INFO): SBD MOMSN=8749385
2018-10-24T16:58:01.705Z,1540400281.705 [Default:A.Wait](INFO): Done Waiting.
2018-10-24T16:58:01.706Z,1540400281.706 [Default:A.Wait] Stopped
2018-10-24T16:58:01.706Z,1540400281.706 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-10-24T16:58:02.124Z,1540400282.124 [Default:CheckIn] Running Loop=1
2018-10-24T16:58:02.124Z,1540400282.124 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-24T16:58:02.124Z,1540400282.124 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-24T16:58:02.525Z,1540400282.525 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-10-24T16:58:11.138Z,1540400291.138 [CommandLine](IMPORTANT): got command restart application
2018-10-24T16:58:12.148Z,1540400292.148 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-24T16:58:12.148Z,1540400292.148 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.333Z,1540400292.333 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-24T16:58:12.333Z,1540400292.333 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.334Z,1540400292.334 [CommandLine](INFO): Join timeout helper Thread ID is 2777
2018-10-24T16:58:12.334Z,1540400292.334 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-24T16:58:12.334Z,1540400292.334 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.335Z,1540400292.335 [NavChartDb](INFO): Join timeout helper Thread ID is 2778
2018-10-24T16:58:12.541Z,1540400292.541 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-24T16:58:12.541Z,1540400292.541 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.542Z,1540400292.542 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-24T16:58:12.542Z,1540400292.542 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.542Z,1540400292.542 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2779
2018-10-24T16:58:12.845Z,1540400292.845 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-24T16:58:12.845Z,1540400292.845 [WetLabsBB2FL](INFO): Powering down
2018-10-24T16:58:12.846Z,1540400292.846 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.865Z,1540400292.865 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-10-24T16:58:12.865Z,1540400292.865 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.865Z,1540400292.865 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2780
2018-10-24T16:58:12.921Z,1540400292.921 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-24T16:58:12.921Z,1540400292.921 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.929Z,1540400292.929 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-24T16:58:12.929Z,1540400292.929 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.929Z,1540400292.929 [Radio_Surface](INFO): Join timeout helper Thread ID is 2781
2018-10-24T16:58:12.957Z,1540400292.957 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-24T16:58:12.957Z,1540400292.957 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.962Z,1540400292.962 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-24T16:58:12.962Z,1540400292.962 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.962Z,1540400292.962 [logger](INFO): Join timeout helper Thread ID is 2782
2018-10-24T16:58:12.969Z,1540400292.969 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-24T16:58:12.969Z,1540400292.969 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.982Z,1540400292.982 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-24T16:58:12.982Z,1540400292.982 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.982Z,1540400292.982 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-24T16:58:12.982Z,1540400292.982 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:12.982Z,1540400292.982 [controlThread](INFO): Join timeout helper Thread ID is 2783
2018-10-24T16:58:13.289Z,1540400293.289 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-24T16:58:13.289Z,1540400293.289 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-24T16:58:13.290Z,1540400293.290 [NAL9602](INFO): Powering down
2018-10-24T16:58:13.292Z,1540400293.292 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-24T16:58:13.293Z,1540400293.293 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-24T16:58:13.293Z,1540400293.293 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-24T16:58:13.294Z,1540400293.294 [MissionManager](INFO): Uninitializing Mission Default
2018-10-24T16:58:13.294Z,1540400293.294 [Default] Stopped
2018-10-24T16:58:13.294Z,1540400293.294 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-24T16:58:13.294Z,1540400293.294 [Default:B.GoToSurface] Stopped
2018-10-24T16:58:13.294Z,1540400293.294 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-24T16:58:13.294Z,1540400293.294 [Default:CheckIn] Stopped
2018-10-24T16:58:13.294Z,1540400293.294 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-24T16:58:13.295Z,1540400293.295 [Default:CheckIn:Read_GPS] Stopped
2018-10-24T16:58:13.297Z,1540400293.297 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-24T16:58:13.298Z,1540400293.298 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-24T16:58:13.298Z,1540400293.298 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-24T16:58:13.298Z,1540400293.298 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-24T16:58:13.298Z,1540400293.298 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-24T16:58:13.299Z,1540400293.299 [BuoyancyServo](INFO): Powering down
2018-10-24T16:58:13.313Z,1540400293.313 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-24T16:58:13.313Z,1540400293.313 [ElevatorServo](INFO): Powering down
2018-10-24T16:58:13.314Z,1540400293.314 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-24T16:58:13.314Z,1540400293.314 [MassServo](INFO): Powering down
2018-10-24T16:58:13.315Z,1540400293.315 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-24T16:58:13.315Z,1540400293.315 [RudderServo](INFO): Powering down
2018-10-24T16:58:13.316Z,1540400293.316 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-24T16:58:13.316Z,1540400293.316 [ThrusterServo](INFO): Powering down
2018-10-24T16:58:13.317Z,1540400293.317 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-24T16:58:13.317Z,1540400293.317 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-24T16:58:13.318Z,1540400293.318 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-24T16:58:13.318Z,1540400293.318 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:13.410Z,1540400293.410 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:13.453Z,1540400293.453 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:13.455Z,1540400293.455 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:13.510Z,1540400293.510 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-24T16:58:13.564Z,1540400293.564 [logger ThreadHandler](INFO): Thread cancelled.