2020-02-05T23:55:50.023Z,1580946950.023 [Supervisor](DEBUG): Initializing supervisor.
2020-02-05T23:55:50.026Z,1580946950.026 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-02-05T23:55:50.027Z,1580946950.027 [SyncHandler](INFO): Protected caller Thread ID is 1448
2020-02-05T23:55:50.027Z,1580946950.027 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-02-05T23:55:50.028Z,1580946950.028 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-02-05T23:55:50.029Z,1580946950.029 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1449
2020-02-05T23:55:50.032Z,1580946950.032 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-02-05T23:55:50.044Z,1580946950.044 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-02-05T23:55:50.045Z,1580946950.045 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-02-05T23:55:50.045Z,1580946950.045 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1450
2020-02-05T23:55:50.046Z,1580946950.046 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-02-05T23:55:50.047Z,1580946950.047 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-02-05T23:55:50.047Z,1580946950.047 [logger ThreadHandler](INFO): Protected caller Thread ID is 1451
2020-02-05T23:55:50.049Z,1580946950.049 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-02-05T23:55:50.050Z,1580946950.050 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-02-05T23:55:50.051Z,1580946950.051 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-02-05T23:55:50.145Z,1580946950.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-02-05T23:55:50.146Z,1580946950.146 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-02-05T23:55:50.348Z,1580946950.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-02-05T23:55:50.349Z,1580946950.349 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-02-05T23:55:50.482Z,1580946950.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-02-05T23:55:50.482Z,1580946950.482 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-02-05T23:55:51.072Z,1580946951.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-02-05T23:55:51.072Z,1580946951.072 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-02-05T23:55:51.523Z,1580946951.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-02-05T23:55:51.523Z,1580946951.523 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-02-05T23:55:51.992Z,1580946951.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-02-05T23:55:51.992Z,1580946951.992 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-02-05T23:55:52.289Z,1580946952.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-02-05T23:55:52.290Z,1580946952.290 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-02-05T23:55:52.609Z,1580946952.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-02-05T23:55:52.610Z,1580946952.610 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-02-05T23:55:53.019Z,1580946953.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-02-05T23:55:53.020Z,1580946953.020 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-02-05T23:55:53.167Z,1580946953.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-02-05T23:55:53.168Z,1580946953.168 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-02-05T23:55:53.272Z,1580946953.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-02-05T23:55:53.272Z,1580946953.272 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-02-05T23:55:53.351Z,1580946953.351 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-02-05T23:55:53.451Z,1580946953.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-02-05T23:55:53.451Z,1580946953.451 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-02-05T23:55:53.643Z,1580946953.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-02-05T23:55:53.643Z,1580946953.643 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-02-05T23:55:53.854Z,1580946953.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-02-05T23:55:53.856Z,1580946953.856 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2020-02-05T23:55:53.857Z,1580946953.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2020-02-05T23:55:53.938Z,1580946953.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2020-02-05T23:55:54.168Z,1580946954.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-02-05T23:55:54.169Z,1580946954.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2020-02-05T23:55:54.263Z,1580946954.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2020-02-05T23:55:54.429Z,1580946954.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2020-02-05T23:55:54.638Z,1580946954.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2020-02-05T23:55:54.845Z,1580946954.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2020-02-05T23:55:55.069Z,1580946955.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2020-02-05T23:55:55.326Z,1580946955.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2020-02-05T23:55:55.796Z,1580946955.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2020-02-05T23:55:55.907Z,1580946955.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2020-02-05T23:55:56.006Z,1580946956.006 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2020-02-05T23:55:56.007Z,1580946956.007 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-02-05T23:55:56.015Z,1580946956.015 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-02-05T23:55:56.118Z,1580946956.118 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-02-05T23:55:56.118Z,1580946956.118 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-02-05T23:55:56.251Z,1580946956.251 [ElevatorServo] Loaded
2020-02-05T23:55:56.251Z,1580946956.251 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-02-05T23:55:56.266Z,1580946956.266 [RudderServo] Loaded
2020-02-05T23:55:56.267Z,1580946956.267 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-02-05T23:55:56.281Z,1580946956.281 [ThrusterServo] Loaded
2020-02-05T23:55:56.281Z,1580946956.281 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-02-05T23:55:56.282Z,1580946956.282 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-02-05T23:55:56.282Z,1580946956.282 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-02-05T23:55:56.428Z,1580946956.428 [SBIT](DEBUG): Construct Startup Built In Test.
2020-02-05T23:55:56.458Z,1580946956.458 [SBIT] Loaded
2020-02-05T23:55:56.458Z,1580946956.458 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-02-05T23:55:56.459Z,1580946956.459 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-02-05T23:55:56.488Z,1580946956.488 [IBIT] Loaded
2020-02-05T23:55:56.489Z,1580946956.489 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-02-05T23:55:56.492Z,1580946956.492 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-02-05T23:55:56.897Z,1580946956.897 [CBIT] Loaded
2020-02-05T23:55:56.897Z,1580946956.897 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-02-05T23:55:56.897Z,1580946956.897 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-02-05T23:55:56.898Z,1580946956.898 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-02-05T23:55:57.095Z,1580946957.095 [Aanderaa_O2] Loaded
2020-02-05T23:55:57.096Z,1580946957.096 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2020-02-05T23:55:57.105Z,1580946957.105 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-02-05T23:55:57.111Z,1580946957.111 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-02-05T23:55:57.112Z,1580946957.112 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-02-05T23:55:57.117Z,1580946957.117 [CTD_Seabird](INFO): created writer for : depth
2020-02-05T23:55:57.118Z,1580946957.118 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-02-05T23:55:57.123Z,1580946957.123 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-02-05T23:55:57.124Z,1580946957.124 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-02-05T23:55:57.129Z,1580946957.129 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-02-05T23:55:57.129Z,1580946957.129 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-02-05T23:55:57.135Z,1580946957.135 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-02-05T23:55:57.136Z,1580946957.136 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-02-05T23:55:57.141Z,1580946957.141 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-02-05T23:55:57.142Z,1580946957.142 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-02-05T23:55:57.147Z,1580946957.147 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-02-05T23:55:57.175Z,1580946957.175 [CTD_Seabird] Loaded
2020-02-05T23:55:57.175Z,1580946957.175 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-02-05T23:55:57.176Z,1580946957.176 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066C4E0
2020-02-05T23:55:57.176Z,1580946957.176 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1530
2020-02-05T23:55:57.207Z,1580946957.207 [ESPComponent] Loaded
2020-02-05T23:55:57.208Z,1580946957.208 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-02-05T23:55:57.223Z,1580946957.223 [PAR_Licor] Loaded
2020-02-05T23:55:57.223Z,1580946957.223 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-02-05T23:55:57.229Z,1580946957.229 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-02-05T23:55:57.229Z,1580946957.229 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-02-05T23:55:57.234Z,1580946957.234 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-02-05T23:55:57.234Z,1580946957.234 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-02-05T23:55:57.238Z,1580946957.238 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-02-05T23:55:57.238Z,1580946957.238 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-02-05T23:55:57.243Z,1580946957.243 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-02-05T23:55:57.243Z,1580946957.243 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-02-05T23:55:57.248Z,1580946957.248 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-02-05T23:55:57.248Z,1580946957.248 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-02-05T23:55:57.253Z,1580946957.253 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-02-05T23:55:57.253Z,1580946957.253 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-02-05T23:55:57.257Z,1580946957.257 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-02-05T23:55:57.257Z,1580946957.257 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-02-05T23:55:57.262Z,1580946957.262 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T23:55:57.267Z,1580946957.267 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T23:55:57.267Z,1580946957.267 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T23:55:57.267Z,1580946957.267 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T23:55:57.272Z,1580946957.272 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T23:55:57.272Z,1580946957.272 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T23:55:57.276Z,1580946957.276 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T23:55:57.276Z,1580946957.276 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-02-05T23:55:57.281Z,1580946957.281 [WetLabsBB2FL] Loaded
2020-02-05T23:55:57.281Z,1580946957.281 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-02-05T23:55:57.282Z,1580946957.282 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069C4E0
2020-02-05T23:55:57.283Z,1580946957.283 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1531
2020-02-05T23:55:57.283Z,1580946957.283 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-02-05T23:55:57.284Z,1580946957.284 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-02-05T23:55:57.326Z,1580946957.326 [DepthRateCalculator] Loaded
2020-02-05T23:55:57.326Z,1580946957.326 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-02-05T23:55:57.332Z,1580946957.332 [PitchRateCalculator] Loaded
2020-02-05T23:55:57.332Z,1580946957.332 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-02-05T23:55:57.348Z,1580946957.348 [SpeedCalculator] Loaded
2020-02-05T23:55:57.348Z,1580946957.348 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-02-05T23:55:57.369Z,1580946957.369 [TempGradientCalculator] Loaded
2020-02-05T23:55:57.370Z,1580946957.370 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-02-05T23:55:57.375Z,1580946957.375 [YawRateCalculator] Loaded
2020-02-05T23:55:57.375Z,1580946957.375 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-02-05T23:55:57.409Z,1580946957.409 [ElevatorOffsetCalculator] Loaded
2020-02-05T23:55:57.410Z,1580946957.410 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-02-05T23:55:57.410Z,1580946957.410 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-02-05T23:55:57.411Z,1580946957.411 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-02-05T23:55:57.443Z,1580946957.443 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-02-05T23:55:57.444Z,1580946957.444 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-02-05T23:55:57.550Z,1580946957.550 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-02-05T23:55:57.550Z,1580946957.550 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-02-05T23:55:57.571Z,1580946957.571 [NavChart] Loaded
2020-02-05T23:55:57.571Z,1580946957.571 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-02-05T23:55:57.575Z,1580946957.575 [UniversalFixResidualReporter] Loaded
2020-02-05T23:55:57.576Z,1580946957.576 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-02-05T23:55:57.576Z,1580946957.576 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-02-05T23:55:57.577Z,1580946957.577 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-02-05T23:55:57.874Z,1580946957.874 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-02-05T23:55:57.879Z,1580946957.879 [AHRS_M2](INFO): created writer for : platform_orientation
2020-02-05T23:55:57.879Z,1580946957.879 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-02-05T23:55:57.885Z,1580946957.885 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-02-05T23:55:57.886Z,1580946957.886 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-02-05T23:55:57.891Z,1580946957.891 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-02-05T23:55:57.892Z,1580946957.892 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-02-05T23:55:57.897Z,1580946957.897 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-02-05T23:55:57.972Z,1580946957.972 [AHRS_M2] Loaded
2020-02-05T23:55:57.973Z,1580946957.973 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-02-05T23:55:58.053Z,1580946958.053 [DataOverHttps] Loaded
2020-02-05T23:55:58.053Z,1580946958.053 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-02-05T23:55:58.054Z,1580946958.054 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2020-02-05T23:55:58.055Z,1580946958.055 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1532
2020-02-05T23:55:58.068Z,1580946958.068 [Depth_Keller] Loaded
2020-02-05T23:55:58.068Z,1580946958.068 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-02-05T23:55:58.163Z,1580946958.163 [NAL9602] Loaded
2020-02-05T23:55:58.163Z,1580946958.163 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-02-05T23:55:58.191Z,1580946958.191 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-02-05T23:55:58.191Z,1580946958.191 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-02-05T23:55:58.192Z,1580946958.192 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-02-05T23:55:58.192Z,1580946958.192 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-02-05T23:55:58.193Z,1580946958.193 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-02-05T23:55:58.194Z,1580946958.194 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-02-05T23:55:58.194Z,1580946958.194 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-02-05T23:55:58.195Z,1580946958.195 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-02-05T23:55:58.195Z,1580946958.195 [Onboard] Loaded
2020-02-05T23:55:58.195Z,1580946958.195 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-02-05T23:55:58.198Z,1580946958.198 [Radio_Surface] Loaded
2020-02-05T23:55:58.199Z,1580946958.199 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-02-05T23:55:58.200Z,1580946958.200 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409BA4E0
2020-02-05T23:55:58.200Z,1580946958.200 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1533
2020-02-05T23:55:58.338Z,1580946958.338 [DAT] Loaded
2020-02-05T23:55:58.339Z,1580946958.339 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-02-05T23:55:59.663Z,1580946959.663 [BPC1] Loaded
2020-02-05T23:55:59.663Z,1580946959.663 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-02-05T23:55:59.664Z,1580946959.664 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-02-05T23:55:59.664Z,1580946959.664 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-02-05T23:55:59.749Z,1580946959.749 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-02-05T23:55:59.750Z,1580946959.750 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-02-05T23:55:59.803Z,1580946959.803 [VerticalControl](DEBUG): Construct VerticalControl.
2020-02-05T23:55:59.895Z,1580946959.895 [VerticalControl] Loaded
2020-02-05T23:55:59.896Z,1580946959.896 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-02-05T23:55:59.896Z,1580946959.896 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-02-05T23:55:59.968Z,1580946959.968 [HorizontalControl] Loaded
2020-02-05T23:55:59.969Z,1580946959.969 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-02-05T23:55:59.969Z,1580946959.969 [SpeedControl](DEBUG): Construct SpeedControl.
2020-02-05T23:55:59.971Z,1580946959.971 [SpeedControl] Loaded
2020-02-05T23:55:59.971Z,1580946959.971 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-02-05T23:55:59.972Z,1580946959.972 [LoopControl](DEBUG): Construct LoopControl.
2020-02-05T23:55:59.973Z,1580946959.973 [LoopControl] Loaded
2020-02-05T23:55:59.973Z,1580946959.973 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-02-05T23:55:59.973Z,1580946959.973 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-02-05T23:55:59.974Z,1580946959.974 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-02-05T23:56:00.041Z,1580946960.041 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-02-05T23:56:00.042Z,1580946960.042 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-02-05T23:56:00.586Z,1580946960.586 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-02-05T23:56:00.589Z,1580946960.589 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-02-05T23:56:00.590Z,1580946960.590 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-02-05T23:56:00.597Z,1580946960.597 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-02-05T23:56:00.598Z,1580946960.598 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF94E0
2020-02-05T23:56:00.598Z,1580946960.598 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1534
2020-02-05T23:56:00.603Z,1580946960.603 [Supervisor](INFO): Main Thread ID is 1319
2020-02-05T23:56:00.603Z,1580946960.603 [Supervisor](DEBUG): Running supervisor.
2020-02-05T23:56:00.604Z,1580946960.604 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1535
2020-02-05T23:56:00.606Z,1580946960.606 [controlThread ThreadHandler](INFO): Handler Thread ID is 1536
2020-02-05T23:56:00.606Z,1580946960.606 [controlThread](DEBUG): Initializing ControlThread
2020-02-05T23:56:00.608Z,1580946960.608 [SBIT](INFO): Initialize SBIT Component.
2020-02-05T23:56:00.608Z,1580946960.608 [SBIT](IMPORTANT): git: 2019-12-10
2020-02-05T23:56:00.609Z,1580946960.609 [SBIT](INFO): git hash: 66d82f7afabd4173e737eff51d4a73f4137f1e60
2020-02-05T23:56:00.609Z,1580946960.609 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-02-05T23:56:00.610Z,1580946960.610 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2020-02-05T23:56:00.611Z,1580946960.611 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2020-02-05T23:56:00.612Z,1580946960.612 [IBIT](INFO): Initialize IBIT Component.
2020-02-05T23:56:00.613Z,1580946960.613 [CBIT](DEBUG): Initialize CBIT Component.
2020-02-05T23:56:00.614Z,1580946960.614 [logger ThreadHandler](INFO): Handler Thread ID is 1537
2020-02-05T23:56:00.625Z,1580946960.625 [CBIT](DEBUG): Initialized mux pins.
2020-02-05T23:56:00.625Z,1580946960.625 [CBIT](DEBUG): Initializing the watchdog timer.
2020-02-05T23:56:00.633Z,1580946960.633 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1538
2020-02-05T23:56:00.634Z,1580946960.634 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-02-05T23:56:00.637Z,1580946960.637 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-02-05T23:56:00.639Z,1580946960.639 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1540
2020-02-05T23:56:00.639Z,1580946960.639 [WetLabsBB2FL](INFO): Powering down
2020-02-05T23:56:00.649Z,1580946960.649 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-02-05T23:56:00.649Z,1580946960.649 [CBIT](DEBUG): Initializing heartbeat.
2020-02-05T23:56:00.673Z,1580946960.673 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1541
2020-02-05T23:56:00.674Z,1580946960.674 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-02-05T23:56:00.693Z,1580946960.693 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1542
2020-02-05T23:56:00.705Z,1580946960.705 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1543
2020-02-05T23:56:00.708Z,1580946960.708 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-02-05T23:56:00.709Z,1580946960.709 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-02-05T23:56:00.709Z,1580946960.709 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-02-05T23:56:00.709Z,1580946960.709 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-02-05T23:56:00.709Z,1580946960.709 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-02-05T23:56:00.709Z,1580946960.709 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-02-05T23:56:00.710Z,1580946960.710 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-02-05T23:56:00.710Z,1580946960.710 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-02-05T23:56:00.710Z,1580946960.710 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-02-05T23:56:00.710Z,1580946960.710 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-02-05T23:56:00.710Z,1580946960.710 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-02-05T23:56:00.710Z,1580946960.710 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-02-05T23:56:00.711Z,1580946960.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-02-05T23:56:00.711Z,1580946960.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-02-05T23:56:00.711Z,1580946960.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-02-05T23:56:00.711Z,1580946960.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-02-05T23:56:00.721Z,1580946960.721 [CBIT](DEBUG): Deactivating GF circuits.
2020-02-05T23:56:00.721Z,1580946960.721 [CBIT](DEBUG): Deactivating emergency mode.
2020-02-05T23:56:00.757Z,1580946960.757 [CBIT](DEBUG): Backplane powered.
2020-02-05T23:56:00.761Z,1580946960.761 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-02-05T23:56:00.761Z,1580946960.761 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-02-05T23:56:00.762Z,1580946960.762 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-02-05T23:56:00.762Z,1580946960.762 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-02-05T23:56:00.763Z,1580946960.763 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-02-05T23:56:00.763Z,1580946960.763 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-02-05T23:56:00.764Z,1580946960.764 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-02-05T23:56:00.764Z,1580946960.764 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-02-05T23:56:00.767Z,1580946960.767 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-02-05T23:56:00.773Z,1580946960.773 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-02-05T23:56:00.773Z,1580946960.773 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-02-05T23:56:00.774Z,1580946960.774 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-02-05T23:56:00.775Z,1580946960.775 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-02-05T23:56:00.809Z,1580946960.809 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-05T23:56:00.845Z,1580946960.845 [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>


2020-02-05T23:56:00.846Z,1580946960.846 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-02-05T23:56:00.934Z,1580946960.934 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-02-05T23:56:00.935Z,1580946960.935 [Default:A.Wait](DEBUG): Construct Wait.
2020-02-05T23:56:00.961Z,1580946960.961 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-05T23:56:00.989Z,1580946960.989 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-02-05T23:56:00.992Z,1580946960.992 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-02-05T23:56:01.011Z,1580946961.011 [Default:E.Execute](DEBUG): Construct Execute.
2020-02-05T23:56:01.027Z,1580946961.027 [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>


2020-02-05T23:56:01.032Z,1580946961.032 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,ElevatorServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-02-05T23:56:01.074Z,1580946961.074 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-05T23:56:01.097Z,1580946961.097 [Radio_Surface](INFO): Powering up
2020-02-05T23:56:01.115Z,1580946961.115 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2020-02-05T23:56:01.150Z,1580946961.150 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-02-05T23:56:01.150Z,1580946961.150 [DAT](INFO): Powering up
2020-02-05T23:56:01.150Z,1580946961.150 [DAT](DEBUG): Initializing DAT.
2020-02-05T23:56:01.205Z,1580946961.205 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-02-05T23:56:01.227Z,1580946961.227 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-05T23:56:01.248Z,1580946961.248 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-05T23:56:01.257Z,1580946961.257 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-05T23:56:01.258Z,1580946961.258 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-05T23:56:01.269Z,1580946961.269 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-05T23:56:01.270Z,1580946961.270 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-02-05T23:56:01.281Z,1580946961.281 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-02-05T23:56:02.205Z,1580946962.205 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-02-05T23:56:02.205Z,1580946962.205 [RudderServo](FAULT): Rudder failed to initialize
2020-02-05T23:56:02.205Z,1580946962.205 [RudderServo] Communications Fault, FailCount= 1
2020-02-05T23:56:02.205Z,1580946962.205 [RudderServo](ERROR): Communications Fault
2020-02-05T23:56:02.316Z,1580946962.316 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-02-05T23:56:02.441Z,1580946962.441 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-05T23:56:02.441Z,1580946962.441 [RudderServo](INFO): Powering down
2020-02-05T23:56:03.183Z,1580946963.183 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-05T23:56:03.302Z,1580946963.302 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-05T23:56:03.306Z,1580946963.306 [CBIT](INFO): Clearing failed state for component RudderServo
2020-02-05T23:56:03.306Z,1580946963.306 [RudderServo] No Fault, FailCount= 1
2020-02-05T23:56:04.669Z,1580946964.669 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:05.109Z,1580946965.109 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:05.549Z,1580946965.549 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:06.097Z,1580946966.097 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:06.541Z,1580946966.541 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:06.985Z,1580946966.985 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:07.433Z,1580946967.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:07.845Z,1580946967.845 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:08.293Z,1580946968.293 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:08.725Z,1580946968.725 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:09.157Z,1580946969.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:09.609Z,1580946969.609 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:10.057Z,1580946970.057 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:10.553Z,1580946970.553 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:10.620Z,1580946970.620 [CBIT](CRITICAL): Environmental Failure. Press:14.828650 PSI. Humidity:25%. Temp:21 C. ABORTING MISSION
2020-02-05T23:56:10.621Z,1580946970.621 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2020-02-05T23:56:10.989Z,1580946970.989 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:11.042Z,1580946971.042 [CommandLine](FAULT): Scheduling is paused
2020-02-05T23:56:11.042Z,1580946971.042 [CBIT](INFO): Critical error at 20200205T235610
2020-02-05T23:56:11.065Z,1580946971.065 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:11.065Z,1580946971.065 [Aanderaa_O2] Communications Fault, FailCount= 1
2020-02-05T23:56:11.065Z,1580946971.065 [Aanderaa_O2](ERROR): Communications Fault
2020-02-05T23:56:11.441Z,1580946971.441 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:11.479Z,1580946971.479 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2020-02-05T23:56:11.601Z,1580946971.601 [Aanderaa_O2](INFO): Powering down
2020-02-05T23:56:11.989Z,1580946971.989 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:12.433Z,1580946972.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:12.453Z,1580946972.453 [DAT](DEBUG): 
2020-02-05T23:56:12.453Z,1580946972.453 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2020-02-05T23:56:12.861Z,1580946972.861 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:12.924Z,1580946972.924 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2020-02-05T23:56:12.924Z,1580946972.924 [Aanderaa_O2] No Fault, FailCount= 1
2020-02-05T23:56:13.313Z,1580946973.313 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:13.685Z,1580946973.685 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:13.685Z,1580946973.685 [CTD_Seabird] Communications Fault, FailCount= 1
2020-02-05T23:56:13.685Z,1580946973.685 [CTD_Seabird](ERROR): Communications Fault
2020-02-05T23:56:13.765Z,1580946973.765 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:13.785Z,1580946973.785 [DAT](DEBUG): MF Frequency Band
2020-02-05T23:56:13.786Z,1580946973.786 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21
2020-02-05T23:56:13.786Z,1580946973.786 [DAT](DEBUG): Oct 12 2000 12:34:57 
2020-02-05T23:56:13.824Z,1580946973.824 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-02-05T23:56:14.089Z,1580946974.089 [CTD_Seabird](INFO): Powering down
2020-02-05T23:56:14.185Z,1580946974.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:14.185Z,1580946974.185 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:14.185Z,1580946974.185 [AHRS_M2] Communications Fault, FailCount= 1
2020-02-05T23:56:14.185Z,1580946974.185 [AHRS_M2](ERROR): Communications Fault
2020-02-05T23:56:14.224Z,1580946974.224 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-02-05T23:56:14.286Z,1580946974.286 [AHRS_M2](INFO): Powering down
2020-02-05T23:56:14.487Z,1580946974.487 [DAT](DEBUG): Features enabled [Bearing]
2020-02-05T23:56:14.488Z,1580946974.488 [DAT](DEBUG): WARNING: diag.rom image not found; POST not performed
2020-02-05T23:56:14.661Z,1580946974.661 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2020-02-05T23:56:14.662Z,1580946974.662 [DAT](INFO): commRate: 800
2020-02-05T23:56:15.064Z,1580946975.064 [DAT](INFO): entering command mode
2020-02-05T23:56:15.100Z,1580946975.100 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-02-05T23:56:15.100Z,1580946975.100 [CTD_Seabird] No Fault, FailCount= 1
2020-02-05T23:56:15.100Z,1580946975.100 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-02-05T23:56:15.100Z,1580946975.100 [AHRS_M2] No Fault, FailCount= 1
2020-02-05T23:56:15.449Z,1580946975.449 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-05T23:56:15.476Z,1580946975.476 [DAT](DEBUG): checking for command mode acknowledgment
2020-02-05T23:56:15.497Z,1580946975.497 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-02-05T23:56:15.509Z,1580946975.509 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-02-05T23:56:15.872Z,1580946975.872 [DAT](DEBUG): checking for command mode acknowledgment
2020-02-05T23:56:16.277Z,1580946976.277 [DAT](DEBUG): checking for command mode acknowledgment
2020-02-05T23:56:16.277Z,1580946976.277 [DAT](INFO): command mode acknowledged
2020-02-05T23:56:16.278Z,1580946976.278 [DAT](INFO): setting verbose to 3
2020-02-05T23:56:16.680Z,1580946976.680 [DAT](DEBUG): checking for verbose setting acknowledgment
2020-02-05T23:56:16.681Z,1580946976.681 [DAT](INFO): set verbose to 3
2020-02-05T23:56:17.086Z,1580946977.086 [DAT](INFO): setting transmit power to 8
2020-02-05T23:56:17.492Z,1580946977.492 [DAT](DEBUG): checking for transmit power setting acknowledgment
2020-02-05T23:56:17.892Z,1580946977.892 [DAT](DEBUG): checking for transmit power setting acknowledgment
2020-02-05T23:56:17.893Z,1580946977.893 [DAT](INFO): set transmit power to 8
2020-02-05T23:56:18.298Z,1580946978.298 [DAT](INFO): setting local address to 4
2020-02-05T23:56:19.005Z,1580946979.005 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:19.026Z,1580946979.026 [DAT](DEBUG): checking for local address setting acknowledgment
2020-02-05T23:56:19.453Z,1580946979.453 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:19.472Z,1580946979.472 [DAT](DEBUG): checking for local address setting acknowledgment
2020-02-05T23:56:19.473Z,1580946979.473 [DAT](INFO): set local address to 4
2020-02-05T23:56:19.893Z,1580946979.893 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:20.329Z,1580946980.329 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:20.765Z,1580946980.765 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:21.205Z,1580946981.205 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:21.637Z,1580946981.637 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:22.089Z,1580946982.089 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:22.521Z,1580946982.521 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:22.969Z,1580946982.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:23.046Z,1580946983.046 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:23.046Z,1580946983.046 [Aanderaa_O2] Communications Fault, FailCount= 2
2020-02-05T23:56:23.047Z,1580946983.047 [Aanderaa_O2](ERROR): Communications Fault
2020-02-05T23:56:23.405Z,1580946983.405 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:23.494Z,1580946983.494 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2020-02-05T23:56:23.629Z,1580946983.629 [Aanderaa_O2](INFO): Powering down
2020-02-05T23:56:23.953Z,1580946983.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:24.389Z,1580946984.389 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:24.825Z,1580946984.825 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:24.880Z,1580946984.880 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2020-02-05T23:56:24.880Z,1580946984.880 [Aanderaa_O2] No Fault, FailCount= 2
2020-02-05T23:56:25.269Z,1580946985.269 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:25.697Z,1580946985.697 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:26.125Z,1580946986.125 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:26.565Z,1580946986.565 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:26.997Z,1580946986.997 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:27.449Z,1580946987.449 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:27.901Z,1580946987.901 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:28.353Z,1580946988.353 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:28.353Z,1580946988.353 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:28.353Z,1580946988.353 [AHRS_M2] Communications Fault, FailCount= 2
2020-02-05T23:56:28.353Z,1580946988.353 [AHRS_M2](ERROR): Communications Fault
2020-02-05T23:56:28.358Z,1580946988.358 [NAL9602](INFO): Powering up NAL9602
2020-02-05T23:56:28.423Z,1580946988.423 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-02-05T23:56:28.486Z,1580946988.486 [AHRS_M2](INFO): Powering down
2020-02-05T23:56:28.549Z,1580946988.549 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:28.549Z,1580946988.549 [CTD_Seabird] Communications Fault, FailCount= 2
2020-02-05T23:56:28.549Z,1580946988.549 [CTD_Seabird](ERROR): Communications Fault
2020-02-05T23:56:28.624Z,1580946988.624 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-02-05T23:56:28.953Z,1580946988.953 [CTD_Seabird](INFO): Powering down
2020-02-05T23:56:29.307Z,1580946989.307 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-02-05T23:56:29.308Z,1580946989.308 [AHRS_M2] No Fault, FailCount= 2
2020-02-05T23:56:29.657Z,1580946989.657 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-05T23:56:30.116Z,1580946990.116 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-02-05T23:56:30.116Z,1580946990.116 [CTD_Seabird] No Fault, FailCount= 2
2020-02-05T23:56:30.297Z,1580946990.297 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-02-05T23:56:30.305Z,1580946990.305 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-02-05T23:56:33.213Z,1580946993.213 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:33.653Z,1580946993.653 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:34.089Z,1580946994.089 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:34.545Z,1580946994.545 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:34.989Z,1580946994.989 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:35.074Z,1580946995.074 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:35.074Z,1580946995.074 [Aanderaa_O2] Communications Fault, FailCount= 3
2020-02-05T23:56:35.074Z,1580946995.074 [Aanderaa_O2](ERROR): Communications Fault
2020-02-05T23:56:35.437Z,1580946995.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:35.511Z,1580946995.511 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2020-02-05T23:56:35.512Z,1580946995.512 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2020-02-05T23:56:35.633Z,1580946995.633 [Aanderaa_O2](INFO): Powering down
2020-02-05T23:56:35.957Z,1580946995.957 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:36.409Z,1580946996.409 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:36.845Z,1580946996.845 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:37.281Z,1580946997.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:37.721Z,1580946997.721 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:38.161Z,1580946998.161 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:38.585Z,1580946998.585 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:39.041Z,1580946999.041 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:39.513Z,1580946999.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:39.518Z,1580946999.518 [NAL9602](INFO): NAL9602 initialized
2020-02-05T23:56:39.977Z,1580946999.977 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:40.465Z,1580947000.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:40.937Z,1580947000.937 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:41.409Z,1580947001.409 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:41.877Z,1580947001.877 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:42.333Z,1580947002.333 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:42.801Z,1580947002.801 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:42.801Z,1580947002.801 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:42.801Z,1580947002.801 [AHRS_M2] Communications Fault, FailCount= 3
2020-02-05T23:56:42.801Z,1580947002.801 [AHRS_M2](ERROR): Communications Fault
2020-02-05T23:56:42.894Z,1580947002.894 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-02-05T23:56:42.953Z,1580947002.953 [AHRS_M2](INFO): Powering down
2020-02-05T23:56:43.361Z,1580947003.361 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:43.361Z,1580947003.361 [CTD_Seabird] Communications Fault, FailCount= 3
2020-02-05T23:56:43.361Z,1580947003.361 [CTD_Seabird](ERROR): Communications Fault
2020-02-05T23:56:43.376Z,1580947003.376 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-02-05T23:56:43.376Z,1580947003.376 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2020-02-05T23:56:43.765Z,1580947003.765 [CTD_Seabird](INFO): Powering down
2020-02-05T23:56:43.774Z,1580947003.774 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-02-05T23:56:43.774Z,1580947003.774 [AHRS_M2] No Fault, FailCount= 3
2020-02-05T23:56:44.109Z,1580947004.109 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-05T23:56:47.198Z,1580947007.198 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 20.000000 millimeter
2020-02-05T23:56:47.199Z,1580947007.199 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2020-02-05T23:56:47.673Z,1580947007.673 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:47.744Z,1580947007.744 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,ElevatorServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-02-05T23:56:48.109Z,1580947008.109 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:48.565Z,1580947008.565 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:49.009Z,1580947009.009 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:49.485Z,1580947009.485 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:49.925Z,1580947009.925 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:50.349Z,1580947010.349 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:50.785Z,1580947010.785 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:51.209Z,1580947011.209 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:51.633Z,1580947011.633 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:52.061Z,1580947012.061 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:52.509Z,1580947012.509 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:52.945Z,1580947012.945 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:53.389Z,1580947013.389 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:53.825Z,1580947013.825 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:54.289Z,1580947014.289 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:54.713Z,1580947014.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:55.141Z,1580947015.141 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:55.597Z,1580947015.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:56.021Z,1580947016.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:56.437Z,1580947016.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:56.857Z,1580947016.857 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:57.281Z,1580947017.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:56:57.281Z,1580947017.281 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-02-05T23:56:57.281Z,1580947017.281 [AHRS_M2] Communications Fault, FailCount= 4
2020-02-05T23:56:57.281Z,1580947017.281 [AHRS_M2](ERROR): Communications Fault
2020-02-05T23:56:57.353Z,1580947017.353 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-02-05T23:56:57.389Z,1580947017.389 [AHRS_M2](INFO): Powering down
2020-02-05T23:56:58.216Z,1580947018.216 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-02-05T23:56:58.216Z,1580947018.216 [AHRS_M2] No Fault, FailCount= 4
2020-02-05T23:56:58.573Z,1580947018.573 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-05T23:57:02.129Z,1580947022.129 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:02.589Z,1580947022.589 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:03.069Z,1580947023.069 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:03.125Z,1580947023.125 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2020-02-05T23:57:03.126Z,1580947023.126 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 40.000000 mm
2020-02-05T23:57:03.517Z,1580947023.517 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:03.965Z,1580947023.965 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:04.421Z,1580947024.421 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:04.473Z,1580947024.473 [SBIT](IMPORTANT): Beginning Startup BIT
2020-02-05T23:57:04.478Z,1580947024.478 [CBIT](IMPORTANT): Beginning ground fault scan
2020-02-05T23:57:04.869Z,1580947024.869 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:05.297Z,1580947025.297 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:05.729Z,1580947025.729 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:06.157Z,1580947026.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:06.713Z,1580947026.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:07.197Z,1580947027.197 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:07.649Z,1580947027.649 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:08.205Z,1580947028.205 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:08.661Z,1580947028.661 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:09.117Z,1580947029.117 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:09.701Z,1580947029.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:10.257Z,1580947030.257 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:10.789Z,1580947030.789 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:11.285Z,1580947031.285 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:11.717Z,1580947031.717 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-02-05T23:57:11.717Z,1580947031.717 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-02-05T23:57:11.717Z,1580947031.717 [AHRS_M2] Communications Fault, FailCount= 5
2020-02-05T23:57:11.717Z,1580947031.717 [AHRS_M2](ERROR): Communications Fault
2020-02-05T23:57:11.808Z,1580947031.808 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-02-05T23:57:11.808Z,1580947031.808 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2020-02-05T23:57:11.885Z,1580947031.885 [AHRS_M2](INFO): Powering down
2020-02-05T23:57:12.005Z,1580947032.005 [CBIT](INFO): Critical error at 20200205T235711
2020-02-05T23:57:12.559Z,1580947032.559 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 40.000000 millimeter
2020-02-05T23:57:16.309Z,1580947036.309 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008646
CHAN A1 (24V): -0.023509
CHAN A2 (12V): -0.005731
CHAN A3 (5V): -0.002563
CHAN B0 (3.3V): -0.000942
CHAN B1 (3.15aV): -0.001555
CHAN B2 (3.15bV): -0.000875
CHAN B3 (GND): 0.000393
OPEN: -0.000936
Full Scale Calc: 4.765 mA, -1.589 mA
2020-02-05T23:57:17.501Z,1580947037.501 [SBIT](ERROR): Could not read massPosReader_.
2020-02-05T23:57:44.165Z,1580947064.165 [SBIT](ERROR): Could not read massPosReader_.
2020-02-05T23:57:44.165Z,1580947064.165 [SBIT](FAULT): Control surface position failure.
2020-02-05T23:57:55.450Z,1580947075.450 [CommandLine](IMPORTANT): got command failComponent
2020-02-05T23:57:55.450Z,1580947075.450 [CommandLine](IMPORTANT): Failed components:
2020-02-05T23:57:55.450Z,1580947075.450 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2020-02-05T23:57:55.450Z,1580947075.450 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2020-02-05T23:57:55.451Z,1580947075.451 [CommandLine](IMPORTANT): AHRS_M2: Communications Fault
2020-02-05T23:57:57.910Z,1580947077.910 [SBIT](ERROR): Could not read massPosReader_.
2020-02-05T23:57:57.911Z,1580947077.911 [SBIT](FAULT): Control surface position failure.
2020-02-05T23:57:58.322Z,1580947078.322 [SBIT](CRITICAL): SBIT FAILED
2020-02-05T23:57:58.338Z,1580947078.338 [CBIT](INFO): Critical error at 20200205T235758
2020-02-05T23:57:58.361Z,1580947078.361 [CommandLine](IMPORTANT): got command configSet list
2020-02-05T23:57:58.362Z,1580947078.362 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-02-05T23:57:58.362Z,1580947078.362 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-02-05T23:57:58.363Z,1580947078.363 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool;

2020-02-05T23:57:58.363Z,1580947078.363 [CommandLine](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-02-05T23:57:58.363Z,1580947078.363 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2020-02-05T23:57:58.363Z,1580947078.363 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-02-05T23:57:58.363Z,1580947078.363 [CommandLine](IMPORTANT): MassServo.loadAtStartup=0 bool;

2020-02-05T23:57:58.363Z,1580947078.363 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=90 cubic_centimeter;

2020-02-05T23:57:58.363Z,1580947078.363 [CommandLine](IMPORTANT): VerticalControl.massDefault=11 millimeter;

2020-02-05T23:57:58.720Z,1580947078.720 [MissionManager](IMPORTANT): Started mission Startup
2020-02-05T23:57:58.720Z,1580947078.720 [Startup] Running Loop=1
2020-02-05T23:57:58.720Z,1580947078.720 [Startup](DEBUG): Aggregate::initialize Startup
2020-02-05T23:57:58.720Z,1580947078.720 [Startup:A.GoToSurface] Running Loop=1
2020-02-05T23:57:58.720Z,1580947078.720 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-05T23:57:58.721Z,1580947078.721 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-05T23:57:58.721Z,1580947078.721 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-05T23:57:58.722Z,1580947078.722 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-05T23:57:58.722Z,1580947078.722 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-05T23:57:58.722Z,1580947078.722 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-05T23:57:58.724Z,1580947078.724 [Startup:StartupSatComms] Running Loop=1
2020-02-05T23:57:58.724Z,1580947078.724 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-02-05T23:57:58.724Z,1580947078.724 [Startup:StartupSatComms:A] Running Loop=1
2020-02-05T23:57:59.116Z,1580947079.116 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-02-05T23:58:01.140Z,1580947081.140 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-02-05T23:58:01.140Z,1580947081.140 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-02-05T23:58:01.140Z,1580947081.140 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-02-05T23:58:01.150Z,1580947081.150 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-02-05T23:58:01.558Z,1580947081.558 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-02-05T23:58:01.558Z,1580947081.558 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-02-05T23:58:29.841Z,1580947109.841 [CommandLine](IMPORTANT): got command configSet list
2020-02-05T23:58:29.842Z,1580947109.842 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-02-05T23:58:29.842Z,1580947109.842 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-02-05T23:58:29.843Z,1580947109.843 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool;

2020-02-05T23:58:29.843Z,1580947109.843 [CommandLine](IMPORTANT): DAT.sbdAddress=-1 enum;

2020-02-05T23:58:29.843Z,1580947109.843 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2020-02-05T23:58:29.843Z,1580947109.843 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-02-05T23:58:29.843Z,1580947109.843 [CommandLine](IMPORTANT): MassServo.loadAtStartup=0 bool;

2020-02-05T23:58:29.843Z,1580947109.843 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=90 cubic_centimeter;

2020-02-05T23:58:29.843Z,1580947109.843 [CommandLine](IMPORTANT): VerticalControl.massDefault=11 millimeter;

2020-02-05T23:58:52.303Z,1580947132.303 [CommandLine](IMPORTANT): got command configSet MassServo.loadAtStartup 1.000000 bool persist
2020-02-05T23:58:52.303Z,1580947132.303 [CommandLine](IMPORTANT): configSet MassServo.loadAtStartup requires a restart to take effect.
2020-02-05T23:58:52.735Z,1580947132.735 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004831
2020-02-05T23:58:57.158Z,1580947137.158 [CommandLine](IMPORTANT): got command restart application
2020-02-05T23:58:58.165Z,1580947138.165 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T23:58:58.165Z,1580947138.165 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.201Z,1580947138.201 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-02-05T23:58:58.201Z,1580947138.201 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.201Z,1580947138.201 [CommandLine](INFO): Join timeout helper Thread ID is 1565
2020-02-05T23:58:58.202Z,1580947138.202 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-05T23:58:58.202Z,1580947138.202 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.202Z,1580947138.202 [NavChartDb](INFO): Join timeout helper Thread ID is 1566
2020-02-05T23:58:58.273Z,1580947138.273 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T23:58:58.273Z,1580947138.273 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.281Z,1580947138.281 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-05T23:58:58.281Z,1580947138.281 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.281Z,1580947138.281 [Radio_Surface](INFO): Join timeout helper Thread ID is 1567
2020-02-05T23:58:58.489Z,1580947138.489 [Radio_Surface](INFO): Powering down
2020-02-05T23:58:58.490Z,1580947138.490 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T23:58:58.490Z,1580947138.490 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.501Z,1580947138.501 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-05T23:58:58.501Z,1580947138.501 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.501Z,1580947138.501 [DataOverHttps](INFO): Join timeout helper Thread ID is 1568
2020-02-05T23:58:58.753Z,1580947138.753 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T23:58:58.753Z,1580947138.753 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.761Z,1580947138.761 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-02-05T23:58:58.761Z,1580947138.761 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:58.761Z,1580947138.761 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1569
2020-02-05T23:58:58.917Z,1580947138.917 [Startup:StartupSatComms:A](INFO): Timed out from 2020-02-05T23:57:58.7Z
2020-02-05T23:58:58.918Z,1580947138.918 [Startup:StartupSatComms:A] Stopped
2020-02-05T23:58:58.918Z,1580947138.918 [Startup:StartupSatComms:B] Running Loop=1
2020-02-05T23:58:59.113Z,1580947139.113 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T23:58:59.114Z,1580947139.114 [WetLabsBB2FL](INFO): Powering down
2020-02-05T23:58:59.114Z,1580947139.114 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.130Z,1580947139.130 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-02-05T23:58:59.130Z,1580947139.130 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.130Z,1580947139.130 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1570
2020-02-05T23:58:59.245Z,1580947139.245 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T23:58:59.245Z,1580947139.245 [CTD_Seabird](INFO): Powering down
2020-02-05T23:58:59.257Z,1580947139.257 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.282Z,1580947139.282 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-05T23:58:59.282Z,1580947139.282 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.282Z,1580947139.282 [logger](INFO): Join timeout helper Thread ID is 1571
2020-02-05T23:58:59.292Z,1580947139.292 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T23:58:59.292Z,1580947139.292 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.295Z,1580947139.295 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-05T23:58:59.295Z,1580947139.295 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.296Z,1580947139.296 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-05T23:58:59.296Z,1580947139.296 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.296Z,1580947139.296 [controlThread](INFO): Join timeout helper Thread ID is 1572
2020-02-05T23:58:59.334Z,1580947139.334 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-05T23:58:59.565Z,1580947139.565 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-05T23:58:59.565Z,1580947139.565 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-05T23:58:59.566Z,1580947139.566 [Aanderaa_O2](INFO): Powering down
2020-02-05T23:58:59.587Z,1580947139.587 [AHRS_M2](INFO): Powering down
2020-02-05T23:58:59.588Z,1580947139.588 [NAL9602](INFO): Powering down
2020-02-05T23:58:59.589Z,1580947139.589 [DAT](INFO): Powering down
2020-02-05T23:58:59.710Z,1580947139.710 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-05T23:58:59.711Z,1580947139.711 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-05T23:58:59.711Z,1580947139.711 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-05T23:58:59.712Z,1580947139.712 [MissionManager](INFO): Uninitializing Mission Default
2020-02-05T23:58:59.714Z,1580947139.714 [MissionManager](INFO): Uninitializing Mission Startup
2020-02-05T23:58:59.714Z,1580947139.714 [Startup] Stopped
2020-02-05T23:58:59.714Z,1580947139.714 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-02-05T23:58:59.714Z,1580947139.714 [Startup:A.GoToSurface] Stopped
2020-02-05T23:58:59.714Z,1580947139.714 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-05T23:58:59.714Z,1580947139.714 [Startup:StartupSatComms] Stopped
2020-02-05T23:58:59.714Z,1580947139.714 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-02-05T23:58:59.715Z,1580947139.715 [Startup:StartupSatComms:B] Stopped
2020-02-05T23:58:59.716Z,1580947139.716 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-05T23:58:59.716Z,1580947139.716 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-05T23:58:59.716Z,1580947139.716 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-05T23:58:59.717Z,1580947139.717 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-05T23:58:59.717Z,1580947139.717 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-05T23:58:59.717Z,1580947139.717 [ElevatorServo](INFO): Powering down
2020-02-05T23:58:59.718Z,1580947139.718 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-05T23:58:59.718Z,1580947139.718 [RudderServo](INFO): Powering down
2020-02-05T23:58:59.719Z,1580947139.719 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-05T23:58:59.719Z,1580947139.719 [ThrusterServo](INFO): Powering down
2020-02-05T23:58:59.720Z,1580947139.720 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-05T23:58:59.720Z,1580947139.720 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-05T23:58:59.720Z,1580947139.720 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-05T23:58:59.720Z,1580947139.720 [CBIT](DEBUG): Powering off loads.
2020-02-05T23:58:59.733Z,1580947139.733 [CBIT](DEBUG): Disabling WDT.
2020-02-05T23:58:59.745Z,1580947139.745 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-05T23:58:59.745Z,1580947139.745 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.781Z,1580947139.781 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.787Z,1580947139.787 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.855Z,1580947139.855 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.860Z,1580947139.860 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.915Z,1580947139.915 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-05T23:58:59.974Z,1580947139.974 [logger ThreadHandler](INFO): Thread cancelled.