2019-06-12T18:32:43.103Z,1560364363.103 [Supervisor](DEBUG): Initializing supervisor.
2019-06-12T18:32:43.106Z,1560364363.106 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-06-12T18:32:43.107Z,1560364363.107 [SyncHandler](INFO): Protected caller Thread ID is 2410
2019-06-12T18:32:43.108Z,1560364363.108 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-06-12T18:32:43.109Z,1560364363.109 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-06-12T18:32:43.109Z,1560364363.109 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2411
2019-06-12T18:32:43.114Z,1560364363.114 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-06-12T18:32:43.126Z,1560364363.126 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-06-12T18:32:43.127Z,1560364363.127 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-06-12T18:32:43.127Z,1560364363.127 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2412
2019-06-12T18:32:43.128Z,1560364363.128 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-06-12T18:32:43.129Z,1560364363.129 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-06-12T18:32:43.129Z,1560364363.129 [logger ThreadHandler](INFO): Protected caller Thread ID is 2413
2019-06-12T18:32:43.131Z,1560364363.131 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-06-12T18:32:43.132Z,1560364363.132 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-06-12T18:32:43.133Z,1560364363.133 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-06-12T18:32:43.623Z,1560364363.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-06-12T18:32:43.623Z,1560364363.623 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-06-12T18:32:43.768Z,1560364363.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-06-12T18:32:43.769Z,1560364363.769 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-06-12T18:32:43.850Z,1560364363.850 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-06-12T18:32:43.948Z,1560364363.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-06-12T18:32:43.949Z,1560364363.949 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-06-12T18:32:44.045Z,1560364364.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-06-12T18:32:44.046Z,1560364364.046 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-06-12T18:32:44.425Z,1560364364.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-06-12T18:32:44.425Z,1560364364.425 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-06-12T18:32:44.529Z,1560364364.529 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-06-12T18:32:44.529Z,1560364364.529 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-06-12T18:32:44.988Z,1560364364.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-06-12T18:32:44.989Z,1560364364.989 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-06-12T18:32:45.192Z,1560364365.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-06-12T18:32:45.193Z,1560364365.193 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-06-12T18:32:45.498Z,1560364365.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-06-12T18:32:45.499Z,1560364365.499 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-06-12T18:32:45.712Z,1560364365.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-06-12T18:32:45.713Z,1560364365.713 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-06-12T18:32:45.856Z,1560364365.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-06-12T18:32:45.856Z,1560364365.856 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-06-12T18:32:46.435Z,1560364366.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-06-12T18:32:46.436Z,1560364366.436 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-06-12T18:32:46.771Z,1560364366.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-06-12T18:32:46.772Z,1560364366.772 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-06-12T18:32:46.985Z,1560364366.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-06-12T18:32:46.988Z,1560364366.988 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-06-12T18:32:46.989Z,1560364366.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-06-12T18:32:47.529Z,1560364367.529 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-06-12T18:32:47.842Z,1560364367.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-06-12T18:32:48.158Z,1560364368.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-06-12T18:32:48.245Z,1560364368.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-06-12T18:32:48.473Z,1560364368.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-06-12T18:32:48.474Z,1560364368.474 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-06-12T18:32:48.570Z,1560364368.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-06-12T18:32:48.679Z,1560364368.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-06-12T18:32:48.774Z,1560364368.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-06-12T18:32:48.922Z,1560364368.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-06-12T18:32:49.022Z,1560364369.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-06-12T18:32:49.123Z,1560364369.123 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-06-12T18:32:49.123Z,1560364369.123 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-06-12T18:32:49.129Z,1560364369.129 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-06-12T18:32:49.153Z,1560364369.153 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-06-12T18:32:49.154Z,1560364369.154 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-06-12T18:32:49.239Z,1560364369.239 [VerticalControl](DEBUG): Construct VerticalControl.
2019-06-12T18:32:49.353Z,1560364369.353 [VerticalControl] Loaded
2019-06-12T18:32:49.353Z,1560364369.353 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-06-12T18:32:49.354Z,1560364369.354 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-06-12T18:32:49.423Z,1560364369.423 [HorizontalControl] Loaded
2019-06-12T18:32:49.423Z,1560364369.423 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-06-12T18:32:49.424Z,1560364369.424 [SpeedControl](DEBUG): Construct SpeedControl.
2019-06-12T18:32:49.429Z,1560364369.429 [SpeedControl] Loaded
2019-06-12T18:32:49.429Z,1560364369.429 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-06-12T18:32:49.430Z,1560364369.430 [LoopControl](DEBUG): Construct LoopControl.
2019-06-12T18:32:49.431Z,1560364369.431 [LoopControl] Loaded
2019-06-12T18:32:49.431Z,1560364369.431 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-06-12T18:32:49.431Z,1560364369.431 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-06-12T18:32:49.432Z,1560364369.432 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-06-12T18:32:49.587Z,1560364369.587 [BuoyancyServo] Loaded
2019-06-12T18:32:49.587Z,1560364369.587 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-06-12T18:32:49.598Z,1560364369.598 [ElevatorServo] Loaded
2019-06-12T18:32:49.599Z,1560364369.599 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-06-12T18:32:49.609Z,1560364369.609 [MassServo] Loaded
2019-06-12T18:32:49.610Z,1560364369.610 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-06-12T18:32:49.620Z,1560364369.620 [RudderServo] Loaded
2019-06-12T18:32:49.621Z,1560364369.621 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-06-12T18:32:49.631Z,1560364369.631 [ThrusterServo] Loaded
2019-06-12T18:32:49.632Z,1560364369.632 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-06-12T18:32:49.632Z,1560364369.632 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-06-12T18:32:49.633Z,1560364369.633 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-06-12T18:32:49.687Z,1560364369.687 [DepthRateCalculator] Loaded
2019-06-12T18:32:49.687Z,1560364369.687 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-06-12T18:32:49.693Z,1560364369.693 [PitchRateCalculator] Loaded
2019-06-12T18:32:49.693Z,1560364369.693 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-06-12T18:32:49.708Z,1560364369.708 [SpeedCalculator] Loaded
2019-06-12T18:32:49.708Z,1560364369.708 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-06-12T18:32:49.729Z,1560364369.729 [TempGradientCalculator] Loaded
2019-06-12T18:32:49.730Z,1560364369.730 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-06-12T18:32:49.735Z,1560364369.735 [YawRateCalculator] Loaded
2019-06-12T18:32:49.735Z,1560364369.735 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-06-12T18:32:49.764Z,1560364369.764 [ElevatorOffsetCalculator] Loaded
2019-06-12T18:32:49.764Z,1560364369.764 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-06-12T18:32:49.764Z,1560364369.764 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-06-12T18:32:49.765Z,1560364369.765 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-06-12T18:32:49.886Z,1560364369.886 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-06-12T18:32:49.887Z,1560364369.887 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-06-12T18:32:49.907Z,1560364369.907 [NavChart] Loaded
2019-06-12T18:32:49.907Z,1560364369.907 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-06-12T18:32:49.911Z,1560364369.911 [UniversalFixResidualReporter] Loaded
2019-06-12T18:32:49.912Z,1560364369.912 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-06-12T18:32:49.912Z,1560364369.912 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-06-12T18:32:49.913Z,1560364369.913 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-06-12T18:32:50.352Z,1560364370.352 [AHRS_M2] Loaded
2019-06-12T18:32:50.352Z,1560364370.352 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-06-12T18:32:50.430Z,1560364370.430 [DataOverHttps] Loaded
2019-06-12T18:32:50.430Z,1560364370.430 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-06-12T18:32:50.431Z,1560364370.431 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408084E0
2019-06-12T18:32:50.431Z,1560364370.431 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2492
2019-06-12T18:32:50.445Z,1560364370.445 [Depth_Keller] Loaded
2019-06-12T18:32:50.445Z,1560364370.445 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-06-12T18:32:50.450Z,1560364370.450 [DropWeight] Loaded
2019-06-12T18:32:50.450Z,1560364370.450 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-06-12T18:32:50.548Z,1560364370.548 [NAL9602] Loaded
2019-06-12T18:32:50.548Z,1560364370.548 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-06-12T18:32:50.564Z,1560364370.564 [Onboard] Loaded
2019-06-12T18:32:50.564Z,1560364370.564 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-06-12T18:32:50.567Z,1560364370.567 [Radio_Surface] Loaded
2019-06-12T18:32:50.568Z,1560364370.568 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-06-12T18:32:50.569Z,1560364370.569 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408384E0
2019-06-12T18:32:50.569Z,1560364370.569 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2493
2019-06-12T18:32:52.061Z,1560364372.061 [BPC1] Loaded
2019-06-12T18:32:52.062Z,1560364372.062 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-06-12T18:32:52.062Z,1560364372.062 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-06-12T18:32:52.063Z,1560364372.063 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-06-12T18:32:52.469Z,1560364372.469 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-06-12T18:32:52.470Z,1560364372.470 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-06-12T18:32:52.747Z,1560364372.747 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-06-12T18:32:52.748Z,1560364372.748 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-06-12T18:32:52.788Z,1560364372.788 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-06-12T18:32:52.789Z,1560364372.789 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-06-12T18:32:53.034Z,1560364373.034 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-06-12T18:32:53.039Z,1560364373.039 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-06-12T18:32:53.041Z,1560364373.041 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-06-12T18:32:53.046Z,1560364373.046 [CTD_Seabird](INFO): created writer for : depth
2019-06-12T18:32:53.047Z,1560364373.047 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-06-12T18:32:53.052Z,1560364373.052 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-06-12T18:32:53.052Z,1560364373.052 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-06-12T18:32:53.058Z,1560364373.058 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-06-12T18:32:53.058Z,1560364373.058 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-06-12T18:32:53.063Z,1560364373.063 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-06-12T18:32:53.064Z,1560364373.064 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-06-12T18:32:53.070Z,1560364373.070 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-06-12T18:32:53.070Z,1560364373.070 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-06-12T18:32:53.075Z,1560364373.075 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-06-12T18:32:53.103Z,1560364373.103 [CTD_Seabird] Loaded
2019-06-12T18:32:53.103Z,1560364373.103 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-06-12T18:32:53.104Z,1560364373.104 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409C44E0
2019-06-12T18:32:53.105Z,1560364373.105 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2494
2019-06-12T18:32:53.119Z,1560364373.119 [PAR_Licor] Loaded
2019-06-12T18:32:53.119Z,1560364373.119 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-06-12T18:32:53.167Z,1560364373.167 [WetLabsBB2FL] Loaded
2019-06-12T18:32:53.168Z,1560364373.168 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-06-12T18:32:53.169Z,1560364373.169 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409F44E0
2019-06-12T18:32:53.169Z,1560364373.169 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2495
2019-06-12T18:32:53.170Z,1560364373.170 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-06-12T18:32:53.170Z,1560364373.170 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-06-12T18:32:53.508Z,1560364373.508 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-06-12T18:32:53.509Z,1560364373.509 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-06-12T18:32:53.666Z,1560364373.666 [SBIT](DEBUG): Construct Startup Built In Test.
2019-06-12T18:32:53.677Z,1560364373.677 [SBIT] Loaded
2019-06-12T18:32:53.678Z,1560364373.678 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-06-12T18:32:53.678Z,1560364373.678 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-06-12T18:32:53.690Z,1560364373.690 [IBIT] Loaded
2019-06-12T18:32:53.690Z,1560364373.690 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-06-12T18:32:53.693Z,1560364373.693 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-06-12T18:32:53.833Z,1560364373.833 [CBIT] Loaded
2019-06-12T18:32:53.833Z,1560364373.833 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-06-12T18:32:53.834Z,1560364373.834 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-06-12T18:32:53.838Z,1560364373.838 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-06-12T18:32:53.839Z,1560364373.839 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-06-12T18:32:53.846Z,1560364373.846 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-06-12T18:32:53.847Z,1560364373.847 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0
2019-06-12T18:32:53.847Z,1560364373.847 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2496
2019-06-12T18:32:53.852Z,1560364373.852 [Supervisor](INFO): Main Thread ID is 8684
2019-06-12T18:32:53.852Z,1560364373.852 [Supervisor](DEBUG): Running supervisor.
2019-06-12T18:32:53.853Z,1560364373.853 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2497
2019-06-12T18:32:53.855Z,1560364373.855 [controlThread ThreadHandler](INFO): Handler Thread ID is 2498
2019-06-12T18:32:53.856Z,1560364373.856 [controlThread](DEBUG): Initializing ControlThread
2019-06-12T18:32:53.856Z,1560364373.856 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-06-12T18:32:53.858Z,1560364373.858 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-06-12T18:32:53.859Z,1560364373.859 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-06-12T18:32:53.859Z,1560364373.859 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-06-12T18:32:53.861Z,1560364373.861 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-06-12T18:32:53.861Z,1560364373.861 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-06-12T18:32:53.861Z,1560364373.861 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-06-12T18:32:53.861Z,1560364373.861 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-06-12T18:32:53.862Z,1560364373.862 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-06-12T18:32:53.862Z,1560364373.862 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-06-12T18:32:53.863Z,1560364373.863 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-06-12T18:32:53.864Z,1560364373.864 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-06-12T18:32:53.869Z,1560364373.869 [SBIT](INFO): Initialize SBIT Component.
2019-06-12T18:32:53.870Z,1560364373.870 [SBIT](IMPORTANT): git: 2019-05-01-10-gcab12ce
2019-06-12T18:32:53.870Z,1560364373.870 [SBIT](INFO): git hash: cab12ce958be6da2bb158d8a74bde728d70637cd
2019-06-12T18:32:53.870Z,1560364373.870 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-06-12T18:32:53.871Z,1560364373.871 [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-06-12T18:32:53.872Z,1560364373.872 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-06-12T18:32:53.873Z,1560364373.873 [IBIT](INFO): Initialize IBIT Component.
2019-06-12T18:32:53.874Z,1560364373.874 [CBIT](DEBUG): Initialize CBIT Component.
2019-06-12T18:32:53.875Z,1560364373.875 [logger ThreadHandler](INFO): Handler Thread ID is 2499
2019-06-12T18:32:53.885Z,1560364373.885 [CBIT](DEBUG): Initialized mux pins.
2019-06-12T18:32:53.885Z,1560364373.885 [CBIT](DEBUG): Initializing the watchdog timer.
2019-06-12T18:32:53.893Z,1560364373.893 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2500
2019-06-12T18:32:53.894Z,1560364373.894 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-06-12T18:32:53.905Z,1560364373.905 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2501
2019-06-12T18:32:53.909Z,1560364373.909 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-06-12T18:32:53.909Z,1560364373.909 [CBIT](DEBUG): Initializing heartbeat.
2019-06-12T18:32:53.917Z,1560364373.917 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2502
2019-06-12T18:32:53.918Z,1560364373.918 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-06-12T18:32:53.921Z,1560364373.921 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-06-12T18:32:53.923Z,1560364373.923 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2504
2019-06-12T18:32:53.924Z,1560364373.924 [WetLabsBB2FL](INFO): Powering down
2019-06-12T18:32:53.951Z,1560364373.951 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2505
2019-06-12T18:32:53.959Z,1560364373.959 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-06-12T18:32:53.959Z,1560364373.959 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-06-12T18:32:53.960Z,1560364373.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-06-12T18:32:53.960Z,1560364373.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-06-12T18:32:53.960Z,1560364373.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-06-12T18:32:53.960Z,1560364373.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-06-12T18:32:53.960Z,1560364373.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-06-12T18:32:53.960Z,1560364373.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-06-12T18:32:53.961Z,1560364373.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-06-12T18:32:53.961Z,1560364373.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-06-12T18:32:53.961Z,1560364373.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-06-12T18:32:53.961Z,1560364373.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-06-12T18:32:53.962Z,1560364373.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-06-12T18:32:53.962Z,1560364373.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-06-12T18:32:53.962Z,1560364373.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-06-12T18:32:53.962Z,1560364373.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-06-12T18:32:53.981Z,1560364373.981 [CBIT](DEBUG): Deactivating GF circuits.
2019-06-12T18:32:53.981Z,1560364373.981 [CBIT](DEBUG): Deactivating emergency mode.
2019-06-12T18:32:54.017Z,1560364374.017 [CBIT](DEBUG): Backplane powered.
2019-06-12T18:32:54.018Z,1560364374.018 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-06-12T18:32:54.030Z,1560364374.030 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-06-12T18:32:54.069Z,1560364374.069 [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-06-12T18:32:54.070Z,1560364374.070 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-06-12T18:32:54.125Z,1560364374.125 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-06-12T18:32:54.126Z,1560364374.126 [Default:A.Wait](DEBUG): Construct Wait.
2019-06-12T18:32:54.128Z,1560364374.128 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-06-12T18:32:54.167Z,1560364374.167 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-06-12T18:32:54.170Z,1560364374.170 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-06-12T18:32:54.188Z,1560364374.188 [Default:E.Execute](DEBUG): Construct Execute.
2019-06-12T18:32:54.191Z,1560364374.191 [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-06-12T18:32:54.213Z,1560364374.213 [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-06-12T18:32:54.227Z,1560364374.227 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-06-12T18:32:54.309Z,1560364374.309 [Radio_Surface](INFO): Powering up
2019-06-12T18:32:54.363Z,1560364374.363 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-06-12T18:32:54.370Z,1560364374.370 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-06-12T18:32:54.371Z,1560364374.371 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-06-12T18:32:54.381Z,1560364374.381 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-06-12T18:32:54.382Z,1560364374.382 [MassServo](DEBUG): Initializing EZServoServo.
2019-06-12T18:32:54.389Z,1560364374.389 [MassServo](DEBUG): Initializing MassServo.
2019-06-12T18:32:54.390Z,1560364374.390 [RudderServo](DEBUG): Initializing EZServoServo.
2019-06-12T18:32:54.397Z,1560364374.397 [RudderServo](DEBUG): Initializing RudderServo.
2019-06-12T18:32:54.398Z,1560364374.398 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-06-12T18:32:54.405Z,1560364374.405 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-06-12T18:32:54.730Z,1560364374.730 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-06-12T18:32:54.730Z,1560364374.730 [DropWeight] Hardware Fault, FailCount= 1
2019-06-12T18:32:54.730Z,1560364374.730 [DropWeight](ERROR): Hardware Fault
2019-06-12T18:32:54.783Z,1560364374.783 [CommandLine](FAULT): Scheduling is paused
2019-06-12T18:32:54.783Z,1560364374.783 [CBIT](INFO): Critical error at 20190612T183254
2019-06-12T18:32:54.784Z,1560364374.784 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-06-12T18:32:54.786Z,1560364374.786 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-06-12T18:32:54.787Z,1560364374.787 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-06-12T18:32:55.366Z,1560364375.366 [CBIT](INFO): Critical error at 20190612T183254
2019-06-12T18:33:04.010Z,1560364384.010 [CBIT](CRITICAL): Environmental Failure. Press:14.921061 PSI. Humidity:43%. Temp:26 C. ABORTING MISSION
2019-06-12T18:33:04.396Z,1560364384.396 [CBIT](INFO): Critical error at 20190612T183304
2019-06-12T18:33:20.886Z,1560364400.886 [NAL9602](INFO): Powering up NAL9602
2019-06-12T18:33:22.580Z,1560364402.580 [SBIT](IMPORTANT): Beginning Startup BIT
2019-06-12T18:33:22.616Z,1560364402.616 [CBIT](IMPORTANT): Beginning ground fault scan
2019-06-12T18:33:31.926Z,1560364411.926 [NAL9602](INFO): NAL9602 initialized
2019-06-12T18:33:32.745Z,1560364412.745 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:33:33.682Z,1560364413.682 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.010461
CHAN A1 (24V): 0.000770
CHAN A2 (12V): -0.005043
CHAN A3 (5V): -0.002571
CHAN B0 (3.3V): -0.000489
CHAN B1 (3.15aV): -0.001732
CHAN B2 (3.15bV): -0.001708
CHAN B3 (GND): 0.002630
OPEN: 0.003564
Full Scale Calc: 4.765 mA, -1.589 mA
2019-06-12T18:34:16.224Z,1560364456.224 [SBIT](IMPORTANT): SBIT PASSED
2019-06-12T18:34:16.250Z,1560364456.250 [CommandLine](IMPORTANT): got command configSet list
2019-06-12T18:34:16.251Z,1560364456.251 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-06-12T18:34:16.251Z,1560364456.251 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-06-12T18:34:16.251Z,1560364456.251 [CommandLine](IMPORTANT): PAR_Licor.darkCount=-15 count;

2019-06-12T18:34:16.252Z,1560364456.252 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-06-12T18:34:16.639Z,1560364456.639 [MissionManager](IMPORTANT): Started mission Startup
2019-06-12T18:34:16.639Z,1560364456.639 [Startup] Running Loop=1
2019-06-12T18:34:16.639Z,1560364456.639 [Startup](DEBUG): Aggregate::initialize Startup
2019-06-12T18:34:16.640Z,1560364456.640 [Startup:A.GoToSurface] Running Loop=1
2019-06-12T18:34:16.640Z,1560364456.640 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-06-12T18:34:16.640Z,1560364456.640 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-06-12T18:34:16.641Z,1560364456.641 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-06-12T18:34:16.641Z,1560364456.641 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-06-12T18:34:16.642Z,1560364456.642 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-06-12T18:34:16.642Z,1560364456.642 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-06-12T18:34:16.643Z,1560364456.643 [Startup:StartupSatComms] Running Loop=1
2019-06-12T18:34:16.643Z,1560364456.643 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-06-12T18:34:16.643Z,1560364456.643 [Startup:StartupSatComms:A] Running Loop=1
2019-06-12T18:34:17.047Z,1560364457.047 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-06-12T18:34:19.198Z,1560364459.198 [CommandLine](IMPORTANT): got command failComponent
2019-06-12T18:34:19.198Z,1560364459.198 [CommandLine](IMPORTANT): Failed components:
2019-06-12T18:34:19.198Z,1560364459.198 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2019-06-12T18:34:38.063Z,1560364478.063 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 80.000000 cubic_centimeter
2019-06-12T18:34:38.064Z,1560364478.064 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2019-06-12T18:34:38.473Z,1560364478.473 [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,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-06-12T18:35:16.858Z,1560364516.858 [Startup:StartupSatComms:A](INFO): Timed out from 2019-06-12T18:34:16.6Z
2019-06-12T18:35:16.858Z,1560364516.858 [Startup:StartupSatComms:A] Stopped
2019-06-12T18:35:16.858Z,1560364516.858 [Startup:StartupSatComms:B] Running Loop=1
2019-06-12T18:35:17.237Z,1560364517.237 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-06-12T18:35:20.814Z,1560364520.814 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004639
2019-06-12T18:35:27.481Z,1560364527.481 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20190612T161649/Courier0040.lzma
2019-06-12T18:35:28.287Z,1560364528.287 [DataOverHttps](INFO): Moved sent file to Logs/20190612T161649/Courier0040.lzma.bak
2019-06-12T18:35:28.287Z,1560364528.287 [DataOverHttps](INFO): SBD MOMSN=11371120
2019-06-12T18:35:40.548Z,1560364540.548 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20190612T183243/Courier0000.lzma
2019-06-12T18:35:41.355Z,1560364541.355 [DataOverHttps](INFO): Moved sent file to Logs/20190612T183243/Courier0000.lzma.bak
2019-06-12T18:35:41.355Z,1560364541.355 [DataOverHttps](INFO): SBD MOMSN=11371126
2019-06-12T18:35:53.889Z,1560364553.889 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-06-12T18:35:53.889Z,1560364553.889 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-12T18:35:53.904Z,1560364553.904 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-12T18:35:54.298Z,1560364554.298 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-12T18:35:54.298Z,1560364554.298 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-06-12T18:35:55.341Z,1560364555.341 [DataOverHttps](INFO): Sending 385 bytes from file Logs/20190612T161649/Express0041.lzma
2019-06-12T18:35:56.146Z,1560364556.146 [DataOverHttps](INFO): Moved sent file to Logs/20190612T161649/Express0041.lzma.bak
2019-06-12T18:35:56.147Z,1560364556.147 [DataOverHttps](INFO): SBD MOMSN=11371132
2019-06-12T18:36:14.927Z,1560364574.927 [DataOverHttps](INFO): Sending 778 bytes from file Logs/20190612T183243/Express0001.lzma
2019-06-12T18:36:15.706Z,1560364575.706 [DataOverHttps](INFO): Moved sent file to Logs/20190612T183243/Express0001.lzma.bak
2019-06-12T18:36:15.707Z,1560364575.707 [DataOverHttps](INFO): SBD MOMSN=11371153
2019-06-12T18:36:16.954Z,1560364576.954 [Startup:StartupSatComms:B](INFO): Timed out from 2019-06-12T18:35:16.9Z
2019-06-12T18:36:16.954Z,1560364576.954 [Startup:StartupSatComms:B] Stopped
2019-06-12T18:36:16.954Z,1560364576.954 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-06-12T18:36:16.954Z,1560364576.954 [Startup:StartupSatComms] Stopped
2019-06-12T18:36:16.954Z,1560364576.954 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-06-12T18:36:16.955Z,1560364576.955 [Startup](INFO): Completed Startup
2019-06-12T18:36:16.955Z,1560364576.955 [MissionManager](INFO): Startup is completed.
2019-06-12T18:36:16.955Z,1560364576.955 [MissionManager](INFO): Uninitializing Mission Startup
2019-06-12T18:36:16.955Z,1560364576.955 [Startup] Stopped
2019-06-12T18:36:16.956Z,1560364576.956 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-06-12T18:36:16.956Z,1560364576.956 [Startup:A.GoToSurface] Stopped
2019-06-12T18:36:16.956Z,1560364576.956 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-06-12T18:36:17.356Z,1560364577.356 [MissionManager](IMPORTANT): Started mission Default
2019-06-12T18:36:17.356Z,1560364577.356 [Default] Running Loop=1
2019-06-12T18:36:17.357Z,1560364577.357 [Default](DEBUG): Aggregate::initialize Default
2019-06-12T18:36:17.357Z,1560364577.357 [Default:B.GoToSurface] Running Loop=1
2019-06-12T18:36:17.357Z,1560364577.357 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-06-12T18:36:17.357Z,1560364577.357 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-06-12T18:36:17.357Z,1560364577.357 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-06-12T18:36:17.358Z,1560364577.358 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-06-12T18:36:17.358Z,1560364577.358 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-06-12T18:36:17.358Z,1560364577.358 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-06-12T18:36:17.359Z,1560364577.359 [Default:A.Wait] Running Loop=1
2019-06-12T18:36:17.359Z,1560364577.359 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-06-12T18:36:30.655Z,1560364590.655 [Default:A.Wait](INFO): Done Waiting.
2019-06-12T18:36:30.655Z,1560364590.655 [Default:A.Wait] Stopped
2019-06-12T18:36:30.655Z,1560364590.655 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-06-12T18:36:31.055Z,1560364591.055 [Default:CheckIn] Running Loop=1
2019-06-12T18:36:31.055Z,1560364591.055 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-06-12T18:36:31.055Z,1560364591.055 [Default:CheckIn:Read_GPS] Running Loop=1
2019-06-12T18:36:31.459Z,1560364591.459 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-06-12T18:36:47.918Z,1560364607.918 [CommandLine](IMPORTANT): got command gfscan
2019-06-12T18:36:48.033Z,1560364608.033 [CBIT](IMPORTANT): Beginning ground fault scan
2019-06-12T18:36:59.382Z,1560364619.382 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009231
CHAN A1 (24V): 0.001245
CHAN A2 (12V): -0.003940
CHAN A3 (5V): -0.003020
CHAN B0 (3.3V): 0.000310
CHAN B1 (3.15aV): -0.000644
CHAN B2 (3.15bV): -0.001676
CHAN B3 (GND): 0.000291
OPEN: -0.000285
Full Scale Calc: 4.765 mA, -1.589 mA
2019-06-12T18:38:35.898Z,1560364715.898 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-06-12T18:38:36.721Z,1560364716.721 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:38:40.757Z,1560364720.757 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:38:43.989Z,1560364723.989 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:38:46.817Z,1560364726.817 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:38:49.649Z,1560364729.649 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:38:51.669Z,1560364731.669 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:38:53.685Z,1560364733.685 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:38:54.911Z,1560364734.911 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-06-12T18:38:54.911Z,1560364734.911 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-12T18:38:54.921Z,1560364734.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-12T18:38:55.394Z,1560364735.394 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-12T18:38:55.394Z,1560364735.394 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-06-12T18:38:56.917Z,1560364736.917 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:38:59.745Z,1560364739.745 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:03.009Z,1560364743.009 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:05.813Z,1560364745.813 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:08.657Z,1560364748.657 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:10.669Z,1560364750.669 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:12.693Z,1560364752.693 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:15.913Z,1560364755.913 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:18.753Z,1560364758.753 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:21.977Z,1560364761.977 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:24.805Z,1560364764.805 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:27.629Z,1560364767.629 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:29.657Z,1560364769.657 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:31.669Z,1560364771.669 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:33.689Z,1560364773.689 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:34.125Z,1560364774.125 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-06-12T18:39:34.128Z,1560364774.128 [BPC1](INFO): Received data from all battery sticks.
2019-06-12T18:39:36.925Z,1560364776.925 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:39.757Z,1560364779.757 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:42.981Z,1560364782.981 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:45.813Z,1560364785.813 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:48.637Z,1560364788.637 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:50.665Z,1560364790.665 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:52.677Z,1560364792.677 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:55.909Z,1560364795.909 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:39:58.737Z,1560364798.737 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:01.969Z,1560364801.969 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:04.797Z,1560364804.797 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:07.625Z,1560364807.625 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:09.661Z,1560364809.661 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:11.673Z,1560364811.673 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:13.689Z,1560364813.689 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:16.925Z,1560364816.925 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:19.749Z,1560364819.749 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:22.977Z,1560364822.977 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:25.809Z,1560364825.809 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:28.633Z,1560364828.633 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:30.653Z,1560364830.653 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:32.673Z,1560364832.673 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:35.905Z,1560364835.905 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:38.733Z,1560364838.733 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:41.969Z,1560364841.969 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:44.797Z,1560364844.797 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:48.025Z,1560364848.025 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:50.853Z,1560364850.853 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:53.689Z,1560364853.689 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:56.933Z,1560364856.933 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:40:59.745Z,1560364859.745 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:02.973Z,1560364862.973 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:05.801Z,1560364865.801 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:08.645Z,1560364868.645 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:10.653Z,1560364870.653 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:12.669Z,1560364872.669 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:15.901Z,1560364875.901 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:18.729Z,1560364878.729 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:21.961Z,1560364881.961 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:24.793Z,1560364884.793 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:27.617Z,1560364887.617 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:29.637Z,1560364889.637 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:31.263Z,1560364891.263 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-12T18:36:31.1Z
2019-06-12T18:41:31.263Z,1560364891.263 [Default:CheckIn:Read_GPS] Stopped
2019-06-12T18:41:31.263Z,1560364891.263 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-06-12T18:41:31.657Z,1560364891.657 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:31.683Z,1560364891.683 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-06-12T18:41:33.681Z,1560364893.681 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:36.937Z,1560364896.937 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:38.180Z,1560364898.180 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20190612T183243/Courier0004.lzma
2019-06-12T18:41:38.986Z,1560364898.986 [DataOverHttps](INFO): Moved sent file to Logs/20190612T183243/Courier0004.lzma.bak
2019-06-12T18:41:38.986Z,1560364898.986 [DataOverHttps](INFO): SBD MOMSN=11371185
2019-06-12T18:41:39.737Z,1560364899.737 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:42.969Z,1560364902.969 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:44.079Z,1560364904.079 [CommandLine](IMPORTANT): got command gfscan
2019-06-12T18:41:44.228Z,1560364904.228 [CBIT](IMPORTANT): Beginning ground fault scan
2019-06-12T18:41:45.797Z,1560364905.797 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:48.649Z,1560364908.649 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:50.665Z,1560364910.665 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:52.685Z,1560364912.685 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:55.215Z,1560364915.215 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.144582
CHAN A1 (24V): 0.006136
CHAN A2 (12V): -0.005088
CHAN A3 (5V): -0.002572
CHAN B0 (3.3V): -0.002129
CHAN B1 (3.15aV): -0.006273
CHAN B2 (3.15bV): -0.001037
CHAN B3 (GND): -0.000536
OPEN: -0.022029
Full Scale Calc: 4.765 mA, -1.589 mA
2019-06-12T18:41:55.929Z,1560364915.929 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:55.960Z,1560364915.960 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-06-12T18:41:55.960Z,1560364915.960 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-06-12T18:41:55.997Z,1560364915.997 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-06-12T18:41:56.402Z,1560364916.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-06-12T18:41:56.402Z,1560364916.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-06-12T18:41:58.674Z,1560364918.674 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20190612T183243/Express0005.lzma
2019-06-12T18:41:58.745Z,1560364918.745 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:41:59.475Z,1560364919.475 [DataOverHttps](INFO): Moved sent file to Logs/20190612T183243/Express0005.lzma.bak
2019-06-12T18:41:59.475Z,1560364919.475 [DataOverHttps](INFO): SBD MOMSN=11371188
2019-06-12T18:42:01.981Z,1560364921.981 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:04.817Z,1560364924.817 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:04.895Z,1560364924.895 [Default:CheckIn:Read_Iridium] Stopped
2019-06-12T18:42:04.895Z,1560364924.895 [Default:CheckIn:C.Wait] Running Loop=1
2019-06-12T18:42:04.896Z,1560364924.896 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-06-12T18:42:07.637Z,1560364927.637 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:09.653Z,1560364929.653 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:11.673Z,1560364931.673 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:14.933Z,1560364934.933 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:17.733Z,1560364937.733 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:20.965Z,1560364940.965 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:23.797Z,1560364943.797 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:26.625Z,1560364946.625 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:28.641Z,1560364948.641 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:30.661Z,1560364950.661 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:32.685Z,1560364952.685 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:35.913Z,1560364955.913 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:38.741Z,1560364958.741 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:41.973Z,1560364961.973 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:44.801Z,1560364964.801 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:47.629Z,1560364967.629 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:49.657Z,1560364969.657 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:49.664Z,1560364969.664 [CommandLine](IMPORTANT): got command quit
2019-06-12T18:42:50.673Z,1560364970.673 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-12T18:42:50.673Z,1560364970.673 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:50.745Z,1560364970.745 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-06-12T18:42:50.745Z,1560364970.745 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:50.746Z,1560364970.746 [CommandLine](INFO): Join timeout helper Thread ID is 2932
2019-06-12T18:42:50.746Z,1560364970.746 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-06-12T18:42:50.746Z,1560364970.746 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:50.747Z,1560364970.747 [NavChartDb](INFO): Join timeout helper Thread ID is 2933
2019-06-12T18:42:50.761Z,1560364970.761 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-12T18:42:50.761Z,1560364970.761 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:50.765Z,1560364970.765 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-06-12T18:42:50.765Z,1560364970.765 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:50.765Z,1560364970.765 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2934
2019-06-12T18:42:51.089Z,1560364971.089 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-12T18:42:51.089Z,1560364971.089 [WetLabsBB2FL](INFO): Powering down
2019-06-12T18:42:51.090Z,1560364971.090 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:51.093Z,1560364971.093 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-06-12T18:42:51.093Z,1560364971.093 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:51.093Z,1560364971.093 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2937
2019-06-12T18:42:51.389Z,1560364971.389 [CTD_Seabird](INFO): Powering down
2019-06-12T18:42:51.401Z,1560364971.401 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-12T18:42:51.401Z,1560364971.401 [CTD_Seabird](INFO): Powering down
2019-06-12T18:42:51.417Z,1560364971.417 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:51.429Z,1560364971.429 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-06-12T18:42:51.429Z,1560364971.429 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:51.429Z,1560364971.429 [Radio_Surface](INFO): Join timeout helper Thread ID is 2938
2019-06-12T18:42:51.485Z,1560364971.485 [Radio_Surface](INFO): Powering down
2019-06-12T18:42:51.486Z,1560364971.486 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-12T18:42:51.486Z,1560364971.486 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:51.502Z,1560364971.502 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-06-12T18:42:51.502Z,1560364971.502 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:51.502Z,1560364971.502 [DataOverHttps](INFO): Join timeout helper Thread ID is 2940
2019-06-12T18:42:51.669Z,1560364971.669 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:53.689Z,1560364973.689 [NAL9602](DEBUG): Fix Requested
2019-06-12T18:42:54.290Z,1560364974.290 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-12T18:42:54.294Z,1560364974.294 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.306Z,1560364974.306 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-06-12T18:42:54.306Z,1560364974.306 [logger ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.306Z,1560364974.306 [logger](INFO): Join timeout helper Thread ID is 2943
2019-06-12T18:42:54.317Z,1560364974.317 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-12T18:42:54.317Z,1560364974.317 [logger ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.325Z,1560364974.325 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-06-12T18:42:54.326Z,1560364974.326 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.326Z,1560364974.326 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-06-12T18:42:54.326Z,1560364974.326 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.326Z,1560364974.326 [controlThread](INFO): Join timeout helper Thread ID is 2944
2019-06-12T18:42:54.477Z,1560364974.477 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-06-12T18:42:54.477Z,1560364974.477 [controlThread](DEBUG): Uninitializing ControlThread
2019-06-12T18:42:54.478Z,1560364974.478 [AHRS_M2](INFO): Powering down
2019-06-12T18:42:54.549Z,1560364974.549 [NAL9602](INFO): Powering down
2019-06-12T18:42:54.551Z,1560364974.551 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-06-12T18:42:54.552Z,1560364974.552 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-06-12T18:42:54.553Z,1560364974.553 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-06-12T18:42:54.553Z,1560364974.553 [MissionManager](INFO): Uninitializing Mission Default
2019-06-12T18:42:54.553Z,1560364974.553 [Default] Stopped
2019-06-12T18:42:54.554Z,1560364974.554 [Default](DEBUG): Aggregate::uninitialize Default
2019-06-12T18:42:54.554Z,1560364974.554 [Default:B.GoToSurface] Stopped
2019-06-12T18:42:54.554Z,1560364974.554 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-06-12T18:42:54.554Z,1560364974.554 [Default:CheckIn] Stopped
2019-06-12T18:42:54.554Z,1560364974.554 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-06-12T18:42:54.554Z,1560364974.554 [Default:CheckIn:C.Wait] Stopped
2019-06-12T18:42:54.554Z,1560364974.554 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-06-12T18:42:54.556Z,1560364974.556 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-06-12T18:42:54.557Z,1560364974.557 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-06-12T18:42:54.557Z,1560364974.557 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-06-12T18:42:54.557Z,1560364974.557 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-06-12T18:42:54.558Z,1560364974.558 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-06-12T18:42:54.558Z,1560364974.558 [BuoyancyServo](INFO): Powering down
2019-06-12T18:42:54.581Z,1560364974.581 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-06-12T18:42:54.581Z,1560364974.581 [ElevatorServo](INFO): Powering down
2019-06-12T18:42:54.582Z,1560364974.582 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-06-12T18:42:54.582Z,1560364974.582 [MassServo](INFO): Powering down
2019-06-12T18:42:54.583Z,1560364974.583 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-06-12T18:42:54.583Z,1560364974.583 [RudderServo](INFO): Powering down
2019-06-12T18:42:54.584Z,1560364974.584 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-06-12T18:42:54.584Z,1560364974.584 [ThrusterServo](INFO): Powering down
2019-06-12T18:42:54.593Z,1560364974.593 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-06-12T18:42:54.594Z,1560364974.594 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-06-12T18:42:54.594Z,1560364974.594 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-06-12T18:42:54.594Z,1560364974.594 [CBIT](DEBUG): Powering off loads.
2019-06-12T18:42:54.605Z,1560364974.605 [CBIT](DEBUG): Disabling WDT.
2019-06-12T18:42:54.617Z,1560364974.617 [CBIT](DEBUG): Opening all GF detection circuits.
2019-06-12T18:42:54.618Z,1560364974.618 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.690Z,1560364974.690 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.696Z,1560364974.696 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.730Z,1560364974.730 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.733Z,1560364974.733 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.764Z,1560364974.764 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-06-12T18:42:54.819Z,1560364974.819 [logger ThreadHandler](INFO): Thread cancelled.