2020-11-19T18:43:56.593Z,1605811436.593 [Supervisor](DEBUG): Initializing supervisor. 2020-11-19T18:43:56.607Z,1605811436.607 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-11-19T18:43:56.613Z,1605811436.613 [SyncHandler](INFO): Protected caller Thread ID is 5604 2020-11-19T18:43:56.615Z,1605811436.615 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-11-19T18:43:56.644Z,1605811436.644 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-11-19T18:43:56.645Z,1605811436.645 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5605 2020-11-19T18:43:56.651Z,1605811436.651 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-11-19T18:43:56.670Z,1605811436.670 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-11-19T18:43:56.671Z,1605811436.671 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-11-19T18:43:56.671Z,1605811436.671 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5606 2020-11-19T18:43:56.672Z,1605811436.672 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-11-19T18:43:56.673Z,1605811436.673 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-11-19T18:43:56.673Z,1605811436.673 [logger ThreadHandler](INFO): Protected caller Thread ID is 5607 2020-11-19T18:43:56.675Z,1605811436.675 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-11-19T18:43:56.675Z,1605811436.675 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-11-19T18:43:56.677Z,1605811436.677 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-11-19T18:43:57.157Z,1605811437.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-11-19T18:43:57.158Z,1605811437.158 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-11-19T18:43:57.294Z,1605811437.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-11-19T18:43:57.294Z,1605811437.294 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-11-19T18:43:57.372Z,1605811437.372 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-11-19T18:43:57.557Z,1605811437.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-11-19T18:43:57.558Z,1605811437.558 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-11-19T18:43:57.653Z,1605811437.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-11-19T18:43:57.653Z,1605811437.653 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-11-19T18:43:57.746Z,1605811437.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-11-19T18:43:57.746Z,1605811437.746 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-11-19T18:43:58.119Z,1605811438.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-11-19T18:43:58.119Z,1605811438.119 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-11-19T18:43:58.222Z,1605811438.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-11-19T18:43:58.222Z,1605811438.222 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-11-19T18:43:58.706Z,1605811438.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-11-19T18:43:58.706Z,1605811438.706 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-11-19T18:43:58.885Z,1605811438.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-11-19T18:43:58.886Z,1605811438.886 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-11-19T18:43:59.179Z,1605811439.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-11-19T18:43:59.179Z,1605811439.179 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-11-19T18:43:59.386Z,1605811439.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-11-19T18:43:59.386Z,1605811439.386 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-11-19T18:43:59.523Z,1605811439.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-11-19T18:43:59.523Z,1605811439.523 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-11-19T18:44:00.167Z,1605811440.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-11-19T18:44:00.168Z,1605811440.168 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-11-19T18:44:01.033Z,1605811441.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-11-19T18:44:01.034Z,1605811441.034 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-11-19T18:44:01.232Z,1605811441.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-11-19T18:44:01.234Z,1605811441.234 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2020-11-19T18:44:01.234Z,1605811441.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2020-11-19T18:44:01.417Z,1605811441.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2020-11-19T18:44:01.498Z,1605811441.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2020-11-19T18:44:01.641Z,1605811441.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2020-11-19T18:44:01.724Z,1605811441.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2020-11-19T18:44:01.970Z,1605811441.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-11-19T18:44:01.971Z,1605811441.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2020-11-19T18:44:02.071Z,1605811442.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2020-11-19T18:44:02.175Z,1605811442.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2020-11-19T18:44:02.265Z,1605811442.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2020-11-19T18:44:02.404Z,1605811442.404 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2020-11-19T18:44:02.499Z,1605811442.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2020-11-19T18:44:02.595Z,1605811442.595 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2020-11-19T18:44:02.595Z,1605811442.595 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-11-19T18:44:02.608Z,1605811442.608 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-11-19T18:44:02.627Z,1605811442.627 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-11-19T18:44:02.628Z,1605811442.628 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-11-19T18:44:02.723Z,1605811442.723 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-11-19T18:44:02.724Z,1605811442.724 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-11-19T18:44:02.785Z,1605811442.785 [VerticalControl](DEBUG): Construct VerticalControl. 2020-11-19T18:44:02.843Z,1605811442.843 [VerticalControl] Loaded 2020-11-19T18:44:02.844Z,1605811442.844 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-11-19T18:44:02.844Z,1605811442.844 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-11-19T18:44:02.880Z,1605811442.880 [HorizontalControl] Loaded 2020-11-19T18:44:02.881Z,1605811442.881 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-11-19T18:44:02.881Z,1605811442.881 [SpeedControl](DEBUG): Construct SpeedControl. 2020-11-19T18:44:02.884Z,1605811442.884 [SpeedControl] Loaded 2020-11-19T18:44:02.885Z,1605811442.885 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-11-19T18:44:02.885Z,1605811442.885 [LoopControl](DEBUG): Construct LoopControl. 2020-11-19T18:44:02.886Z,1605811442.886 [LoopControl] Loaded 2020-11-19T18:44:02.886Z,1605811442.886 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-11-19T18:44:02.886Z,1605811442.886 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-11-19T18:44:02.887Z,1605811442.887 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-11-19T18:44:02.993Z,1605811442.993 [BuoyancyServo] Loaded 2020-11-19T18:44:02.993Z,1605811442.993 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-11-19T18:44:03.009Z,1605811443.009 [ElevatorServo] Loaded 2020-11-19T18:44:03.010Z,1605811443.010 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-11-19T18:44:03.025Z,1605811443.025 [MassServo] Loaded 2020-11-19T18:44:03.025Z,1605811443.025 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-11-19T18:44:03.040Z,1605811443.040 [RudderServo] Loaded 2020-11-19T18:44:03.040Z,1605811443.040 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-11-19T18:44:03.055Z,1605811443.055 [ThrusterServo] Loaded 2020-11-19T18:44:03.055Z,1605811443.055 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-11-19T18:44:03.055Z,1605811443.055 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-11-19T18:44:03.056Z,1605811443.056 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-11-19T18:44:03.106Z,1605811443.106 [DepthRateCalculator] Loaded 2020-11-19T18:44:03.106Z,1605811443.106 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-11-19T18:44:03.109Z,1605811443.109 [PitchRateCalculator] Loaded 2020-11-19T18:44:03.109Z,1605811443.109 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-11-19T18:44:03.117Z,1605811443.117 [SpeedCalculator] Loaded 2020-11-19T18:44:03.117Z,1605811443.117 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-11-19T18:44:03.128Z,1605811443.128 [TempGradientCalculator] Loaded 2020-11-19T18:44:03.128Z,1605811443.128 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-11-19T18:44:03.131Z,1605811443.131 [YawRateCalculator] Loaded 2020-11-19T18:44:03.131Z,1605811443.131 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-11-19T18:44:03.145Z,1605811443.145 [ElevatorOffsetCalculator] Loaded 2020-11-19T18:44:03.146Z,1605811443.146 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-11-19T18:44:03.146Z,1605811443.146 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-11-19T18:44:03.147Z,1605811443.147 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-11-19T18:44:03.223Z,1605811443.223 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-11-19T18:44:03.224Z,1605811443.224 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-11-19T18:44:03.235Z,1605811443.235 [NavChart] Loaded 2020-11-19T18:44:03.235Z,1605811443.235 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-11-19T18:44:03.239Z,1605811443.239 [UniversalFixResidualReporter] Loaded 2020-11-19T18:44:03.239Z,1605811443.239 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-11-19T18:44:03.239Z,1605811443.239 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-11-19T18:44:03.240Z,1605811443.240 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-11-19T18:44:03.666Z,1605811443.666 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-11-19T18:44:03.669Z,1605811443.669 [AHRS_M2](INFO): created writer for : platform_orientation 2020-11-19T18:44:03.671Z,1605811443.671 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-11-19T18:44:03.674Z,1605811443.674 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-11-19T18:44:03.674Z,1605811443.674 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-11-19T18:44:03.676Z,1605811443.676 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-11-19T18:44:03.677Z,1605811443.677 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-11-19T18:44:03.680Z,1605811443.680 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-11-19T18:44:03.823Z,1605811443.823 [AHRS_M2] Loaded 2020-11-19T18:44:03.823Z,1605811443.823 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-11-19T18:44:03.923Z,1605811443.923 [DataOverHttps] Loaded 2020-11-19T18:44:03.923Z,1605811443.923 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-11-19T18:44:03.924Z,1605811443.924 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408504E0 2020-11-19T18:44:03.925Z,1605811443.925 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5689 2020-11-19T18:44:03.941Z,1605811443.941 [Depth_Keller] Loaded 2020-11-19T18:44:03.941Z,1605811443.941 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-11-19T18:44:03.995Z,1605811443.995 [NAL9602] Loaded 2020-11-19T18:44:03.995Z,1605811443.995 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-11-19T18:44:04.021Z,1605811444.021 [Onboard] Loaded 2020-11-19T18:44:04.021Z,1605811444.021 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-11-19T18:44:04.022Z,1605811444.022 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408804E0 2020-11-19T18:44:04.023Z,1605811444.023 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5690 2020-11-19T18:44:04.033Z,1605811444.033 [Radio_Surface] Loaded 2020-11-19T18:44:04.033Z,1605811444.033 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-11-19T18:44:04.034Z,1605811444.034 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B04E0 2020-11-19T18:44:04.035Z,1605811444.035 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5691 2020-11-19T18:44:04.108Z,1605811444.108 [DAT] Loaded 2020-11-19T18:44:04.108Z,1605811444.108 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-11-19T18:44:04.885Z,1605811444.885 [BPC1] Loaded 2020-11-19T18:44:04.885Z,1605811444.885 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-11-19T18:44:04.886Z,1605811444.886 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-11-19T18:44:04.886Z,1605811444.886 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-11-19T18:44:05.013Z,1605811445.013 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-11-19T18:44:05.014Z,1605811445.014 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-11-19T18:44:05.110Z,1605811445.110 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-11-19T18:44:05.111Z,1605811445.111 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-11-19T18:44:05.140Z,1605811445.140 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-11-19T18:44:05.140Z,1605811445.140 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-11-19T18:44:05.335Z,1605811445.335 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-11-19T18:44:05.338Z,1605811445.338 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-11-19T18:44:05.339Z,1605811445.339 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-11-19T18:44:05.341Z,1605811445.341 [CTD_Seabird](INFO): created writer for : depth 2020-11-19T18:44:05.342Z,1605811445.342 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-11-19T18:44:05.345Z,1605811445.345 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-11-19T18:44:05.345Z,1605811445.345 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-11-19T18:44:05.348Z,1605811445.348 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-11-19T18:44:05.348Z,1605811445.348 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-11-19T18:44:05.351Z,1605811445.351 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-11-19T18:44:05.352Z,1605811445.352 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-11-19T18:44:05.355Z,1605811445.355 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-11-19T18:44:05.355Z,1605811445.355 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-11-19T18:44:05.358Z,1605811445.358 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-11-19T18:44:05.371Z,1605811445.371 [CTD_Seabird] Loaded 2020-11-19T18:44:05.371Z,1605811445.371 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-11-19T18:44:05.372Z,1605811445.372 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A584E0 2020-11-19T18:44:05.373Z,1605811445.373 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5692 2020-11-19T18:44:05.402Z,1605811445.402 [ESPComponent] Loaded 2020-11-19T18:44:05.402Z,1605811445.402 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-11-19T18:44:05.418Z,1605811445.418 [PAR_Licor] Loaded 2020-11-19T18:44:05.418Z,1605811445.418 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-11-19T18:44:05.431Z,1605811445.431 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-11-19T18:44:05.431Z,1605811445.431 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-11-19T18:44:05.433Z,1605811445.433 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-11-19T18:44:05.433Z,1605811445.433 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-11-19T18:44:05.435Z,1605811445.435 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-11-19T18:44:05.435Z,1605811445.435 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-11-19T18:44:05.437Z,1605811445.437 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-11-19T18:44:05.437Z,1605811445.437 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-11-19T18:44:05.439Z,1605811445.439 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-11-19T18:44:05.439Z,1605811445.439 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-11-19T18:44:05.441Z,1605811445.441 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-11-19T18:44:05.441Z,1605811445.441 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-11-19T18:44:05.443Z,1605811445.443 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-11-19T18:44:05.443Z,1605811445.443 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-11-19T18:44:05.445Z,1605811445.445 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-11-19T18:44:05.447Z,1605811445.447 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-11-19T18:44:05.448Z,1605811445.448 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-11-19T18:44:05.448Z,1605811445.448 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-11-19T18:44:05.450Z,1605811445.450 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-11-19T18:44:05.450Z,1605811445.450 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-11-19T18:44:05.452Z,1605811445.452 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-11-19T18:44:05.452Z,1605811445.452 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-11-19T18:44:05.454Z,1605811445.454 [WetLabsBB2FL] Loaded 2020-11-19T18:44:05.455Z,1605811445.455 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-11-19T18:44:05.456Z,1605811445.456 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A884E0 2020-11-19T18:44:05.456Z,1605811445.456 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5693 2020-11-19T18:44:05.457Z,1605811445.457 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-11-19T18:44:05.457Z,1605811445.457 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-11-19T18:44:06.064Z,1605811446.064 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-11-19T18:44:06.064Z,1605811446.064 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-11-19T18:44:06.219Z,1605811446.219 [SBIT](DEBUG): Construct Startup Built In Test. 2020-11-19T18:44:06.227Z,1605811446.227 [SBIT] Loaded 2020-11-19T18:44:06.228Z,1605811446.228 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-11-19T18:44:06.228Z,1605811446.228 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-11-19T18:44:06.240Z,1605811446.240 [IBIT] Loaded 2020-11-19T18:44:06.241Z,1605811446.241 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-11-19T18:44:06.244Z,1605811446.244 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-11-19T18:44:06.340Z,1605811446.340 [CBIT] Loaded 2020-11-19T18:44:06.340Z,1605811446.340 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-11-19T18:44:06.340Z,1605811446.340 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-11-19T18:44:06.344Z,1605811446.344 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-11-19T18:44:06.345Z,1605811446.345 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-11-19T18:44:06.357Z,1605811446.357 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-11-19T18:44:06.362Z,1605811446.362 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0 2020-11-19T18:44:06.363Z,1605811446.363 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5694 2020-11-19T18:44:06.367Z,1605811446.367 [Supervisor](INFO): Main Thread ID is 5602 2020-11-19T18:44:06.367Z,1605811446.367 [Supervisor](DEBUG): Running supervisor. 2020-11-19T18:44:06.368Z,1605811446.368 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5695 2020-11-19T18:44:06.371Z,1605811446.371 [controlThread ThreadHandler](INFO): Handler Thread ID is 5696 2020-11-19T18:44:06.372Z,1605811446.372 [controlThread](DEBUG): Initializing ControlThread 2020-11-19T18:44:06.373Z,1605811446.373 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-11-19T18:44:06.374Z,1605811446.374 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-11-19T18:44:06.375Z,1605811446.375 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-11-19T18:44:06.375Z,1605811446.375 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-11-19T18:44:06.377Z,1605811446.377 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-11-19T18:44:06.377Z,1605811446.377 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-11-19T18:44:06.377Z,1605811446.377 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-11-19T18:44:06.378Z,1605811446.378 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-11-19T18:44:06.378Z,1605811446.378 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-11-19T18:44:06.378Z,1605811446.378 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-11-19T18:44:06.379Z,1605811446.379 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-11-19T18:44:06.380Z,1605811446.380 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-11-19T18:44:06.385Z,1605811446.385 [SBIT](INFO): Initialize SBIT Component. 2020-11-19T18:44:06.386Z,1605811446.386 [SBIT](IMPORTANT): git: 2020-10-07_A-8-g2edcf16 2020-11-19T18:44:06.386Z,1605811446.386 [SBIT](INFO): git hash: 2edcf162c99e1cdac7c4e1eb7e8495acc35f264a 2020-11-19T18:44:06.386Z,1605811446.386 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-11-19T18:44:06.387Z,1605811446.387 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020 2020-11-19T18:44:06.388Z,1605811446.388 [SBIT](INFO): Beginning SBIT in 34.000000 seconds. 2020-11-19T18:44:06.389Z,1605811446.389 [IBIT](INFO): Initialize IBIT Component. 2020-11-19T18:44:06.390Z,1605811446.390 [CBIT](DEBUG): Initialize CBIT Component. 2020-11-19T18:44:06.391Z,1605811446.391 [logger ThreadHandler](INFO): Handler Thread ID is 5697 2020-11-19T18:44:06.401Z,1605811446.401 [CBIT](DEBUG): Initialized mux pins. 2020-11-19T18:44:06.401Z,1605811446.401 [CBIT](DEBUG): Initializing the watchdog timer. 2020-11-19T18:44:06.409Z,1605811446.409 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5698 2020-11-19T18:44:06.410Z,1605811446.411 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-11-19T18:44:06.421Z,1605811446.421 [Onboard ThreadHandler](INFO): Handler Thread ID is 5699 2020-11-19T18:44:06.426Z,1605811446.426 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2020-11-19T18:44:06.426Z,1605811446.426 [CBIT](DEBUG): Initializing heartbeat. 2020-11-19T18:44:06.451Z,1605811446.451 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5700 2020-11-19T18:44:06.457Z,1605811446.457 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5701 2020-11-19T18:44:06.458Z,1605811446.458 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-11-19T18:44:06.467Z,1605811446.467 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5703 2020-11-19T18:44:06.468Z,1605811446.468 [WetLabsBB2FL](INFO): Powering down 2020-11-19T18:44:06.501Z,1605811446.501 [CBIT](DEBUG): Deactivating GF circuits. 2020-11-19T18:44:06.501Z,1605811446.501 [CBIT](DEBUG): Deactivating emergency mode. 2020-11-19T18:44:06.537Z,1605811446.537 [CBIT](DEBUG): Backplane powered. 2020-11-19T18:44:06.593Z,1605811446.593 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-11-19T18:44:06.596Z,1605811446.596 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5704 2020-11-19T18:44:06.608Z,1605811446.608 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-11-19T18:44:06.611Z,1605811446.611 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-11-19T18:44:06.611Z,1605811446.611 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-11-19T18:44:06.611Z,1605811446.611 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-11-19T18:44:06.612Z,1605811446.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-11-19T18:44:06.612Z,1605811446.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-11-19T18:44:06.612Z,1605811446.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-11-19T18:44:06.612Z,1605811446.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-11-19T18:44:06.612Z,1605811446.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-11-19T18:44:06.612Z,1605811446.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-11-19T18:44:06.612Z,1605811446.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-11-19T18:44:06.613Z,1605811446.613 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-11-19T18:44:06.613Z,1605811446.613 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-11-19T18:44:06.613Z,1605811446.613 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-11-19T18:44:06.613Z,1605811446.613 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-11-19T18:44:06.613Z,1605811446.613 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-11-19T18:44:06.613Z,1605811446.613 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-11-19T18:44:06.776Z,1605811446.776 [MissionManager](DEBUG): 2020-11-19T18:44:06.780Z,1605811446.780 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-11-19T18:44:06.853Z,1605811446.853 [Radio_Surface](INFO): Powering up 2020-11-19T18:44:06.924Z,1605811446.924 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-11-19T18:44:06.925Z,1605811446.925 [Default:A.Wait](DEBUG): Construct Wait. 2020-11-19T18:44:06.927Z,1605811446.927 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-11-19T18:44:06.976Z,1605811446.976 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-11-19T18:44:06.978Z,1605811446.978 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-11-19T18:44:06.998Z,1605811446.998 [Default:E.Execute](DEBUG): Construct Execute. 2020-11-19T18:44:07.035Z,1605811447.035 [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 2020-11-19T18:44:07.039Z,1605811447.039 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,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, 2020-11-19T18:44:07.054Z,1605811447.054 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-11-19T18:44:07.167Z,1605811447.167 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2020-11-19T18:44:07.170Z,1605811447.170 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-11-19T18:44:07.170Z,1605811447.171 [DAT](INFO): Powering up 2020-11-19T18:44:07.171Z,1605811447.171 [DAT](DEBUG): Initializing DAT. 2020-11-19T18:44:07.173Z,1605811447.173 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-11-19T18:44:07.393Z,1605811447.393 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-11-19T18:44:07.476Z,1605811447.476 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-11-19T18:44:07.485Z,1605811447.485 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-11-19T18:44:07.487Z,1605811447.487 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-11-19T18:44:07.493Z,1605811447.493 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-11-19T18:44:07.494Z,1605811447.494 [MassServo](DEBUG): Initializing EZServoServo. 2020-11-19T18:44:07.501Z,1605811447.501 [MassServo](DEBUG): Initializing MassServo. 2020-11-19T18:44:07.502Z,1605811447.502 [RudderServo](DEBUG): Initializing EZServoServo. 2020-11-19T18:44:07.509Z,1605811447.509 [RudderServo](DEBUG): Initializing RudderServo. 2020-11-19T18:44:07.510Z,1605811447.510 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-11-19T18:44:07.517Z,1605811447.517 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-11-19T18:44:07.520Z,1605811447.520 [CommandLine](FAULT): Scheduling is paused 2020-11-19T18:44:07.520Z,1605811447.520 [CBIT](INFO): Critical error at 20201119T184406 2020-11-19T18:44:07.520Z,1605811447.520 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-11-19T18:44:19.004Z,1605811459.004 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004994 2020-11-19T18:44:34.198Z,1605811474.198 [NAL9602](INFO): Powering up NAL9602 2020-11-19T18:44:41.111Z,1605811481.111 [SBIT](IMPORTANT): Beginning Startup BIT 2020-11-19T18:44:41.120Z,1605811481.120 [CBIT](IMPORTANT): Beginning ground fault scan 2020-11-19T18:44:45.123Z,1605811485.123 [NAL9602](INFO): NAL9602 initialized 2020-11-19T18:44:52.022Z,1605811492.022 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.023251 CHAN A1 (24V): -0.007243 CHAN A2 (12V): -0.004941 CHAN A3 (5V): -0.002505 CHAN B0 (3.3V): -0.001292 CHAN B1 (3.15aV): -0.001256 CHAN B2 (3.15bV): -0.000757 CHAN B3 (GND): -0.000874 OPEN: 0.004846 Full Scale Calc: 4.765 mA, -1.589 mA 2020-11-19T18:45:07.331Z,1605811507.331 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2020-11-19T18:45:07.331Z,1605811507.331 [DAT] Communications Fault, FailCount= 1 2020-11-19T18:45:07.331Z,1605811507.331 [DAT](ERROR): Communications Fault 2020-11-19T18:45:07.386Z,1605811507.386 [CBIT](ERROR): Communications Fault in component: DAT 2020-11-19T18:45:07.731Z,1605811507.731 [DAT](INFO): Powering down 2020-11-19T18:45:08.613Z,1605811508.613 [CBIT](INFO): Clearing failed state for component DAT 2020-11-19T18:45:08.613Z,1605811508.613 [DAT] No Fault, FailCount= 1 2020-11-19T18:45:10.976Z,1605811510.976 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-11-19T18:45:10.976Z,1605811510.976 [DAT](INFO): Powering up 2020-11-19T18:45:10.977Z,1605811510.977 [DAT](DEBUG): Initializing DAT. 2020-11-19T18:45:11.057Z,1605811511.057 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-11-19T18:45:23.493Z,1605811523.493 [DAT](INFO): commRate: 800 2020-11-19T18:45:25.517Z,1605811525.517 [DAT](INFO): entering command mode 2020-11-19T18:45:25.915Z,1605811525.915 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:26.319Z,1605811526.319 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:26.727Z,1605811526.727 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:27.128Z,1605811527.128 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:27.527Z,1605811527.527 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:27.935Z,1605811527.935 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:28.335Z,1605811528.335 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:28.751Z,1605811528.751 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:29.162Z,1605811529.162 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:29.562Z,1605811529.562 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:29.969Z,1605811529.969 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:30.378Z,1605811530.378 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:30.763Z,1605811530.763 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:31.167Z,1605811531.167 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:31.575Z,1605811531.575 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:31.976Z,1605811531.976 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:32.375Z,1605811532.375 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:32.787Z,1605811532.787 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:33.187Z,1605811533.187 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:33.592Z,1605811533.592 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:33.995Z,1605811533.995 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:34.413Z,1605811534.413 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:34.814Z,1605811534.814 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:35.229Z,1605811535.229 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:35.242Z,1605811535.242 [SBIT](IMPORTANT): SBIT PASSED 2020-11-19T18:45:35.242Z,1605811535.242 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-11-19T18:45:35.243Z,1605811535.243 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2020-11-19T18:45:35.243Z,1605811535.243 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2020-11-19T18:45:35.243Z,1605811535.243 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2020-11-19T18:45:35.243Z,1605811535.243 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-11-19T18:45:35.243Z,1605811535.243 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2020-11-19T18:45:35.243Z,1605811535.243 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-11-19T18:45:35.243Z,1605811535.243 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2020-11-19T18:45:35.244Z,1605811535.244 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter; 2020-11-19T18:45:35.244Z,1605811535.244 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2020-11-19T18:45:35.244Z,1605811535.244 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2020-11-19T18:45:35.244Z,1605811535.244 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2020-11-19T18:45:35.244Z,1605811535.244 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter; 2020-11-19T18:45:35.244Z,1605811535.244 [SBIT](IMPORTANT): VerticalControl.massDefault=3.7 millimeter; 2020-11-19T18:45:35.626Z,1605811535.626 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:35.642Z,1605811535.642 [MissionManager](IMPORTANT): Started mission Startup 2020-11-19T18:45:35.643Z,1605811535.643 [Startup] Running Loop=1 2020-11-19T18:45:35.643Z,1605811535.643 [Startup](DEBUG): Aggregate::initialize Startup 2020-11-19T18:45:35.643Z,1605811535.643 [Startup:A.GoToSurface] Running Loop=1 2020-11-19T18:45:35.643Z,1605811535.643 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-11-19T18:45:35.644Z,1605811535.644 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-11-19T18:45:35.644Z,1605811535.644 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-11-19T18:45:35.644Z,1605811535.644 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-11-19T18:45:35.645Z,1605811535.645 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-11-19T18:45:35.645Z,1605811535.645 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-11-19T18:45:35.647Z,1605811535.647 [Startup:StartupSatComms] Running Loop=1 2020-11-19T18:45:35.647Z,1605811535.647 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-11-19T18:45:35.647Z,1605811535.647 [Startup:StartupSatComms:A] Running Loop=1 2020-11-19T18:45:36.019Z,1605811536.019 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:36.032Z,1605811536.032 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-11-19T18:45:36.419Z,1605811536.419 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:36.823Z,1605811536.823 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:37.227Z,1605811537.227 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:37.631Z,1605811537.631 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:38.035Z,1605811538.035 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:38.439Z,1605811538.439 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:38.843Z,1605811538.843 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:39.248Z,1605811539.248 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:39.661Z,1605811539.661 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:40.074Z,1605811540.074 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:40.474Z,1605811540.474 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:40.874Z,1605811540.874 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-19T18:45:40.874Z,1605811540.874 [DAT](FAULT): failed to enter command mode 2020-11-19T18:45:41.271Z,1605811541.271 [DAT](INFO): entering command mode 2020-11-19T18:45:41.672Z,1605811541.672 [DAT](INFO): setting verbose to 3 2020-11-19T18:45:42.072Z,1605811542.072 [DAT](INFO): set verbose to 3 2020-11-19T18:45:42.072Z,1605811542.072 [DAT](INFO): setting DatVerbose to 27440 2020-11-19T18:45:42.484Z,1605811542.484 [DAT](INFO): set DatVerbose to 27440 2020-11-19T18:45:42.484Z,1605811542.484 [DAT](INFO): setting transmit power to 8 2020-11-19T18:45:42.884Z,1605811542.884 [DAT](INFO): set transmit power to 8 2020-11-19T18:45:42.884Z,1605811542.884 [DAT](INFO): setting local address to 5 2020-11-19T18:45:43.288Z,1605811543.288 [DAT](INFO): set local address to 5 2020-11-19T18:46:34.600Z,1605811594.600 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-11-19T18:46:35.822Z,1605811595.822 [Startup:StartupSatComms:A](INFO): Timed out from 2020-11-19T18:45:35.6Z 2020-11-19T18:46:35.822Z,1605811595.822 [Startup:StartupSatComms:A] Stopped 2020-11-19T18:46:35.822Z,1605811595.822 [Startup:StartupSatComms:B] Running Loop=1 2020-11-19T18:46:36.236Z,1605811596.236 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-11-19T18:47:06.642Z,1605811626.642 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-11-19T18:47:06.642Z,1605811626.642 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-11-19T18:47:06.684Z,1605811626.684 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-11-19T18:47:07.065Z,1605811627.065 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-11-19T18:47:07.065Z,1605811627.065 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-11-19T18:47:26.373Z,1605811646.373 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20201117T062821/Courier0615.lzma 2020-11-19T18:47:27.376Z,1605811647.376 [DataOverHttps](INFO): Moved sent file to Logs/20201117T062821/Courier0615.lzma.bak 2020-11-19T18:47:27.376Z,1605811647.376 [DataOverHttps](INFO): SBD MOMSN=13283550 2020-11-19T18:47:36.142Z,1605811656.142 [Startup:StartupSatComms:B](INFO): Timed out from 2020-11-19T18:46:35.8Z 2020-11-19T18:47:36.142Z,1605811656.142 [Startup:StartupSatComms:B] Stopped 2020-11-19T18:47:36.142Z,1605811656.142 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-11-19T18:47:36.142Z,1605811656.142 [Startup:StartupSatComms] Stopped 2020-11-19T18:47:36.142Z,1605811656.142 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-11-19T18:47:36.143Z,1605811656.143 [Startup](INFO): Completed Startup 2020-11-19T18:47:36.143Z,1605811656.143 [MissionManager](INFO): Startup is completed. 2020-11-19T18:47:36.143Z,1605811656.143 [MissionManager](INFO): Uninitializing Mission Startup 2020-11-19T18:47:36.143Z,1605811656.143 [Startup] Stopped 2020-11-19T18:47:36.143Z,1605811656.143 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-11-19T18:47:36.143Z,1605811656.143 [Startup:A.GoToSurface] Stopped 2020-11-19T18:47:36.143Z,1605811656.143 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-11-19T18:47:36.542Z,1605811656.542 [MissionManager](IMPORTANT): Started mission Default 2020-11-19T18:47:36.542Z,1605811656.542 [Default] Running Loop=1 2020-11-19T18:47:36.542Z,1605811656.542 [Default](DEBUG): Aggregate::initialize Default 2020-11-19T18:47:36.542Z,1605811656.542 [Default:B.GoToSurface] Running Loop=1 2020-11-19T18:47:36.542Z,1605811656.542 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-11-19T18:47:36.542Z,1605811656.542 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-11-19T18:47:36.543Z,1605811656.543 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-11-19T18:47:36.543Z,1605811656.543 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-11-19T18:47:36.543Z,1605811656.543 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-11-19T18:47:36.543Z,1605811656.543 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-11-19T18:47:36.544Z,1605811656.544 [Default:A.Wait] Running Loop=1 2020-11-19T18:47:36.544Z,1605811656.544 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-11-19T18:47:49.872Z,1605811669.872 [Default:A.Wait](INFO): Done Waiting. 2020-11-19T18:47:49.872Z,1605811669.872 [Default:A.Wait] Stopped 2020-11-19T18:47:49.872Z,1605811669.872 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-11-19T18:47:50.263Z,1605811670.263 [Default:CheckIn] Running Loop=1 2020-11-19T18:47:50.263Z,1605811670.263 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-11-19T18:47:50.263Z,1605811670.263 [Default:CheckIn:Read_GPS] Running Loop=1 2020-11-19T18:47:50.680Z,1605811670.680 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-11-19T18:48:03.251Z,1605811683.251 [CommandLine](IMPORTANT): got command failComponent 2020-11-19T18:48:03.251Z,1605811683.251 [CommandLine](IMPORTANT): Failed components: 2020-11-19T18:48:03.251Z,1605811683.251 [CommandLine](IMPORTANT): No failed Components. 2020-11-19T18:49:30.917Z,1605811770.917 [DataOverHttps](INFO): Sending 322 bytes from file Logs/20201118T215738/Courier0000.lzma 2020-11-19T18:49:31.919Z,1605811771.919 [DataOverHttps](INFO): Moved sent file to Logs/20201118T215738/Courier0000.lzma.bak 2020-11-19T18:49:31.919Z,1605811771.919 [DataOverHttps](INFO): SBD MOMSN=13283633 2020-11-19T18:49:47.823Z,1605811787.823 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-11-19T18:50:07.126Z,1605811807.126 [DataOverHttps](IMPORTANT): SBD MTMSN=20201119T185019 2020-11-19T18:50:07.638Z,1605811807.638 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-11-19T18:50:07.638Z,1605811807.638 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-11-19T18:50:07.648Z,1605811807.648 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-11-19T18:50:08.056Z,1605811808.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-11-19T18:50:08.056Z,1605811808.056 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-11-19T18:50:14.414Z,1605811814.414 [DataOverHttps](INFO): Received command:restart sys 2020-11-19T18:50:14.446Z,1605811814.446 [CommandLine](IMPORTANT): got command restart system 2020-11-19T18:50:16.505Z,1605811816.505 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-19T18:50:16.506Z,1605811816.506 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-11-19T18:50:16.661Z,1605811816.661 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-11-19T18:50:16.662Z,1605811816.662 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-11-19T18:50:16.662Z,1605811816.662 [CommandLine](INFO): Join timeout helper Thread ID is 5726 2020-11-19T18:50:16.663Z,1605811816.663 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-11-19T18:50:16.663Z,1605811816.663 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-11-19T18:50:16.664Z,1605811816.664 [NavChartDb](INFO): Join timeout helper Thread ID is 5727 2020-11-19T18:50:16.669Z,1605811816.669 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-19T18:50:16.670Z,160581181