2020-08-31T18:31:12.439Z,1598898672.439 [Supervisor](DEBUG): Initializing supervisor. 2020-08-31T18:31:12.441Z,1598898672.441 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-08-31T18:31:12.442Z,1598898672.442 [SyncHandler](INFO): Protected caller Thread ID is 826 2020-08-31T18:31:12.442Z,1598898672.442 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-08-31T18:31:12.443Z,1598898672.443 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-08-31T18:31:12.444Z,1598898672.444 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 827 2020-08-31T18:31:12.446Z,1598898672.446 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-08-31T18:31:12.459Z,1598898672.459 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-08-31T18:31:12.460Z,1598898672.460 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-08-31T18:31:12.460Z,1598898672.460 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 828 2020-08-31T18:31:12.461Z,1598898672.461 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-08-31T18:31:12.462Z,1598898672.462 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-08-31T18:31:12.462Z,1598898672.462 [logger ThreadHandler](INFO): Protected caller Thread ID is 829 2020-08-31T18:31:12.464Z,1598898672.464 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-08-31T18:31:12.465Z,1598898672.465 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-08-31T18:31:12.469Z,1598898672.469 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-08-31T18:31:12.562Z,1598898672.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-08-31T18:31:12.564Z,1598898672.564 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-08-31T18:31:13.148Z,1598898673.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-08-31T18:31:13.148Z,1598898673.148 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-08-31T18:31:13.337Z,1598898673.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-08-31T18:31:13.339Z,1598898673.339 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-08-31T18:31:13.437Z,1598898673.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-08-31T18:31:13.437Z,1598898673.437 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-08-31T18:31:13.538Z,1598898673.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-08-31T18:31:13.539Z,1598898673.539 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-08-31T18:31:13.620Z,1598898673.620 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-08-31T18:31:13.755Z,1598898673.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-08-31T18:31:13.757Z,1598898673.757 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-08-31T18:31:14.039Z,1598898674.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-08-31T18:31:14.041Z,1598898674.041 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-08-31T18:31:14.484Z,1598898674.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-08-31T18:31:14.484Z,1598898674.484 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-08-31T18:31:14.626Z,1598898674.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-08-31T18:31:14.626Z,1598898674.626 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-08-31T18:31:14.815Z,1598898674.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-08-31T18:31:14.817Z,1598898674.817 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-08-31T18:31:15.277Z,1598898675.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-08-31T18:31:15.279Z,1598898675.279 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-08-31T18:31:15.490Z,1598898675.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-08-31T18:31:15.492Z,1598898675.492 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-08-31T18:31:15.690Z,1598898675.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-08-31T18:31:15.692Z,1598898675.692 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-08-31T18:31:16.225Z,1598898676.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-08-31T18:31:16.226Z,1598898676.226 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-08-31T18:31:16.934Z,1598898676.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-08-31T18:31:16.936Z,1598898676.936 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-08-31T18:31:16.938Z,1598898676.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-08-31T18:31:17.022Z,1598898677.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-08-31T18:31:17.178Z,1598898677.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-08-31T18:31:17.284Z,1598898677.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-08-31T18:31:17.368Z,1598898677.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-08-31T18:31:17.461Z,1598898677.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-08-31T18:31:17.659Z,1598898677.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-08-31T18:31:17.886Z,1598898677.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-08-31T18:31:17.886Z,1598898677.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-08-31T18:31:17.979Z,1598898677.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-08-31T18:31:18.076Z,1598898678.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-08-31T18:31:18.205Z,1598898678.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-08-31T18:31:18.303Z,1598898678.303 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2020-08-31T18:31:18.307Z,1598898678.307 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-08-31T18:31:18.400Z,1598898678.400 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-08-31T18:31:18.401Z,1598898678.401 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-08-31T18:31:18.464Z,1598898678.464 [VerticalControl](DEBUG): Construct VerticalControl. 2020-08-31T18:31:18.574Z,1598898678.574 [VerticalControl] Loaded 2020-08-31T18:31:18.575Z,1598898678.575 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-08-31T18:31:18.575Z,1598898678.575 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-08-31T18:31:18.651Z,1598898678.651 [HorizontalControl] Loaded 2020-08-31T18:31:18.652Z,1598898678.652 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-08-31T18:31:18.652Z,1598898678.652 [SpeedControl](DEBUG): Construct SpeedControl. 2020-08-31T18:31:18.657Z,1598898678.657 [SpeedControl] Loaded 2020-08-31T18:31:18.658Z,1598898678.658 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-08-31T18:31:18.658Z,1598898678.658 [LoopControl](DEBUG): Construct LoopControl. 2020-08-31T18:31:18.659Z,1598898678.659 [LoopControl] Loaded 2020-08-31T18:31:18.659Z,1598898678.659 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-08-31T18:31:18.660Z,1598898678.660 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-08-31T18:31:18.660Z,1598898678.660 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-08-31T18:31:18.714Z,1598898678.714 [DepthRateCalculator] Loaded 2020-08-31T18:31:18.714Z,1598898678.714 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-08-31T18:31:18.719Z,1598898678.719 [PitchRateCalculator] Loaded 2020-08-31T18:31:18.719Z,1598898678.719 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-08-31T18:31:18.734Z,1598898678.734 [SpeedCalculator] Loaded 2020-08-31T18:31:18.734Z,1598898678.734 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-08-31T18:31:18.754Z,1598898678.754 [TempGradientCalculator] Loaded 2020-08-31T18:31:18.755Z,1598898678.755 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-08-31T18:31:18.760Z,1598898678.760 [YawRateCalculator] Loaded 2020-08-31T18:31:18.760Z,1598898678.760 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-08-31T18:31:18.788Z,1598898678.788 [ElevatorOffsetCalculator] Loaded 2020-08-31T18:31:18.788Z,1598898678.788 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-08-31T18:31:18.788Z,1598898678.788 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-08-31T18:31:18.789Z,1598898678.789 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-08-31T18:31:18.829Z,1598898678.829 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-08-31T18:31:18.829Z,1598898678.829 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-08-31T18:31:18.956Z,1598898678.956 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-08-31T18:31:18.956Z,1598898678.956 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-08-31T18:31:19.280Z,1598898679.280 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-08-31T18:31:19.280Z,1598898679.280 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-08-31T18:31:19.401Z,1598898679.401 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-08-31T18:31:19.403Z,1598898679.403 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-08-31T18:31:19.962Z,1598898679.962 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-08-31T18:31:19.966Z,1598898679.966 [AHRS_M2](INFO): created writer for : platform_orientation 2020-08-31T18:31:19.969Z,1598898679.969 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-08-31T18:31:19.978Z,1598898679.978 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-08-31T18:31:19.978Z,1598898679.978 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-08-31T18:31:19.983Z,1598898679.983 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-08-31T18:31:19.983Z,1598898679.983 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-08-31T18:31:19.989Z,1598898679.989 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-08-31T18:31:20.163Z,1598898680.163 [AHRS_M2] Loaded 2020-08-31T18:31:20.163Z,1598898680.163 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-08-31T18:31:20.308Z,1598898680.308 [DataOverHttps] Loaded 2020-08-31T18:31:20.308Z,1598898680.308 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-08-31T18:31:20.309Z,1598898680.309 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0 2020-08-31T18:31:20.309Z,1598898680.309 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 911 2020-08-31T18:31:20.339Z,1598898680.339 [Depth_Keller] Loaded 2020-08-31T18:31:20.339Z,1598898680.339 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-08-31T18:31:20.343Z,1598898680.343 [DropWeight] Loaded 2020-08-31T18:31:20.344Z,1598898680.344 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-08-31T18:31:20.452Z,1598898680.452 [NAL9602] Loaded 2020-08-31T18:31:20.452Z,1598898680.452 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-08-31T18:31:20.499Z,1598898680.499 [Onboard] Loaded 2020-08-31T18:31:20.500Z,1598898680.500 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-08-31T18:31:20.500Z,1598898680.500 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0 2020-08-31T18:31:20.501Z,1598898680.501 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 912 2020-08-31T18:31:20.519Z,1598898680.519 [Radio_Surface] Loaded 2020-08-31T18:31:20.520Z,1598898680.520 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-08-31T18:31:20.521Z,1598898680.521 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0 2020-08-31T18:31:20.521Z,1598898680.521 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 913 2020-08-31T18:31:20.578Z,1598898680.578 [RDI_Pathfinder] Loaded 2020-08-31T18:31:20.578Z,1598898680.578 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-08-31T18:31:20.723Z,1598898680.723 [DAT] Loaded 2020-08-31T18:31:20.723Z,1598898680.723 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-08-31T18:31:22.173Z,1598898682.173 [BPC1] Loaded 2020-08-31T18:31:22.173Z,1598898682.173 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-08-31T18:31:22.205Z,1598898682.205 [AMEcho] Loaded 2020-08-31T18:31:22.205Z,1598898682.205 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread. 2020-08-31T18:31:22.205Z,1598898682.205 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-08-31T18:31:22.206Z,1598898682.206 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-08-31T18:31:22.510Z,1598898682.510 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-08-31T18:31:22.510Z,1598898682.510 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-08-31T18:31:22.568Z,1598898682.568 [NavChart] Loaded 2020-08-31T18:31:22.568Z,1598898682.568 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-08-31T18:31:22.572Z,1598898682.572 [UniversalFixResidualReporter] Loaded 2020-08-31T18:31:22.572Z,1598898682.572 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-08-31T18:31:22.573Z,1598898682.573 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-08-31T18:31:22.663Z,1598898682.663 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-08-31T18:31:22.824Z,1598898682.824 [SBIT](DEBUG): Construct Startup Built In Test. 2020-08-31T18:31:22.835Z,1598898682.835 [SBIT] Loaded 2020-08-31T18:31:22.835Z,1598898682.835 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-08-31T18:31:22.835Z,1598898682.835 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-08-31T18:31:22.848Z,1598898682.848 [IBIT] Loaded 2020-08-31T18:31:22.848Z,1598898682.848 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-08-31T18:31:22.851Z,1598898682.851 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-08-31T18:31:22.987Z,1598898682.987 [CBIT] Loaded 2020-08-31T18:31:22.987Z,1598898682.987 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-08-31T18:31:22.987Z,1598898682.987 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-08-31T18:31:22.988Z,1598898682.988 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-08-31T18:31:23.129Z,1598898683.129 [BuoyancyServo] Loaded 2020-08-31T18:31:23.129Z,1598898683.129 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-08-31T18:31:23.157Z,1598898683.157 [ElevatorServo] Loaded 2020-08-31T18:31:23.157Z,1598898683.157 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-08-31T18:31:23.184Z,1598898683.184 [MassServo] Loaded 2020-08-31T18:31:23.184Z,1598898683.184 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-08-31T18:31:23.211Z,1598898683.211 [RudderServo] Loaded 2020-08-31T18:31:23.211Z,1598898683.211 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-08-31T18:31:23.237Z,1598898683.237 [ThrusterServo] Loaded 2020-08-31T18:31:23.237Z,1598898683.237 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-08-31T18:31:23.237Z,1598898683.237 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-08-31T18:31:23.238Z,1598898683.238 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-08-31T18:31:23.267Z,1598898683.267 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-08-31T18:31:23.268Z,1598898683.268 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-08-31T18:31:23.580Z,1598898683.580 [BackSeatDriver] Loaded 2020-08-31T18:31:23.580Z,1598898683.580 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-08-31T18:31:23.581Z,1598898683.581 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0 2020-08-31T18:31:23.582Z,1598898683.582 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 914 2020-08-31T18:31:23.610Z,1598898683.610 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-08-31T18:31:23.615Z,1598898683.615 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-08-31T18:31:23.615Z,1598898683.615 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-08-31T18:31:23.620Z,1598898683.620 [CTD_Seabird](INFO): created writer for : depth 2020-08-31T18:31:23.621Z,1598898683.621 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-08-31T18:31:23.625Z,1598898683.625 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-08-31T18:31:23.626Z,1598898683.626 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-08-31T18:31:23.631Z,1598898683.631 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-08-31T18:31:23.631Z,1598898683.631 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-08-31T18:31:23.636Z,1598898683.636 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-08-31T18:31:23.637Z,1598898683.637 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-08-31T18:31:23.642Z,1598898683.642 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-08-31T18:31:23.643Z,1598898683.643 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-08-31T18:31:23.648Z,1598898683.648 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-08-31T18:31:23.674Z,1598898683.674 [CTD_Seabird] Loaded 2020-08-31T18:31:23.674Z,1598898683.674 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-08-31T18:31:23.675Z,1598898683.675 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B334E0 2020-08-31T18:31:23.675Z,1598898683.675 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 915 2020-08-31T18:31:23.697Z,1598898683.697 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-08-31T18:31:23.697Z,1598898683.697 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-08-31T18:31:23.701Z,1598898683.701 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-08-31T18:31:23.701Z,1598898683.701 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-08-31T18:31:23.705Z,1598898683.705 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-08-31T18:31:23.705Z,1598898683.705 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-08-31T18:31:23.710Z,1598898683.710 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-08-31T18:31:23.710Z,1598898683.710 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-08-31T18:31:23.714Z,1598898683.714 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-08-31T18:31:23.714Z,1598898683.714 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-08-31T18:31:23.719Z,1598898683.719 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-08-31T18:31:23.719Z,1598898683.719 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-08-31T18:31:23.723Z,1598898683.723 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-08-31T18:31:23.723Z,1598898683.723 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-08-31T18:31:23.727Z,1598898683.727 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-08-31T18:31:23.731Z,1598898683.731 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-08-31T18:31:23.732Z,1598898683.732 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-08-31T18:31:23.732Z,1598898683.732 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-08-31T18:31:23.736Z,1598898683.736 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-08-31T18:31:23.736Z,1598898683.736 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-08-31T18:31:23.740Z,1598898683.740 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-08-31T18:31:23.740Z,1598898683.740 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-08-31T18:31:23.745Z,1598898683.745 [WetLabsBB2FL] Loaded 2020-08-31T18:31:23.745Z,1598898683.745 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-08-31T18:31:23.746Z,1598898683.746 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0 2020-08-31T18:31:23.746Z,1598898683.746 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 916 2020-08-31T18:31:23.747Z,1598898683.747 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-08-31T18:31:23.751Z,1598898683.751 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-08-31T18:31:23.751Z,1598898683.751 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-08-31T18:31:23.758Z,1598898683.758 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-08-31T18:31:23.759Z,1598898683.759 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0 2020-08-31T18:31:23.759Z,1598898683.759 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 917 2020-08-31T18:31:23.764Z,1598898683.764 [Supervisor](INFO): Main Thread ID is 822 2020-08-31T18:31:23.764Z,1598898683.764 [Supervisor](DEBUG): Running supervisor. 2020-08-31T18:31:23.765Z,1598898683.765 [CommandLine ThreadHandler](INFO): Handler Thread ID is 918 2020-08-31T18:31:23.767Z,1598898683.767 [controlThread ThreadHandler](INFO): Handler Thread ID is 919 2020-08-31T18:31:23.767Z,1598898683.767 [controlThread](DEBUG): Initializing ControlThread 2020-08-31T18:31:23.768Z,1598898683.768 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-08-31T18:31:23.770Z,1598898683.770 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-08-31T18:31:23.770Z,1598898683.770 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-08-31T18:31:23.771Z,1598898683.771 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-08-31T18:31:23.771Z,1598898683.771 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-08-31T18:31:23.772Z,1598898683.772 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-08-31T18:31:23.772Z,1598898683.772 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-08-31T18:31:23.772Z,1598898683.772 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-08-31T18:31:23.773Z,1598898683.773 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-08-31T18:31:23.773Z,1598898683.773 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-08-31T18:31:23.779Z,1598898683.779 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-08-31T18:31:23.779Z,1598898683.779 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-08-31T18:31:23.779Z,1598898683.779 [SBIT](INFO): Initialize SBIT Component. 2020-08-31T18:31:23.780Z,1598898683.780 [SBIT](IMPORTANT): git: 2020-08-31 2020-08-31T18:31:23.780Z,1598898683.780 [SBIT](INFO): git hash: b6c0ec5a69f4280de3f7606c4f2437bb78ebd873 2020-08-31T18:31:23.780Z,1598898683.780 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-08-31T18:31:23.781Z,1598898683.781 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020 2020-08-31T18:31:23.782Z,1598898683.782 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2020-08-31T18:31:23.783Z,1598898683.783 [IBIT](INFO): Initialize IBIT Component. 2020-08-31T18:31:23.784Z,1598898683.784 [CBIT](DEBUG): Initialize CBIT Component. 2020-08-31T18:31:23.785Z,1598898683.785 [logger ThreadHandler](INFO): Handler Thread ID is 920 2020-08-31T18:31:23.796Z,1598898683.796 [CBIT](DEBUG): Initialized mux pins. 2020-08-31T18:31:23.796Z,1598898683.796 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2020-08-31T18:31:23.796Z,1598898683.796 [CBIT](DEBUG): Initializing the watchdog timer. 2020-08-31T18:31:23.804Z,1598898683.804 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 921 2020-08-31T18:31:23.805Z,1598898683.805 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-08-31T18:31:23.816Z,1598898683.816 [Onboard ThreadHandler](INFO): Handler Thread ID is 922 2020-08-31T18:31:23.820Z,1598898683.820 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-08-31T18:31:23.820Z,1598898683.820 [CBIT](DEBUG): Initializing heartbeat. 2020-08-31T18:31:23.834Z,1598898683.834 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 923 2020-08-31T18:31:23.856Z,1598898683.856 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 924 2020-08-31T18:31:23.876Z,1598898683.876 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 925 2020-08-31T18:31:23.877Z,1598898683.877 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-08-31T18:31:23.881Z,1598898683.881 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 927 2020-08-31T18:31:23.882Z,1598898683.882 [WetLabsBB2FL](INFO): Powering down 2020-08-31T18:31:23.892Z,1598898683.892 [CBIT](DEBUG): Deactivating GF circuits. 2020-08-31T18:31:23.892Z,1598898683.892 [CBIT](DEBUG): Deactivating emergency mode. 2020-08-31T18:31:23.928Z,1598898683.928 [CBIT](DEBUG): Backplane powered. 2020-08-31T18:31:23.930Z,1598898683.930 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-08-31T18:31:23.995Z,1598898683.995 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 928 2020-08-31T18:31:24.099Z,1598898684.099 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-08-31T18:31:24.099Z,1598898684.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-08-31T18:31:24.099Z,1598898684.099 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-08-31T18:31:24.099Z,1598898684.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-08-31T18:31:24.099Z,1598898684.099 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-08-31T18:31:24.100Z,1598898684.100 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-08-31T18:31:24.100Z,1598898684.100 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-08-31T18:31:24.100Z,1598898684.100 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-08-31T18:31:24.100Z,1598898684.100 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-08-31T18:31:24.100Z,1598898684.100 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-08-31T18:31:24.100Z,1598898684.100 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-08-31T18:31:24.100Z,1598898684.100 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-08-31T18:31:24.101Z,1598898684.101 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-08-31T18:31:24.101Z,1598898684.101 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-08-31T18:31:24.101Z,1598898684.101 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-08-31T18:31:24.101Z,1598898684.101 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-08-31T18:31:24.193Z,1598898684.193 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-08-31T18:31:24.217Z,1598898684.217 [MissionManager](DEBUG): 2020-08-31T18:31:24.217Z,1598898684.217 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-08-31T18:31:24.240Z,1598898684.240 [Radio_Surface](INFO): Powering up 2020-08-31T18:31:24.345Z,1598898684.345 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-08-31T18:31:24.346Z,1598898684.346 [Default:A.Wait](DEBUG): Construct Wait. 2020-08-31T18:31:24.347Z,1598898684.347 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-08-31T18:31:24.370Z,1598898684.370 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-08-31T18:31:24.400Z,1598898684.400 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-08-31T18:31:24.406Z,1598898684.406 [Default:E.Execute](DEBUG): Construct Execute. 2020-08-31T18:31:24.409Z,1598898684.409 [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-08-31T18:31:24.426Z,1598898684.426 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,AMEcho,Depth_Keller,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-08-31T18:31:24.432Z,1598898684.432 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-08-31T18:31:24.539Z,1598898684.539 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-08-31T18:31:24.551Z,1598898684.551 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-08-31T18:31:24.551Z,1598898684.551 [DAT](INFO): Powering up 2020-08-31T18:31:24.552Z,1598898684.552 [DAT](DEBUG): Initializing DAT. 2020-08-31T18:31:24.565Z,1598898684.565 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-08-31T18:31:24.624Z,1598898684.624 [AMEcho](INFO): Powering up 2020-08-31T18:31:24.648Z,1598898684.648 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-08-31T18:31:24.694Z,1598898684.694 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-08-31T18:31:24.700Z,1598898684.700 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-08-31T18:31:24.702Z,1598898684.702 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-08-31T18:31:24.712Z,1598898684.712 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-08-31T18:31:24.713Z,1598898684.713 [MassServo](DEBUG): Initializing EZServoServo. 2020-08-31T18:31:24.720Z,1598898684.720 [MassServo](DEBUG): Initializing MassServo. 2020-08-31T18:31:24.721Z,1598898684.721 [RudderServo](DEBUG): Initializing EZServoServo. 2020-08-31T18:31:24.728Z,1598898684.728 [RudderServo](DEBUG): Initializing RudderServo. 2020-08-31T18:31:24.729Z,1598898684.729 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-08-31T18:31:24.736Z,1598898684.736 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-08-31T18:31:25.136Z,1598898685.136 [AMEcho](INFO): Powering down 2020-08-31T18:31:25.568Z,1598898685.568 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-08-31T18:31:25.568Z,1598898685.568 [RudderServo](FAULT): Rudder failed to initialize 2020-08-31T18:31:25.568Z,1598898685.568 [RudderServo] Communications Fault, FailCount= 1 2020-08-31T18:31:25.568Z,1598898685.568 [RudderServo](ERROR): Communications Fault 2020-08-31T18:31:25.727Z,1598898685.727 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-08-31T18:31:25.886Z,1598898685.886 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-08-31T18:31:25.887Z,1598898685.887 [RudderServo](INFO): Powering down 2020-08-31T18:31:26.555Z,1598898686.555 [RudderServo](DEBUG): Initializing EZServoServo. 2020-08-31T18:31:26.673Z,1598898686.673 [RudderServo](DEBUG): Initializing RudderServo. 2020-08-31T18:31:26.677Z,1598898686.677 [CBIT](INFO): Clearing failed state for component RudderServo 2020-08-31T18:31:26.677Z,1598898686.677 [RudderServo] No Fault, FailCount= 1 2020-08-31T18:31:30.916Z,1598898690.916 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2020-08-31T18:31:37.470Z,1598898697.470 [DAT](INFO): commRate: 800 2020-08-31T18:31:37.470Z,1598898697.470 [DAT](INFO): commRate: 800 2020-08-31T18:31:37.863Z,1598898697.863 [DAT](INFO): entering command mode 2020-08-31T18:31:38.262Z,1598898698.262 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:38.680Z,1598898698.680 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:39.074Z,1598898699.074 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:39.481Z,1598898699.481 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:39.897Z,1598898699.897 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:40.286Z,1598898700.286 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:40.690Z,1598898700.690 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:41.104Z,1598898701.104 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:41.498Z,1598898701.498 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:41.950Z,1598898701.950 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:42.323Z,1598898702.323 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:42.721Z,1598898702.721 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:43.114Z,1598898703.114 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:43.528Z,1598898703.528 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:43.922Z,1598898703.922 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:44.337Z,1598898704.337 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:44.730Z,1598898704.730 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:45.134Z,1598898705.134 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:45.548Z,1598898705.548 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:45.946Z,1598898705.946 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:46.376Z,1598898706.376 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:46.746Z,1598898706.746 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:47.153Z,1598898707.153 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:47.578Z,1598898707.578 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:47.958Z,1598898707.958 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:48.366Z,1598898708.366 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:48.781Z,1598898708.781 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:49.170Z,1598898709.170 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:49.578Z,1598898709.578 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:50.005Z,1598898710.005 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:50.382Z,1598898710.382 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:50.793Z,1598898710.793 [NAL9602](INFO): Powering up NAL9602 2020-08-31T18:31:50.795Z,1598898710.795 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:51.205Z,1598898711.205 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:51.645Z,1598898711.645 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:52.006Z,1598898712.006 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:52.429Z,1598898712.429 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:52.806Z,1598898712.806 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:53.214Z,1598898713.214 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-31T18:31:53.214Z,1598898713.214 [DAT](FAULT): failed to enter command mode 2020-08-31T18:31:53.630Z,1598898713.630 [DAT](INFO): entering command mode 2020-08-31T18:31:54.023Z,1598898714.023 [DAT](INFO): setting verbose to 3 2020-08-31T18:31:54.461Z,1598898714.461 [DAT](INFO): set verbose to 3 2020-08-31T18:31:54.462Z,1598898714.462 [DAT](INFO): setting DatVerbose to 27440 2020-08-31T18:31:54.831Z,1598898714.831 [DAT](INFO): set DatVerbose to 27440 2020-08-31T18:31:54.832Z,1598898714.832 [DAT](INFO): setting transmit power to 8 2020-08-31T18:31:55.240Z,1598898715.240 [DAT](INFO): set transmit power to 8 2020-08-31T18:31:55.240Z,1598898715.240 [DAT](INFO): setting local address to 9 2020-08-31T18:31:55.665Z,1598898715.665 [DAT](INFO): set local address to 9 2020-08-31T18:32:01.709Z,1598898721.709 [NAL9602](INFO): NAL9602 initialized 2020-08-31T18:32:03.359Z,1598898723.359 [SBIT](IMPORTANT): Beginning Startup BIT 2020-08-31T18:32:03.387Z,1598898723.387 [CBIT](IMPORTANT): Beginning ground fault scan 2020-08-31T18:32:14.335Z,1598898734.335 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.011045 CHAN A1 (24V): 0.036874 CHAN A2 (12V): -0.007074 CHAN A3 (5V): -0.001801 CHAN B0 (3.3V): 0.000797 CHAN B1 (3.15aV): -0.000494 CHAN B2 (3.15bV): -0.000338 CHAN B3 (GND): 0.001779 OPEN: -0.003597 Full Scale Calc: 4.765 mA, -1.589 mA 2020-08-31T18:32:57.513Z,1598898777.513 [SBIT](IMPORTANT): SBIT PASSED 2020-08-31T18:32:57.513Z,1598898777.513 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2020-08-31T18:32:57.951Z,1598898777.951 [MissionManager](IMPORTANT): Started mission Startup 2020-08-31T18:32:57.951Z,1598898777.951 [Startup] Running Loop=1 2020-08-31T18:32:57.951Z,1598898777.951 [Startup](DEBUG): Aggregate::initialize Startup 2020-08-31T18:32:57.951Z,1598898777.951 [Startup:A.GoToSurface] Running Loop=1 2020-08-31T18:32:57.951Z,1598898777.951 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-08-31T18:32:57.952Z,1598898777.952 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-08-31T18:32:57.952Z,1598898777.952 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-08-31T18:32:57.953Z,1598898777.953 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-08-31T18:32:57.953Z,1598898777.953 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-08-31T18:32:57.953Z,1598898777.953 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-08-31T18:32:57.955Z,1598898777.955 [Startup:StartupSatComms] Running Loop=1 2020-08-31T18:32:57.955Z,1598898777.955 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-08-31T18:32:57.955Z,1598898777.955 [Startup:StartupSatComms:A] Running Loop=1 2020-08-31T18:32:58.383Z,1598898778.383 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-08-31T18:33:52.903Z,1598898832.903 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005493 2020-08-31T18:33:58.065Z,1598898838.065 [Startup:StartupSatComms:A](INFO): Timed out from 2020-08-31T18:32:57.0Z 2020-08-31T18:33:58.065Z,1598898838.065 [Startup:StartupSatComms:A] Stopped 2020-08-31T18:33:58.065Z,1598898838.065 [Startup:StartupSatComms:B] Running Loop=1 2020-08-31T18:33:58.472Z,1598898838.472 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-08-31T18:34:05.391Z,1598898845.391 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200831T182151/Courier0004.lzma 2020-08-31T18:34:06.394Z,1598898846.394 [DataOverHttps](INFO): Moved sent file to Logs/20200831T182151/Courier0004.lzma.bak 2020-08-31T18:34:06.394Z,1598898846.394 [DataOverHttps](INFO): SBD MOMSN=12625908 2020-08-31T18:34:22.285Z,1598898862.285 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20200831T182622/Courier0000.lzma 2020-08-31T18:34:23.277Z,1598898863.277 [DataOverHttps](INFO): Moved sent file to Logs/20200831T182622/Courier0000.lzma.bak 2020-08-31T18:34:23.278Z,1598898863.278 [DataOverHttps](INFO): SBD MOMSN=12625910 2020-08-31T18:34:23.945Z,1598898863.945 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-08-31T18:34:23.945Z,1598898863.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-08-31T18:34:23.955Z,1598898863.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-08-31T18:34:24.368Z,1598898864.368 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-08-31T18:34:24.368Z,1598898864.368 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-08-31T18:34:38.980Z,1598898878.980 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200831T182622/Courier0004.lzma 2020-08-31T18:34:39.981Z,1598898879.981 [DataOverHttps](INFO): Moved sent file to Logs/20200831T182622/Courier0004.lzma.bak 2020-08-31T18:34:39.981Z,1598898879.981 [DataOverHttps](INFO): SBD MOMSN=12625915 2020-08-31T18:34:57.091Z,1598898897.091 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200831T183112/Courier0000.lzma 2020-08-31T18:34:58.093Z,1598898898.093 [DataOverHttps](INFO): Moved sent file to Logs/20200831T183112/Courier0000.lzma.bak 2020-08-31T18:34:58.094Z,1598898898.094 [DataOverHttps](INFO): SBD MOMSN=12625917 2020-08-31T18:34:58.281Z,1598898898.281 [Startup:StartupSatComms:B](INFO): Timed out from 2020-08-31T18:33:58.1Z 2020-08-31T18:34:58.281Z,1598898898.281 [Startup:StartupSatComms:B] Stopped 2020-08-31T18:34:58.281Z,1598898898.281 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-08-31T18:34:58.281Z,1598898898.281 [Startup:StartupSatComms] Stopped 2020-08-31T18:34:58.281Z,1598898898.281 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-08-31T18:34:58.282Z,1598898898.282 [Startup](INFO): Completed Startup 2020-08-31T18:34:58.282Z,1598898898.282 [MissionManager](INFO): Startup is completed. 2020-08-31T18:34:58.282Z,1598898898.282 [MissionManager](INFO): Uninitializing Mission Startup 2020-08-31T18:34:58.283Z,1598898898.283 [Startup] Stopped 2020-08-31T18:34:58.283Z,1598898898.283 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-08-31T18:34:58.283Z,1598898898.283 [Startup:A.GoToSurface] Stopped 2020-08-31T18:34:58.283Z,1598898898.283 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-08-31T18:34:58.681Z,1598898898.681 [MissionManager](IMPORTANT): Started mission Default 2020-08-31T18:34:58.681Z,1598898898.681 [Default] Running Loop=1 2020-08-31T18:34:58.681Z,1598898898.681 [Default](DEBUG): Aggregate::initialize Default 2020-08-31T18:34:58.681Z,1598898898.681 [Default:B.GoToSurface] Running Loop=1 2020-08-31T18:34:58.681Z,1598898898.681 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-08-31T18:34:58.681Z,1598898898.681 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-08-31T18:34:58.682Z,1598898898.682 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-08-31T18:34:58.682Z,1598898898.682 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-08-31T18:34:58.682Z,1598898898.682 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-08-31T18:34:58.682Z,1598898898.682 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-08-31T18:34:58.683Z,1598898898.683 [Default:A.Wait] Running Loop=1 2020-08-31T18:34:58.683Z,1598898898.683 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-08-31T18:35:11.994Z,1598898911.994 [Default:A.Wait](INFO): Done Waiting. 2020-08-31T18:35:11.994Z,1598898911.994 [Default:A.Wait] Stopped 2020-08-31T18:35:11.994Z,1598898911.994 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-08-31T18:35:12.512Z,1598898912.512 [Default:CheckIn] Running Loop=1 2020-08-31T18:35:12.512Z,1598898912.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-08-31T18:35:12.512Z,1598898912.512 [Default:CheckIn:Read_GPS] Running Loop=1 2020-08-31T18:35:12.825Z,1598898912.825 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-08-31T18:35:41.141Z,1598898941.141 [CommandLine](IMPORTANT): got command restart system 2020-08-31T18:35:43.224Z,1598898943.224 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2020-08-31T18:35:43.224Z,1598898943.224 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-31T18:35:43.225Z,1598898943.225 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-08-31T18:35:43.228Z,1598898943.228 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-08-31T18:35:43.228Z,1598898943.228 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-08-31T18:35:43.229Z,1598898943.229 [CommandLine](INFO): Join timeout helper Thread ID is 975 2020-08-31T18:35:43.229Z,1598898943.229 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-08-31T18:35:43.229Z,1598898943.229 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-08-31T18:35:43.230Z,1598898943.230 [NavChartDb](INFO): Join timeout helper Thread ID is 976 2020-08-31T18:35:43.304Z,1598898943.304 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-31T18:35:43.305Z,1598898943.305 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-08-31T18:35:43.315Z,1598898943.315 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-08-31T18:35:43.315Z,1598898943.315 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-08-31T18:35:43.317Z,1598898943.317 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 977 2020-08-31T18:35:43.536Z,1598898943.536 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-31T18:35:43.536Z,1598898943.536 [WetLabsBB2FL](INFO): Powering down 2020-08-31T18:35:43.537Z,1598898943.537 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-08-31T18:35:43.540Z,1598898943.540 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-08-31T18:35:43.540Z,1598898943.540 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-08-31T18:35:43.540Z,1598898943.540 [CTD_Seabird](INFO): Join timeout helper Thread ID is 978 2020-08-31T18:35:43.988Z,1598898943.988 [CTD_Seabird](INFO): Powering down 2020-08-31T18:35:43.000Z,1598898944.000 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-31T18:35:43.000Z,1598898944.000 [CTD_Seabird](INFO): Powering down 2020-08-3