2019-09-17T14:56:49.016Z,1568732209.016 [Supervisor](DEBUG): Initializing supervisor.
2019-09-17T14:56:49.018Z,1568732209.018 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-09-17T14:56:49.019Z,1568732209.019 [SyncHandler](INFO): Protected caller Thread ID is 7369
2019-09-17T14:56:49.019Z,1568732209.019 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-09-17T14:56:49.020Z,1568732209.020 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-09-17T14:56:49.021Z,1568732209.021 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7370
2019-09-17T14:56:49.023Z,1568732209.023 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-09-17T14:56:49.034Z,1568732209.034 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-09-17T14:56:49.035Z,1568732209.035 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-09-17T14:56:49.036Z,1568732209.036 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7371
2019-09-17T14:56:49.037Z,1568732209.037 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-09-17T14:56:49.037Z,1568732209.037 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-09-17T14:56:49.038Z,1568732209.038 [logger ThreadHandler](INFO): Protected caller Thread ID is 7372
2019-09-17T14:56:49.040Z,1568732209.040 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-09-17T14:56:49.040Z,1568732209.040 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-09-17T14:56:49.042Z,1568732209.042 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-09-17T14:56:49.537Z,1568732209.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-09-17T14:56:49.538Z,1568732209.538 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-09-17T14:56:49.698Z,1568732209.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-09-17T14:56:49.698Z,1568732209.698 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-09-17T14:56:49.797Z,1568732209.797 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-09-17T14:56:49.913Z,1568732209.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-09-17T14:56:49.913Z,1568732209.913 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-09-17T14:56:50.026Z,1568732210.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-09-17T14:56:50.026Z,1568732210.026 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-09-17T14:56:50.465Z,1568732210.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-09-17T14:56:50.466Z,1568732210.466 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-09-17T14:56:50.588Z,1568732210.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-09-17T14:56:50.588Z,1568732210.588 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-09-17T14:56:51.088Z,1568732211.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-09-17T14:56:51.088Z,1568732211.088 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-09-17T14:56:51.287Z,1568732211.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-09-17T14:56:51.287Z,1568732211.287 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-09-17T14:56:51.589Z,1568732211.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-09-17T14:56:51.589Z,1568732211.589 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-09-17T14:56:51.822Z,1568732211.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-09-17T14:56:51.823Z,1568732211.823 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-09-17T14:56:52.013Z,1568732212.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-09-17T14:56:52.014Z,1568732212.014 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-09-17T14:56:52.631Z,1568732212.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-09-17T14:56:52.632Z,1568732212.632 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-09-17T14:56:52.977Z,1568732212.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-09-17T14:56:52.978Z,1568732212.978 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-09-17T14:56:53.377Z,1568732213.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-09-17T14:56:53.379Z,1568732213.379 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-09-17T14:56:53.379Z,1568732213.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-09-17T14:56:53.750Z,1568732213.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-09-17T14:56:53.887Z,1568732213.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-09-17T14:56:54.120Z,1568732214.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-09-17T14:56:54.377Z,1568732214.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-09-17T14:56:54.973Z,1568732214.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-09-17T14:56:54.974Z,1568732214.974 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-09-17T14:56:55.118Z,1568732215.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-09-17T14:56:55.295Z,1568732215.295 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-09-17T14:56:55.446Z,1568732215.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-09-17T14:56:55.695Z,1568732215.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-09-17T14:56:55.867Z,1568732215.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-09-17T14:56:56.016Z,1568732216.016 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-09-17T14:56:56.016Z,1568732216.016 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-09-17T14:56:56.021Z,1568732216.021 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-09-17T14:56:56.035Z,1568732216.035 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-09-17T14:56:56.035Z,1568732216.035 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-09-17T14:56:56.113Z,1568732216.113 [VerticalControl](DEBUG): Construct VerticalControl.
2019-09-17T14:56:56.223Z,1568732216.223 [VerticalControl] Loaded
2019-09-17T14:56:56.224Z,1568732216.224 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-09-17T14:56:56.224Z,1568732216.224 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-09-17T14:56:56.291Z,1568732216.291 [HorizontalControl] Loaded
2019-09-17T14:56:56.291Z,1568732216.291 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-09-17T14:56:56.292Z,1568732216.292 [SpeedControl](DEBUG): Construct SpeedControl.
2019-09-17T14:56:56.298Z,1568732216.298 [SpeedControl] Loaded
2019-09-17T14:56:56.298Z,1568732216.298 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-09-17T14:56:56.299Z,1568732216.299 [LoopControl](DEBUG): Construct LoopControl.
2019-09-17T14:56:56.299Z,1568732216.299 [LoopControl] Loaded
2019-09-17T14:56:56.299Z,1568732216.299 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-09-17T14:56:56.300Z,1568732216.300 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-09-17T14:56:56.300Z,1568732216.300 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-09-17T14:56:56.403Z,1568732216.403 [BuoyancyServo] Loaded
2019-09-17T14:56:56.403Z,1568732216.403 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-09-17T14:56:56.414Z,1568732216.414 [ElevatorServo] Loaded
2019-09-17T14:56:56.414Z,1568732216.414 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-09-17T14:56:56.425Z,1568732216.425 [MassServo] Loaded
2019-09-17T14:56:56.425Z,1568732216.425 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-09-17T14:56:56.435Z,1568732216.435 [RudderServo] Loaded
2019-09-17T14:56:56.436Z,1568732216.436 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-09-17T14:56:56.447Z,1568732216.447 [ThrusterServo] Loaded
2019-09-17T14:56:56.447Z,1568732216.447 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-09-17T14:56:56.447Z,1568732216.447 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-09-17T14:56:56.448Z,1568732216.448 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-09-17T14:56:56.488Z,1568732216.488 [DepthRateCalculator] Loaded
2019-09-17T14:56:56.488Z,1568732216.488 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-09-17T14:56:56.493Z,1568732216.493 [PitchRateCalculator] Loaded
2019-09-17T14:56:56.493Z,1568732216.493 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-09-17T14:56:56.508Z,1568732216.508 [SpeedCalculator] Loaded
2019-09-17T14:56:56.509Z,1568732216.509 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-09-17T14:56:56.529Z,1568732216.529 [TempGradientCalculator] Loaded
2019-09-17T14:56:56.529Z,1568732216.529 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-09-17T14:56:56.534Z,1568732216.534 [YawRateCalculator] Loaded
2019-09-17T14:56:56.534Z,1568732216.534 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-09-17T14:56:56.563Z,1568732216.563 [ElevatorOffsetCalculator] Loaded
2019-09-17T14:56:56.563Z,1568732216.563 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-09-17T14:56:56.564Z,1568732216.564 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-09-17T14:56:56.564Z,1568732216.564 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-09-17T14:56:56.666Z,1568732216.666 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-09-17T14:56:56.666Z,1568732216.666 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-09-17T14:56:56.687Z,1568732216.687 [NavChart] Loaded
2019-09-17T14:56:56.687Z,1568732216.687 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-09-17T14:56:56.691Z,1568732216.691 [UniversalFixResidualReporter] Loaded
2019-09-17T14:56:56.691Z,1568732216.691 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-09-17T14:56:56.691Z,1568732216.691 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-09-17T14:56:56.692Z,1568732216.692 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-09-17T14:56:56.929Z,1568732216.929 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-09-17T14:56:56.934Z,1568732216.934 [AHRS_M2](INFO): created writer for : platform_orientation
2019-09-17T14:56:56.935Z,1568732216.935 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-09-17T14:56:56.940Z,1568732216.940 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-09-17T14:56:56.941Z,1568732216.941 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-09-17T14:56:56.946Z,1568732216.946 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-09-17T14:56:56.947Z,1568732216.947 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-09-17T14:56:56.952Z,1568732216.952 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-09-17T14:56:57.021Z,1568732217.021 [AHRS_M2] Loaded
2019-09-17T14:56:57.021Z,1568732217.021 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-09-17T14:56:57.099Z,1568732217.099 [DataOverHttps] Loaded
2019-09-17T14:56:57.099Z,1568732217.099 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-09-17T14:56:57.100Z,1568732217.100 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0
2019-09-17T14:56:57.100Z,1568732217.100 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7452
2019-09-17T14:56:57.113Z,1568732217.113 [Depth_Keller] Loaded
2019-09-17T14:56:57.114Z,1568732217.114 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-09-17T14:56:57.118Z,1568732217.118 [DropWeight] Loaded
2019-09-17T14:56:57.119Z,1568732217.119 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-09-17T14:56:57.163Z,1568732217.163 [DVL_micro] Loaded
2019-09-17T14:56:57.164Z,1568732217.164 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-09-17T14:56:57.258Z,1568732217.258 [NAL9602] Loaded
2019-09-17T14:56:57.258Z,1568732217.258 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-09-17T14:56:57.274Z,1568732217.274 [Onboard] Loaded
2019-09-17T14:56:57.274Z,1568732217.274 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-09-17T14:56:57.277Z,1568732217.277 [Radio_Surface] Loaded
2019-09-17T14:56:57.277Z,1568732217.277 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-09-17T14:56:57.278Z,1568732217.278 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408284E0
2019-09-17T14:56:57.279Z,1568732217.279 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7453
2019-09-17T14:56:57.404Z,1568732217.404 [DAT] Loaded
2019-09-17T14:56:57.405Z,1568732217.405 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-09-17T14:56:58.894Z,1568732218.894 [BPC1] Loaded
2019-09-17T14:56:58.894Z,1568732218.894 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-09-17T14:56:58.895Z,1568732218.895 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-09-17T14:56:58.895Z,1568732218.895 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-09-17T14:56:58.963Z,1568732218.963 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-09-17T14:56:58.964Z,1568732218.964 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-09-17T14:56:59.063Z,1568732219.063 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-09-17T14:56:59.064Z,1568732219.064 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-09-17T14:56:59.095Z,1568732219.095 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-09-17T14:56:59.096Z,1568732219.096 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-09-17T14:56:59.265Z,1568732219.265 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-09-17T14:56:59.270Z,1568732219.270 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-09-17T14:56:59.270Z,1568732219.270 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-09-17T14:56:59.275Z,1568732219.275 [CTD_Seabird](INFO): created writer for : depth
2019-09-17T14:56:59.276Z,1568732219.276 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-09-17T14:56:59.281Z,1568732219.281 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-09-17T14:56:59.281Z,1568732219.281 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-09-17T14:56:59.286Z,1568732219.286 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-09-17T14:56:59.287Z,1568732219.287 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-09-17T14:56:59.292Z,1568732219.292 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-09-17T14:56:59.293Z,1568732219.293 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-09-17T14:56:59.298Z,1568732219.298 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-09-17T14:56:59.298Z,1568732219.298 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-09-17T14:56:59.304Z,1568732219.304 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-09-17T14:56:59.304Z,1568732219.304 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-09-17T14:56:59.309Z,1568732219.309 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-09-17T14:56:59.310Z,1568732219.310 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-09-17T14:56:59.310Z,1568732219.310 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-09-17T14:56:59.343Z,1568732219.343 [CTD_Seabird] Loaded
2019-09-17T14:56:59.343Z,1568732219.343 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-09-17T14:56:59.345Z,1568732219.345 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0
2019-09-17T14:56:59.345Z,1568732219.345 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7454
2019-09-17T14:56:59.359Z,1568732219.359 [PAR_Licor] Loaded
2019-09-17T14:56:59.360Z,1568732219.360 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-09-17T14:56:59.406Z,1568732219.406 [WetLabsBB2FL] Loaded
2019-09-17T14:56:59.406Z,1568732219.406 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-09-17T14:56:59.407Z,1568732219.407 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2019-09-17T14:56:59.408Z,1568732219.408 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7455
2019-09-17T14:56:59.408Z,1568732219.408 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-09-17T14:56:59.409Z,1568732219.409 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-09-17T14:56:59.901Z,1568732219.901 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-09-17T14:56:59.902Z,1568732219.902 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-09-17T14:57:00.032Z,1568732220.032 [SBIT](DEBUG): Construct Startup Built In Test.
2019-09-17T14:57:00.042Z,1568732220.042 [SBIT] Loaded
2019-09-17T14:57:00.043Z,1568732220.043 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-09-17T14:57:00.043Z,1568732220.043 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-09-17T14:57:00.055Z,1568732220.055 [IBIT] Loaded
2019-09-17T14:57:00.055Z,1568732220.055 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-09-17T14:57:00.058Z,1568732220.058 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-09-17T14:57:00.194Z,1568732220.194 [CBIT] Loaded
2019-09-17T14:57:00.194Z,1568732220.194 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-09-17T14:57:00.195Z,1568732220.195 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-09-17T14:57:00.198Z,1568732220.198 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-09-17T14:57:00.199Z,1568732220.199 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-09-17T14:57:00.206Z,1568732220.206 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-09-17T14:57:00.207Z,1568732220.207 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-09-17T14:57:00.207Z,1568732220.207 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7456
2019-09-17T14:57:00.212Z,1568732220.212 [Supervisor](INFO): Main Thread ID is 7368
2019-09-17T14:57:00.212Z,1568732220.212 [Supervisor](DEBUG): Running supervisor.
2019-09-17T14:57:00.213Z,1568732220.213 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7457
2019-09-17T14:57:00.215Z,1568732220.215 [controlThread ThreadHandler](INFO): Handler Thread ID is 7458
2019-09-17T14:57:00.215Z,1568732220.215 [controlThread](DEBUG): Initializing ControlThread
2019-09-17T14:57:00.216Z,1568732220.216 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-09-17T14:57:00.218Z,1568732220.218 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-09-17T14:57:00.218Z,1568732220.218 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-09-17T14:57:00.219Z,1568732220.219 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-09-17T14:57:00.220Z,1568732220.220 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-09-17T14:57:00.221Z,1568732220.221 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-09-17T14:57:00.221Z,1568732220.221 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-09-17T14:57:00.221Z,1568732220.221 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-09-17T14:57:00.222Z,1568732220.222 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-09-17T14:57:00.222Z,1568732220.222 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-09-17T14:57:00.223Z,1568732220.223 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-09-17T14:57:00.224Z,1568732220.224 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-09-17T14:57:00.233Z,1568732220.233 [SBIT](INFO): Initialize SBIT Component.
2019-09-17T14:57:00.234Z,1568732220.234 [SBIT](IMPORTANT): git: 2019-09-12-A
2019-09-17T14:57:00.234Z,1568732220.234 [SBIT](INFO): git hash: eb701a7227645e802542eb6676257ee77fc4e0fc
2019-09-17T14:57:00.234Z,1568732220.234 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-09-17T14:57:00.235Z,1568732220.235 [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-09-17T14:57:00.236Z,1568732220.236 [SBIT](INFO): Beginning SBIT in 37.000000 seconds.
2019-09-17T14:57:00.237Z,1568732220.237 [IBIT](INFO): Initialize IBIT Component.
2019-09-17T14:57:00.238Z,1568732220.238 [CBIT](DEBUG): Initialize CBIT Component.
2019-09-17T14:57:00.239Z,1568732220.239 [logger ThreadHandler](INFO): Handler Thread ID is 7459
2019-09-17T14:57:00.249Z,1568732220.249 [CBIT](DEBUG): Initialized mux pins.
2019-09-17T14:57:00.249Z,1568732220.249 [CBIT](DEBUG): Initializing the watchdog timer.
2019-09-17T14:57:00.257Z,1568732220.257 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7460
2019-09-17T14:57:00.258Z,1568732220.258 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-09-17T14:57:00.269Z,1568732220.269 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7461
2019-09-17T14:57:00.273Z,1568732220.273 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-09-17T14:57:00.273Z,1568732220.273 [CBIT](DEBUG): Initializing heartbeat.
2019-09-17T14:57:00.281Z,1568732220.281 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7462
2019-09-17T14:57:00.282Z,1568732220.282 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-17T14:57:00.285Z,1568732220.285 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-17T14:57:00.286Z,1568732220.286 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7464
2019-09-17T14:57:00.288Z,1568732220.288 [WetLabsBB2FL](INFO): Powering down
2019-09-17T14:57:00.314Z,1568732220.314 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7465
2019-09-17T14:57:00.317Z,1568732220.317 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-09-17T14:57:00.317Z,1568732220.317 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-09-17T14:57:00.318Z,1568732220.318 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-09-17T14:57:00.318Z,1568732220.318 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-09-17T14:57:00.318Z,1568732220.318 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-09-17T14:57:00.318Z,1568732220.318 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-09-17T14:57:00.318Z,1568732220.318 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-09-17T14:57:00.319Z,1568732220.319 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-09-17T14:57:00.319Z,1568732220.319 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-09-17T14:57:00.319Z,1568732220.319 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-09-17T14:57:00.319Z,1568732220.319 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-09-17T14:57:00.319Z,1568732220.319 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-09-17T14:57:00.320Z,1568732220.320 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-09-17T14:57:00.320Z,1568732220.320 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-09-17T14:57:00.320Z,1568732220.320 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-09-17T14:57:00.320Z,1568732220.320 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-09-17T14:57:00.345Z,1568732220.345 [CBIT](DEBUG): Deactivating GF circuits.
2019-09-17T14:57:00.345Z,1568732220.345 [CBIT](DEBUG): Deactivating emergency mode.
2019-09-17T14:57:00.381Z,1568732220.381 [CBIT](DEBUG): Backplane powered.
2019-09-17T14:57:00.382Z,1568732220.382 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-09-17T14:57:00.391Z,1568732220.391 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-17T14:57:00.401Z,1568732220.401 [MissionManager](DEBUG):
2019-09-17T14:57:00.402Z,1568732220.402 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-09-17T14:57:00.473Z,1568732220.473 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-09-17T14:57:00.474Z,1568732220.474 [Default:A.Wait](DEBUG): Construct Wait.
2019-09-17T14:57:00.475Z,1568732220.475 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-17T14:57:00.514Z,1568732220.514 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-09-17T14:57:00.517Z,1568732220.517 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-09-17T14:57:00.545Z,1568732220.545 [Default:E.Execute](DEBUG): Construct Execute.
2019-09-17T14:57:00.551Z,1568732220.551 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-09-17T14:57:00.556Z,1568732220.556 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,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-09-17T14:57:00.577Z,1568732220.577 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-17T14:57:00.658Z,1568732220.658 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-09-17T14:57:00.659Z,1568732220.659 [DAT](INFO): Powering up
2019-09-17T14:57:00.659Z,1568732220.659 [DAT](DEBUG): Initializing DAT.
2019-09-17T14:57:00.673Z,1568732220.673 [Radio_Surface](INFO): Powering up
2019-09-17T14:57:00.713Z,1568732220.713 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-09-17T14:57:00.813Z,1568732220.813 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-09-17T14:57:00.821Z,1568732220.821 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-09-17T14:57:00.822Z,1568732220.822 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-09-17T14:57:00.833Z,1568732220.833 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-09-17T14:57:00.834Z,1568732220.834 [MassServo](DEBUG): Initializing EZServoServo.
2019-09-17T14:57:00.845Z,1568732220.845 [MassServo](DEBUG): Initializing MassServo.
2019-09-17T14:57:00.846Z,1568732220.846 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-17T14:57:00.857Z,1568732220.857 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-17T14:57:00.858Z,1568732220.858 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-09-17T14:57:00.865Z,1568732220.865 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-09-17T14:57:01.062Z,1568732221.062 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2019-09-17T14:57:01.062Z,1568732221.062 [DropWeight] Hardware Fault, FailCount= 1
2019-09-17T14:57:01.062Z,1568732221.062 [DropWeight](ERROR): Hardware Fault
2019-09-17T14:57:01.123Z,1568732221.123 [CommandLine](FAULT): Scheduling is paused
2019-09-17T14:57:01.124Z,1568732221.124 [CBIT](INFO): Critical error at 20190917T145701
2019-09-17T14:57:01.124Z,1568732221.124 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-09-17T14:57:01.127Z,1568732221.127 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-09-17T14:57:01.127Z,1568732221.127 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-09-17T14:57:01.729Z,1568732221.729 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-09-17T14:57:01.729Z,1568732221.729 [RudderServo](FAULT): Rudder failed to initialize
2019-09-17T14:57:01.729Z,1568732221.729 [RudderServo] Communications Fault, FailCount= 1
2019-09-17T14:57:01.729Z,1568732221.729 [RudderServo](ERROR): Communications Fault
2019-09-17T14:57:01.850Z,1568732221.850 [CBIT](INFO): Critical error at 20190917T145701
2019-09-17T14:57:01.853Z,1568732221.853 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-09-17T14:57:02.058Z,1568732222.058 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-17T14:57:02.058Z,1568732222.058 [RudderServo](INFO): Powering down
2019-09-17T14:57:02.721Z,1568732222.721 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-17T14:57:02.842Z,1568732222.842 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-17T14:57:02.846Z,1568732222.846 [CBIT](INFO): Clearing failed state for component RudderServo
2019-09-17T14:57:02.846Z,1568732222.846 [RudderServo] No Fault, FailCount= 1
2019-09-17T14:57:04.201Z,1568732224.201 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:04.641Z,1568732224.641 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:05.141Z,1568732225.141 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:05.737Z,1568732225.737 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:06.213Z,1568732226.213 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:06.713Z,1568732226.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:07.205Z,1568732227.205 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:07.749Z,1568732227.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:08.225Z,1568732228.225 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:08.797Z,1568732228.797 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:09.213Z,1568732229.213 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:09.645Z,1568732229.645 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:10.081Z,1568732230.081 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:10.533Z,1568732230.533 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:10.625Z,1568732230.625 [CBIT](CRITICAL): Environmental Failure. Press:14.930421 PSI. Humidity:45%. Temp:24 C. ABORTING MISSION
2019-09-17T14:57:10.957Z,1568732230.957 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:11.071Z,1568732231.071 [CBIT](INFO): Critical error at 20190917T145710
2019-09-17T14:57:11.433Z,1568732231.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:11.901Z,1568732231.901 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:12.337Z,1568732232.337 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:12.757Z,1568732232.757 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:13.169Z,1568732233.169 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:13.272Z,1568732233.272 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005456
2019-09-17T14:57:13.333Z,1568732233.333 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-09-17T14:57:13.333Z,1568732233.333 [CTD_Seabird] Communications Fault, FailCount= 1
2019-09-17T14:57:13.333Z,1568732233.333 [CTD_Seabird](ERROR): Communications Fault
2019-09-17T14:57:13.613Z,1568732233.613 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:13.613Z,1568732233.613 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-17T14:57:13.613Z,1568732233.613 [AHRS_M2] Communications Fault, FailCount= 1
2019-09-17T14:57:13.613Z,1568732233.613 [AHRS_M2](ERROR): Communications Fault
2019-09-17T14:57:13.697Z,1568732233.697 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-17T14:57:13.697Z,1568732233.697 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-09-17T14:57:13.702Z,1568732233.702 [AHRS_M2](INFO): Powering down
2019-09-17T14:57:13.737Z,1568732233.737 [CTD_Seabird](INFO): Powering down
2019-09-17T14:57:13.897Z,1568732233.897 [DAT](INFO): setting local address to 5
2019-09-17T14:57:14.126Z,1568732234.126 [DAT](INFO): set local address to 5
2019-09-17T14:57:14.609Z,1568732234.609 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-17T14:57:14.609Z,1568732234.609 [AHRS_M2] No Fault, FailCount= 1
2019-09-17T14:57:14.912Z,1568732234.912 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-17T14:57:15.001Z,1568732235.001 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-09-17T14:57:15.001Z,1568732235.001 [CTD_Seabird] No Fault, FailCount= 1
2019-09-17T14:57:15.097Z,1568732235.097 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-17T14:57:15.098Z,1568732235.098 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-17T14:57:18.469Z,1568732238.469 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:18.474Z,1568732238.474 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-17T14:57:18.474Z,1568732238.474 [DVL_micro] Communications Fault, FailCount= 1
2019-09-17T14:57:18.474Z,1568732238.474 [DVL_micro](ERROR): Communications Fault
2019-09-17T14:57:18.557Z,1568732238.557 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-17T14:57:18.885Z,1568732238.885 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:18.961Z,1568732238.961 [DVL_micro](INFO): Powering down
2019-09-17T14:57:19.389Z,1568732239.389 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:19.789Z,1568732239.789 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:19.869Z,1568732239.869 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-17T14:57:19.869Z,1568732239.869 [DVL_micro] No Fault, FailCount= 1
2019-09-17T14:57:20.201Z,1568732240.201 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:20.637Z,1568732240.637 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:21.097Z,1568732241.097 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:21.509Z,1568732241.509 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:21.929Z,1568732241.929 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:22.361Z,1568732242.361 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:22.773Z,1568732242.773 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:23.193Z,1568732243.193 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:23.621Z,1568732243.621 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:24.053Z,1568732244.053 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:24.473Z,1568732244.473 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:24.913Z,1568732244.913 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:25.317Z,1568732245.317 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:25.749Z,1568732245.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:26.157Z,1568732246.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:26.573Z,1568732246.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:26.997Z,1568732246.997 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:27.429Z,1568732247.429 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:27.435Z,1568732247.435 [NAL9602](INFO): Powering up NAL9602
2019-09-17T14:57:27.853Z,1568732247.853 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:27.853Z,1568732247.853 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-17T14:57:27.853Z,1568732247.853 [AHRS_M2] Communications Fault, FailCount= 2
2019-09-17T14:57:27.853Z,1568732247.853 [AHRS_M2](ERROR): Communications Fault
2019-09-17T14:57:27.953Z,1568732247.953 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-17T14:57:27.958Z,1568732247.958 [AHRS_M2](INFO): Powering down
2019-09-17T14:57:28.149Z,1568732248.149 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-09-17T14:57:28.149Z,1568732248.149 [CTD_Seabird] Communications Fault, FailCount= 2
2019-09-17T14:57:28.149Z,1568732248.149 [CTD_Seabird](ERROR): Communications Fault
2019-09-17T14:57:28.441Z,1568732248.441 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-09-17T14:57:28.553Z,1568732248.553 [CTD_Seabird](INFO): Powering down
2019-09-17T14:57:28.861Z,1568732248.861 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-17T14:57:28.861Z,1568732248.861 [AHRS_M2] No Fault, FailCount= 2
2019-09-17T14:57:29.168Z,1568732249.168 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-17T14:57:29.659Z,1568732249.659 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-09-17T14:57:29.659Z,1568732249.659 [CTD_Seabird] No Fault, FailCount= 2
2019-09-17T14:57:29.897Z,1568732249.897 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-17T14:57:29.907Z,1568732249.907 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-17T14:57:32.713Z,1568732252.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:33.149Z,1568732253.149 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:33.569Z,1568732253.569 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:34.001Z,1568732254.001 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:34.413Z,1568732254.413 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:34.825Z,1568732254.825 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:35.281Z,1568732255.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:35.705Z,1568732255.705 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:36.125Z,1568732256.125 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:36.549Z,1568732256.549 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:36.953Z,1568732256.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:37.373Z,1568732257.373 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:37.841Z,1568732257.841 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:37.846Z,1568732257.846 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-17T14:57:37.846Z,1568732257.846 [DVL_micro] Communications Fault, FailCount= 2
2019-09-17T14:57:37.846Z,1568732257.846 [DVL_micro](ERROR): Communications Fault
2019-09-17T14:57:37.971Z,1568732257.971 [SBIT](IMPORTANT): Beginning Startup BIT
2019-09-17T14:57:37.989Z,1568732257.989 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-17T14:57:37.989Z,1568732257.989 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-17T14:57:38.325Z,1568732258.325 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:38.401Z,1568732258.401 [DVL_micro](INFO): Powering down
2019-09-17T14:57:38.905Z,1568732258.905 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:38.939Z,1568732258.939 [NAL9602](INFO): NAL9602 initialized
2019-09-17T14:57:39.501Z,1568732259.501 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:39.622Z,1568732259.622 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-17T14:57:39.622Z,1568732259.622 [DVL_micro] No Fault, FailCount= 2
2019-09-17T14:57:40.045Z,1568732260.045 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:40.097Z,1568732260.097 [NAL9602](DEBUG): Fix Requested
2019-09-17T14:57:40.649Z,1568732260.649 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:41.281Z,1568732261.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:41.817Z,1568732261.817 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:42.513Z,1568732262.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:42.513Z,1568732262.513 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-17T14:57:42.513Z,1568732262.513 [AHRS_M2] Communications Fault, FailCount= 3
2019-09-17T14:57:42.513Z,1568732262.513 [AHRS_M2](ERROR): Communications Fault
2019-09-17T14:57:42.674Z,1568732262.674 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-17T14:57:42.680Z,1568732262.680 [AHRS_M2](INFO): Powering down
2019-09-17T14:57:42.949Z,1568732262.949 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-09-17T14:57:42.949Z,1568732262.949 [CTD_Seabird] Communications Fault, FailCount= 3
2019-09-17T14:57:42.949Z,1568732262.949 [CTD_Seabird](ERROR): Communications Fault
2019-09-17T14:57:43.278Z,1568732263.278 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-09-17T14:57:43.278Z,1568732263.278 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-09-17T14:57:43.353Z,1568732263.353 [CTD_Seabird](INFO): Powering down
2019-09-17T14:57:43.693Z,1568732263.693 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-17T14:57:43.693Z,1568732263.693 [AHRS_M2] No Fault, FailCount= 3
2019-09-17T14:57:43.888Z,1568732263.888 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-17T14:57:47.445Z,1568732267.445 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:47.969Z,1568732267.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:48.405Z,1568732268.405 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:48.849Z,1568732268.849 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:49.373Z,1568732269.373 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:49.465Z,1568732269.465 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.005890
CHAN A1 (24V): 0.177833
CHAN A2 (12V): -0.004863
CHAN A3 (5V): -0.002960
CHAN B0 (3.3V): -0.000580
CHAN B1 (3.15aV): -0.001741
CHAN B2 (3.15bV): -0.001352
CHAN B3 (GND): -0.000687
OPEN: 0.004838
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-17T14:57:49.593Z,1568732269.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:50.185Z,1568732270.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:50.673Z,1568732270.673 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:51.105Z,1568732271.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:51.235Z,1568732271.235 [SBIT](FAULT): Mass: EXPECTED:0.001750 ACTUAL:0.000568
2019-09-17T14:57:51.565Z,1568732271.565 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:52.025Z,1568732272.025 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:52.497Z,1568732272.497 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:52.981Z,1568732272.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:53.445Z,1568732273.445 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:54.005Z,1568732274.005 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:54.497Z,1568732274.497 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:54.993Z,1568732274.993 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:55.469Z,1568732275.469 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:55.981Z,1568732275.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:56.465Z,1568732276.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:56.929Z,1568732276.929 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:57:56.929Z,1568732276.929 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-17T14:57:56.929Z,1568732276.929 [AHRS_M2] Communications Fault, FailCount= 4
2019-09-17T14:57:56.929Z,1568732276.929 [AHRS_M2](ERROR): Communications Fault
2019-09-17T14:57:57.105Z,1568732277.105 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-17T14:57:57.110Z,1568732277.110 [AHRS_M2](INFO): Powering down
2019-09-17T14:57:57.518Z,1568732277.518 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-17T14:57:57.518Z,1568732277.518 [DVL_micro] Communications Fault, FailCount= 3
2019-09-17T14:57:57.518Z,1568732277.518 [DVL_micro](ERROR): Communications Fault
2019-09-17T14:57:57.694Z,1568732277.694 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-17T14:57:57.695Z,1568732277.695 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-17T14:57:57.993Z,1568732277.993 [DVL_micro](INFO): Powering down
2019-09-17T14:57:58.131Z,1568732278.131 [CBIT](INFO): Critical error at 20190917T145757
2019-09-17T14:57:58.134Z,1568732278.134 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-17T14:57:58.134Z,1568732278.134 [AHRS_M2] No Fault, FailCount= 4
2019-09-17T14:57:58.320Z,1568732278.320 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-17T14:58:01.877Z,1568732281.877 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:02.305Z,1568732282.305 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:02.721Z,1568732282.721 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:03.161Z,1568732283.161 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:03.593Z,1568732283.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:04.021Z,1568732284.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:04.461Z,1568732284.461 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:04.877Z,1568732284.877 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:05.297Z,1568732285.297 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:05.701Z,1568732285.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:06.117Z,1568732286.117 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:06.521Z,1568732286.521 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:06.933Z,1568732286.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:07.357Z,1568732287.357 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:07.865Z,1568732287.865 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:08.313Z,1568732288.313 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:08.753Z,1568732288.753 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:09.169Z,1568732289.169 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:09.589Z,1568732289.589 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:09.981Z,1568732289.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:10.385Z,1568732290.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:10.789Z,1568732290.789 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:11.201Z,1568732291.201 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-09-17T14:58:11.201Z,1568732291.201 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-17T14:58:11.201Z,1568732291.201 [AHRS_M2] Communications Fault, FailCount= 5
2019-09-17T14:58:11.201Z,1568732291.201 [AHRS_M2](ERROR): Communications Fault
2019-09-17T14:58:11.266Z,1568732291.266 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-17T14:58:11.266Z,1568732291.266 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-09-17T14:58:11.271Z,1568732291.271 [AHRS_M2](INFO): Powering down
2019-09-17T14:58:11.368Z,1568732291.368 [CBIT](INFO): Critical error at 20190917T145811
2019-09-17T14:58:31.603Z,1568732311.603 [SBIT](CRITICAL): SBIT FAILED
2019-09-17T14:58:31.620Z,1568732311.620 [CommandLine](IMPORTANT): got command configSet list
2019-09-17T14:58:31.629Z,1568732311.629 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-09-17T14:58:31.630Z,1568732311.630 [CommandLine](IMPORTANT): Express linearApproximation Tracking.range_to_contact 10.000000 meter;
2019-09-17T14:58:31.630Z,1568732311.630 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=270 cubic_centimeter;
2019-09-17T14:58:31.630Z,1568732311.630 [CommandLine](IMPORTANT): VerticalControl.massDefault=-4.25 millimeter;
2019-09-17T14:58:31.658Z,1568732311.658 [CBIT](INFO): Critical error at 20190917T145831
2019-09-17T14:58:32.003Z,1568732312.003 [MissionManager](IMPORTANT): Started mission Startup
2019-09-17T14:58:32.003Z,1568732312.003 [Startup] Running Loop=1
2019-09-17T14:58:32.004Z,1568732312.004 [Startup](DEBUG): Aggregate::initialize Startup
2019-09-17T14:58:32.004Z,1568732312.004 [Startup:A.GoToSurface] Running Loop=1
2019-09-17T14:58:32.004Z,1568732312.004 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-17T14:58:32.004Z,1568732312.004 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-17T14:58:32.005Z,1568732312.005 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-17T14:58:32.005Z,1568732312.005 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-17T14:58:32.006Z,1568732312.006 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-17T14:58:32.006Z,1568732312.006 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-17T14:58:32.007Z,1568732312.007 [Startup:StartupSatComms] Running Loop=1
2019-09-17T14:58:32.007Z,1568732312.007 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-09-17T14:58:32.008Z,1568732312.008 [Startup:StartupSatComms:A] Running Loop=1
2019-09-17T14:58:32.411Z,1568732312.411 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-09-17T14:59:00.292Z,1568732340.292 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-09-17T14:59:00.293Z,1568732340.293 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-09-17T14:59:00.293Z,1568732340.293 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-17T14:59:00.303Z,1568732340.303 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-17T14:59:00.713Z,1568732340.713 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-17T14:59:00.713Z,1568732340.713 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-09-17T14:59:08.075Z,1568732348.075 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 20.000000 millimeter
2019-09-17T14:59:08.076Z,1568732348.076 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2019-09-17T14:59:08.370Z,1568732348.370 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-09-17T14:59:32.248Z,1568732372.248 [Startup:StartupSatComms:A](INFO): Timed out from 2019-09-17T14:58:32.0Z
2019-09-17T14:59:32.248Z,1568732372.248 [Startup:StartupSatComms:A] Stopped
2019-09-17T14:59:32.249Z,1568732372.249 [Startup:StartupSatComms:B] Running Loop=1
2019-09-17T14:59:32.647Z,1568732372.647 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-17T14:59:39.824Z,1568732379.824 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190916T200134/Courier0022.lzma
2019-09-17T14:59:41.830Z,1568732381.830 [DataOverHttps](INFO): Moved sent file to Logs/20190916T200134/Courier0022.lzma.bak
2019-09-17T14:59:41.831Z,1568732381.831 [DataOverHttps](INFO): SBD MOMSN=11783710
2019-09-17T14:59:50.430Z,1568732390.430 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 30.000000 millimeter
2019-09-17T14:59:59.345Z,1568732399.345 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20190917T145648/Courier0000.lzma
2019-09-17T15:00:01.351Z,1568732401.351 [DataOverHttps](INFO): Moved sent file to Logs/20190917T145648/Courier0000.lzma.bak
2019-09-17T15:00:01.351Z,1568732401.351 [DataOverHttps](INFO): SBD MOMSN=11783718
2019-09-17T15:00:10.214Z,1568732410.214 [CommandLine](IMPORTANT): got command quit
2019-09-17T15:00:11.221Z,1568732411.221 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-17T15:00:11.221Z,1568732411.221 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.281Z,1568732411.281 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-09-17T15:00:11.281Z,1568732411.281 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.281Z,1568732411.281 [CommandLine](INFO): Join timeout helper Thread ID is 7492
2019-09-17T15:00:11.282Z,1568732411.282 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-17T15:00:11.282Z,1568732411.282 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.282Z,1568732411.282 [NavChartDb](INFO): Join timeout helper Thread ID is 7493
2019-09-17T15:00:11.561Z,1568732411.561 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-17T15:00:11.561Z,1568732411.561 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.581Z,1568732411.581 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-17T15:00:11.581Z,1568732411.581 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.581Z,1568732411.581 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7494
2019-09-17T15:00:11.853Z,1568732411.853 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-17T15:00:11.853Z,1568732411.853 [WetLabsBB2FL](INFO): Powering down
2019-09-17T15:00:11.854Z,1568732411.854 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.865Z,1568732411.865 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-17T15:00:11.865Z,1568732411.865 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.865Z,1568732411.865 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7495
2019-09-17T15:00:11.949Z,1568732411.949 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-17T15:00:11.949Z,1568732411.949 [CTD_Seabird](INFO): Powering down
2019-09-17T15:00:11.961Z,1568732411.961 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.973Z,1568732411.973 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-17T15:00:11.973Z,1568732411.973 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:11.973Z,1568732411.973 [Radio_Surface](INFO): Join timeout helper Thread ID is 7496
2019-09-17T15:00:12.193Z,1568732412.193 [Radio_Surface](INFO): Powering down
2019-09-17T15:00:12.194Z,1568732412.194 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-17T15:00:12.194Z,1568732412.194 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.216Z,1568732412.216 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-17T15:00:12.216Z,1568732412.216 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.217Z,1568732412.217 [DataOverHttps](INFO): Join timeout helper Thread ID is 7497
2019-09-17T15:00:12.229Z,1568732412.229 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-17T15:00:12.233Z,1568732412.233 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.237Z,1568732412.237 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-17T15:00:12.238Z,1568732412.238 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.238Z,1568732412.238 [logger](INFO): Join timeout helper Thread ID is 7498
2019-09-17T15:00:12.261Z,1568732412.261 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-17T15:00:12.261Z,1568732412.261 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.277Z,1568732412.277 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-17T15:00:12.278Z,1568732412.278 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.278Z,1568732412.278 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-17T15:00:12.278Z,1568732412.278 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.278Z,1568732412.278 [controlThread](INFO): Join timeout helper Thread ID is 7499
2019-09-17T15:00:12.280Z,1568732412.280 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-17T15:00:12.280Z,1568732412.280 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-17T15:00:12.280Z,1568732412.280 [AHRS_M2](INFO): Powering down
2019-09-17T15:00:12.282Z,1568732412.282 [DVL_micro](INFO): Powering down
2019-09-17T15:00:12.282Z,1568732412.282 [NAL9602](INFO): Powering down
2019-09-17T15:00:12.284Z,1568732412.284 [DAT](INFO): Powering down
2019-09-17T15:00:12.402Z,1568732412.402 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-17T15:00:12.403Z,1568732412.403 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-17T15:00:12.404Z,1568732412.404 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-17T15:00:12.404Z,1568732412.404 [MissionManager](INFO): Uninitializing Mission Default
2019-09-17T15:00:12.406Z,1568732412.406 [MissionManager](INFO): Uninitializing Mission Startup
2019-09-17T15:00:12.406Z,1568732412.406 [Startup] Stopped
2019-09-17T15:00:12.406Z,1568732412.406 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-09-17T15:00:12.406Z,1568732412.406 [Startup:A.GoToSurface] Stopped
2019-09-17T15:00:12.407Z,1568732412.407 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-17T15:00:12.407Z,1568732412.407 [Startup:StartupSatComms] Stopped
2019-09-17T15:00:12.407Z,1568732412.407 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-09-17T15:00:12.407Z,1568732412.407 [Startup:StartupSatComms:B] Stopped
2019-09-17T15:00:12.408Z,1568732412.408 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-17T15:00:12.408Z,1568732412.408 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-17T15:00:12.409Z,1568732412.409 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-17T15:00:12.409Z,1568732412.409 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-17T15:00:12.409Z,1568732412.409 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-17T15:00:12.409Z,1568732412.409 [BuoyancyServo](INFO): Powering down
2019-09-17T15:00:12.421Z,1568732412.421 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-17T15:00:12.421Z,1568732412.421 [ElevatorServo](INFO): Powering down
2019-09-17T15:00:12.422Z,1568732412.422 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-17T15:00:12.422Z,1568732412.422 [MassServo](INFO): Powering down
2019-09-17T15:00:12.423Z,1568732412.423 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-17T15:00:12.423Z,1568732412.423 [RudderServo](INFO): Powering down
2019-09-17T15:00:12.423Z,1568732412.423 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-17T15:00:12.424Z,1568732412.424 [ThrusterServo](INFO): Powering down
2019-09-17T15:00:12.424Z,1568732412.424 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-17T15:00:12.425Z,1568732412.425 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-17T15:00:12.425Z,1568732412.425 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-17T15:00:12.425Z,1568732412.425 [CBIT](DEBUG): Powering off loads.
2019-09-17T15:00:12.437Z,1568732412.437 [CBIT](DEBUG): Disabling WDT.
2019-09-17T15:00:12.449Z,1568732412.449 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-17T15:00:12.449Z,1568732412.449 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.534Z,1568732412.534 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.542Z,1568732412.542 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.578Z,1568732412.578 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.581Z,1568732412.581 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.615Z,1568732412.615 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-17T15:00:12.674Z,1568732412.674 [logger ThreadHandler](INFO): Thread cancelled.