2019-05-14T20:32:02.022Z,1557865922.022 [Supervisor](DEBUG): Initializing supervisor.
2019-05-14T20:32:02.025Z,1557865922.025 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-14T20:32:02.025Z,1557865922.025 [SyncHandler](INFO): Protected caller Thread ID is 4228
2019-05-14T20:32:02.026Z,1557865922.026 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-14T20:32:02.027Z,1557865922.027 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-14T20:32:02.027Z,1557865922.027 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4229
2019-05-14T20:32:02.030Z,1557865922.030 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-14T20:32:02.042Z,1557865922.042 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-14T20:32:02.043Z,1557865922.043 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-14T20:32:02.043Z,1557865922.043 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4230
2019-05-14T20:32:02.044Z,1557865922.044 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-14T20:32:02.045Z,1557865922.045 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-14T20:32:02.046Z,1557865922.046 [logger ThreadHandler](INFO): Protected caller Thread ID is 4231
2019-05-14T20:32:02.047Z,1557865922.047 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-14T20:32:02.048Z,1557865922.048 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-14T20:32:02.050Z,1557865922.050 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-14T20:32:02.784Z,1557865922.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-14T20:32:02.784Z,1557865922.784 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-14T20:32:03.163Z,1557865923.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-14T20:32:03.164Z,1557865923.164 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-14T20:32:03.566Z,1557865923.566 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-14T20:32:04.041Z,1557865924.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-14T20:32:04.042Z,1557865924.042 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-14T20:32:04.139Z,1557865924.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-14T20:32:04.140Z,1557865924.140 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-14T20:32:04.523Z,1557865924.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-14T20:32:04.524Z,1557865924.524 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-14T20:32:04.628Z,1557865924.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-14T20:32:04.629Z,1557865924.629 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-14T20:32:05.084Z,1557865925.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-14T20:32:05.085Z,1557865925.085 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-14T20:32:05.289Z,1557865925.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-14T20:32:05.290Z,1557865925.290 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-14T20:32:05.594Z,1557865925.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-14T20:32:05.595Z,1557865925.595 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-14T20:32:05.810Z,1557865925.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-14T20:32:05.811Z,1557865925.811 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-14T20:32:05.955Z,1557865925.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-14T20:32:05.956Z,1557865925.956 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-14T20:32:06.495Z,1557865926.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-14T20:32:06.496Z,1557865926.496 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-14T20:32:06.831Z,1557865926.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-14T20:32:06.831Z,1557865926.831 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-14T20:32:07.038Z,1557865927.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-14T20:32:07.040Z,1557865927.040 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-05-14T20:32:07.041Z,1557865927.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-05-14T20:32:07.230Z,1557865927.230 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-05-14T20:32:07.316Z,1557865927.316 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-05-14T20:32:07.435Z,1557865927.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-05-14T20:32:07.524Z,1557865927.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-05-14T20:32:07.753Z,1557865927.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-14T20:32:07.754Z,1557865927.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-05-14T20:32:07.851Z,1557865927.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-05-14T20:32:07.963Z,1557865927.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-05-14T20:32:08.059Z,1557865928.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-05-14T20:32:08.209Z,1557865928.209 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-05-14T20:32:08.312Z,1557865928.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-05-14T20:32:08.422Z,1557865928.422 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-05-14T20:32:08.422Z,1557865928.422 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-14T20:32:08.427Z,1557865928.427 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-14T20:32:08.440Z,1557865928.440 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-14T20:32:08.441Z,1557865928.441 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-14T20:32:08.504Z,1557865928.504 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-14T20:32:08.614Z,1557865928.614 [VerticalControl] Loaded
2019-05-14T20:32:08.614Z,1557865928.614 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-14T20:32:08.615Z,1557865928.615 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-14T20:32:08.681Z,1557865928.681 [HorizontalControl] Loaded
2019-05-14T20:32:08.682Z,1557865928.682 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-14T20:32:08.682Z,1557865928.682 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-14T20:32:08.687Z,1557865928.687 [SpeedControl] Loaded
2019-05-14T20:32:08.688Z,1557865928.688 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-14T20:32:08.688Z,1557865928.688 [LoopControl](DEBUG): Construct LoopControl.
2019-05-14T20:32:08.689Z,1557865928.689 [LoopControl] Loaded
2019-05-14T20:32:08.689Z,1557865928.689 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-14T20:32:08.690Z,1557865928.690 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-14T20:32:08.690Z,1557865928.690 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-14T20:32:08.951Z,1557865928.951 [BuoyancyServo] Loaded
2019-05-14T20:32:08.952Z,1557865928.952 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-14T20:32:08.964Z,1557865928.964 [ElevatorServo] Loaded
2019-05-14T20:32:08.965Z,1557865928.965 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-14T20:32:08.975Z,1557865928.975 [MassServo] Loaded
2019-05-14T20:32:08.975Z,1557865928.975 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-14T20:32:08.986Z,1557865928.986 [RudderServo] Loaded
2019-05-14T20:32:08.986Z,1557865928.986 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-14T20:32:08.997Z,1557865928.997 [ThrusterServo] Loaded
2019-05-14T20:32:08.997Z,1557865928.997 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-14T20:32:08.998Z,1557865928.998 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-14T20:32:08.998Z,1557865928.998 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-14T20:32:09.075Z,1557865929.075 [DepthRateCalculator] Loaded
2019-05-14T20:32:09.075Z,1557865929.075 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-14T20:32:09.080Z,1557865929.080 [PitchRateCalculator] Loaded
2019-05-14T20:32:09.081Z,1557865929.081 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-14T20:32:09.095Z,1557865929.095 [SpeedCalculator] Loaded
2019-05-14T20:32:09.095Z,1557865929.095 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-14T20:32:09.148Z,1557865929.148 [TempGradientCalculator] Loaded
2019-05-14T20:32:09.149Z,1557865929.149 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-14T20:32:09.154Z,1557865929.154 [YawRateCalculator] Loaded
2019-05-14T20:32:09.154Z,1557865929.154 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-14T20:32:09.187Z,1557865929.187 [ElevatorOffsetCalculator] Loaded
2019-05-14T20:32:09.188Z,1557865929.188 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-14T20:32:09.188Z,1557865929.188 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-14T20:32:09.189Z,1557865929.189 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-14T20:32:09.366Z,1557865929.366 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-14T20:32:09.366Z,1557865929.366 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-14T20:32:09.386Z,1557865929.386 [NavChart] Loaded
2019-05-14T20:32:09.386Z,1557865929.386 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-14T20:32:09.390Z,1557865929.390 [UniversalFixResidualReporter] Loaded
2019-05-14T20:32:09.390Z,1557865929.390 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-14T20:32:09.391Z,1557865929.391 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-14T20:32:09.391Z,1557865929.391 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-14T20:32:09.928Z,1557865929.928 [AHRS_M2] Loaded
2019-05-14T20:32:09.928Z,1557865929.928 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-05-14T20:32:10.015Z,1557865930.015 [DataOverHttps] Loaded
2019-05-14T20:32:10.015Z,1557865930.015 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-14T20:32:10.016Z,1557865930.016 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408044E0
2019-05-14T20:32:10.017Z,1557865930.017 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4310
2019-05-14T20:32:10.030Z,1557865930.030 [Depth_Keller] Loaded
2019-05-14T20:32:10.030Z,1557865930.030 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-14T20:32:10.035Z,1557865930.035 [DropWeight] Loaded
2019-05-14T20:32:10.035Z,1557865930.035 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-05-14T20:32:10.130Z,1557865930.130 [NAL9602] Loaded
2019-05-14T20:32:10.131Z,1557865930.131 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-14T20:32:10.146Z,1557865930.146 [Onboard] Loaded
2019-05-14T20:32:10.146Z,1557865930.146 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-14T20:32:10.149Z,1557865930.149 [Radio_Surface] Loaded
2019-05-14T20:32:10.150Z,1557865930.150 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-14T20:32:10.150Z,1557865930.150 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408344E0
2019-05-14T20:32:10.151Z,1557865930.151 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4311
2019-05-14T20:32:11.596Z,1557865931.596 [BPC1] Loaded
2019-05-14T20:32:11.596Z,1557865931.596 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-14T20:32:11.596Z,1557865931.596 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-14T20:32:11.597Z,1557865931.597 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-14T20:32:11.664Z,1557865931.664 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-14T20:32:11.664Z,1557865931.664 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-14T20:32:11.761Z,1557865931.761 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-14T20:32:11.761Z,1557865931.761 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-14T20:32:11.787Z,1557865931.787 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-14T20:32:11.788Z,1557865931.788 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-14T20:32:12.025Z,1557865932.025 [CTD_NeilBrown] Loaded
2019-05-14T20:32:12.025Z,1557865932.025 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-14T20:32:12.026Z,1557865932.026 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409C04E0
2019-05-14T20:32:12.027Z,1557865932.027 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4312
2019-05-14T20:32:12.036Z,1557865932.036 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-14T20:32:12.040Z,1557865932.040 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-14T20:32:12.042Z,1557865932.042 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-14T20:32:12.047Z,1557865932.047 [CTD_Seabird](INFO): created writer for : depth
2019-05-14T20:32:12.047Z,1557865932.047 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-14T20:32:12.052Z,1557865932.052 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-14T20:32:12.053Z,1557865932.053 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-14T20:32:12.057Z,1557865932.057 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-14T20:32:12.058Z,1557865932.058 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-14T20:32:12.063Z,1557865932.063 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-14T20:32:12.063Z,1557865932.063 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-14T20:32:12.068Z,1557865932.068 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-14T20:32:12.069Z,1557865932.069 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-14T20:32:12.074Z,1557865932.074 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-14T20:32:12.100Z,1557865932.100 [CTD_Seabird] Loaded
2019-05-14T20:32:12.100Z,1557865932.100 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-14T20:32:12.101Z,1557865932.101 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409F04E0
2019-05-14T20:32:12.101Z,1557865932.101 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4313
2019-05-14T20:32:12.115Z,1557865932.115 [PAR_Licor] Loaded
2019-05-14T20:32:12.116Z,1557865932.116 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-14T20:32:12.162Z,1557865932.162 [WetLabsBB2FL] Loaded
2019-05-14T20:32:12.162Z,1557865932.162 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-14T20:32:12.163Z,1557865932.163 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A204E0
2019-05-14T20:32:12.164Z,1557865932.164 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4314
2019-05-14T20:32:12.164Z,1557865932.164 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-14T20:32:12.165Z,1557865932.165 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-14T20:32:12.424Z,1557865932.424 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-14T20:32:12.424Z,1557865932.424 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-14T20:32:12.554Z,1557865932.554 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-14T20:32:12.564Z,1557865932.564 [SBIT] Loaded
2019-05-14T20:32:12.565Z,1557865932.565 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-14T20:32:12.565Z,1557865932.565 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-14T20:32:12.577Z,1557865932.577 [IBIT] Loaded
2019-05-14T20:32:12.577Z,1557865932.577 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-14T20:32:12.580Z,1557865932.580 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-14T20:32:12.717Z,1557865932.717 [CBIT] Loaded
2019-05-14T20:32:12.717Z,1557865932.717 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-14T20:32:12.718Z,1557865932.718 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-14T20:32:12.721Z,1557865932.721 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-14T20:32:12.722Z,1557865932.722 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-14T20:32:12.729Z,1557865932.729 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-14T20:32:12.730Z,1557865932.730 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF84E0
2019-05-14T20:32:12.730Z,1557865932.730 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4315
2019-05-14T20:32:12.735Z,1557865932.735 [Supervisor](INFO): Main Thread ID is 5615
2019-05-14T20:32:12.735Z,1557865932.735 [Supervisor](DEBUG): Running supervisor.
2019-05-14T20:32:12.736Z,1557865932.736 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4316
2019-05-14T20:32:12.738Z,1557865932.738 [controlThread ThreadHandler](INFO): Handler Thread ID is 4317
2019-05-14T20:32:12.738Z,1557865932.738 [controlThread](DEBUG): Initializing ControlThread
2019-05-14T20:32:12.739Z,1557865932.739 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-14T20:32:12.741Z,1557865932.741 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-14T20:32:12.741Z,1557865932.741 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-14T20:32:12.742Z,1557865932.742 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-14T20:32:12.743Z,1557865932.743 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-14T20:32:12.744Z,1557865932.744 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-14T20:32:12.744Z,1557865932.744 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-14T20:32:12.744Z,1557865932.744 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-14T20:32:12.745Z,1557865932.745 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-14T20:32:12.745Z,1557865932.745 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-14T20:32:12.746Z,1557865932.746 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-14T20:32:12.747Z,1557865932.747 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-14T20:32:12.754Z,1557865932.754 [SBIT](INFO): Initialize SBIT Component.
2019-05-14T20:32:12.755Z,1557865932.755 [SBIT](IMPORTANT): git: 2019-04-10-1-g18bae04
2019-05-14T20:32:12.755Z,1557865932.755 [SBIT](INFO): git hash: 18bae04951e434e73e54670b57def3d69c769fdd
2019-05-14T20:32:12.755Z,1557865932.755 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-14T20:32:12.756Z,1557865932.756 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-14T20:32:12.757Z,1557865932.757 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-05-14T20:32:12.758Z,1557865932.758 [IBIT](INFO): Initialize IBIT Component.
2019-05-14T20:32:12.759Z,1557865932.759 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-14T20:32:12.760Z,1557865932.760 [logger ThreadHandler](INFO): Handler Thread ID is 4318
2019-05-14T20:32:12.770Z,1557865932.770 [CBIT](DEBUG): Initialized mux pins.
2019-05-14T20:32:12.770Z,1557865932.770 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-14T20:32:12.778Z,1557865932.778 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4319
2019-05-14T20:32:12.779Z,1557865932.779 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-14T20:32:12.790Z,1557865932.790 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4320
2019-05-14T20:32:12.794Z,1557865932.794 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-14T20:32:12.794Z,1557865932.794 [CBIT](DEBUG): Initializing heartbeat.
2019-05-14T20:32:12.802Z,1557865932.802 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4321
2019-05-14T20:32:12.810Z,1557865932.810 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4322
2019-05-14T20:32:12.811Z,1557865932.811 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:32:12.814Z,1557865932.814 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:32:12.815Z,1557865932.815 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4324
2019-05-14T20:32:12.817Z,1557865932.817 [WetLabsBB2FL](INFO): Powering down
2019-05-14T20:32:12.842Z,1557865932.842 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4325
2019-05-14T20:32:12.848Z,1557865932.848 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-14T20:32:12.848Z,1557865932.848 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-14T20:32:12.848Z,1557865932.848 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-14T20:32:12.848Z,1557865932.848 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-14T20:32:12.849Z,1557865932.849 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-14T20:32:12.849Z,1557865932.849 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-14T20:32:12.849Z,1557865932.849 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-14T20:32:12.849Z,1557865932.849 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-14T20:32:12.849Z,1557865932.849 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-14T20:32:12.850Z,1557865932.850 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-14T20:32:12.850Z,1557865932.850 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-14T20:32:12.850Z,1557865932.850 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-14T20:32:12.850Z,1557865932.850 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-14T20:32:12.851Z,1557865932.851 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-14T20:32:12.851Z,1557865932.851 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-14T20:32:12.851Z,1557865932.851 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-14T20:32:12.866Z,1557865932.866 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-14T20:32:12.866Z,1557865932.866 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-14T20:32:12.902Z,1557865932.902 [CBIT](DEBUG): Backplane powered.
2019-05-14T20:32:12.903Z,1557865932.903 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-14T20:32:12.915Z,1557865932.915 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-14T20:32:12.958Z,1557865932.958 [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>


2019-05-14T20:32:12.959Z,1557865932.959 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-14T20:32:13.013Z,1557865933.013 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-14T20:32:13.029Z,1557865933.029 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-14T20:32:13.031Z,1557865933.031 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-14T20:32:13.072Z,1557865933.072 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-14T20:32:13.074Z,1557865933.074 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-14T20:32:13.097Z,1557865933.097 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-14T20:32:13.110Z,1557865933.110 [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>


2019-05-14T20:32:13.115Z,1557865933.115 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-14T20:32:13.126Z,1557865933.126 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:32:13.163Z,1557865933.163 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-05-14T20:32:13.194Z,1557865933.194 [Radio_Surface](INFO): Powering up
2019-05-14T20:32:13.274Z,1557865933.274 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-14T20:32:13.304Z,1557865933.304 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:32:13.310Z,1557865933.310 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-14T20:32:13.311Z,1557865933.311 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:32:13.318Z,1557865933.318 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-14T20:32:13.319Z,1557865933.319 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:32:13.326Z,1557865933.326 [MassServo](DEBUG): Initializing MassServo.
2019-05-14T20:32:13.327Z,1557865933.327 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:32:13.334Z,1557865933.334 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-14T20:32:13.339Z,1557865933.339 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:32:13.346Z,1557865933.346 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-14T20:32:13.540Z,1557865933.540 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-05-14T20:32:13.540Z,1557865933.540 [DropWeight] Hardware Fault, FailCount= 1
2019-05-14T20:32:13.540Z,1557865933.540 [DropWeight](ERROR): Hardware Fault
2019-05-14T20:32:13.606Z,1557865933.606 [CommandLine](FAULT): Scheduling is paused
2019-05-14T20:32:13.607Z,1557865933.607 [CBIT](INFO): Critical error at 20190514T203213
2019-05-14T20:32:13.607Z,1557865933.607 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-14T20:32:13.610Z,1557865933.610 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-05-14T20:32:13.611Z,1557865933.611 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-05-14T20:32:14.286Z,1557865934.286 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-14T20:32:14.286Z,1557865934.286 [RudderServo](FAULT): Rudder failed to initialize
2019-05-14T20:32:14.286Z,1557865934.286 [RudderServo] Communications Fault, FailCount= 1
2019-05-14T20:32:14.286Z,1557865934.286 [RudderServo](ERROR): Communications Fault
2019-05-14T20:32:14.395Z,1557865934.395 [CBIT](INFO): Critical error at 20190514T203213
2019-05-14T20:32:14.398Z,1557865934.398 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-14T20:32:14.599Z,1557865934.599 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-14T20:32:14.599Z,1557865934.599 [RudderServo](INFO): Powering down
2019-05-14T20:32:15.256Z,1557865935.256 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-14T20:32:15.375Z,1557865935.375 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-14T20:32:15.379Z,1557865935.379 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-14T20:32:15.379Z,1557865935.379 [RudderServo] No Fault, FailCount= 1
2019-05-14T20:32:16.746Z,1557865936.746 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:17.150Z,1557865937.150 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:17.674Z,1557865937.674 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:18.274Z,1557865938.274 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:18.722Z,1557865938.722 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:19.218Z,1557865939.218 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:20.010Z,1557865940.010 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:20.458Z,1557865940.458 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:20.880Z,1557865940.880 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:21.294Z,1557865941.294 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:21.738Z,1557865941.738 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:22.174Z,1557865942.174 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:22.586Z,1557865942.586 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:23.094Z,1557865943.094 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:23.502Z,1557865943.502 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:23.906Z,1557865943.906 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:24.298Z,1557865944.298 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:24.702Z,1557865944.702 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:25.102Z,1557865945.102 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:25.506Z,1557865945.506 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:25.862Z,1557865945.862 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:32:25.862Z,1557865945.862 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-14T20:32:25.862Z,1557865945.862 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:32:25.930Z,1557865945.930 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:25.982Z,1557865945.982 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:32:26.266Z,1557865946.266 [CTD_Seabird](INFO): Powering down
2019-05-14T20:32:26.322Z,1557865946.322 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:26.322Z,1557865946.322 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:32:26.322Z,1557865946.322 [AHRS_M2] Communications Fault, FailCount= 1
2019-05-14T20:32:26.322Z,1557865946.322 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:32:26.390Z,1557865946.390 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:32:26.414Z,1557865946.414 [AHRS_M2](INFO): Powering down
2019-05-14T20:32:27.289Z,1557865947.289 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-14T20:32:27.290Z,1557865947.290 [AHRS_M2] No Fault, FailCount= 1
2019-05-14T20:32:27.290Z,1557865947.290 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-14T20:32:27.290Z,1557865947.290 [CTD_Seabird] No Fault, FailCount= 1
2019-05-14T20:32:27.618Z,1557865947.618 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:32:27.626Z,1557865947.626 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:32:27.631Z,1557865947.631 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:32:31.170Z,1557865951.170 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:31.578Z,1557865951.578 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:31.982Z,1557865951.982 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:32.382Z,1557865952.382 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:32.782Z,1557865952.782 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:33.190Z,1557865953.190 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:33.582Z,1557865953.582 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:34.002Z,1557865954.002 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:34.406Z,1557865954.406 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:34.830Z,1557865954.830 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:35.246Z,1557865955.246 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:35.626Z,1557865955.626 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:36.030Z,1557865956.030 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:36.442Z,1557865956.442 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:36.850Z,1557865956.850 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:37.270Z,1557865957.270 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:37.670Z,1557865957.670 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:38.086Z,1557865958.086 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:38.474Z,1557865958.474 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:38.898Z,1557865958.898 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:39.314Z,1557865959.314 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:39.734Z,1557865959.734 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:40.150Z,1557865960.150 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:40.155Z,1557865960.155 [NAL9602](INFO): Powering up NAL9602
2019-05-14T20:32:40.578Z,1557865960.578 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:40.578Z,1557865960.578 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:32:40.578Z,1557865960.578 [AHRS_M2] Communications Fault, FailCount= 2
2019-05-14T20:32:40.578Z,1557865960.578 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:32:40.667Z,1557865960.667 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:32:40.673Z,1557865960.673 [AHRS_M2](INFO): Powering down
2019-05-14T20:32:40.679Z,1557865960.679 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:32:40.682Z,1557865960.682 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-14T20:32:40.682Z,1557865960.682 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:32:40.830Z,1557865960.830 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:32:41.088Z,1557865961.088 [CTD_Seabird](INFO): Powering down
2019-05-14T20:32:41.119Z,1557865961.119 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-14T20:32:41.123Z,1557865961.123 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-14T20:32:41.591Z,1557865961.591 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-14T20:32:41.591Z,1557865961.591 [AHRS_M2] No Fault, FailCount= 2
2019-05-14T20:32:41.882Z,1557865961.882 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:32:42.402Z,1557865962.402 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-14T20:32:42.402Z,1557865962.402 [CTD_Seabird] No Fault, FailCount= 2
2019-05-14T20:32:42.434Z,1557865962.434 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:32:42.438Z,1557865962.438 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:32:45.530Z,1557865965.530 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:46.086Z,1557865966.086 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:46.654Z,1557865966.654 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:47.186Z,1557865967.186 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:47.642Z,1557865967.642 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:48.078Z,1557865968.078 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:48.502Z,1557865968.502 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:49.038Z,1557865969.038 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:49.478Z,1557865969.478 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:49.906Z,1557865969.906 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:50.406Z,1557865970.406 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:50.834Z,1557865970.834 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:51.282Z,1557865971.282 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:51.287Z,1557865971.287 [NAL9602](INFO): NAL9602 initialized
2019-05-14T20:32:51.380Z,1557865971.380 [CommandLine](IMPORTANT): got command failComponent
2019-05-14T20:32:51.380Z,1557865971.380 [CommandLine](IMPORTANT): Failed components:
2019-05-14T20:32:51.380Z,1557865971.380 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2019-05-14T20:32:51.846Z,1557865971.846 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:52.402Z,1557865972.402 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:52.422Z,1557865972.422 [NAL9602](DEBUG): Fix Requested
2019-05-14T20:32:52.802Z,1557865972.802 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:53.290Z,1557865973.290 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:53.410Z,1557865973.410 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.078828
CHAN A1 (24V): 0.149822
CHAN A2 (12V): 0.062372
CHAN A3 (5V): 0.008208
CHAN B0 (3.3V): -0.002522
CHAN B1 (3.15aV): -0.003910
CHAN B2 (3.15bV): -0.004715
CHAN B3 (GND): -0.028433
OPEN: 0.004540
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-14T20:32:53.594Z,1557865973.594 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:54.142Z,1557865974.142 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:54.546Z,1557865974.546 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:54.958Z,1557865974.958 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:32:54.958Z,1557865974.958 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:32:54.958Z,1557865974.958 [AHRS_M2] Communications Fault, FailCount= 3
2019-05-14T20:32:54.958Z,1557865974.958 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:32:55.072Z,1557865975.072 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:32:55.077Z,1557865975.077 [AHRS_M2](INFO): Powering down
2019-05-14T20:32:55.491Z,1557865975.491 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:32:55.491Z,1557865975.491 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-14T20:32:55.491Z,1557865975.491 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:32:55.563Z,1557865975.563 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:32:55.564Z,1557865975.564 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-14T20:32:55.895Z,1557865975.895 [CTD_Seabird](INFO): Powering down
2019-05-14T20:32:56.106Z,1557865976.106 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-14T20:32:56.106Z,1557865976.106 [AHRS_M2] No Fault, FailCount= 3
2019-05-14T20:32:56.286Z,1557865976.286 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:32:59.938Z,1557865979.938 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:00.446Z,1557865980.446 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:00.926Z,1557865980.926 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:01.478Z,1557865981.478 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:02.154Z,1557865982.154 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:02.570Z,1557865982.570 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:02.978Z,1557865982.978 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:03.402Z,1557865983.402 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:03.802Z,1557865983.802 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:04.202Z,1557865984.202 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:04.614Z,1557865984.614 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:05.026Z,1557865985.026 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:05.454Z,1557865985.454 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:05.882Z,1557865985.882 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:06.306Z,1557865986.306 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:06.726Z,1557865986.726 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:07.146Z,1557865987.146 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:07.558Z,1557865987.558 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:07.970Z,1557865987.970 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:08.390Z,1557865988.390 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:08.806Z,1557865988.806 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:09.218Z,1557865989.218 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:09.218Z,1557865989.218 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:33:09.218Z,1557865989.218 [AHRS_M2] Communications Fault, FailCount= 4
2019-05-14T20:33:09.218Z,1557865989.218 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:33:09.323Z,1557865989.323 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:33:09.328Z,1557865989.328 [AHRS_M2](INFO): Powering down
2019-05-14T20:33:10.231Z,1557865990.231 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-14T20:33:10.231Z,1557865990.231 [AHRS_M2] No Fault, FailCount= 4
2019-05-14T20:33:10.538Z,1557865990.538 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-14T20:33:14.094Z,1557865994.094 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:14.490Z,1557865994.490 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:14.906Z,1557865994.906 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:15.326Z,1557865995.326 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:15.738Z,1557865995.738 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:16.138Z,1557865996.138 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:16.518Z,1557865996.518 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:16.922Z,1557865996.922 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:17.326Z,1557865997.326 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:17.738Z,1557865997.738 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:18.134Z,1557865998.134 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:18.546Z,1557865998.546 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:18.958Z,1557865998.958 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:19.382Z,1557865999.382 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:19.758Z,1557865999.758 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:20.162Z,1557866000.162 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:20.570Z,1557866000.570 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:20.982Z,1557866000.982 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:21.390Z,1557866001.390 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:21.802Z,1557866001.802 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:22.194Z,1557866002.194 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:22.614Z,1557866002.614 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:23.158Z,1557866003.158 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:23.966Z,1557866003.966 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-05-14T20:33:23.966Z,1557866003.966 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-05-14T20:33:23.966Z,1557866003.966 [AHRS_M2] Communications Fault, FailCount= 5
2019-05-14T20:33:23.966Z,1557866003.966 [AHRS_M2](ERROR): Communications Fault
2019-05-14T20:33:24.099Z,1557866004.099 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-05-14T20:33:24.099Z,1557866004.099 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-05-14T20:33:24.105Z,1557866004.105 [AHRS_M2](INFO): Powering down
2019-05-14T20:33:24.316Z,1557866004.316 [CBIT](INFO): Critical error at 20190514T203324
2019-05-14T20:33:34.660Z,1557866014.660 [SBIT](IMPORTANT): SBIT PASSED
2019-05-14T20:33:34.699Z,1557866014.699 [CommandLine](IMPORTANT): got command configSet list
2019-05-14T20:33:34.699Z,1557866014.699 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-14T20:33:34.700Z,1557866014.700 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-05-14T20:33:35.074Z,1557866015.074 [MissionManager](IMPORTANT): Started mission Startup
2019-05-14T20:33:35.075Z,1557866015.075 [Startup] Running Loop=1
2019-05-14T20:33:35.075Z,1557866015.075 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-14T20:33:35.075Z,1557866015.075 [Startup:A.GoToSurface] Running Loop=1
2019-05-14T20:33:35.075Z,1557866015.075 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-14T20:33:35.076Z,1557866015.076 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-14T20:33:35.076Z,1557866015.076 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-14T20:33:35.076Z,1557866015.076 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-14T20:33:35.077Z,1557866015.077 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-14T20:33:35.077Z,1557866015.077 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-14T20:33:35.079Z,1557866015.079 [Startup:StartupSatComms] Running Loop=1
2019-05-14T20:33:35.079Z,1557866015.079 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-14T20:33:35.079Z,1557866015.079 [Startup:StartupSatComms:A] Running Loop=1
2019-05-14T20:33:35.449Z,1557866015.449 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-14T20:34:13.033Z,1557866053.033 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-14T20:34:13.033Z,1557866053.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-14T20:34:13.033Z,1557866053.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-14T20:34:13.045Z,1557866053.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-14T20:34:13.439Z,1557866053.439 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-14T20:34:13.439Z,1557866053.439 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-14T20:34:35.266Z,1557866075.266 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-14T20:33:35.1Z
2019-05-14T20:34:35.266Z,1557866075.266 [Startup:StartupSatComms:A] Stopped
2019-05-14T20:34:35.266Z,1557866075.266 [Startup:StartupSatComms:B] Running Loop=1
2019-05-14T20:34:35.651Z,1557866075.651 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-14T20:34:38.780Z,1557866078.780 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004785
2019-05-14T20:34:44.654Z,1557866084.654 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20190514T180300/Courier0043.lzma
2019-05-14T20:34:45.460Z,1557866085.460 [DataOverHttps](INFO): Moved sent file to Logs/20190514T180300/Courier0043.lzma.bak
2019-05-14T20:34:45.460Z,1557866085.460 [DataOverHttps](INFO): SBD MOMSN=11055774
2019-05-14T20:35:07.254Z,1557866107.254 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20190514T203201/Courier0000.lzma
2019-05-14T20:35:08.059Z,1557866108.059 [DataOverHttps](INFO): Moved sent file to Logs/20190514T203201/Courier0000.lzma.bak
2019-05-14T20:35:08.059Z,1557866108.059 [DataOverHttps](INFO): SBD MOMSN=11055777
2019-05-14T20:35:08.507Z,1557866108.507 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-05-14T20:35:25.571Z,1557866125.571 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-14T20:35:25.571Z,1557866125.571 [CTD_Seabird] No Fault, FailCount= 3
2019-05-14T20:35:25.942Z,1557866125.942 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:35:25.944Z,1557866125.944 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:35:35.268Z,1557866135.268 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-14T20:34:35.3Z
2019-05-14T20:35:35.268Z,1557866135.268 [Startup:StartupSatComms:B] Stopped
2019-05-14T20:35:35.268Z,1557866135.268 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-14T20:35:35.268Z,1557866135.268 [Startup:StartupSatComms] Stopped
2019-05-14T20:35:35.268Z,1557866135.268 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-14T20:35:35.269Z,1557866135.269 [Startup](INFO): Completed Startup
2019-05-14T20:35:35.269Z,1557866135.269 [MissionManager](INFO): Startup is completed.
2019-05-14T20:35:35.282Z,1557866135.282 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-14T20:35:35.282Z,1557866135.282 [Startup] Stopped
2019-05-14T20:35:35.282Z,1557866135.282 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-14T20:35:35.282Z,1557866135.282 [Startup:A.GoToSurface] Stopped
2019-05-14T20:35:35.282Z,1557866135.282 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-14T20:35:35.511Z,1557866135.511 [MissionManager](IMPORTANT): Started mission Default
2019-05-14T20:35:35.511Z,1557866135.511 [Default] Running Loop=1
2019-05-14T20:35:35.511Z,1557866135.511 [Default](DEBUG): Aggregate::initialize Default
2019-05-14T20:35:35.511Z,1557866135.511 [Default:B.GoToSurface] Running Loop=1
2019-05-14T20:35:35.511Z,1557866135.511 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-14T20:35:35.511Z,1557866135.511 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-14T20:35:35.512Z,1557866135.512 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-14T20:35:35.512Z,1557866135.512 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-14T20:35:35.512Z,1557866135.512 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-14T20:35:35.513Z,1557866135.513 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-14T20:35:35.513Z,1557866135.513 [Default:A.Wait] Running Loop=1
2019-05-14T20:35:35.513Z,1557866135.513 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-14T20:35:38.994Z,1557866138.994 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:35:38.994Z,1557866138.994 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-14T20:35:38.994Z,1557866138.994 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:35:39.031Z,1557866139.031 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:35:39.398Z,1557866139.398 [CTD_Seabird](INFO): Powering down
2019-05-14T20:35:40.627Z,1557866140.627 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-14T20:35:40.627Z,1557866140.627 [CTD_Seabird] No Fault, FailCount= 1
2019-05-14T20:35:40.738Z,1557866140.738 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:35:40.746Z,1557866140.746 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:35:41.713Z,1557866141.713 [DataOverHttps](INFO): Sending 744 bytes from file Logs/20190514T180300/Express0044.lzma
2019-05-14T20:35:42.515Z,1557866142.515 [DataOverHttps](INFO): Moved sent file to Logs/20190514T180300/Express0044.lzma.bak
2019-05-14T20:35:42.516Z,1557866142.516 [DataOverHttps](INFO): SBD MOMSN=11055783
2019-05-14T20:35:48.742Z,1557866148.742 [Default:A.Wait](INFO): Done Waiting.
2019-05-14T20:35:48.742Z,1557866148.742 [Default:A.Wait] Stopped
2019-05-14T20:35:48.742Z,1557866148.742 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-14T20:35:49.418Z,1557866149.418 [Default:CheckIn] Running Loop=1
2019-05-14T20:35:49.418Z,1557866149.418 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-14T20:35:49.418Z,1557866149.418 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-14T20:35:49.600Z,1557866149.600 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-14T20:35:53.794Z,1557866153.794 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:35:53.794Z,1557866153.794 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-14T20:35:53.794Z,1557866153.794 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:35:54.028Z,1557866154.028 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:35:54.198Z,1557866154.198 [CTD_Seabird](INFO): Powering down
2019-05-14T20:35:55.247Z,1557866155.247 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-14T20:35:55.247Z,1557866155.247 [CTD_Seabird] No Fault, FailCount= 2
2019-05-14T20:35:55.546Z,1557866155.546 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-14T20:35:55.552Z,1557866155.552 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-14T20:36:08.630Z,1557866168.630 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-14T20:36:08.630Z,1557866168.630 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-14T20:36:08.630Z,1557866168.630 [CTD_Seabird](ERROR): Communications Fault
2019-05-14T20:36:08.964Z,1557866168.964 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-14T20:36:08.965Z,1557866168.965 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-14T20:36:09.034Z,1557866169.034 [CTD_Seabird](INFO): Powering down
2019-05-14T20:36:14.167Z,1557866174.167 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-14T20:36:14.167Z,1557866174.167 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-14T20:36:14.167Z,1557866174.167 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-14T20:36:14.178Z,1557866174.178 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-14T20:36:14.597Z,1557866174.597 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-14T20:36:14.597Z,1557866174.597 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-14T20:36:17.447Z,1557866177.447 [CommandLine](IMPORTANT): got command restart application
2019-05-14T20:36:18.454Z,1557866178.454 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:18.454Z,1557866178.454 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:18.642Z,1557866178.642 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-14T20:36:18.642Z,1557866178.642 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:18.642Z,1557866178.642 [CommandLine](INFO): Join timeout helper Thread ID is 4358
2019-05-14T20:36:18.643Z,1557866178.643 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-14T20:36:18.643Z,1557866178.643 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:18.643Z,1557866178.643 [NavChartDb](INFO): Join timeout helper Thread ID is 4359
2019-05-14T20:36:18.690Z,1557866178.690 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:18.690Z,1557866178.690 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:18.691Z,1557866178.691 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-14T20:36:18.691Z,1557866178.691 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:18.691Z,1557866178.691 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4360
2019-05-14T20:36:18.858Z,1557866178.858 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:18.858Z,1557866178.858 [WetLabsBB2FL](INFO): Powering down
2019-05-14T20:36:18.859Z,1557866178.859 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:18.866Z,1557866178.866 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-14T20:36:18.866Z,1557866178.866 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:18.866Z,1557866178.866 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4361
2019-05-14T20:36:19.266Z,1557866179.266 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:19.266Z,1557866179.266 [CTD_Seabird](INFO): Powering down
2019-05-14T20:36:19.278Z,1557866179.278 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:19.294Z,1557866179.294 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-14T20:36:19.294Z,1557866179.294 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:19.294Z,1557866179.294 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4362
2019-05-14T20:36:19.486Z,1557866179.486 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:19.486Z,1557866179.486 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:19.491Z,1557866179.491 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-14T20:36:19.491Z,1557866179.491 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:19.491Z,1557866179.491 [Radio_Surface](INFO): Join timeout helper Thread ID is 4363
2019-05-14T20:36:19.690Z,1557866179.690 [Radio_Surface](INFO): Powering down
2019-05-14T20:36:19.691Z,1557866179.691 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:19.691Z,1557866179.691 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:19.702Z,1557866179.702 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-14T20:36:19.703Z,1557866179.703 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:19.703Z,1557866179.703 [DataOverHttps](INFO): Join timeout helper Thread ID is 4364
2019-05-14T20:36:21.953Z,1557866181.953 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:21.957Z,1557866181.957 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:21.966Z,1557866181.966 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-14T20:36:21.967Z,1557866181.967 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:21.967Z,1557866181.967 [logger](INFO): Join timeout helper Thread ID is 4365
2019-05-14T20:36:21.998Z,1557866181.998 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:21.998Z,1557866181.998 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.006Z,1557866182.006 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-14T20:36:22.007Z,1557866182.007 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.007Z,1557866182.007 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-14T20:36:22.007Z,1557866182.007 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.007Z,1557866182.007 [controlThread](INFO): Join timeout helper Thread ID is 4366
2019-05-14T20:36:22.210Z,1557866182.210 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-14T20:36:22.210Z,1557866182.210 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-14T20:36:22.210Z,1557866182.210 [AHRS_M2](INFO): Powering down
2019-05-14T20:36:22.212Z,1557866182.212 [NAL9602](INFO): Powering down
2019-05-14T20:36:22.214Z,1557866182.214 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-14T20:36:22.215Z,1557866182.215 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-14T20:36:22.215Z,1557866182.215 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-14T20:36:22.215Z,1557866182.215 [MissionManager](INFO): Uninitializing Mission Default
2019-05-14T20:36:22.216Z,1557866182.216 [Default] Stopped
2019-05-14T20:36:22.216Z,1557866182.216 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-14T20:36:22.216Z,1557866182.216 [Default:B.GoToSurface] Stopped
2019-05-14T20:36:22.216Z,1557866182.216 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-14T20:36:22.216Z,1557866182.216 [Default:CheckIn] Stopped
2019-05-14T20:36:22.216Z,1557866182.216 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-14T20:36:22.216Z,1557866182.216 [Default:CheckIn:Read_GPS] Stopped
2019-05-14T20:36:22.218Z,1557866182.218 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-14T20:36:22.219Z,1557866182.219 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-14T20:36:22.219Z,1557866182.219 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-14T20:36:22.219Z,1557866182.219 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-14T20:36:22.219Z,1557866182.219 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-14T20:36:22.219Z,1557866182.219 [BuoyancyServo](INFO): Powering down
2019-05-14T20:36:22.234Z,1557866182.234 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-14T20:36:22.234Z,1557866182.234 [ElevatorServo](INFO): Powering down
2019-05-14T20:36:22.235Z,1557866182.235 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-14T20:36:22.235Z,1557866182.235 [MassServo](INFO): Powering down
2019-05-14T20:36:22.236Z,1557866182.236 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-14T20:36:22.236Z,1557866182.236 [RudderServo](INFO): Powering down
2019-05-14T20:36:22.236Z,1557866182.236 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-14T20:36:22.237Z,1557866182.237 [ThrusterServo](INFO): Powering down
2019-05-14T20:36:22.237Z,1557866182.237 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-14T20:36:22.238Z,1557866182.238 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-14T20:36:22.238Z,1557866182.238 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-14T20:36:22.238Z,1557866182.238 [CBIT](DEBUG): Powering off loads.
2019-05-14T20:36:22.250Z,1557866182.250 [CBIT](DEBUG): Disabling WDT.
2019-05-14T20:36:22.262Z,1557866182.262 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-14T20:36:22.262Z,1557866182.262 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.336Z,1557866182.336 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.342Z,1557866182.342 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.384Z,1557866182.384 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.387Z,1557866182.387 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.390Z,1557866182.390 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.428Z,1557866182.428 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-14T20:36:22.484Z,1557866182.484 [logger ThreadHandler](INFO): Thread cancelled.