2019-02-15T02:13:37.300Z,1550196817.300 [Supervisor](DEBUG): Initializing supervisor.
2019-02-15T02:13:37.302Z,1550196817.302 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-15T02:13:37.303Z,1550196817.303 [SyncHandler](INFO): Protected caller Thread ID is 806
2019-02-15T02:13:37.303Z,1550196817.303 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-15T02:13:37.304Z,1550196817.304 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-15T02:13:37.305Z,1550196817.305 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807
2019-02-15T02:13:37.307Z,1550196817.307 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-15T02:13:37.319Z,1550196817.319 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-15T02:13:37.320Z,1550196817.320 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-15T02:13:37.320Z,1550196817.320 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808
2019-02-15T02:13:37.321Z,1550196817.321 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-15T02:13:37.322Z,1550196817.322 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-15T02:13:37.322Z,1550196817.322 [logger ThreadHandler](INFO): Protected caller Thread ID is 809
2019-02-15T02:13:37.324Z,1550196817.324 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-15T02:13:37.325Z,1550196817.325 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-15T02:13:37.329Z,1550196817.329 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-15T02:13:37.534Z,1550196817.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-15T02:13:37.535Z,1550196817.535 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-15T02:13:37.617Z,1550196817.617 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-15T02:13:38.075Z,1550196818.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-15T02:13:38.076Z,1550196818.076 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-15T02:13:38.418Z,1550196818.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-15T02:13:38.419Z,1550196818.419 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-15T02:13:38.517Z,1550196818.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-15T02:13:38.517Z,1550196818.517 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-15T02:13:38.828Z,1550196818.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-15T02:13:38.829Z,1550196818.829 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-15T02:13:39.033Z,1550196819.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-15T02:13:39.033Z,1550196819.033 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-15T02:13:39.497Z,1550196819.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-15T02:13:39.498Z,1550196819.498 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-15T02:13:39.604Z,1550196819.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-15T02:13:39.606Z,1550196819.606 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-15T02:13:39.710Z,1550196819.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-15T02:13:39.711Z,1550196819.711 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-15T02:13:40.213Z,1550196820.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-15T02:13:40.214Z,1550196820.214 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-15T02:13:40.623Z,1550196820.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-15T02:13:40.624Z,1550196820.624 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-15T02:13:40.961Z,1550196820.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-15T02:13:40.961Z,1550196820.961 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-15T02:13:41.515Z,1550196821.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-15T02:13:41.516Z,1550196821.516 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-15T02:13:41.669Z,1550196821.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-15T02:13:41.671Z,1550196821.671 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-02-15T02:13:41.675Z,1550196821.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-02-15T02:13:41.769Z,1550196821.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-02-15T02:13:41.857Z,1550196821.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-02-15T02:13:41.965Z,1550196821.965 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-02-15T02:13:42.050Z,1550196822.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-02-15T02:13:42.149Z,1550196822.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-02-15T02:13:42.250Z,1550196822.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-02-15T02:13:42.439Z,1550196822.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-02-15T02:13:42.517Z,1550196822.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-02-15T02:13:42.685Z,1550196822.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-02-15T02:13:42.815Z,1550196822.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-02-15T02:13:42.937Z,1550196822.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-02-15T02:13:43.168Z,1550196823.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-15T02:13:43.169Z,1550196823.169 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-02-15T02:13:43.173Z,1550196823.173 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-15T02:13:43.259Z,1550196823.259 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-15T02:13:43.375Z,1550196823.375 [VerticalControl] Loaded
2019-02-15T02:13:43.375Z,1550196823.375 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-15T02:13:43.376Z,1550196823.376 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-15T02:13:43.445Z,1550196823.445 [HorizontalControl] Loaded
2019-02-15T02:13:43.445Z,1550196823.445 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-15T02:13:43.446Z,1550196823.446 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-15T02:13:43.452Z,1550196823.452 [SpeedControl] Loaded
2019-02-15T02:13:43.452Z,1550196823.452 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-15T02:13:43.453Z,1550196823.453 [LoopControl](DEBUG): Construct LoopControl.
2019-02-15T02:13:43.454Z,1550196823.454 [LoopControl] Loaded
2019-02-15T02:13:43.454Z,1550196823.454 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-15T02:13:43.455Z,1550196823.455 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-15T02:13:43.455Z,1550196823.455 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-15T02:13:43.479Z,1550196823.479 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-15T02:13:43.480Z,1550196823.480 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-15T02:13:43.606Z,1550196823.606 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-15T02:13:43.607Z,1550196823.607 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-15T02:13:43.749Z,1550196823.749 [BuoyancyServo] Loaded
2019-02-15T02:13:43.749Z,1550196823.749 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-15T02:13:43.761Z,1550196823.761 [ElevatorServo] Loaded
2019-02-15T02:13:43.761Z,1550196823.761 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-15T02:13:43.772Z,1550196823.772 [MassServo] Loaded
2019-02-15T02:13:43.772Z,1550196823.772 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-15T02:13:43.783Z,1550196823.783 [RudderServo] Loaded
2019-02-15T02:13:43.784Z,1550196823.784 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-15T02:13:43.795Z,1550196823.795 [ThrusterServo] Loaded
2019-02-15T02:13:43.795Z,1550196823.795 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-15T02:13:43.796Z,1550196823.796 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-15T02:13:43.796Z,1550196823.796 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-15T02:13:43.921Z,1550196823.921 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-15T02:13:43.921Z,1550196823.921 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-15T02:13:43.980Z,1550196823.980 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-15T02:13:43.981Z,1550196823.981 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-15T02:13:44.040Z,1550196824.040 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-15T02:13:44.040Z,1550196824.040 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-15T02:13:44.056Z,1550196824.056 [NavChart] Loaded
2019-02-15T02:13:44.056Z,1550196824.056 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-15T02:13:44.060Z,1550196824.060 [UniversalFixResidualReporter] Loaded
2019-02-15T02:13:44.060Z,1550196824.060 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-15T02:13:44.061Z,1550196824.061 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-15T02:13:44.061Z,1550196824.061 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-15T02:13:44.165Z,1550196824.165 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-15T02:13:44.166Z,1550196824.166 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-15T02:13:44.755Z,1550196824.755 [DataOverHttps] Loaded
2019-02-15T02:13:44.756Z,1550196824.756 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-15T02:13:44.769Z,1550196824.769 [Depth_Keller] Loaded
2019-02-15T02:13:44.769Z,1550196824.769 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-15T02:13:44.775Z,1550196824.775 [DropWeight] Loaded
2019-02-15T02:13:44.775Z,1550196824.775 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-15T02:13:44.873Z,1550196824.873 [NAL9602] Loaded
2019-02-15T02:13:44.873Z,1550196824.873 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-15T02:13:44.890Z,1550196824.890 [Onboard] Loaded
2019-02-15T02:13:44.890Z,1550196824.890 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-15T02:13:44.897Z,1550196824.897 [Radio_Surface] Loaded
2019-02-15T02:13:44.897Z,1550196824.897 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-15T02:13:44.899Z,1550196824.899 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-15T02:13:44.899Z,1550196824.899 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 891
2019-02-15T02:13:45.051Z,1550196825.051 [PNI_TCM] Loaded
2019-02-15T02:13:45.052Z,1550196825.052 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-15T02:13:45.112Z,1550196825.112 [Rowe_600LCM] Loaded
2019-02-15T02:13:45.113Z,1550196825.113 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-15T02:13:45.114Z,1550196825.114 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-15T02:13:45.114Z,1550196825.114 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 892
2019-02-15T02:13:45.115Z,1550196825.115 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-15T02:13:45.115Z,1550196825.115 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-15T02:13:45.170Z,1550196825.170 [DepthRateCalculator] Loaded
2019-02-15T02:13:45.171Z,1550196825.171 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-15T02:13:45.176Z,1550196825.176 [PitchRateCalculator] Loaded
2019-02-15T02:13:45.176Z,1550196825.176 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-15T02:13:45.188Z,1550196825.188 [SpeedCalculator] Loaded
2019-02-15T02:13:45.188Z,1550196825.188 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-15T02:13:45.209Z,1550196825.209 [TempGradientCalculator] Loaded
2019-02-15T02:13:45.210Z,1550196825.210 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-15T02:13:45.215Z,1550196825.215 [YawRateCalculator] Loaded
2019-02-15T02:13:45.215Z,1550196825.215 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-15T02:13:45.245Z,1550196825.245 [ElevatorOffsetCalculator] Loaded
2019-02-15T02:13:45.246Z,1550196825.246 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-15T02:13:45.246Z,1550196825.246 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-15T02:13:45.247Z,1550196825.247 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-15T02:13:45.479Z,1550196825.479 [Aanderaa_O2] Loaded
2019-02-15T02:13:45.479Z,1550196825.479 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-15T02:13:45.563Z,1550196825.563 [CTD_NeilBrown] Loaded
2019-02-15T02:13:45.563Z,1550196825.563 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-15T02:13:45.564Z,1550196825.564 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0
2019-02-15T02:13:45.565Z,1550196825.565 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 893
2019-02-15T02:13:45.574Z,1550196825.574 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-15T02:13:45.579Z,1550196825.579 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-15T02:13:45.581Z,1550196825.581 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-15T02:13:45.586Z,1550196825.586 [CTD_Seabird](INFO): created writer for : depth
2019-02-15T02:13:45.586Z,1550196825.586 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-15T02:13:45.591Z,1550196825.591 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-15T02:13:45.592Z,1550196825.592 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-15T02:13:45.597Z,1550196825.597 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-15T02:13:45.597Z,1550196825.597 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-15T02:13:45.603Z,1550196825.603 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-15T02:13:45.603Z,1550196825.603 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-15T02:13:45.609Z,1550196825.609 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-15T02:13:45.609Z,1550196825.609 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-15T02:13:45.614Z,1550196825.614 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-15T02:13:45.641Z,1550196825.641 [CTD_Seabird] Loaded
2019-02-15T02:13:45.641Z,1550196825.641 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-15T02:13:45.643Z,1550196825.643 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4098C4E0
2019-02-15T02:13:45.643Z,1550196825.643 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894
2019-02-15T02:13:45.658Z,1550196825.658 [PAR_Licor] Loaded
2019-02-15T02:13:45.658Z,1550196825.658 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-15T02:13:45.706Z,1550196825.706 [WetLabsBB2FL] Loaded
2019-02-15T02:13:45.707Z,1550196825.707 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-15T02:13:45.708Z,1550196825.708 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409BC4E0
2019-02-15T02:13:45.708Z,1550196825.708 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895
2019-02-15T02:13:45.709Z,1550196825.709 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-15T02:13:45.709Z,1550196825.709 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-15T02:13:45.753Z,1550196825.753 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-15T02:13:45.753Z,1550196825.753 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-15T02:13:46.035Z,1550196826.035 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-15T02:13:46.036Z,1550196826.036 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-15T02:13:46.545Z,1550196826.545 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-15T02:13:46.556Z,1550196826.556 [SBIT] Loaded
2019-02-15T02:13:46.557Z,1550196826.557 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-15T02:13:46.557Z,1550196826.557 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-15T02:13:46.570Z,1550196826.570 [IBIT] Loaded
2019-02-15T02:13:46.571Z,1550196826.571 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-15T02:13:46.574Z,1550196826.574 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-15T02:13:46.966Z,1550196826.966 [CBIT] Loaded
2019-02-15T02:13:46.967Z,1550196826.967 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-15T02:13:46.967Z,1550196826.967 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-15T02:13:46.971Z,1550196826.971 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-15T02:13:46.972Z,1550196826.972 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-15T02:13:46.979Z,1550196826.979 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-15T02:13:46.980Z,1550196826.980 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA64E0
2019-02-15T02:13:46.980Z,1550196826.980 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 896
2019-02-15T02:13:46.985Z,1550196826.985 [Supervisor](INFO): Main Thread ID is 802
2019-02-15T02:13:46.985Z,1550196826.985 [Supervisor](DEBUG): Running supervisor.
2019-02-15T02:13:46.985Z,1550196826.985 [CommandLine ThreadHandler](INFO): Handler Thread ID is 897
2019-02-15T02:13:46.988Z,1550196826.988 [controlThread ThreadHandler](INFO): Handler Thread ID is 898
2019-02-15T02:13:46.988Z,1550196826.988 [controlThread](DEBUG): Initializing ControlThread
2019-02-15T02:13:46.989Z,1550196826.989 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-15T02:13:46.991Z,1550196826.991 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-15T02:13:46.991Z,1550196826.991 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-15T02:13:46.992Z,1550196826.992 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-15T02:13:46.994Z,1550196826.994 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-15T02:13:46.995Z,1550196826.995 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-15T02:13:46.995Z,1550196826.995 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-15T02:13:47.001Z,1550196827.001 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-15T02:13:47.001Z,1550196827.001 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-15T02:13:47.001Z,1550196827.001 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-15T02:13:47.001Z,1550196827.001 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-15T02:13:47.002Z,1550196827.002 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-15T02:13:47.002Z,1550196827.002 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-15T02:13:47.007Z,1550196827.007 [SBIT](INFO): Initialize SBIT Component.
2019-02-15T02:13:47.007Z,1550196827.007 [SBIT](IMPORTANT): git: 2019-01-14A-50-g9cfb34a
2019-02-15T02:13:47.008Z,1550196827.008 [SBIT](INFO): git hash: 9cfb34a5380b67c155f39980646156fa9a5482a4
2019-02-15T02:13:47.008Z,1550196827.008 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-15T02:13:47.009Z,1550196827.009 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-02-15T02:13:47.010Z,1550196827.010 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-02-15T02:13:47.011Z,1550196827.011 [IBIT](INFO): Initialize IBIT Component.
2019-02-15T02:13:47.012Z,1550196827.012 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-15T02:13:47.012Z,1550196827.012 [logger ThreadHandler](INFO): Handler Thread ID is 899
2019-02-15T02:13:47.023Z,1550196827.023 [CBIT](DEBUG): Initialized mux pins.
2019-02-15T02:13:47.023Z,1550196827.023 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-02-15T02:13:47.023Z,1550196827.023 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-15T02:13:47.031Z,1550196827.031 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 900
2019-02-15T02:13:47.037Z,1550196827.037 [Radio_Surface](INFO): Powering up
2019-02-15T02:13:47.043Z,1550196827.043 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 901
2019-02-15T02:13:47.044Z,1550196827.044 [Rowe_600LCM](INFO): Initializing
2019-02-15T02:13:47.051Z,1550196827.051 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-15T02:13:47.051Z,1550196827.051 [CBIT](DEBUG): Initializing heartbeat.
2019-02-15T02:13:47.139Z,1550196827.139 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 902
2019-02-15T02:13:47.140Z,1550196827.140 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-15T02:13:47.140Z,1550196827.140 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-15T02:13:47.141Z,1550196827.141 [CTD_NeilBrown](INFO): Powering down
2019-02-15T02:13:47.167Z,1550196827.167 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 903
2019-02-15T02:13:47.167Z,1550196827.167 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-15T02:13:47.172Z,1550196827.172 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-15T02:13:47.267Z,1550196827.267 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-15T02:13:47.278Z,1550196827.278 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 905
2019-02-15T02:13:47.279Z,1550196827.279 [WetLabsBB2FL](INFO): Powering down
2019-02-15T02:13:47.297Z,1550196827.297 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 906
2019-02-15T02:13:47.307Z,1550196827.307 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-15T02:13:47.307Z,1550196827.307 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-15T02:13:47.310Z,1550196827.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-15T02:13:47.310Z,1550196827.310 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-15T02:13:47.310Z,1550196827.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-15T02:13:47.432Z,1550196827.432 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-15T02:13:47.444Z,1550196827.444 [MissionManager](DEBUG):
2019-02-15T02:13:47.445Z,1550196827.445 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-15T02:13:47.600Z,1550196827.600 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-15T02:13:47.601Z,1550196827.601 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-15T02:13:47.615Z,1550196827.615 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-15T02:13:47.638Z,1550196827.638 [Rowe_600LCM](INFO): Powering up
2019-02-15T02:13:47.665Z,1550196827.665 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-15T02:13:47.688Z,1550196827.688 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-15T02:13:47.694Z,1550196827.694 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-15T02:13:47.726Z,1550196827.726 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-02-15T02:13:47.745Z,1550196827.745 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-15T02:13:47.789Z,1550196827.789 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-15T02:13:47.816Z,1550196827.816 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-02-15T02:13:48.084Z,1550196828.084 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-15T02:13:48.531Z,1550196828.531 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:48.543Z,1550196828.543 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-15T02:13:48.561Z,1550196828.561 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:48.570Z,1550196828.570 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-15T02:13:48.611Z,1550196828.611 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:48.619Z,1550196828.619 [MassServo](DEBUG): Initializing MassServo.
2019-02-15T02:13:48.644Z,1550196828.644 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:48.659Z,1550196828.659 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-15T02:13:48.674Z,1550196828.674 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:48.695Z,1550196828.695 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-15T02:13:48.756Z,1550196828.756 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-02-15T02:13:48.977Z,1550196828.977 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2019-02-15T02:13:48.977Z,1550196828.977 [DropWeight] Hardware Fault, FailCount= 1
2019-02-15T02:13:48.977Z,1550196828.977 [DropWeight](ERROR): Hardware Fault
2019-02-15T02:13:49.387Z,1550196829.387 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-15T02:13:49.387Z,1550196829.387 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-15T02:13:49.387Z,1550196829.387 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-15T02:13:49.387Z,1550196829.387 [BuoyancyServo](ERROR): Communications Fault
2019-02-15T02:13:49.700Z,1550196829.700 [CommandLine](FAULT): Scheduling is paused
2019-02-15T02:13:49.701Z,1550196829.701 [CBIT](INFO): Critical error at 20190215T021348
2019-02-15T02:13:49.701Z,1550196829.701 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-15T02:13:49.704Z,1550196829.704 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-15T02:13:49.704Z,1550196829.704 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-02-15T02:13:49.704Z,1550196829.704 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-02-15T02:13:49.795Z,1550196829.795 [CBIT](INFO): Critical error at 20190215T021349
2019-02-15T02:13:50.923Z,1550196830.923 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-02-15T02:13:50.923Z,1550196830.923 [MassServo] Communications Fault, FailCount= 1
2019-02-15T02:13:50.923Z,1550196830.923 [MassServo](ERROR): Communications Fault
2019-02-15T02:13:50.927Z,1550196830.927 [CBIT](ERROR): Communications Fault in component: MassServo
2019-02-15T02:13:51.023Z,1550196831.023 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-15T02:13:51.023Z,1550196831.023 [MassServo](INFO): Powering down
2019-02-15T02:13:51.591Z,1550196831.591 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-15T02:13:51.673Z,1550196831.673 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-15T02:13:51.923Z,1550196831.923 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:51.924Z,1550196831.924 [MassServo](DEBUG): Initializing MassServo.
2019-02-15T02:13:52.027Z,1550196832.027 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-15T02:13:52.027Z,1550196832.027 [BuoyancyServo] No Fault, FailCount= 1
2019-02-15T02:13:52.028Z,1550196832.028 [CBIT](INFO): Clearing failed state for component MassServo
2019-02-15T02:13:52.028Z,1550196832.028 [MassServo] No Fault, FailCount= 1
2019-02-15T02:13:52.238Z,1550196832.238 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:52.255Z,1550196832.255 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-15T02:13:53.084Z,1550196833.084 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-02-15T02:13:54.111Z,1550196834.111 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-02-15T02:13:54.111Z,1550196834.111 [MassServo] Communications Fault, FailCount= 2
2019-02-15T02:13:54.111Z,1550196834.111 [MassServo](ERROR): Communications Fault
2019-02-15T02:13:54.115Z,1550196834.115 [CBIT](ERROR): Communications Fault in component: MassServo
2019-02-15T02:13:54.210Z,1550196834.210 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-15T02:13:54.210Z,1550196834.210 [MassServo](INFO): Powering down
2019-02-15T02:13:55.010Z,1550196835.010 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:55.023Z,1550196835.023 [MassServo](DEBUG): Initializing MassServo.
2019-02-15T02:13:55.027Z,1550196835.027 [CBIT](INFO): Clearing failed state for component MassServo
2019-02-15T02:13:55.027Z,1550196835.027 [MassServo] No Fault, FailCount= 2
2019-02-15T02:13:55.755Z,1550196835.755 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-15T02:13:55.755Z,1550196835.755 [PNI_TCM] Data Fault, FailCount= 1
2019-02-15T02:13:55.755Z,1550196835.755 [PNI_TCM](ERROR): Data Fault
2019-02-15T02:13:55.863Z,1550196835.863 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-15T02:13:56.154Z,1550196836.154 [PNI_TCM](INFO): Powering down
2019-02-15T02:13:57.318Z,1550196837.318 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-02-15T02:13:57.319Z,1550196837.319 [MassServo] Communications Fault, FailCount= 3
2019-02-15T02:13:57.319Z,1550196837.319 [MassServo](ERROR): Communications Fault
2019-02-15T02:13:57.321Z,1550196837.321 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-02-15T02:13:57.324Z,1550196837.324 [CBIT](CRITICAL): Environmental Failure. Press:14.649905 PSI. Humidity:31%. Temp:24 C. ABORTING MISSION
2019-02-15T02:13:57.324Z,1550196837.324 [CBIT](ERROR): Communications Fault in component: MassServo
2019-02-15T02:13:57.487Z,1550196837.487 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-15T02:13:57.488Z,1550196837.488 [MassServo](INFO): Powering down
2019-02-15T02:13:57.491Z,1550196837.491 [CBIT](INFO): Critical error at 20190215T021357
2019-02-15T02:13:57.493Z,1550196837.493 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-15T02:13:57.493Z,1550196837.493 [PNI_TCM] No Fault, FailCount= 1
2019-02-15T02:13:58.155Z,1550196838.155 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-15T02:13:58.155Z,1550196838.155 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-15T02:13:58.155Z,1550196838.155 [Aanderaa_O2](ERROR): Communications Fault
2019-02-15T02:13:58.225Z,1550196838.225 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:13:58.226Z,1550196838.226 [MassServo](DEBUG): Initializing MassServo.
2019-02-15T02:13:58.235Z,1550196838.235 [CBIT](INFO): Clearing failed state for component MassServo
2019-02-15T02:13:58.235Z,1550196838.235 [MassServo] No Fault, FailCount= 3
2019-02-15T02:13:58.235Z,1550196838.235 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-15T02:13:58.567Z,1550196838.567 [Aanderaa_O2](INFO): Powering down
2019-02-15T02:13:59.405Z,1550196839.405 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-15T02:13:59.405Z,1550196839.405 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-15T02:14:00.523Z,1550196840.523 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-02-15T02:14:00.523Z,1550196840.523 [MassServo] Communications Fault, FailCount= 4
2019-02-15T02:14:00.523Z,1550196840.523 [MassServo](ERROR): Communications Fault
2019-02-15T02:14:00.527Z,1550196840.527 [CBIT](ERROR): Communications Fault in component: MassServo
2019-02-15T02:14:00.643Z,1550196840.643 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-15T02:14:00.643Z,1550196840.643 [MassServo](INFO): Powering down
2019-02-15T02:14:01.008Z,1550196841.008 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-02-15T02:14:01.223Z,1550196841.223 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-15T02:14:01.223Z,1550196841.223 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-15T02:14:01.223Z,1550196841.223 [CTD_Seabird](ERROR): Communications Fault
2019-02-15T02:14:01.400Z,1550196841.400 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-15T02:14:01.400Z,1550196841.400 [MassServo](DEBUG): Initializing MassServo.
2019-02-15T02:14:01.417Z,1550196841.417 [CBIT](INFO): Clearing failed state for component MassServo
2019-02-15T02:14:01.417Z,1550196841.417 [MassServo] No Fault, FailCount= 4
2019-02-15T02:14:01.418Z,1550196841.418 [CBIT](ERROR): Communications Fa