2019-11-11T17:01:58.079Z,1573491718.079 [Supervisor](DEBUG): Initializing supervisor.
2019-11-11T17:01:58.082Z,1573491718.082 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-11-11T17:01:58.083Z,1573491718.083 [SyncHandler](INFO): Protected caller Thread ID is 807
2019-11-11T17:01:58.083Z,1573491718.083 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-11-11T17:01:58.084Z,1573491718.084 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-11-11T17:01:58.084Z,1573491718.084 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2019-11-11T17:01:58.087Z,1573491718.087 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-11-11T17:01:58.098Z,1573491718.098 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-11-11T17:01:58.099Z,1573491718.099 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-11-11T17:01:58.099Z,1573491718.099 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2019-11-11T17:01:58.100Z,1573491718.100 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-11-11T17:01:58.101Z,1573491718.101 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-11-11T17:01:58.101Z,1573491718.101 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2019-11-11T17:01:58.103Z,1573491718.103 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-11-11T17:01:58.104Z,1573491718.104 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-11-11T17:01:58.108Z,1573491718.108 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-11-11T17:01:58.313Z,1573491718.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-11-11T17:01:58.315Z,1573491718.315 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-11-11T17:01:58.397Z,1573491718.397 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-11-11T17:01:58.840Z,1573491718.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-11-11T17:01:58.840Z,1573491718.840 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-11-11T17:01:59.179Z,1573491719.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-11-11T17:01:59.181Z,1573491719.181 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-11-11T17:01:59.277Z,1573491719.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-11-11T17:01:59.278Z,1573491719.278 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-11-11T17:01:59.583Z,1573491719.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-11-11T17:01:59.585Z,1573491719.585 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-11-11T17:01:59.792Z,1573491719.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-11-11T17:01:59.794Z,1573491719.794 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-11-11T17:02:00.488Z,1573491720.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-11-11T17:02:00.488Z,1573491720.488 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-11-11T17:02:00.846Z,1573491720.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-11-11T17:02:00.848Z,1573491720.848 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-11-11T17:02:00.949Z,1573491720.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-11-11T17:02:00.951Z,1573491720.951 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-11-11T17:02:01.607Z,1573491721.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-11-11T17:02:01.609Z,1573491721.609 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-11-11T17:02:02.015Z,1573491722.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-11-11T17:02:02.016Z,1573491722.016 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-11-11T17:02:02.217Z,1573491722.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-11-11T17:02:02.218Z,1573491722.218 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-11-11T17:02:02.364Z,1573491722.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-11-11T17:02:02.365Z,1573491722.365 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-11-11T17:02:02.519Z,1573491722.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-11-11T17:02:02.521Z,1573491722.521 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-11-11T17:02:02.523Z,1573491722.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-11-11T17:02:02.614Z,1573491722.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-11-11T17:02:02.700Z,1573491722.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-11-11T17:02:02.804Z,1573491722.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-11-11T17:02:02.887Z,1573491722.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-11-11T17:02:02.983Z,1573491722.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-11-11T17:02:03.085Z,1573491723.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-11-11T17:02:03.297Z,1573491723.297 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-11-11T17:02:03.375Z,1573491723.375 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-11-11T17:02:03.543Z,1573491723.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-11-11T17:02:03.692Z,1573491723.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-11-11T17:02:03.810Z,1573491723.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-11-11T17:02:04.039Z,1573491724.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-11-11T17:02:04.039Z,1573491724.039 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-11-11T17:02:04.050Z,1573491724.050 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-11-11T17:02:04.115Z,1573491724.115 [VerticalControl](DEBUG): Construct VerticalControl.
2019-11-11T17:02:04.228Z,1573491724.228 [VerticalControl] Loaded
2019-11-11T17:02:04.229Z,1573491724.229 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-11-11T17:02:04.229Z,1573491724.229 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-11-11T17:02:04.299Z,1573491724.299 [HorizontalControl] Loaded
2019-11-11T17:02:04.299Z,1573491724.299 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-11-11T17:02:04.300Z,1573491724.300 [SpeedControl](DEBUG): Construct SpeedControl.
2019-11-11T17:02:04.306Z,1573491724.306 [SpeedControl] Loaded
2019-11-11T17:02:04.306Z,1573491724.306 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-11-11T17:02:04.307Z,1573491724.307 [LoopControl](DEBUG): Construct LoopControl.
2019-11-11T17:02:04.307Z,1573491724.307 [LoopControl] Loaded
2019-11-11T17:02:04.307Z,1573491724.307 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-11-11T17:02:04.308Z,1573491724.308 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-11-11T17:02:04.309Z,1573491724.309 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-11-11T17:02:04.332Z,1573491724.332 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-11-11T17:02:04.333Z,1573491724.333 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-11-11T17:02:04.456Z,1573491724.456 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-11-11T17:02:04.456Z,1573491724.456 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-11-11T17:02:04.607Z,1573491724.607 [BuoyancyServo] Loaded
2019-11-11T17:02:04.607Z,1573491724.607 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-11-11T17:02:04.618Z,1573491724.618 [ElevatorServo] Loaded
2019-11-11T17:02:04.619Z,1573491724.619 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-11-11T17:02:04.630Z,1573491724.630 [MassServo] Loaded
2019-11-11T17:02:04.630Z,1573491724.630 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-11-11T17:02:04.641Z,1573491724.641 [RudderServo] Loaded
2019-11-11T17:02:04.641Z,1573491724.641 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-11-11T17:02:04.652Z,1573491724.652 [ThrusterServo] Loaded
2019-11-11T17:02:04.652Z,1573491724.652 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-11-11T17:02:04.653Z,1573491724.653 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-11-11T17:02:04.655Z,1573491724.655 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-11-11T17:02:04.779Z,1573491724.779 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-11-11T17:02:04.779Z,1573491724.779 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-11-11T17:02:04.800Z,1573491724.800 [NavChart] Loaded
2019-11-11T17:02:04.800Z,1573491724.800 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-11-11T17:02:04.804Z,1573491724.804 [UniversalFixResidualReporter] Loaded
2019-11-11T17:02:04.805Z,1573491724.805 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-11-11T17:02:04.805Z,1573491724.805 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-11-11T17:02:04.807Z,1573491724.807 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-11-11T17:02:04.918Z,1573491724.918 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-11-11T17:02:04.919Z,1573491724.919 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-11-11T17:02:05.373Z,1573491725.373 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-11-11T17:02:05.378Z,1573491725.378 [AHRS_M2](INFO): created writer for : platform_orientation
2019-11-11T17:02:05.379Z,1573491725.379 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-11-11T17:02:05.385Z,1573491725.385 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-11-11T17:02:05.385Z,1573491725.385 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-11-11T17:02:05.390Z,1573491725.390 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-11-11T17:02:05.391Z,1573491725.391 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-11-11T17:02:05.396Z,1573491725.396 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-11-11T17:02:05.572Z,1573491725.572 [AHRS_M2] Loaded
2019-11-11T17:02:05.573Z,1573491725.573 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-11-11T17:02:05.722Z,1573491725.722 [DataOverHttps] Loaded
2019-11-11T17:02:05.723Z,1573491725.723 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-11-11T17:02:05.724Z,1573491725.724 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408644E0
2019-11-11T17:02:05.724Z,1573491725.724 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 892
2019-11-11T17:02:05.738Z,1573491725.738 [Depth_Keller] Loaded
2019-11-11T17:02:05.738Z,1573491725.738 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-11-11T17:02:05.743Z,1573491725.743 [DropWeight] Loaded
2019-11-11T17:02:05.743Z,1573491725.743 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-11-11T17:02:05.839Z,1573491725.839 [NAL9602] Loaded
2019-11-11T17:02:05.839Z,1573491725.839 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-11-11T17:02:05.855Z,1573491725.855 [Onboard] Loaded
2019-11-11T17:02:05.855Z,1573491725.855 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-11-11T17:02:05.858Z,1573491725.858 [Radio_Surface] Loaded
2019-11-11T17:02:05.859Z,1573491725.859 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-11-11T17:02:05.860Z,1573491725.860 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408944E0
2019-11-11T17:02:05.860Z,1573491725.860 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 893
2019-11-11T17:02:07.334Z,1573491727.334 [BPC1] Loaded
2019-11-11T17:02:07.335Z,1573491727.335 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-11-11T17:02:07.335Z,1573491727.335 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-11-11T17:02:07.336Z,1573491727.336 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-11-11T17:02:07.457Z,1573491727.457 [DepthRateCalculator] Loaded
2019-11-11T17:02:07.457Z,1573491727.457 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-11-11T17:02:07.463Z,1573491727.463 [PitchRateCalculator] Loaded
2019-11-11T17:02:07.463Z,1573491727.463 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-11-11T17:02:07.530Z,1573491727.530 [SpeedCalculator] Loaded
2019-11-11T17:02:07.530Z,1573491727.530 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-11-11T17:02:07.623Z,1573491727.623 [TempGradientCalculator] Loaded
2019-11-11T17:02:07.623Z,1573491727.623 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-11-11T17:02:07.640Z,1573491727.640 [YawRateCalculator] Loaded
2019-11-11T17:02:07.640Z,1573491727.640 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-11-11T17:02:07.740Z,1573491727.740 [ElevatorOffsetCalculator] Loaded
2019-11-11T17:02:07.757Z,1573491727.757 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-11-11T17:02:07.758Z,1573491727.758 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-11-11T17:02:07.797Z,1573491727.797 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-11-11T17:02:08.560Z,1573491728.560 [CANONSampler] Loaded
2019-11-11T17:02:08.560Z,1573491728.560 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2019-11-11T17:02:08.570Z,1573491728.570 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-11-11T17:02:08.575Z,1573491728.575 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-11-11T17:02:08.576Z,1573491728.576 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-11-11T17:02:08.580Z,1573491728.580 [CTD_Seabird](INFO): created writer for : depth
2019-11-11T17:02:08.581Z,1573491728.581 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-11-11T17:02:08.586Z,1573491728.586 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-11-11T17:02:08.587Z,1573491728.587 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-11-11T17:02:08.592Z,1573491728.592 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-11-11T17:02:08.592Z,1573491728.592 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-11-11T17:02:08.597Z,1573491728.597 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-11-11T17:02:08.598Z,1573491728.598 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-11-11T17:02:08.603Z,1573491728.603 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-11-11T17:02:08.604Z,1573491728.604 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-11-11T17:02:08.609Z,1573491728.609 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-11-11T17:02:08.609Z,1573491728.609 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-11-11T17:02:08.615Z,1573491728.615 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-11-11T17:02:08.615Z,1573491728.615 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-11-11T17:02:08.616Z,1573491728.616 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-11-11T17:02:08.648Z,1573491728.648 [CTD_Seabird] Loaded
2019-11-11T17:02:08.648Z,1573491728.648 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-11-11T17:02:08.649Z,1573491728.649 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409964E0
2019-11-11T17:02:08.649Z,1573491728.649 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894
2019-11-11T17:02:08.664Z,1573491728.664 [PAR_Licor] Loaded
2019-11-11T17:02:08.664Z,1573491728.664 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-11-11T17:02:08.670Z,1573491728.670 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2019-11-11T17:02:08.671Z,1573491728.671 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2019-11-11T17:02:08.675Z,1573491728.675 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2019-11-11T17:02:08.675Z,1573491728.675 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2019-11-11T17:02:08.679Z,1573491728.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2019-11-11T17:02:08.679Z,1573491728.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2019-11-11T17:02:08.683Z,1573491728.683 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2019-11-11T17:02:08.684Z,1573491728.684 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2019-11-11T17:02:08.688Z,1573491728.688 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2019-11-11T17:02:08.688Z,1573491728.688 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2019-11-11T17:02:08.692Z,1573491728.692 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2019-11-11T17:02:08.693Z,1573491728.693 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2019-11-11T17:02:08.697Z,1573491728.697 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2019-11-11T17:02:08.697Z,1573491728.697 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2019-11-11T17:02:08.701Z,1573491728.701 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2019-11-11T17:02:08.706Z,1573491728.706 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2019-11-11T17:02:08.707Z,1573491728.707 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-11-11T17:02:08.707Z,1573491728.707 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-11-11T17:02:08.711Z,1573491728.711 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-11-11T17:02:08.711Z,1573491728.711 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-11-11T17:02:08.715Z,1573491728.715 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-11-11T17:02:08.715Z,1573491728.715 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-11-11T17:02:08.720Z,1573491728.720 [WetLabsBB2FL] Loaded
2019-11-11T17:02:08.720Z,1573491728.720 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-11-11T17:02:08.721Z,1573491728.721 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409C64E0
2019-11-11T17:02:08.722Z,1573491728.722 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895
2019-11-11T17:02:08.722Z,1573491728.722 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-11-11T17:02:08.723Z,1573491728.723 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-11-11T17:02:08.770Z,1573491728.770 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-11-11T17:02:08.771Z,1573491728.771 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-11-11T17:02:09.105Z,1573491729.105 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-11-11T17:02:09.106Z,1573491729.106 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-11-11T17:02:09.262Z,1573491729.262 [SBIT](DEBUG): Construct Startup Built In Test.
2019-11-11T17:02:09.274Z,1573491729.274 [SBIT] Loaded
2019-11-11T17:02:09.274Z,1573491729.274 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-11-11T17:02:09.275Z,1573491729.275 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-11-11T17:02:09.288Z,1573491729.288 [IBIT] Loaded
2019-11-11T17:02:09.288Z,1573491729.288 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-11-11T17:02:09.291Z,1573491729.291 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-11-11T17:02:09.430Z,1573491729.430 [CBIT] Loaded
2019-11-11T17:02:09.430Z,1573491729.430 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-11-11T17:02:09.431Z,1573491729.431 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-11-11T17:02:09.434Z,1573491729.434 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-11-11T17:02:09.435Z,1573491729.435 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-11-11T17:02:09.442Z,1573491729.442 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-11-11T17:02:09.443Z,1573491729.443 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0
2019-11-11T17:02:09.443Z,1573491729.443 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 896
2019-11-11T17:02:09.448Z,1573491729.448 [Supervisor](INFO): Main Thread ID is 803
2019-11-11T17:02:09.448Z,1573491729.448 [Supervisor](DEBUG): Running supervisor.
2019-11-11T17:02:09.449Z,1573491729.449 [CommandLine ThreadHandler](INFO): Handler Thread ID is 897
2019-11-11T17:02:09.451Z,1573491729.451 [controlThread ThreadHandler](INFO): Handler Thread ID is 898
2019-11-11T17:02:09.451Z,1573491729.451 [controlThread](DEBUG): Initializing ControlThread
2019-11-11T17:02:09.452Z,1573491729.452 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-11-11T17:02:09.454Z,1573491729.454 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-11-11T17:02:09.454Z,1573491729.454 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-11-11T17:02:09.455Z,1573491729.455 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-11-11T17:02:09.457Z,1573491729.457 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-11-11T17:02:09.457Z,1573491729.457 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-11-11T17:02:09.463Z,1573491729.463 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-11-11T17:02:09.463Z,1573491729.463 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-11-11T17:02:09.464Z,1573491729.464 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-11-11T17:02:09.464Z,1573491729.464 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-11-11T17:02:09.464Z,1573491729.464 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-11-11T17:02:09.465Z,1573491729.465 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-11-11T17:02:09.469Z,1573491729.469 [SBIT](INFO): Initialize SBIT Component.
2019-11-11T17:02:09.470Z,1573491729.470 [SBIT](IMPORTANT): git: 2019-11-07
2019-11-11T17:02:09.470Z,1573491729.470 [SBIT](INFO): git hash: 07feadc1200998dd4bacd6666d2dcfe2ccb01d5a
2019-11-11T17:02:09.470Z,1573491729.470 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-11-11T17:02:09.471Z,1573491729.471 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-11-11T17:02:09.472Z,1573491729.472 [SBIT](INFO): Beginning SBIT in 41.000000 seconds.
2019-11-11T17:02:09.473Z,1573491729.473 [IBIT](INFO): Initialize IBIT Component.
2019-11-11T17:02:09.474Z,1573491729.474 [CBIT](DEBUG): Initialize CBIT Component.
2019-11-11T17:02:09.475Z,1573491729.475 [logger ThreadHandler](INFO): Handler Thread ID is 899
2019-11-11T17:02:09.485Z,1573491729.485 [CBIT](DEBUG): Initialized mux pins.
2019-11-11T17:02:09.485Z,1573491729.485 [CBIT](DEBUG): Initializing the watchdog timer.
2019-11-11T17:02:09.493Z,1573491729.493 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 900
2019-11-11T17:02:09.494Z,1573491729.494 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-11-11T17:02:09.505Z,1573491729.505 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 901
2019-11-11T17:02:09.509Z,1573491729.509 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-11-11T17:02:09.509Z,1573491729.509 [CBIT](DEBUG): Initializing heartbeat.
2019-11-11T17:02:09.517Z,1573491729.517 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 902
2019-11-11T17:02:09.518Z,1573491729.518 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-11-11T17:02:09.521Z,1573491729.521 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-11-11T17:02:09.522Z,1573491729.522 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 904
2019-11-11T17:02:09.524Z,1573491729.524 [WetLabsBB2FL](INFO): Powering down
2019-11-11T17:02:09.581Z,1573491729.581 [CBIT](DEBUG): Deactivating GF circuits.
2019-11-11T17:02:09.581Z,1573491729.581 [CBIT](DEBUG): Deactivating emergency mode.
2019-11-11T17:02:09.617Z,1573491729.617 [CBIT](DEBUG): Backplane powered.
2019-11-11T17:02:09.638Z,1573491729.638 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-11-11T17:02:09.646Z,1573491729.646 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 905
2019-11-11T17:02:09.828Z,1573491729.828 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-11-11T17:02:09.828Z,1573491729.828 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-11-11T17:02:09.828Z,1573491729.828 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-11-11T17:02:09.828Z,1573491729.828 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-11-11T17:02:09.829Z,1573491729.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-11-11T17:02:09.829Z,1573491729.829 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-11-11T17:02:09.829Z,1573491729.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-11-11T17:02:09.829Z,1573491729.829 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-11-11T17:02:09.829Z,1573491729.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-11-11T17:02:09.830Z,1573491729.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-11-11T17:02:09.830Z,1573491729.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-11-11T17:02:09.830Z,1573491729.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-11-11T17:02:09.830Z,1573491729.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-11-11T17:02:09.830Z,1573491729.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-11-11T17:02:09.831Z,1573491729.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-11-11T17:02:09.831Z,1573491729.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-11-11T17:02:09.841Z,1573491729.841 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-11-11T17:02:09.909Z,1573491729.909 [Radio_Surface](INFO): Powering up
2019-11-11T17:02:09.915Z,1573491729.915 [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-11-11T17:02:09.916Z,1573491729.916 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-11-11T17:02:09.994Z,1573491729.994 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-11-11T17:02:09.995Z,1573491729.995 [Default:A.Wait](DEBUG): Construct Wait.
2019-11-11T17:02:10.009Z,1573491730.009 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-11-11T17:02:10.036Z,1573491730.036 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-11-11T17:02:10.038Z,1573491730.038 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-11-11T17:02:10.056Z,1573491730.056 [Default:E.Execute](DEBUG): Construct Execute.
2019-11-11T17:02:10.059Z,1573491730.059 [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-11-11T17:02:10.081Z,1573491730.081 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,CANONSampler,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-11-11T17:02:10.086Z,1573491730.086 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-11-11T17:02:10.188Z,1573491730.188 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-11-11T17:02:10.245Z,1573491730.245 [CANONSampler](INFO): Powering down
2019-11-11T17:02:10.383Z,1573491730.383 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-11-11T17:02:10.449Z,1573491730.449 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-11-11T17:02:10.457Z,1573491730.457 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-11-11T17:02:10.458Z,1573491730.458 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-11-11T17:02:10.465Z,1573491730.465 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-11-11T17:02:10.466Z,1573491730.466 [MassServo](DEBUG): Initializing EZServoServo.
2019-11-11T17:02:10.477Z,1573491730.477 [MassServo](DEBUG): Initializing MassServo.
2019-11-11T17:02:10.478Z,1573491730.478 [RudderServo](DEBUG): Initializing EZServoServo.
2019-11-11T17:02:10.485Z,1573491730.485 [RudderServo](DEBUG): Initializing RudderServo.
2019-11-11T17:02:10.486Z,1573491730.486 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-11-11T17:02:10.493Z,1573491730.493 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-11-11T17:02:35.962Z,1573491755.962 [NAL9602](INFO): Powering up NAL9602
2019-11-11T17:02:46.870Z,1573491766.870 [NAL9602](INFO): NAL9602 initialized
2019-11-11T17:02:50.990Z,1573491770.990 [SBIT](IMPORTANT): Beginning Startup BIT
2019-11-11T17:02:50.994Z,1573491770.994 [CBIT](IMPORTANT): Beginning ground fault scan
2019-11-11T17:03:02.005Z,1573491782.005 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): -0.180658
CHAN A2 (12V): -0.001898
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001626
CHAN B1 (3.15aV): 0.000098
CHAN B2 (3.15bV): -0.000220
CHAN B3 (GND): 0.000336
OPEN: -0.000380
Full Scale Calc: 4.765 mA, -1.589 mA
2019-11-11T17:03:44.314Z,1573491824.314 [SBIT](IMPORTANT): SBIT PASSED
2019-11-11T17:03:44.380Z,1573491824.380 [CommandLine](IMPORTANT): got command configSet list
2019-11-11T17:03:44.380Z,1573491824.380 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-11-11T17:03:44.381Z,1573491824.381 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere;

2019-11-11T17:03:44.381Z,1573491824.381 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-11-11T17:03:44.382Z,1573491824.382 [CommandLine](IMPORTANT): CTD_Seabird.verbosity=0 count;

2019-11-11T17:03:44.382Z,1573491824.382 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-11-11T17:03:44.382Z,1573491824.382 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-11-11T17:03:44.719Z,1573491824.719 [MissionManager](IMPORTANT): Started mission Startup
2019-11-11T17:03:44.719Z,1573491824.719 [Startup] Running Loop=1
2019-11-11T17:03:44.719Z,1573491824.719 [Startup](DEBUG): Aggregate::initialize Startup
2019-11-11T17:03:44.719Z,1573491824.719 [Startup:A.GoToSurface] Running Loop=1
2019-11-11T17:03:44.719Z,1573491824.719 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-11-11T17:03:44.720Z,1573491824.720 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-11-11T17:03:44.720Z,1573491824.720 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-11-11T17:03:44.721Z,1573491824.721 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-11-11T17:03:44.721Z,1573491824.721 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-11-11T17:03:44.722Z,1573491824.722 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-11-11T17:03:44.723Z,1573491824.723 [Startup:StartupSatComms] Running Loop=1
2019-11-11T17:03:44.723Z,1573491824.723 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-11-11T17:03:44.723Z,1573491824.723 [Startup:StartupSatComms:A] Running Loop=1
2019-11-11T17:03:45.123Z,1573491825.123 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-11-11T17:04:42.547Z,1573491882.547 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005090
2019-11-11T17:04:44.955Z,1573491884.955 [Startup:StartupSatComms:A](INFO): Timed out from 2019-11-11T17:03:44.7Z
2019-11-11T17:04:44.955Z,1573491884.955 [Startup:StartupSatComms:A] Stopped
2019-11-11T17:04:44.955Z,1573491884.955 [Startup:StartupSatComms:B] Running Loop=1
2019-11-11T17:04:45.365Z,1573491885.365 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-11-11T17:04:51.074Z,1573491891.074 [DataOverHttps](INFO): Sending 669 bytes from file Logs/20191111T170158/Express0001.lzma
2019-11-11T17:04:53.078Z,1573491893.078 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Express0001.lzma.bak
2019-11-11T17:04:53.079Z,1573491893.079 [DataOverHttps](INFO): SBD MOMSN=11972304
2019-11-11T17:04:54.238Z,1573491894.238 [Startup:StartupSatComms:B] Stopped
2019-11-11T17:04:54.238Z,1573491894.238 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-11-11T17:04:54.239Z,1573491894.239 [Startup:StartupSatComms] Stopped
2019-11-11T17:04:54.239Z,1573491894.239 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-11-11T17:04:54.239Z,1573491894.239 [Startup](INFO): Completed Startup
2019-11-11T17:04:54.240Z,1573491894.240 [MissionManager](INFO): Startup is completed.
2019-11-11T17:04:54.240Z,1573491894.240 [MissionManager](INFO): Uninitializing Mission Startup
2019-11-11T17:04:54.240Z,1573491894.240 [Startup] Stopped
2019-11-11T17:04:54.240Z,1573491894.240 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-11-11T17:04:54.240Z,1573491894.240 [Startup:A.GoToSurface] Stopped
2019-11-11T17:04:54.240Z,1573491894.240 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-11-11T17:04:54.654Z,1573491894.654 [MissionManager](IMPORTANT): Started mission Default
2019-11-11T17:04:54.654Z,1573491894.654 [Default] Running Loop=1
2019-11-11T17:04:54.654Z,1573491894.654 [Default](DEBUG): Aggregate::initialize Default
2019-11-11T17:04:54.654Z,1573491894.654 [Default:B.GoToSurface] Running Loop=1
2019-11-11T17:04:54.654Z,1573491894.654 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-11-11T17:04:54.655Z,1573491894.655 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-11-11T17:04:54.655Z,1573491894.655 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-11-11T17:04:54.655Z,1573491894.655 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-11-11T17:04:54.655Z,1573491894.655 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-11-11T17:04:54.656Z,1573491894.656 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-11-11T17:04:54.656Z,1573491894.656 [Default:A.Wait] Running Loop=1
2019-11-11T17:04:54.656Z,1573491894.656 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-11-11T17:05:07.989Z,1573491907.989 [Default:A.Wait](INFO): Done Waiting.
2019-11-11T17:05:07.989Z,1573491907.989 [Default:A.Wait] Stopped
2019-11-11T17:05:07.989Z,1573491907.989 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-11-11T17:05:08.388Z,1573491908.388 [Default:CheckIn] Running Loop=1
2019-11-11T17:05:08.388Z,1573491908.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-11T17:05:08.388Z,1573491908.388 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-11T17:05:08.783Z,1573491908.783 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-11-11T17:05:09.602Z,1573491909.602 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-11-11T17:05:09.602Z,1573491909.602 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:05:09.613Z,1573491909.613 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:05:10.011Z,1573491910.011 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:05:10.011Z,1573491910.011 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-11-11T17:07:50.364Z,1573492070.364 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-11-11T17:08:10.590Z,1573492090.590 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-11-11T17:08:10.590Z,1573492090.590 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:08:10.620Z,1573492090.620 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:08:11.005Z,1573492091.005 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:08:11.005Z,1573492091.005 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-11-11T17:08:25.956Z,1573492105.956 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-11-11T17:08:25.959Z,1573492105.959 [BPC1](INFO): Received data from all battery sticks.
2019-11-11T17:10:08.574Z,1573492208.574 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-11-11T17:05:08.4Z
2019-11-11T17:10:08.574Z,1573492208.574 [Default:CheckIn:Read_GPS] Stopped
2019-11-11T17:10:08.574Z,1573492208.574 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-11T17:10:08.976Z,1573492208.976 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-11-11T17:10:15.430Z,1573492215.430 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20191111T170158/Courier0004.lzma
2019-11-11T17:10:17.443Z,1573492217.443 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Courier0004.lzma.bak
2019-11-11T17:10:17.444Z,1573492217.444 [DataOverHttps](INFO): SBD MOMSN=11972322
2019-11-11T17:10:33.993Z,1573492233.993 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20191111T170158/Express0005.lzma
2019-11-11T17:10:35.998Z,1573492235.998 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Express0005.lzma.bak
2019-11-11T17:10:35.998Z,1573492235.998 [DataOverHttps](INFO): SBD MOMSN=11972324
2019-11-11T17:10:37.243Z,1573492237.243 [Default:CheckIn:Read_Iridium] Stopped
2019-11-11T17:10:37.243Z,1573492237.243 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-11T17:10:37.243Z,1573492237.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-11T17:11:11.613Z,1573492271.613 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-11-11T17:11:11.613Z,1573492271.613 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:11:11.634Z,1573492271.634 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:11:12.036Z,1573492272.036 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:11:12.041Z,1573492272.041 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-11-11T17:12:49.318Z,1573492369.318 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-11-11T17:12:49.318Z,1573492369.318 [NAL9602] Data Fault, FailCount= 1
2019-11-11T17:12:49.318Z,1573492369.318 [NAL9602](ERROR): Data Fault
2019-11-11T17:12:49.387Z,1573492369.387 [CBIT](ERROR): Data Fault in component: NAL9602
2019-11-11T17:12:49.726Z,1573492369.726 [NAL9602](INFO): Powering down
2019-11-11T17:12:50.572Z,1573492370.572 [CBIT](INFO): Clearing failed state for component NAL9602
2019-11-11T17:12:50.572Z,1573492370.572 [NAL9602] No Fault, FailCount= 1
2019-11-11T17:13:20.026Z,1573492400.026 [NAL9602](INFO): Powering up NAL9602
2019-11-11T17:13:30.934Z,1573492410.934 [NAL9602](INFO): NAL9602 initialized
2019-11-11T17:14:12.601Z,1573492452.601 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-11-11T17:14:12.601Z,1573492452.601 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:14:12.643Z,1573492452.643 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:14:13.013Z,1573492453.013 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:14:13.013Z,1573492453.013 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-11-11T17:15:37.821Z,1573492537.821 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-11T17:15:37.821Z,1573492537.821 [Default:CheckIn:C.Wait] Stopped
2019-11-11T17:15:37.821Z,1573492537.821 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-11T17:15:37.821Z,1573492537.821 [Default:CheckIn:D] Running Loop=1
2019-11-11T17:15:38.231Z,1573492538.231 [Default:CheckIn:D] Stopped
2019-11-11T17:15:38.231Z,1573492538.231 [Default:CheckIn:E] Running Loop=1
2019-11-11T17:15:38.644Z,1573492538.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.726280 min 
2019-11-11T17:15:38.644Z,1573492538.644 [Default:CheckIn:E] Stopped
2019-11-11T17:15:38.644Z,1573492538.644 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-11T17:15:38.644Z,1573492538.644 [Default:CheckIn] Stopped
2019-11-11T17:15:38.645Z,1573492538.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-11T17:15:38.645Z,1573492538.645 [Default:CheckIn](INFO): Running loop #2
2019-11-11T17:15:38.645Z,1573492538.645 [Default:CheckIn] Running Loop=2
2019-11-11T17:15:38.645Z,1573492538.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-11T17:15:38.645Z,1573492538.645 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-11T17:17:13.573Z,1573492633.573 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-11-11T17:17:13.573Z,1573492633.573 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:17:13.582Z,1573492633.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:17:13.990Z,1573492633.990 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:17:13.990Z,1573492633.990 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-11-11T17:19:26.879Z,1573492766.879 [CommandLine](IMPORTANT): got command burn on
2019-11-11T17:19:26.879Z,1573492766.879 [CommandLine](IMPORTANT): Activating dropweight wire
2019-11-11T17:19:30.445Z,1573492770.445 [CommandLine](IMPORTANT): got command burn off
2019-11-11T17:19:30.446Z,1573492770.446 [CommandLine](IMPORTANT): Deactivating dropweight wire
2019-11-11T17:19:54.215Z,1573492794.215 [CommandLine](IMPORTANT): got command get CANONSampler.loadAtStartup 
2019-11-11T17:19:54.216Z,1573492794.216 [CommandLine](IMPORTANT): CANONSampler.loadAtStartup 1 bool
2019-11-11T17:20:14.569Z,1573492814.569 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-11-11T17:20:14.570Z,1573492814.570 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:20:14.579Z,1573492814.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:20:14.986Z,1573492814.986 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:20:14.986Z,1573492814.986 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-11-11T17:20:38.811Z,1573492838.811 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-11-11T17:15:38.6Z
2019-11-11T17:20:38.811Z,1573492838.811 [Default:CheckIn:Read_GPS] Stopped
2019-11-11T17:20:38.811Z,1573492838.811 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-11T17:20:45.912Z,1573492845.912 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191111T170158/Courier0007.lzma
2019-11-11T17:20:47.918Z,1573492847.918 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Courier0007.lzma.bak
2019-11-11T17:20:47.918Z,1573492847.918 [DataOverHttps](INFO): SBD MOMSN=11972334
2019-11-11T17:21:04.477Z,1573492864.477 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20191111T170158/Express0008.lzma
2019-11-11T17:21:06.482Z,1573492866.482 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Express0008.lzma.bak
2019-11-11T17:21:06.483Z,1573492866.483 [DataOverHttps](INFO): SBD MOMSN=11972336
2019-11-11T17:21:07.509Z,1573492867.509 [Default:CheckIn:Read_Iridium] Stopped
2019-11-11T17:21:07.509Z,1573492867.509 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-11T17:21:07.509Z,1573492867.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-11T17:23:15.554Z,1573492995.554 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-11-11T17:23:15.554Z,1573492995.554 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:23:15.572Z,1573492995.572 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:23:15.970Z,1573492995.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:23:15.970Z,1573492995.970 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-11-11T17:23:34.106Z,1573493014.106 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-11-11T17:23:34.106Z,1573493014.106 [NAL9602] Data Fault, FailCount= 2
2019-11-11T17:23:34.107Z,1573493014.107 [NAL9602](ERROR): Data Fault
2019-11-11T17:23:34.164Z,1573493014.164 [CBIT](ERROR): Data Fault in component: NAL9602
2019-11-11T17:23:34.511Z,1573493014.511 [NAL9602](INFO): Powering down
2019-11-11T17:23:35.359Z,1573493015.359 [CBIT](INFO): Clearing failed state for component NAL9602
2019-11-11T17:23:35.359Z,1573493015.359 [NAL9602] No Fault, FailCount= 2
2019-11-11T17:24:04.822Z,1573493044.822 [NAL9602](INFO): Powering up NAL9602
2019-11-11T17:24:15.718Z,1573493055.718 [NAL9602](INFO): NAL9602 initialized
2019-11-11T17:26:08.064Z,1573493168.064 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-11T17:26:08.064Z,1573493168.064 [Default:CheckIn:C.Wait] Stopped
2019-11-11T17:26:08.064Z,1573493168.064 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-11T17:26:08.064Z,1573493168.064 [Default:CheckIn:D] Running Loop=1
2019-11-11T17:26:08.476Z,1573493168.476 [Default:CheckIn:D] Stopped
2019-11-11T17:26:08.476Z,1573493168.476 [Default:CheckIn:E] Running Loop=1
2019-11-11T17:26:08.867Z,1573493168.867 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.230359 min 
2019-11-11T17:26:08.867Z,1573493168.867 [Default:CheckIn:E] Stopped
2019-11-11T17:26:08.867Z,1573493168.867 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-11T17:26:08.867Z,1573493168.867 [Default:CheckIn] Stopped
2019-11-11T17:26:08.868Z,1573493168.868 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-11T17:26:08.868Z,1573493168.868 [Default:CheckIn](INFO): Running loop #3
2019-11-11T17:26:08.868Z,1573493168.868 [Default:CheckIn] Running Loop=3
2019-11-11T17:26:08.868Z,1573493168.868 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-11T17:26:08.868Z,1573493168.868 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-11T17:26:16.562Z,1573493176.562 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-11-11T17:26:16.562Z,1573493176.562 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:26:16.572Z,1573493176.572 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:26:16.978Z,1573493176.978 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:26:16.978Z,1573493176.978 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-11-11T17:29:17.557Z,1573493357.557 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-11-11T17:29:17.557Z,1573493357.557 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:29:17.578Z,1573493357.578 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:29:17.986Z,1573493357.986 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:29:17.986Z,1573493357.986 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-11-11T17:31:09.051Z,1573493469.051 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-11-11T17:26:08.9Z
2019-11-11T17:31:09.051Z,1573493469.051 [Default:CheckIn:Read_GPS] Stopped
2019-11-11T17:31:09.051Z,1573493469.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-11T17:31:16.048Z,1573493476.048 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191111T170158/Courier0010.lzma
2019-11-11T17:31:18.054Z,1573493478.054 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Courier0010.lzma.bak
2019-11-11T17:31:18.054Z,1573493478.054 [DataOverHttps](INFO): SBD MOMSN=11972351
2019-11-11T17:31:34.593Z,1573493494.593 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20191111T170158/Express0011.lzma
2019-11-11T17:31:36.598Z,1573493496.598 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Express0011.lzma.bak
2019-11-11T17:31:36.598Z,1573493496.598 [DataOverHttps](INFO): SBD MOMSN=11972353
2019-11-11T17:31:37.723Z,1573493497.723 [Default:CheckIn:Read_Iridium] Stopped
2019-11-11T17:31:37.723Z,1573493497.723 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-11T17:31:37.723Z,1573493497.723 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-11T17:32:18.542Z,1573493538.542 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-11-11T17:32:18.542Z,1573493538.542 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:32:18.552Z,1573493538.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:32:18.955Z,1573493538.955 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:32:18.955Z,1573493538.955 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-11-11T17:34:18.503Z,1573493658.503 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-11-11T17:34:18.503Z,1573493658.503 [NAL9602] Data Fault, FailCount= 3
2019-11-11T17:34:18.503Z,1573493658.503 [NAL9602](ERROR): Data Fault
2019-11-11T17:34:18.533Z,1573493658.533 [CBIT](ERROR): Data Fault in component: NAL9602
2019-11-11T17:34:18.912Z,1573493658.912 [NAL9602](INFO): Powering down
2019-11-11T17:34:19.756Z,1573493659.756 [CBIT](INFO): Clearing failed state for component NAL9602
2019-11-11T17:34:19.756Z,1573493659.756 [NAL9602] No Fault, FailCount= 3
2019-11-11T17:34:49.206Z,1573493689.206 [NAL9602](INFO): Powering up NAL9602
2019-11-11T17:35:00.114Z,1573493700.114 [NAL9602](INFO): NAL9602 initialized
2019-11-11T17:35:19.534Z,1573493719.534 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-11-11T17:35:19.534Z,1573493719.534 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:35:19.543Z,1573493719.543 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:35:19.950Z,1573493719.950 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:35:19.950Z,1573493719.950 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-11-11T17:36:38.333Z,1573493798.333 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-11T17:36:38.333Z,1573493798.333 [Default:CheckIn:C.Wait] Stopped
2019-11-11T17:36:38.334Z,1573493798.334 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-11T17:36:38.334Z,1573493798.334 [Default:CheckIn:D] Running Loop=1
2019-11-11T17:36:38.732Z,1573493798.732 [Default:CheckIn:D] Stopped
2019-11-11T17:36:38.732Z,1573493798.732 [Default:CheckIn:E] Running Loop=1
2019-11-11T17:36:39.116Z,1573493799.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.734635 min 
2019-11-11T17:36:39.116Z,1573493799.116 [Default:CheckIn:E] Stopped
2019-11-11T17:36:39.116Z,1573493799.116 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-11T17:36:39.116Z,1573493799.116 [Default:CheckIn] Stopped
2019-11-11T17:36:39.116Z,1573493799.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-11T17:36:39.116Z,1573493799.116 [Default:CheckIn](INFO): Running loop #4
2019-11-11T17:36:39.129Z,1573493799.129 [Default:CheckIn] Running Loop=4
2019-11-11T17:36:39.129Z,1573493799.129 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-11T17:36:39.129Z,1573493799.129 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-11T17:38:20.539Z,1573493900.539 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-11-11T17:38:20.539Z,1573493900.539 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:38:20.549Z,1573493900.549 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:38:20.958Z,1573493900.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:38:20.958Z,1573493900.958 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-11-11T17:41:21.531Z,1573494081.531 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-11-11T17:41:21.531Z,1573494081.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:41:21.541Z,1573494081.541 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:41:21.953Z,1573494081.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:41:21.953Z,1573494081.953 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-11-11T17:41:39.303Z,1573494099.303 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-11-11T17:36:39.1Z
2019-11-11T17:41:39.303Z,1573494099.303 [Default:CheckIn:Read_GPS] Stopped
2019-11-11T17:41:39.303Z,1573494099.303 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-11T17:41:46.376Z,1573494106.376 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191111T170158/Courier0013.lzma
2019-11-11T17:41:48.382Z,1573494108.382 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Courier0013.lzma.bak
2019-11-11T17:41:48.383Z,1573494108.383 [DataOverHttps](INFO): SBD MOMSN=11972362
2019-11-11T17:42:04.003Z,1573494124.003 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20191111T170158/Express0014.lzma
2019-11-11T17:42:06.002Z,1573494126.002 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Express0014.lzma.bak
2019-11-11T17:42:06.002Z,1573494126.002 [DataOverHttps](INFO): SBD MOMSN=11972364
2019-11-11T17:42:07.179Z,1573494127.179 [Default:CheckIn:Read_Iridium] Stopped
2019-11-11T17:42:07.179Z,1573494127.179 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-11T17:42:07.179Z,1573494127.179 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-11T17:44:22.510Z,1573494262.510 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-11-11T17:44:22.510Z,1573494262.510 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:44:22.540Z,1573494262.540 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:44:22.922Z,1573494262.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:44:22.922Z,1573494262.922 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-11-11T17:45:03.286Z,1573494303.286 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-11-11T17:45:03.286Z,1573494303.286 [NAL9602] Data Fault, FailCount= 4
2019-11-11T17:45:03.286Z,1573494303.286 [NAL9602](ERROR): Data Fault
2019-11-11T17:45:03.340Z,1573494303.340 [CBIT](ERROR): Data Fault in component: NAL9602
2019-11-11T17:45:03.690Z,1573494303.690 [NAL9602](INFO): Powering down
2019-11-11T17:45:04.534Z,1573494304.534 [CBIT](INFO): Clearing failed state for component NAL9602
2019-11-11T17:45:04.534Z,1573494304.534 [NAL9602] No Fault, FailCount= 4
2019-11-11T17:45:33.996Z,1573494333.996 [NAL9602](INFO): Powering up NAL9602
2019-11-11T17:45:44.898Z,1573494344.898 [NAL9602](INFO): NAL9602 initialized
2019-11-11T17:47:07.756Z,1573494427.756 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-11T17:47:07.756Z,1573494427.756 [Default:CheckIn:C.Wait] Stopped
2019-11-11T17:47:07.756Z,1573494427.756 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-11T17:47:07.756Z,1573494427.756 [Default:CheckIn:D] Running Loop=1
2019-11-11T17:47:08.144Z,1573494428.144 [Default:CheckIn:D] Stopped
2019-11-11T17:47:08.144Z,1573494428.144 [Default:CheckIn:E] Running Loop=1
2019-11-11T17:47:08.575Z,1573494428.575 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.224833 min 
2019-11-11T17:47:08.575Z,1573494428.575 [Default:CheckIn:E] Stopped
2019-11-11T17:47:08.575Z,1573494428.575 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-11T17:47:08.576Z,1573494428.576 [Default:CheckIn] Stopped
2019-11-11T17:47:08.576Z,1573494428.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-11T17:47:08.576Z,1573494428.576 [Default:CheckIn](INFO): Running loop #5
2019-11-11T17:47:08.576Z,1573494428.576 [Default:CheckIn] Running Loop=5
2019-11-11T17:47:08.576Z,1573494428.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-11T17:47:08.576Z,1573494428.576 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-11T17:47:23.506Z,1573494443.506 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-11-11T17:47:23.506Z,1573494443.506 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:47:23.515Z,1573494443.515 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:47:23.918Z,1573494443.918 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:47:23.918Z,1573494443.918 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-11-11T17:50:24.498Z,1573494624.498 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2019-11-11T17:50:24.498Z,1573494624.498 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:50:24.508Z,1573494624.508 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:50:24.930Z,1573494624.930 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:50:24.930Z,1573494624.930 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2019-11-11T17:52:08.731Z,1573494728.731 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-11-11T17:47:08.6Z
2019-11-11T17:52:08.731Z,1573494728.731 [Default:CheckIn:Read_GPS] Stopped
2019-11-11T17:52:08.731Z,1573494728.731 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-11T17:52:15.620Z,1573494735.620 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191111T170158/Courier0016.lzma
2019-11-11T17:52:17.642Z,1573494737.642 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Courier0016.lzma.bak
2019-11-11T17:52:17.643Z,1573494737.643 [DataOverHttps](INFO): SBD MOMSN=11972374
2019-11-11T17:52:34.188Z,1573494754.188 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20191111T170158/Express0017.lzma
2019-11-11T17:52:36.194Z,1573494756.194 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Express0017.lzma.bak
2019-11-11T17:52:36.194Z,1573494756.194 [DataOverHttps](INFO): SBD MOMSN=11972376
2019-11-11T17:52:37.430Z,1573494757.430 [Default:CheckIn:Read_Iridium] Stopped
2019-11-11T17:52:37.430Z,1573494757.430 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-11T17:52:37.430Z,1573494757.430 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-11T17:53:25.520Z,1573494805.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2019-11-11T17:53:25.520Z,1573494805.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:53:25.530Z,1573494805.530 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:53:25.914Z,1573494805.914 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:53:25.914Z,1573494805.914 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2019-11-11T17:55:46.050Z,1573494946.050 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-11-11T17:55:46.050Z,1573494946.050 [NAL9602] Data Fault, FailCount= 5
2019-11-11T17:55:46.050Z,1573494946.050 [NAL9602](ERROR): Data Fault
2019-11-11T17:55:46.108Z,1573494946.108 [CBIT](ERROR): Data Fault in component: NAL9602
2019-11-11T17:55:46.108Z,1573494946.108 [CBIT](CRITICAL): Data Fault in component: NAL9602
2019-11-11T17:55:46.454Z,1573494946.454 [NAL9602](INFO): Powering down
2019-11-11T17:55:46.489Z,1573494946.489 [CommandLine](FAULT): Scheduling is paused
2019-11-11T17:55:46.489Z,1573494946.489 [CBIT](INFO): Critical error at 20191111T175546
2019-11-11T17:55:46.490Z,1573494946.490 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-11-11T17:56:26.483Z,1573494986.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2019-11-11T17:56:26.483Z,1573494986.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:56:26.496Z,1573494986.496 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:56:26.898Z,1573494986.898 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:56:26.898Z,1573494986.898 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2019-11-11T17:57:37.994Z,1573495057.994 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-11T17:57:37.994Z,1573495057.994 [Default:CheckIn:C.Wait] Stopped
2019-11-11T17:57:37.994Z,1573495057.994 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-11T17:57:37.994Z,1573495057.994 [Default:CheckIn:D] Running Loop=1
2019-11-11T17:57:38.396Z,1573495058.396 [Default:CheckIn:D] Stopped
2019-11-11T17:57:38.396Z,1573495058.396 [Default:CheckIn:E] Running Loop=1
2019-11-11T17:57:38.806Z,1573495058.806 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.729036 min 
2019-11-11T17:57:38.806Z,1573495058.806 [Default:CheckIn:E] Stopped
2019-11-11T17:57:38.806Z,1573495058.806 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-11T17:57:38.806Z,1573495058.806 [Default:CheckIn] Stopped
2019-11-11T17:57:38.807Z,1573495058.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-11T17:57:38.807Z,1573495058.807 [Default:CheckIn](INFO): Running loop #6
2019-11-11T17:57:38.807Z,1573495058.807 [Default:CheckIn] Running Loop=6
2019-11-11T17:57:38.807Z,1573495058.807 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-11T17:57:38.807Z,1573495058.807 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-11T17:57:46.107Z,1573495066.107 [CBIT](INFO): Clearing failed state for component NAL9602
2019-11-11T17:57:46.107Z,1573495066.107 [NAL9602] No Fault, FailCount= 5
2019-11-11T17:57:46.442Z,1573495066.442 [NAL9602](INFO): Powering up NAL9602
2019-11-11T17:57:57.361Z,1573495077.361 [NAL9602](INFO): NAL9602 initialized
2019-11-11T17:59:27.478Z,1573495167.478 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2019-11-11T17:59:27.478Z,1573495167.478 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T17:59:27.488Z,1573495167.488 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T17:59:27.896Z,1573495167.896 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-11T17:59:27.896Z,1573495167.896 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2019-11-11T18:02:28.482Z,1573495348.482 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2019-11-11T18:02:28.482Z,1573495348.482 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-11T18:02:28.491Z,1573495348.491 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T18:02:28.492Z,1573495348.492 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-11T18:02:38.984Z,1573495358.984 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-11-11T17:57:38.8Z
2019-11-11T18:02:38.984Z,1573495358.984 [Default:CheckIn:Read_GPS] Stopped
2019-11-11T18:02:38.984Z,1573495358.984 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-11T18:02:46.096Z,1573495366.096 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20191111T170158/Courier0019.lzma
2019-11-11T18:02:48.102Z,1573495368.102 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Courier0019.lzma.bak
2019-11-11T18:02:48.102Z,1573495368.102 [DataOverHttps](INFO): SBD MOMSN=11972387
2019-11-11T18:03:03.655Z,1573495383.655 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20191111T170158/Express0020.lzma
2019-11-11T18:03:05.658Z,1573495385.658 [DataOverHttps](INFO): Moved sent file to Logs/20191111T170158/Express0020.lzma.bak
2019-11-11T18:03:05.659Z,1573495385.659 [DataOverHttps](INFO): SBD MOMSN=11972390
2019-11-11T18:03:06.854Z,1573495386.854 [Default:CheckIn:Read_Iridium] Stopped
2019-11-11T18:03:06.854Z,1573495386.854 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-11T18:03:06.854Z,1573495386.854 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-11T18:04:57.535Z,1573495497.535 [CommandLine](IMPORTANT): got command restart system
2019-11-11T18:04:59.593Z,1573495499.593 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-11T18:04:59.593Z,1573495499.593 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:04:59.689Z,1573495499.689 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-11-11T18:04:59.689Z,1573495499.689 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:04:59.690Z,1573495499.690 [CommandLine](INFO): Join timeout helper Thread ID is 998
2019-11-11T18:04:59.690Z,1573495499.690 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-11-11T18:04:59.690Z,1573495499.690 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:04:59.691Z,1573495499.691 [NavChartDb](INFO): Join timeout helper Thread ID is 999
2019-11-11T18:04:59.789Z,1573495499.789 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-11T18:04:59.789Z,1573495499.789 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:04:59.809Z,1573495499.809 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-11-11T18:04:59.809Z,1573495499.809 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:04:59.809Z,1573495499.809 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1000
2019-11-11T18:04:59.849Z,1573495499.849 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-11T18:04:59.849Z,1573495499.849 [WetLabsBB2FL](INFO): Powering down
2019-11-11T18:04:59.850Z,1573495499.850 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:04:59.853Z,1573495499.853 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-11-11T18:04:59.853Z,1573495499.853 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:04:59.853Z,1573495499.853 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1001
2019-11-11T18:05:00.221Z,1573495500.221 [CTD_Seabird](INFO): Powering down
2019-11-11T18:05:00.233Z,1573495500.233 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-11T18:05:00.233Z,1573495500.233 [CTD_Seabird](INFO): Powering down
2019-11-11T18:05:00.249Z,1573495500.249 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.253Z,1573495500.253 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-11-11T18:05:00.253Z,1573495500.253 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.253Z,1573495500.253 [Radio_Surface](INFO): Join timeout helper Thread ID is 1002
2019-11-11T18:05:00.509Z,1573495500.509 [Radio_Surface](INFO): Powering down
2019-11-11T18:05:00.510Z,1573495500.510 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-11T18:05:00.510Z,1573495500.510 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.518Z,1573495500.518 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-11-11T18:05:00.518Z,1573495500.518 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.518Z,1573495500.518 [DataOverHttps](INFO): Join timeout helper Thread ID is 1003
2019-11-11T18:05:00.573Z,1573495500.573 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-11T18:05:00.573Z,1573495500.573 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.578Z,1573495500.578 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-11-11T18:05:00.578Z,1573495500.578 [logger ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.578Z,1573495500.578 [logger](INFO): Join timeout helper Thread ID is 1004
2019-11-11T18:05:00.609Z,1573495500.609 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-11T18:05:00.609Z,1573495500.609 [logger ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.618Z,1573495500.618 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-11-11T18:05:00.618Z,1573495500.618 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.618Z,1573495500.618 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-11-11T18:05:00.618Z,1573495500.618 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.618Z,1573495500.618 [controlThread](INFO): Join timeout helper Thread ID is 1005
2019-11-11T18:05:00.745Z,1573495500.745 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-11T18:05:00.745Z,1573495500.745 [controlThread](DEBUG): Uninitializing ControlThread
2019-11-11T18:05:00.746Z,1573495500.746 [AHRS_M2](INFO): Powering down
2019-11-11T18:05:00.817Z,1573495500.817 [NAL9602](INFO): Powering down
2019-11-11T18:05:00.820Z,1573495500.820 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-11-11T18:05:00.821Z,1573495500.821 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-11-11T18:05:00.822Z,1573495500.822 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-11-11T18:05:00.823Z,1573495500.823 [MissionManager](INFO): Uninitializing Mission Default
2019-11-11T18:05:00.823Z,1573495500.823 [Default] Stopped
2019-11-11T18:05:00.823Z,1573495500.823 [Default](DEBUG): Aggregate::uninitialize Default
2019-11-11T18:05:00.823Z,1573495500.823 [Default:B.GoToSurface] Stopped
2019-11-11T18:05:00.823Z,1573495500.823 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-11-11T18:05:00.823Z,1573495500.823 [Default:CheckIn] Stopped
2019-11-11T18:05:00.823Z,1573495500.823 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-11T18:05:00.823Z,1573495500.823 [Default:CheckIn:C.Wait] Stopped
2019-11-11T18:05:00.823Z,1573495500.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-11T18:05:00.826Z,1573495500.826 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-11-11T18:05:00.826Z,1573495500.826 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-11-11T18:05:00.827Z,1573495500.827 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-11-11T18:05:00.827Z,1573495500.827 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-11-11T18:05:00.827Z,1573495500.827 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-11-11T18:05:00.828Z,1573495500.828 [BuoyancyServo](INFO): Powering down
2019-11-11T18:05:00.841Z,1573495500.841 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-11-11T18:05:00.841Z,1573495500.841 [ElevatorServo](INFO): Powering down
2019-11-11T18:05:00.842Z,1573495500.842 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-11-11T18:05:00.842Z,1573495500.842 [MassServo](INFO): Powering down
2019-11-11T18:05:00.843Z,1573495500.843 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-11-11T18:05:00.843Z,1573495500.843 [RudderServo](INFO): Powering down
2019-11-11T18:05:00.844Z,1573495500.844 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-11-11T18:05:00.844Z,1573495500.844 [ThrusterServo](INFO): Powering down
2019-11-11T18:05:00.845Z,1573495500.845 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-11-11T18:05:00.845Z,1573495500.845 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-11-11T18:05:00.846Z,1573495500.846 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-11-11T18:05:00.846Z,1573495500.846 [CBIT](DEBUG): Powering off loads.
2019-11-11T18:05:00.857Z,1573495500.857 [CBIT](DEBUG): Disabling WDT.
2019-11-11T18:05:00.869Z,1573495500.869 [CBIT](DEBUG): Opening all GF detection circuits.
2019-11-11T18:05:00.870Z,1573495500.870 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.960Z,1573495500.960 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:00.967Z,1573495500.967 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:01.002Z,1573495501.002 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:01.007Z,1573495501.007 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:01.057Z,1573495501.057 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-11T18:05:01.118Z,1573495501.118 [logger ThreadHandler](INFO): Thread cancelled.