2019-01-22T16:45:43.809Z,1548175543.809 [Supervisor](DEBUG): Initializing supervisor.
2019-01-22T16:45:43.821Z,1548175543.821 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-01-22T16:45:43.821Z,1548175543.821 [SyncHandler](INFO): Protected caller Thread ID is 801
2019-01-22T16:45:43.822Z,1548175543.822 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-01-22T16:45:43.833Z,1548175543.833 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-01-22T16:45:43.833Z,1548175543.833 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 802
2019-01-22T16:45:43.836Z,1548175543.836 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-01-22T16:45:43.858Z,1548175543.858 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-01-22T16:45:43.860Z,1548175543.860 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-01-22T16:45:43.860Z,1548175543.860 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 803
2019-01-22T16:45:43.862Z,1548175543.862 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-01-22T16:45:43.885Z,1548175543.885 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-01-22T16:45:43.885Z,1548175543.885 [logger ThreadHandler](INFO): Protected caller Thread ID is 804
2019-01-22T16:45:43.887Z,1548175543.887 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-01-22T16:45:43.887Z,1548175543.887 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-01-22T16:45:43.892Z,1548175543.892 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-01-22T16:45:44.093Z,1548175544.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-01-22T16:45:44.097Z,1548175544.097 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-01-22T16:45:44.284Z,1548175544.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-01-22T16:45:44.286Z,1548175544.286 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-01-22T16:45:44.578Z,1548175544.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-01-22T16:45:44.579Z,1548175544.579 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-01-22T16:45:44.992Z,1548175544.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-01-22T16:45:44.994Z,1548175544.994 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-01-22T16:45:45.353Z,1548175545.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-01-22T16:45:45.497Z,1548175545.497 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-01-22T16:45:45.949Z,1548175545.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-01-22T16:45:45.950Z,1548175545.950 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-01-22T16:45:46.392Z,1548175546.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-01-22T16:45:46.393Z,1548175546.393 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-01-22T16:45:46.840Z,1548175546.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-01-22T16:45:46.841Z,1548175546.841 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-01-22T16:45:46.923Z,1548175546.923 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-01-22T16:45:47.222Z,1548175547.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-01-22T16:45:47.224Z,1548175547.224 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-01-22T16:45:47.369Z,1548175547.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-01-22T16:45:47.370Z,1548175547.370 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-01-22T16:45:47.577Z,1548175547.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-01-22T16:45:47.578Z,1548175547.578 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-01-22T16:45:47.777Z,1548175547.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-01-22T16:45:47.779Z,1548175547.779 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-01-22T16:45:47.875Z,1548175547.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-01-22T16:45:47.875Z,1548175547.875 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-01-22T16:45:48.019Z,1548175548.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-01-22T16:45:48.021Z,1548175548.021 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-01-22T16:45:48.023Z,1548175548.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-01-22T16:45:48.246Z,1548175548.246 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-01-22T16:45:48.246Z,1548175548.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-01-22T16:45:48.350Z,1548175548.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-01-22T16:45:48.499Z,1548175548.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-01-22T16:45:48.591Z,1548175548.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-01-22T16:45:48.676Z,1548175548.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-01-22T16:45:48.831Z,1548175548.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-01-22T16:45:49.029Z,1548175549.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-01-22T16:45:49.127Z,1548175549.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-01-22T16:45:49.221Z,1548175549.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-01-22T16:45:49.319Z,1548175549.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-01-22T16:45:49.401Z,1548175549.401 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-01-22T16:45:49.405Z,1548175549.405 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-01-22T16:45:49.534Z,1548175549.534 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-01-22T16:45:49.535Z,1548175549.535 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-01-22T16:45:49.558Z,1548175549.558 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-01-22T16:45:49.560Z,1548175549.560 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-01-22T16:45:49.616Z,1548175549.616 [DepthRateCalculator] Loaded
2019-01-22T16:45:49.616Z,1548175549.616 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-01-22T16:45:49.622Z,1548175549.622 [PitchRateCalculator] Loaded
2019-01-22T16:45:49.622Z,1548175549.622 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-01-22T16:45:49.638Z,1548175549.638 [SpeedCalculator] Loaded
2019-01-22T16:45:49.638Z,1548175549.638 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-01-22T16:45:49.658Z,1548175549.658 [TempGradientCalculator] Loaded
2019-01-22T16:45:49.659Z,1548175549.659 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-01-22T16:45:49.664Z,1548175549.664 [YawRateCalculator] Loaded
2019-01-22T16:45:49.665Z,1548175549.665 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-01-22T16:45:49.704Z,1548175549.704 [ElevatorOffsetCalculator] Loaded
2019-01-22T16:45:49.704Z,1548175549.704 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-01-22T16:45:49.704Z,1548175549.704 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-01-22T16:45:49.706Z,1548175549.706 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-01-22T16:45:49.747Z,1548175549.747 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-01-22T16:45:49.748Z,1548175549.748 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-01-22T16:45:50.267Z,1548175550.267 [AcousticModem_Benthos_ATM900] Loaded
2019-01-22T16:45:50.268Z,1548175550.268 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-01-22T16:45:50.406Z,1548175550.406 [DataOverHttps] Loaded
2019-01-22T16:45:50.407Z,1548175550.407 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-01-22T16:45:50.420Z,1548175550.420 [Depth_Keller] Loaded
2019-01-22T16:45:50.421Z,1548175550.421 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-01-22T16:45:50.425Z,1548175550.425 [DropWeight] Loaded
2019-01-22T16:45:50.426Z,1548175550.426 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-01-22T16:45:50.521Z,1548175550.521 [NAL9602] Loaded
2019-01-22T16:45:50.522Z,1548175550.522 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-01-22T16:45:50.537Z,1548175550.537 [Onboard] Loaded
2019-01-22T16:45:50.537Z,1548175550.537 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-01-22T16:45:50.547Z,1548175550.547 [Radio_Surface] Loaded
2019-01-22T16:45:50.547Z,1548175550.547 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-01-22T16:45:50.549Z,1548175550.549 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407D54E0
2019-01-22T16:45:50.549Z,1548175550.549 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883
2019-01-22T16:45:50.701Z,1548175550.701 [PNI_TCM] Loaded
2019-01-22T16:45:50.701Z,1548175550.701 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-01-22T16:45:50.787Z,1548175550.787 [Rowe_600LCM] Loaded
2019-01-22T16:45:50.788Z,1548175550.788 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-01-22T16:45:50.789Z,1548175550.789 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408054E0
2019-01-22T16:45:50.817Z,1548175550.817 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 884
2019-01-22T16:45:52.802Z,1548175552.802 [BPC1] Loaded
2019-01-22T16:45:52.802Z,1548175552.802 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-01-22T16:45:52.802Z,1548175552.802 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-01-22T16:45:52.803Z,1548175552.803 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-01-22T16:45:52.962Z,1548175552.962 [SBIT](DEBUG): Construct Startup Built In Test.
2019-01-22T16:45:52.985Z,1548175552.985 [SBIT] Loaded
2019-01-22T16:45:52.985Z,1548175552.985 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-01-22T16:45:52.986Z,1548175552.986 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-01-22T16:45:52.997Z,1548175552.997 [IBIT] Loaded
2019-01-22T16:45:52.998Z,1548175552.998 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-01-22T16:45:53.001Z,1548175553.001 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-01-22T16:45:53.136Z,1548175553.136 [CBIT] Loaded
2019-01-22T16:45:53.136Z,1548175553.136 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-01-22T16:45:53.136Z,1548175553.136 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-01-22T16:45:53.137Z,1548175553.137 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-01-22T16:45:53.384Z,1548175553.384 [Aanderaa_O2] Loaded
2019-01-22T16:45:53.384Z,1548175553.384 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-01-22T16:45:53.393Z,1548175553.393 [CTD_Seabird](DEBUG): add sea_water_density
2019-01-22T16:45:53.393Z,1548175553.393 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-01-22T16:45:53.393Z,1548175553.393 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_density
2019-01-22T16:45:53.399Z,1548175553.399 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-01-22T16:45:53.399Z,1548175553.399 [CTD_Seabird](DEBUG): added writer, now dataWriters_.size()=1
2019-01-22T16:45:53.400Z,1548175553.400 [CTD_Seabird](DEBUG): add depth
2019-01-22T16:45:53.400Z,1548175553.400 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-01-22T16:45:53.400Z,1548175553.400 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter depth
2019-01-22T16:45:53.405Z,1548175553.405 [CTD_Seabird](INFO): created writer for : depth
2019-01-22T16:45:53.405Z,1548175553.405 [CTD_Seabird](DEBUG): added writer, now dataWriters_.size()=2
2019-01-22T16:45:53.406Z,1548175553.406 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-01-22T16:45:53.406Z,1548175553.406 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_pressure
2019-01-22T16:45:53.410Z,1548175553.411 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-01-22T16:45:53.411Z,1548175553.411 [CTD_Seabird](DEBUG): added writer, now dataWriters_.size()=3
2019-01-22T16:45:53.411Z,1548175553.411 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-01-22T16:45:53.411Z,1548175553.411 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_salinity
2019-01-22T16:45:53.416Z,1548175553.416 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-01-22T16:45:53.417Z,1548175553.417 [CTD_Seabird](DEBUG): added writer, now dataWriters_.size()=4
2019-01-22T16:45:53.417Z,1548175553.417 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-01-22T16:45:53.417Z,1548175553.417 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_temperature
2019-01-22T16:45:53.422Z,1548175553.422 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-01-22T16:45:53.422Z,1548175553.422 [CTD_Seabird](DEBUG): added writer, now dataWriters_.size()=5
2019-01-22T16:45:53.423Z,1548175553.423 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-01-22T16:45:53.423Z,1548175553.423 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter sea_water_electrical_conductivity
2019-01-22T16:45:53.428Z,1548175553.428 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-01-22T16:45:53.428Z,1548175553.428 [CTD_Seabird](DEBUG): added writer, now dataWriters_.size()=6
2019-01-22T16:45:53.429Z,1548175553.429 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-01-22T16:45:53.429Z,1548175553.429 [CTD_Seabird](DEBUG): LcmsSlateWriter::add() new dataWriter speed_of_sound_in_sea_water
2019-01-22T16:45:53.434Z,1548175553.434 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-01-22T16:45:53.434Z,1548175553.434 [CTD_Seabird](DEBUG): added writer, now dataWriters_.size()=7
2019-01-22T16:45:53.460Z,1548175553.460 [CTD_Seabird] Loaded
2019-01-22T16:45:53.461Z,1548175553.461 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-01-22T16:45:53.462Z,1548175553.462 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2019-01-22T16:45:53.462Z,1548175553.462 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 885
2019-01-22T16:45:53.479Z,1548175553.479 [ESPComponent] Loaded
2019-01-22T16:45:53.479Z,1548175553.479 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-01-22T16:45:53.493Z,1548175553.493 [PAR_Licor] Loaded
2019-01-22T16:45:53.493Z,1548175553.493 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-01-22T16:45:53.541Z,1548175553.541 [WetLabsBB2FL] Loaded
2019-01-22T16:45:53.542Z,1548175553.542 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-01-22T16:45:53.543Z,1548175553.543 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2019-01-22T16:45:53.543Z,1548175553.543 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 886
2019-01-22T16:45:53.544Z,1548175553.544 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-01-22T16:45:53.544Z,1548175553.544 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-01-22T16:45:53.824Z,1548175553.824 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-01-22T16:45:53.826Z,1548175553.826 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-01-22T16:45:53.913Z,1548175553.913 [VerticalControl](DEBUG): Construct VerticalControl.
2019-01-22T16:45:54.007Z,1548175554.007 [VerticalControl] Loaded
2019-01-22T16:45:54.007Z,1548175554.007 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-01-22T16:45:54.008Z,1548175554.008 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-01-22T16:45:54.067Z,1548175554.067 [HorizontalControl] Loaded
2019-01-22T16:45:54.068Z,1548175554.068 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-01-22T16:45:54.068Z,1548175554.068 [SpeedControl](DEBUG): Construct SpeedControl.
2019-01-22T16:45:54.074Z,1548175554.074 [SpeedControl] Loaded
2019-01-22T16:45:54.074Z,1548175554.074 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-01-22T16:45:54.075Z,1548175554.075 [LoopControl](DEBUG): Construct LoopControl.
2019-01-22T16:45:54.075Z,1548175554.075 [LoopControl] Loaded
2019-01-22T16:45:54.075Z,1548175554.075 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-01-22T16:45:54.076Z,1548175554.076 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-01-22T16:45:54.077Z,1548175554.077 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-01-22T16:45:54.178Z,1548175554.178 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-01-22T16:45:54.179Z,1548175554.179 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-01-22T16:45:54.296Z,1548175554.296 [BuoyancyServo] Loaded
2019-01-22T16:45:54.297Z,1548175554.297 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-01-22T16:45:54.308Z,1548175554.308 [ElevatorServo] Loaded
2019-01-22T16:45:54.308Z,1548175554.308 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-01-22T16:45:54.319Z,1548175554.319 [MassServo] Loaded
2019-01-22T16:45:54.319Z,1548175554.319 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-01-22T16:45:54.330Z,1548175554.330 [RudderServo] Loaded
2019-01-22T16:45:54.331Z,1548175554.331 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-01-22T16:45:54.342Z,1548175554.342 [ThrusterServo] Loaded
2019-01-22T16:45:54.342Z,1548175554.342 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-01-22T16:45:54.342Z,1548175554.342 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-01-22T16:45:54.344Z,1548175554.344 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-01-22T16:45:54.458Z,1548175554.458 [DeadReckonUsingSpeedCalculator] Loaded
2019-01-22T16:45:54.458Z,1548175554.458 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-01-22T16:45:54.474Z,1548175554.474 [NavChart] Loaded
2019-01-22T16:45:54.474Z,1548175554.474 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-01-22T16:45:54.478Z,1548175554.478 [UniversalFixResidualReporter] Loaded
2019-01-22T16:45:54.478Z,1548175554.478 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-01-22T16:45:54.479Z,1548175554.479 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-01-22T16:45:54.482Z,1548175554.482 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-01-22T16:45:54.483Z,1548175554.483 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-01-22T16:45:54.490Z,1548175554.490 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-01-22T16:45:54.491Z,1548175554.491 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A8F4E0
2019-01-22T16:45:54.491Z,1548175554.491 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 887
2019-01-22T16:45:54.496Z,1548175554.496 [Supervisor](INFO): Main Thread ID is 797
2019-01-22T16:45:54.496Z,1548175554.496 [Supervisor](DEBUG): Running supervisor.
2019-01-22T16:45:54.496Z,1548175554.496 [CommandLine ThreadHandler](INFO): Handler Thread ID is 888
2019-01-22T16:45:54.499Z,1548175554.499 [controlThread ThreadHandler](INFO): Handler Thread ID is 889
2019-01-22T16:45:54.499Z,1548175554.499 [controlThread](DEBUG): Initializing ControlThread
2019-01-22T16:45:54.500Z,1548175554.500 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-01-22T16:45:54.500Z,1548175554.500 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-01-22T16:45:54.501Z,1548175554.501 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-01-22T16:45:54.501Z,1548175554.501 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-01-22T16:45:54.501Z,1548175554.501 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-01-22T16:45:54.502Z,1548175554.502 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-01-22T16:45:54.507Z,1548175554.507 [SBIT](INFO): Initialize SBIT Component.
2019-01-22T16:45:54.508Z,1548175554.508 [SBIT](IMPORTANT): git: 2018-12-19-107-g24bf875
2019-01-22T16:45:54.508Z,1548175554.508 [SBIT](INFO): git hash: 24bf875b04112199aa48ee71d81ecc44dc24b0ce
2019-01-22T16:45:54.508Z,1548175554.508 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-01-22T16:45:54.509Z,1548175554.509 [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-01-22T16:45:54.511Z,1548175554.511 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-01-22T16:45:54.511Z,1548175554.511 [IBIT](INFO): Initialize IBIT Component.
2019-01-22T16:45:54.512Z,1548175554.512 [CBIT](DEBUG): Initialize CBIT Component.
2019-01-22T16:45:54.513Z,1548175554.513 [logger ThreadHandler](INFO): Handler Thread ID is 890
2019-01-22T16:45:54.525Z,1548175554.525 [CBIT](DEBUG): Initialized mux pins.
2019-01-22T16:45:54.525Z,1548175554.525 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-01-22T16:45:54.525Z,1548175554.525 [CBIT](DEBUG): Initializing the watchdog timer.
2019-01-22T16:45:54.533Z,1548175554.533 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 891
2019-01-22T16:45:54.539Z,1548175554.539 [Radio_Surface](INFO): Powering up
2019-01-22T16:45:54.545Z,1548175554.545 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 892
2019-01-22T16:45:54.546Z,1548175554.546 [Rowe_600LCM](INFO): Initializing
2019-01-22T16:45:54.553Z,1548175554.553 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-01-22T16:45:54.553Z,1548175554.553 [CBIT](DEBUG): Initializing heartbeat.
2019-01-22T16:45:54.625Z,1548175554.625 [CBIT](DEBUG): Deactivating GF circuits.
2019-01-22T16:45:54.625Z,1548175554.625 [CBIT](DEBUG): Deactivating emergency mode.
2019-01-22T16:45:54.649Z,1548175554.649 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 893
2019-01-22T16:45:54.649Z,1548175554.649 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-01-22T16:45:54.654Z,1548175554.654 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-01-22T16:45:54.730Z,1548175554.730 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-01-22T16:45:54.732Z,1548175554.732 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-01-22T16:45:54.732Z,1548175554.732 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-01-22T16:45:54.749Z,1548175554.749 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-01-22T16:45:54.751Z,1548175554.751 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T16:45:54.752Z,1548175554.752 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T16:45:54.752Z,1548175554.752 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T16:45:54.753Z,1548175554.753 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-01-22T16:45:54.753Z,1548175554.753 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-01-22T16:45:54.754Z,1548175554.754 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-01-22T16:45:54.766Z,1548175554.766 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896
2019-01-22T16:45:54.777Z,1548175554.777 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-01-22T16:45:54.777Z,1548175554.777 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-01-22T16:45:54.777Z,1548175554.777 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-01-22T16:45:54.777Z,1548175554.777 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-01-22T16:45:54.778Z,1548175554.778 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-01-22T16:45:54.778Z,1548175554.778 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-01-22T16:45:54.778Z,1548175554.778 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-01-22T16:45:54.778Z,1548175554.778 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-01-22T16:45:54.778Z,1548175554.778 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-01-22T16:45:54.778Z,1548175554.778 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-01-22T16:45:54.779Z,1548175554.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-01-22T16:45:54.779Z,1548175554.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-01-22T16:45:54.779Z,1548175554.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-01-22T16:45:54.779Z,1548175554.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-01-22T16:45:54.779Z,1548175554.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-01-22T16:45:54.780Z,1548175554.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-01-22T16:45:54.801Z,1548175554.801 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 895
2019-01-22T16:45:54.802Z,1548175554.802 [WetLabsBB2FL](INFO): Powering down
2019-01-22T16:45:54.931Z,1548175554.931 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-22T16:45:55.027Z,1548175555.027 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-01-22T16:45:55.033Z,1548175555.033 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-01-22T16:45:55.077Z,1548175555.077 [Rowe_600LCM](INFO): Powering up
2019-01-22T16:45:55.135Z,1548175555.135 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-01-22T16:45:55.136Z,1548175555.136 [Default:A.Wait](DEBUG): Construct Wait.
2019-01-22T16:45:55.138Z,1548175555.138 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-22T16:45:55.185Z,1548175555.185 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-01-22T16:45:55.188Z,1548175555.188 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-01-22T16:45:55.229Z,1548175555.229 [Default:E.Execute](DEBUG): Construct Execute.
2019-01-22T16:45:55.233Z,1548175555.233 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-01-22T16:45:55.257Z,1548175555.257 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-01-22T16:45:55.305Z,1548175555.305 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-01-22T16:45:55.305Z,1548175555.305 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-01-22T16:45:55.332Z,1548175555.332 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-01-22T16:45:55.364Z,1548175555.364 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-01-22T16:45:55.607Z,1548175555.607 [ESPComponent](INFO): powering down ESP
2019-01-22T16:45:56.040Z,1548175556.040 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-22T16:45:56.457Z,1548175556.457 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-22T16:45:56.473Z,1548175556.473 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-22T16:45:56.503Z,1548175556.503 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-01-22T16:45:56.513Z,1548175556.513 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-01-22T16:45:56.527Z,1548175556.527 [MassServo](DEBUG): Initializing EZServoServo.
2019-01-22T16:45:56.545Z,1548175556.545 [MassServo](DEBUG): Initializing MassServo.
2019-01-22T16:45:56.559Z,1548175556.559 [RudderServo](DEBUG): Initializing EZServoServo.
2019-01-22T16:45:56.565Z,1548175556.565 [RudderServo](DEBUG): Initializing RudderServo.
2019-01-22T16:45:56.583Z,1548175556.583 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-01-22T16:45:56.589Z,1548175556.589 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-01-22T16:45:56.895Z,1548175556.895 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-01-22T16:45:56.895Z,1548175556.895 [DropWeight] Hardware Fault, FailCount= 1
2019-01-22T16:45:56.895Z,1548175556.895 [DropWeight](ERROR): Hardware Fault
2019-01-22T16:45:57.309Z,1548175557.309 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-01-22T16:45:57.309Z,1548175557.309 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-01-22T16:45:57.309Z,1548175557.309 [BuoyancyServo] Communications Fault, FailCount= 1
2019-01-22T16:45:57.309Z,1548175557.309 [BuoyancyServo](ERROR): Communications Fault
2019-01-22T16:45:57.610Z,1548175557.610 [CommandLine](FAULT): Scheduling is paused
2019-01-22T16:45:57.611Z,1548175557.611 [CBIT](INFO): Critical error at 20190122T164556
2019-01-22T16:45:57.611Z,1548175557.611 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-01-22T16:45:57.614Z,1548175557.614 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-01-22T16:45:57.615Z,1548175557.615 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-01-22T16:45:57.615Z,1548175557.615 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-01-22T16:45:57.750Z,1548175557.750 [CBIT](INFO): Critical error at 20190122T164557
2019-01-22T16:45:59.311Z,1548175559.311 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-01-22T16:45:59.479Z,1548175559.479 [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-01-22T16:45:59.854Z,1548175559.854 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-01-22T16:45:59.854Z,1548175559.854 [BuoyancyServo] No Fault, FailCount= 1
2019-01-22T16:46:00.196Z,1548175560.196 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-22T16:46:00.217Z,1548175560.217 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-22T16:46:02.874Z,1548175562.874 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-01-22T16:46:04.474Z,1548175564.474 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2019-01-22T16:46:04.474Z,1548175564.474 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2019-01-22T16:46:04.475Z,1548175564.475 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2019-01-22T16:46:04.475Z,1548175564.475 [AcousticModem_Benthos_ATM900](DEBUG): Jan 16 2019 17:53:35 
2019-01-22T16:46:04.569Z,1548175564.569 [CBIT](CRITICAL): Environmental Failure. Press:14.916336 PSI. Humidity:25%. Temp:23 C. ABORTING MISSION
2019-01-22T16:46:04.873Z,1548175564.873 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2019-01-22T16:46:05.011Z,1548175565.011 [CBIT](INFO): Critical error at 20190122T164604
2019-01-22T16:46:05.673Z,1548175565.673 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2019-01-22T16:46:05.710Z,1548175565.710 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:46:05.710Z,1548175565.710 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T16:46:05.710Z,1548175565.710 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:46:05.838Z,1548175565.838 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:46:06.074Z,1548175566.074 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2019-01-22T16:46:06.075Z,1548175566.075 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2019-01-22T16:46:06.075Z,1548175566.075 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2019-01-22T16:46:06.149Z,1548175566.149 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:46:06.473Z,1548175566.473 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2019-01-22T16:46:06.873Z,1548175566.873 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2019-01-22T16:46:06.873Z,1548175566.873 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2019-01-22T16:46:07.096Z,1548175567.096 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:46:07.096Z,1548175567.096 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T16:46:07.273Z,1548175567.273 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2019-01-22T16:46:17.319Z,1548175577.319 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:46:17.319Z,1548175577.319 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T16:46:17.319Z,1548175577.319 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:46:17.430Z,1548175577.430 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:46:17.701Z,1548175577.701 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:46:18.577Z,1548175578.577 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:46:18.577Z,1548175578.577 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T16:46:18.961Z,1548175578.961 [SBIT](IMPORTANT): Beginning Startup BIT
2019-01-22T16:46:18.966Z,1548175578.966 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-22T16:46:20.878Z,1548175580.878 [NAL9602](INFO): Powering up NAL9602
2019-01-22T16:46:29.054Z,1548175589.054 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:46:29.054Z,1548175589.054 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T16:46:29.054Z,1548175589.054 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:46:29.183Z,1548175589.183 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:46:29.184Z,1548175589.184 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T16:46:29.438Z,1548175589.438 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:46:29.955Z,1548175589.955 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009097
CHAN A1 (24V): -0.012699
CHAN A2 (12V): -0.005155
CHAN A3 (5V): -0.002315
CHAN B0 (3.3V): -0.000658
CHAN B1 (3.15aV): -0.001779
CHAN B2 (3.15bV): -0.001103
CHAN B3 (GND): -0.000601
OPEN: 0.004526
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-22T16:46:31.814Z,1548175591.814 [NAL9602](INFO): NAL9602 initialized
2019-01-22T16:47:12.991Z,1548175632.991 [SBIT](IMPORTANT): SBIT PASSED
2019-01-22T16:47:13.048Z,1548175633.048 [CommandLine](IMPORTANT): got command configSet list
2019-01-22T16:47:13.049Z,1548175633.049 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-01-22T16:47:13.064Z,1548175633.064 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-01-22T16:47:13.065Z,1548175633.065 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-01-22T16:47:13.065Z,1548175633.065 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-01-22T16:47:13.066Z,1548175633.066 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2019-01-22T16:47:13.382Z,1548175633.382 [MissionManager](IMPORTANT): Started mission Startup
2019-01-22T16:47:13.382Z,1548175633.382 [Startup] Running Loop=1
2019-01-22T16:47:13.383Z,1548175633.383 [Startup](DEBUG): Aggregate::initialize Startup
2019-01-22T16:47:13.383Z,1548175633.383 [Startup:A.GoToSurface] Running Loop=1
2019-01-22T16:47:13.383Z,1548175633.383 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-22T16:47:13.383Z,1548175633.383 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-22T16:47:13.384Z,1548175633.384 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-22T16:47:13.384Z,1548175633.384 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-22T16:47:13.385Z,1548175633.385 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-22T16:47:13.385Z,1548175633.385 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-22T16:47:13.391Z,1548175633.391 [Startup:StartupSatComms] Running Loop=1
2019-01-22T16:47:13.391Z,1548175633.391 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-01-22T16:47:13.392Z,1548175633.392 [Startup:StartupSatComms:A] Running Loop=1
2019-01-22T16:47:13.778Z,1548175633.778 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-01-22T16:48:13.392Z,1548175693.392 [Startup:StartupSatComms:A](INFO): Timed out from 2019-01-22T16:47:13.4Z
2019-01-22T16:48:13.392Z,1548175693.392 [Startup:StartupSatComms:A] Stopped
2019-01-22T16:48:13.392Z,1548175693.392 [Startup:StartupSatComms:B] Running Loop=1
2019-01-22T16:48:13.839Z,1548175693.839 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-01-22T16:48:54.977Z,1548175734.977 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000173.887791
2019-01-22T16:48:54.977Z,1548175734.977 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-01-22T16:48:54.977Z,1548175734.977 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T16:48:54.990Z,1548175734.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T16:48:55.442Z,1548175735.442 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T16:48:55.442Z,1548175735.442 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-01-22T16:48:55.783Z,1548175735.783 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T16:48:55.783Z,1548175735.783 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T16:48:55.784Z,1548175735.784 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T16:48:59.458Z,1548175739.458 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:48:59.458Z,1548175739.458 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T16:49:10.190Z,1548175750.190 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:49:10.190Z,1548175750.190 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T16:49:10.191Z,1548175750.191 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:49:10.395Z,1548175750.395 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:49:10.710Z,1548175750.710 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:49:11.540Z,1548175751.540 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:49:11.544Z,1548175751.544 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T16:49:13.494Z,1548175753.494 [Startup:StartupSatComms:B](INFO): Timed out from 2019-01-22T16:48:13.4Z
2019-01-22T16:49:13.495Z,1548175753.495 [Startup:StartupSatComms:B] Stopped
2019-01-22T16:49:13.495Z,1548175753.495 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-01-22T16:49:13.495Z,1548175753.495 [Startup:StartupSatComms] Stopped
2019-01-22T16:49:13.495Z,1548175753.495 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-01-22T16:49:13.496Z,1548175753.496 [Startup](INFO): Completed Startup
2019-01-22T16:49:13.496Z,1548175753.496 [MissionManager](INFO): Startup is completed.
2019-01-22T16:49:13.496Z,1548175753.496 [MissionManager](INFO): Uninitializing Mission Startup
2019-01-22T16:49:13.496Z,1548175753.496 [Startup] Stopped
2019-01-22T16:49:13.496Z,1548175753.496 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-01-22T16:49:13.496Z,1548175753.496 [Startup:A.GoToSurface] Stopped
2019-01-22T16:49:13.496Z,1548175753.496 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-22T16:49:13.812Z,1548175753.812 [MissionManager](IMPORTANT): Started mission Default
2019-01-22T16:49:13.814Z,1548175753.814 [Default] Running Loop=1
2019-01-22T16:49:13.814Z,1548175753.814 [Default](DEBUG): Aggregate::initialize Default
2019-01-22T16:49:13.815Z,1548175753.815 [Default:B.GoToSurface] Running Loop=1
2019-01-22T16:49:13.815Z,1548175753.815 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-22T16:49:13.816Z,1548175753.816 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-22T16:49:13.816Z,1548175753.816 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-22T16:49:13.818Z,1548175753.818 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-22T16:49:13.821Z,1548175753.821 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-22T16:49:13.821Z,1548175753.821 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-22T16:49:13.822Z,1548175753.822 [Default:A.Wait] Running Loop=1
2019-01-22T16:49:13.822Z,1548175753.822 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-01-22T16:49:27.536Z,1548175767.536 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:49:27.536Z,1548175767.536 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T16:49:27.536Z,1548175767.536 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:49:27.608Z,1548175767.608 [Default:A.Wait](INFO): Done Waiting.
2019-01-22T16:49:27.609Z,1548175767.609 [Default:A.Wait] Stopped
2019-01-22T16:49:27.609Z,1548175767.609 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-01-22T16:49:27.655Z,1548175767.655 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:49:27.856Z,1548175767.856 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:49:27.875Z,1548175767.875 [Default:CheckIn] Running Loop=1
2019-01-22T16:49:27.875Z,1548175767.875 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-22T16:49:27.876Z,1548175767.876 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-22T16:49:28.217Z,1548175768.217 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-01-22T16:49:28.475Z,1548175768.475 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004510
2019-01-22T16:49:28.596Z,1548175768.596 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:49:28.596Z,1548175768.596 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T16:49:38.941Z,1548175778.941 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:49:38.941Z,1548175778.941 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T16:49:38.941Z,1548175778.941 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:49:38.986Z,1548175778.986 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:49:38.986Z,1548175778.986 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T16:49:39.343Z,1548175779.343 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:51:35.549Z,1548175895.549 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-01-22T16:51:55.974Z,1548175915.974 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000354.885338
2019-01-22T16:51:55.974Z,1548175915.974 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-01-22T16:51:55.975Z,1548175915.975 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T16:51:55.996Z,1548175915.996 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T16:51:56.394Z,1548175916.394 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T16:51:56.395Z,1548175916.395 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-01-22T16:51:56.790Z,1548175916.790 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T16:51:56.791Z,1548175916.791 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T16:51:56.791Z,1548175916.791 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T16:52:09.201Z,1548175929.201 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:52:09.201Z,1548175929.201 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T16:52:21.290Z,1548175941.290 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:52:21.290Z,1548175941.290 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T16:52:21.290Z,1548175941.290 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:52:21.330Z,1548175941.330 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:52:21.438Z,1548175941.438 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:52:22.219Z,1548175942.219 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:52:22.219Z,1548175942.219 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T16:52:32.654Z,1548175952.654 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:52:32.654Z,1548175952.654 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T16:52:32.655Z,1548175952.655 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:52:32.748Z,1548175952.748 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:52:32.994Z,1548175952.994 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:52:33.802Z,1548175953.802 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:52:33.802Z,1548175953.802 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T16:52:44.241Z,1548175964.241 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:52:44.241Z,1548175964.241 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T16:52:44.241Z,1548175964.241 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:52:44.280Z,1548175964.280 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:52:44.285Z,1548175964.285 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T16:52:44.644Z,1548175964.644 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:54:28.164Z,1548176068.164 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-01-22T16:49:27.9Z
2019-01-22T16:54:28.165Z,1548176068.165 [Default:CheckIn:Read_GPS] Stopped
2019-01-22T16:54:28.165Z,1548176068.165 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-01-22T16:54:28.550Z,1548176068.550 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-01-22T16:54:34.558Z,1548176074.558 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190118T013445/Courier0082.lzma
2019-01-22T16:54:35.359Z,1548176075.359 [DataOverHttps](INFO): Moved sent file to Logs/20190118T013445/Courier0082.lzma.bak
2019-01-22T16:54:35.359Z,1548176075.359 [DataOverHttps](INFO): SBD MOMSN=9593506
2019-01-22T16:54:45.421Z,1548176085.421 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2019-01-22T16:54:45.421Z,1548176085.421 [PNI_TCM] Data Fault, FailCount= 1
2019-01-22T16:54:45.421Z,1548176085.421 [PNI_TCM](ERROR): Data Fault
2019-01-22T16:54:45.517Z,1548176085.517 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-01-22T16:54:45.577Z,1548176085.577 [PNI_TCM](INFO): Powering down
2019-01-22T16:54:46.977Z,1548176086.977 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-01-22T16:54:46.977Z,1548176086.977 [PNI_TCM] No Fault, FailCount= 1
2019-01-22T16:54:55.110Z,1548176095.110 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20190122T164543/Courier0000.lzma
2019-01-22T16:54:55.893Z,1548176095.893 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Courier0000.lzma.bak
2019-01-22T16:54:55.894Z,1548176095.894 [DataOverHttps](INFO): SBD MOMSN=9593508
2019-01-22T16:54:58.303Z,1548176098.303 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000537.213706
2019-01-22T16:54:58.303Z,1548176098.303 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-01-22T16:54:58.303Z,1548176098.303 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T16:54:58.316Z,1548176098.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T16:54:58.498Z,1548176098.498 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T16:54:58.498Z,1548176098.498 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-01-22T16:55:02.658Z,1548176102.658 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T16:55:02.659Z,1548176102.659 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T16:55:02.659Z,1548176102.659 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T16:55:12.004Z,1548176112.004 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190122T164543/Courier0004.lzma
2019-01-22T16:55:12.799Z,1548176112.799 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Courier0004.lzma.bak
2019-01-22T16:55:12.799Z,1548176112.799 [DataOverHttps](INFO): SBD MOMSN=9593514
2019-01-22T16:55:15.140Z,1548176115.140 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:55:15.141Z,1548176115.141 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T16:55:24.582Z,1548176124.582 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190122T164543/Courier0007.lzma
2019-01-22T16:55:25.382Z,1548176125.382 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Courier0007.lzma.bak
2019-01-22T16:55:25.383Z,1548176125.383 [DataOverHttps](INFO): SBD MOMSN=9593516
2019-01-22T16:55:25.445Z,1548176125.445 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:55:25.446Z,1548176125.446 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T16:55:25.446Z,1548176125.446 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:55:25.464Z,1548176125.464 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:55:27.601Z,1548176127.601 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:55:31.366Z,1548176131.366 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:55:31.366Z,1548176131.366 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T16:55:36.678Z,1548176136.678 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20190122T164935/Courier0000.lzma
2019-01-22T16:55:37.458Z,1548176137.458 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164935/Courier0000.lzma.bak
2019-01-22T16:55:37.458Z,1548176137.458 [DataOverHttps](INFO): SBD MOMSN=9593518
2019-01-22T16:55:41.617Z,1548176141.617 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:55:41.617Z,1548176141.617 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T16:55:41.617Z,1548176141.617 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:55:41.667Z,1548176141.667 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:55:45.382Z,1548176145.382 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:55:45.997Z,1548176145.997 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:55:45.998Z,1548176145.998 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T16:55:52.737Z,1548176152.737 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190118T013445/Express0083.lzma
2019-01-22T16:55:53.503Z,1548176153.503 [DataOverHttps](INFO): Moved sent file to Logs/20190118T013445/Express0083.lzma.bak
2019-01-22T16:55:53.504Z,1548176153.504 [DataOverHttps](INFO): SBD MOMSN=9593524
2019-01-22T16:55:57.548Z,1548176157.548 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:55:57.548Z,1548176157.548 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T16:55:57.548Z,1548176157.548 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:55:57.568Z,1548176157.568 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:55:57.568Z,1548176157.568 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T16:55:57.682Z,1548176157.682 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:56:07.584Z,1548176167.584 [DataOverHttps](INFO): Sending 828 bytes from file Logs/20190122T164543/Express0001.lzma
2019-01-22T16:56:08.378Z,1548176168.378 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Express0001.lzma.bak
2019-01-22T16:56:08.378Z,1548176168.378 [DataOverHttps](INFO): SBD MOMSN=9593526
2019-01-22T16:56:21.797Z,1548176181.797 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20190122T164543/Express0005.lzma
2019-01-22T16:56:22.554Z,1548176182.554 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Express0005.lzma.bak
2019-01-22T16:56:22.554Z,1548176182.554 [DataOverHttps](INFO): SBD MOMSN=9593549
2019-01-22T16:56:32.758Z,1548176192.758 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-01-22T16:56:32.758Z,1548176192.758 [NAL9602] Data Fault, FailCount= 1
2019-01-22T16:56:32.758Z,1548176192.758 [NAL9602](ERROR): Data Fault
2019-01-22T16:56:32.863Z,1548176192.863 [CBIT](ERROR): Data Fault in component: NAL9602
2019-01-22T16:56:33.110Z,1548176193.110 [NAL9602](INFO): Powering down
2019-01-22T16:56:34.079Z,1548176194.079 [CBIT](INFO): Clearing failed state for component NAL9602
2019-01-22T16:56:34.079Z,1548176194.079 [NAL9602] No Fault, FailCount= 1
2019-01-22T16:56:38.200Z,1548176198.200 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190122T164543/Express0008.lzma
2019-01-22T16:56:38.923Z,1548176198.923 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Express0008.lzma.bak
2019-01-22T16:56:38.923Z,1548176198.923 [DataOverHttps](INFO): SBD MOMSN=9593561
2019-01-22T16:56:52.733Z,1548176212.733 [DataOverHttps](INFO): Sending 1126 bytes from file Logs/20190122T164935/Express0001.lzma
2019-01-22T16:56:53.522Z,1548176213.522 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164935/Express0001.lzma.bak
2019-01-22T16:56:53.523Z,1548176213.523 [DataOverHttps](INFO): SBD MOMSN=9593563
2019-01-22T16:56:57.491Z,1548176217.491 [Default:CheckIn:Read_Iridium] Stopped
2019-01-22T16:56:57.491Z,1548176217.491 [Default:CheckIn:C.Wait] Running Loop=1
2019-01-22T16:56:57.491Z,1548176217.491 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-01-22T16:57:03.162Z,1548176223.162 [NAL9602](INFO): Powering up NAL9602
2019-01-22T16:57:14.362Z,1548176234.362 [NAL9602](INFO): NAL9602 initialized
2019-01-22T16:58:02.933Z,1548176282.933 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000721.843863
2019-01-22T16:58:02.934Z,1548176282.934 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-01-22T16:58:02.934Z,1548176282.934 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T16:58:02.946Z,1548176282.946 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T16:58:03.346Z,1548176283.346 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T16:58:03.346Z,1548176283.346 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-01-22T16:58:03.746Z,1548176283.746 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T16:58:03.747Z,1548176283.747 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T16:58:03.747Z,1548176283.747 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T16:58:27.775Z,1548176307.775 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:58:27.775Z,1548176307.775 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T16:58:38.530Z,1548176318.530 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:58:38.531Z,1548176318.531 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T16:58:38.531Z,1548176318.531 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:58:38.550Z,1548176318.550 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:58:38.928Z,1548176318.928 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:58:39.806Z,1548176319.806 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:58:39.806Z,1548176319.806 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T16:58:50.531Z,1548176330.531 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:58:50.531Z,1548176330.531 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T16:58:50.531Z,1548176330.531 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:58:50.576Z,1548176330.576 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:58:50.975Z,1548176330.975 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:58:51.799Z,1548176331.799 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T16:58:51.799Z,1548176331.799 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T16:59:07.355Z,1548176347.355 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T16:59:07.355Z,1548176347.355 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T16:59:07.356Z,1548176347.356 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T16:59:07.412Z,1548176347.412 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T16:59:07.412Z,1548176347.412 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T16:59:07.521Z,1548176347.521 [Aanderaa_O2](INFO): Powering down
2019-01-22T16:59:59.077Z,1548176399.077 [BPC1](FAULT): Battery stick #1 (s/n: 00A2) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-01-22T16:59:59.078Z,1548176399.078 [BPC1](FAULT): Battery stick #5 (s/n: 00BB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-01-22T16:59:59.079Z,1548176399.079 [BPC1](FAULT): Battery stick #6 (s/n: 00B8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-01-22T16:59:59.079Z,1548176399.079 [BPC1](FAULT): Battery stick #9 (s/n: 007D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.080Z,1548176399.080 [BPC1](FAULT): Battery stick #12 (s/n: 00B5) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-01-22T16:59:59.085Z,1548176399.085 [BPC1](FAULT): Battery stick #17 (s/n: 009F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.086Z,1548176399.086 [BPC1](FAULT): Battery stick #18 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-01-22T16:59:59.087Z,1548176399.087 [BPC1](FAULT): Battery stick #19 (s/n: 0095) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.088Z,1548176399.088 [BPC1](FAULT): Battery stick #21 (s/n: 0085) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.088Z,1548176399.088 [BPC1](FAULT): Battery stick #28 (s/n: 008C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-01-22T16:59:59.093Z,1548176399.093 [BPC1](FAULT): Battery stick #29 (s/n: 007C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.094Z,1548176399.094 [BPC1](FAULT): Battery stick #31 (s/n: 00B6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-01-22T16:59:59.095Z,1548176399.095 [BPC1](FAULT): Battery stick #32 (s/n: 009D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.096Z,1548176399.096 [BPC1](FAULT): Battery stick #33 (s/n: 0093) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4A50.
2019-01-22T16:59:59.101Z,1548176399.101 [BPC1](FAULT): Battery stick #34 (s/n: 0068) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-01-22T16:59:59.102Z,1548176399.102 [BPC1](FAULT): Battery stick #36 (s/n: 008A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.103Z,1548176399.103 [BPC1](FAULT): Battery stick #40 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.103Z,1548176399.103 [BPC1](FAULT): Battery stick #43 (s/n: 00A6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.104Z,1548176399.104 [BPC1](FAULT): Battery stick #50 (s/n: 0091) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.113Z,1548176399.113 [BPC1](FAULT): Battery stick #53 (s/n: 0088) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-01-22T16:59:59.114Z,1548176399.114 [BPC1](FAULT): Battery stick #54 (s/n: 0098) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.115Z,1548176399.115 [BPC1](FAULT): Battery stick #56 (s/n: 00AD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2019-01-22T16:59:59.116Z,1548176399.116 [BPC1](FAULT): Battery stick #59 (s/n: 00A0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.120Z,1548176399.120 [BPC1](FAULT): Battery stick #61 (s/n: 007F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-01-22T16:59:59.120Z,1548176399.120 [BPC1](INFO): Calculating totals. Valid battery stick count: 29. Valid reserve battery stick count: 4.
2019-01-22T17:00:57.163Z,1548176457.163 [CBIT](INFO): Clearing failed state for component DropWeight
2019-01-22T17:00:57.163Z,1548176457.163 [DropWeight] No Fault, FailCount= 1
2019-01-22T17:01:04.031Z,1548176464.031 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000902.941776
2019-01-22T17:01:04.031Z,1548176464.031 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-01-22T17:01:04.031Z,1548176464.031 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:01:04.044Z,1548176464.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:01:04.513Z,1548176464.513 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:01:04.513Z,1548176464.513 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-01-22T17:01:04.836Z,1548176464.836 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:01:04.837Z,1548176464.837 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:01:04.837Z,1548176464.837 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:01:37.639Z,1548176497.639 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:01:37.640Z,1548176497.640 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T17:01:48.031Z,1548176508.031 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:01:48.031Z,1548176508.031 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T17:01:48.031Z,1548176508.031 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:01:48.083Z,1548176508.083 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:01:48.479Z,1548176508.479 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:01:49.249Z,1548176509.249 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:01:49.249Z,1548176509.249 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T17:01:57.636Z,1548176517.636 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-01-22T17:01:57.636Z,1548176517.636 [Default:CheckIn:C.Wait] Stopped
2019-01-22T17:01:57.636Z,1548176517.636 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-01-22T17:01:57.636Z,1548176517.636 [Default:CheckIn:D] Running Loop=1
2019-01-22T17:01:58.032Z,1548176518.032 [Default:CheckIn:D] Stopped
2019-01-22T17:01:58.032Z,1548176518.032 [Default:CheckIn:E] Running Loop=1
2019-01-22T17:01:58.576Z,1548176518.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.736956 min 
2019-01-22T17:01:58.587Z,1548176518.587 [Default:CheckIn:E] Stopped
2019-01-22T17:01:58.587Z,1548176518.587 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-01-22T17:01:58.587Z,1548176518.587 [Default:CheckIn] Stopped
2019-01-22T17:01:58.587Z,1548176518.587 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-22T17:01:58.587Z,1548176518.587 [Default:CheckIn](INFO): Running loop #2
2019-01-22T17:01:58.587Z,1548176518.587 [Default:CheckIn] Running Loop=2
2019-01-22T17:01:58.587Z,1548176518.587 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-22T17:01:58.588Z,1548176518.588 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-22T17:02:02.821Z,1548176522.821 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:02:02.821Z,1548176522.821 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T17:02:02.821Z,1548176522.821 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:02:02.839Z,1548176522.839 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:02:02.970Z,1548176522.970 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:02:03.736Z,1548176523.736 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:02:03.736Z,1548176523.736 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T17:02:14.106Z,1548176534.106 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:02:14.106Z,1548176534.106 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T17:02:14.106Z,1548176534.106 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:02:14.125Z,1548176534.125 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:02:14.126Z,1548176534.126 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T17:02:14.530Z,1548176534.530 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:04:05.052Z,1548176645.052 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001083.962869
2019-01-22T17:04:05.052Z,1548176645.052 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-01-22T17:04:05.052Z,1548176645.052 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:04:05.081Z,1548176645.081 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:04:05.497Z,1548176645.497 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:04:05.497Z,1548176645.497 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-01-22T17:04:05.858Z,1548176645.858 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:04:05.859Z,1548176645.859 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:04:05.859Z,1548176645.859 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:04:44.320Z,1548176684.320 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:04:44.320Z,1548176684.320 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T17:04:55.055Z,1548176695.055 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:04:55.055Z,1548176695.055 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T17:04:55.055Z,1548176695.055 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:04:55.074Z,1548176695.074 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:04:55.459Z,1548176695.459 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:04:56.276Z,1548176696.276 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:04:56.276Z,1548176696.276 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T17:05:06.851Z,1548176706.851 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:05:06.851Z,1548176706.851 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T17:05:06.851Z,1548176706.851 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:05:06.924Z,1548176706.924 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:05:07.209Z,1548176707.209 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:05:08.023Z,1548176708.023 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:05:08.023Z,1548176708.023 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T17:05:18.422Z,1548176718.422 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:05:18.422Z,1548176718.422 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T17:05:18.422Z,1548176718.422 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:05:18.441Z,1548176718.441 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:05:18.441Z,1548176718.441 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T17:05:18.835Z,1548176718.835 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:06:59.032Z,1548176819.032 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-01-22T17:01:58.6Z
2019-01-22T17:06:59.032Z,1548176819.032 [Default:CheckIn:Read_GPS] Stopped
2019-01-22T17:06:59.032Z,1548176819.032 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-01-22T17:07:04.525Z,1548176824.525 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190122T164543/Courier0010.lzma
2019-01-22T17:07:05.004Z,1548176825.004 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Courier0010.lzma.bak
2019-01-22T17:07:05.004Z,1548176825.004 [DataOverHttps](INFO): SBD MOMSN=9593619
2019-01-22T17:07:08.930Z,1548176828.930 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001267.805416
2019-01-22T17:07:08.930Z,1548176828.930 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-01-22T17:07:08.930Z,1548176828.930 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:07:08.998Z,1548176828.998 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:07:09.153Z,1548176829.153 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:07:09.153Z,1548176829.153 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-01-22T17:07:12.699Z,1548176832.699 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:07:12.700Z,1548176832.700 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:07:12.700Z,1548176832.700 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:07:19.855Z,1548176839.855 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-01-22T17:07:19.855Z,1548176839.855 [NAL9602] Data Fault, FailCount= 2
2019-01-22T17:07:19.855Z,1548176839.855 [NAL9602](ERROR): Data Fault
2019-01-22T17:07:20.046Z,1548176840.046 [CBIT](ERROR): Data Fault in component: NAL9602
2019-01-22T17:07:20.085Z,1548176840.085 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190122T164543/Express0011.lzma
2019-01-22T17:07:20.097Z,1548176840.097 [NAL9602](INFO): Powering down
2019-01-22T17:07:20.858Z,1548176840.858 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Express0011.lzma.bak
2019-01-22T17:07:20.858Z,1548176840.858 [DataOverHttps](INFO): SBD MOMSN=9593621
2019-01-22T17:07:21.031Z,1548176841.031 [CBIT](INFO): Clearing failed state for component NAL9602
2019-01-22T17:07:21.032Z,1548176841.032 [NAL9602] No Fault, FailCount= 2
2019-01-22T17:07:24.646Z,1548176844.646 [Default:CheckIn:Read_Iridium] Stopped
2019-01-22T17:07:24.646Z,1548176844.646 [Default:CheckIn:C.Wait] Running Loop=1
2019-01-22T17:07:24.646Z,1548176844.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-01-22T17:07:48.513Z,1548176868.513 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:07:48.514Z,1548176868.514 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T17:07:50.438Z,1548176870.438 [NAL9602](INFO): Powering up NAL9602
2019-01-22T17:07:59.274Z,1548176879.274 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:07:59.274Z,1548176879.274 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T17:07:59.274Z,1548176879.274 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:07:59.293Z,1548176879.293 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:07:59.688Z,1548176879.688 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:08:00.498Z,1548176880.498 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:08:00.499Z,1548176880.499 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T17:08:01.638Z,1548176881.638 [NAL9602](INFO): NAL9602 initialized
2019-01-22T17:08:10.880Z,1548176890.880 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:08:10.880Z,1548176890.880 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T17:08:10.880Z,1548176890.880 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:08:10.904Z,1548176890.904 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:08:11.283Z,1548176891.283 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:08:12.194Z,1548176892.194 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:08:12.194Z,1548176892.194 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T17:08:22.881Z,1548176902.881 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:08:22.881Z,1548176902.881 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T17:08:22.881Z,1548176902.881 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:08:22.914Z,1548176902.914 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:08:22.914Z,1548176902.914 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T17:08:23.281Z,1548176903.281 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:10:13.088Z,1548177013.088 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001451.999021
2019-01-22T17:10:13.088Z,1548177013.088 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-01-22T17:10:13.088Z,1548177013.088 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:10:13.102Z,1548177013.102 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:10:13.513Z,1548177013.513 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:10:13.513Z,1548177013.513 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-01-22T17:10:13.880Z,1548177013.880 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:10:13.880Z,1548177013.880 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:10:13.881Z,1548177013.881 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:10:53.194Z,1548177053.194 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:10:53.194Z,1548177053.194 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T17:11:03.938Z,1548177063.938 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:11:03.938Z,1548177063.938 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T17:11:03.938Z,1548177063.938 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:11:03.974Z,1548177063.974 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:11:04.401Z,1548177064.401 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:11:05.197Z,1548177065.197 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:11:05.198Z,1548177065.198 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T17:11:15.550Z,1548177075.550 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:11:15.551Z,1548177075.551 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T17:11:15.551Z,1548177075.551 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:11:15.598Z,1548177075.598 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:11:15.947Z,1548177075.947 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:11:16.800Z,1548177076.800 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:11:16.800Z,1548177076.800 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T17:11:29.148Z,1548177089.148 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:11:29.148Z,1548177089.148 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T17:11:29.148Z,1548177089.148 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:11:29.198Z,1548177089.198 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:11:29.199Z,1548177089.199 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T17:11:29.272Z,1548177089.272 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:12:24.871Z,1548177144.871 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-01-22T17:12:24.872Z,1548177144.872 [Default:CheckIn:C.Wait] Stopped
2019-01-22T17:12:24.872Z,1548177144.872 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-01-22T17:12:24.872Z,1548177144.872 [Default:CheckIn:D] Running Loop=1
2019-01-22T17:12:25.271Z,1548177145.271 [Default:CheckIn:D] Stopped
2019-01-22T17:12:25.271Z,1548177145.271 [Default:CheckIn:E] Running Loop=1
2019-01-22T17:12:25.670Z,1548177145.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.190965 min 
2019-01-22T17:12:25.670Z,1548177145.670 [Default:CheckIn:E] Stopped
2019-01-22T17:12:25.670Z,1548177145.670 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-01-22T17:12:25.670Z,1548177145.670 [Default:CheckIn] Stopped
2019-01-22T17:12:25.670Z,1548177145.670 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-22T17:12:25.671Z,1548177145.671 [Default:CheckIn](INFO): Running loop #3
2019-01-22T17:12:25.671Z,1548177145.671 [Default:CheckIn] Running Loop=3
2019-01-22T17:12:25.671Z,1548177145.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-22T17:12:25.671Z,1548177145.671 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-22T17:13:14.149Z,1548177194.149 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001633.059879
2019-01-22T17:13:14.149Z,1548177194.149 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-01-22T17:13:14.149Z,1548177194.149 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:13:14.169Z,1548177194.169 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:13:14.635Z,1548177194.635 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:13:14.635Z,1548177194.635 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-01-22T17:13:14.945Z,1548177194.945 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:13:14.946Z,1548177194.946 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:13:14.946Z,1548177194.946 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:13:59.448Z,1548177239.448 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:13:59.448Z,1548177239.448 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T17:14:10.209Z,1548177250.209 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:14:10.209Z,1548177250.209 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T17:14:10.210Z,1548177250.210 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:14:10.228Z,1548177250.228 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:14:10.656Z,1548177250.656 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:14:11.441Z,1548177251.441 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:14:11.441Z,1548177251.441 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T17:14:21.816Z,1548177261.816 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:14:21.817Z,1548177261.817 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T17:14:21.817Z,1548177261.817 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:14:21.836Z,1548177261.836 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:14:22.216Z,1548177262.216 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:14:23.053Z,1548177263.053 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:14:23.053Z,1548177263.053 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T17:14:33.512Z,1548177273.512 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:14:33.512Z,1548177273.512 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T17:14:33.512Z,1548177273.512 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:14:33.539Z,1548177273.539 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:14:33.540Z,1548177273.540 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T17:14:33.921Z,1548177273.921 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:16:15.183Z,1548177375.183 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001814.094003
2019-01-22T17:16:15.183Z,1548177375.183 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2019-01-22T17:16:15.183Z,1548177375.183 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:16:15.195Z,1548177375.195 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:16:15.600Z,1548177375.600 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:16:15.600Z,1548177375.600 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2019-01-22T17:16:15.994Z,1548177375.994 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:16:15.995Z,1548177375.995 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:16:15.995Z,1548177375.995 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:17:03.689Z,1548177423.689 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:17:03.689Z,1548177423.689 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T17:17:14.045Z,1548177434.045 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:17:14.045Z,1548177434.045 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T17:17:14.045Z,1548177434.045 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:17:14.090Z,1548177434.090 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:17:14.451Z,1548177434.451 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:17:15.261Z,1548177435.261 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:17:15.261Z,1548177435.261 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T17:17:26.060Z,1548177446.060 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:17:26.060Z,1548177446.060 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T17:17:26.060Z,1548177446.060 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:17:26.077Z,1548177446.077 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-01-22T17:12:25.7Z
2019-01-22T17:17:26.077Z,1548177446.077 [Default:CheckIn:Read_GPS] Stopped
2019-01-22T17:17:26.077Z,1548177446.077 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-01-22T17:17:26.088Z,1548177446.088 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:17:29.657Z,1548177449.657 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:17:31.657Z,1548177451.657 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190122T164543/Courier0013.lzma
2019-01-22T17:17:31.891Z,1548177451.891 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:17:31.891Z,1548177451.891 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T17:17:32.147Z,1548177452.147 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Courier0013.lzma.bak
2019-01-22T17:17:32.147Z,1548177452.147 [DataOverHttps](INFO): SBD MOMSN=9593634
2019-01-22T17:17:42.317Z,1548177462.317 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:17:42.317Z,1548177462.317 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T17:17:42.318Z,1548177462.318 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:17:42.336Z,1548177462.336 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:17:42.337Z,1548177462.337 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T17:17:42.725Z,1548177462.725 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:17:46.391Z,1548177466.391 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190122T164543/Express0014.lzma
2019-01-22T17:17:47.191Z,1548177467.191 [DataOverHttps](INFO): Moved sent file to Logs/20190122T164543/Express0014.lzma.bak
2019-01-22T17:17:47.191Z,1548177467.191 [DataOverHttps](INFO): SBD MOMSN=9593636
2019-01-22T17:17:51.464Z,1548177471.464 [Default:CheckIn:Read_Iridium] Stopped
2019-01-22T17:17:51.464Z,1548177471.464 [Default:CheckIn:C.Wait] Running Loop=1
2019-01-22T17:17:51.464Z,1548177471.464 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-01-22T17:18:02.944Z,1548177482.944 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-01-22T17:18:02.944Z,1548177482.944 [NAL9602] Data Fault, FailCount= 3
2019-01-22T17:18:02.944Z,1548177482.944 [NAL9602](ERROR): Data Fault
2019-01-22T17:18:03.102Z,1548177483.102 [CBIT](ERROR): Data Fault in component: NAL9602
2019-01-22T17:18:03.334Z,1548177483.334 [NAL9602](INFO): Powering down
2019-01-22T17:18:04.194Z,1548177484.194 [CBIT](INFO): Clearing failed state for component NAL9602
2019-01-22T17:18:04.194Z,1548177484.194 [NAL9602] No Fault, FailCount= 3
2019-01-22T17:18:33.726Z,1548177513.726 [NAL9602](INFO): Powering up NAL9602
2019-01-22T17:18:44.526Z,1548177524.526 [NAL9602](INFO): NAL9602 initialized
2019-01-22T17:19:16.254Z,1548177556.254 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001995.165226
2019-01-22T17:19:16.254Z,1548177556.254 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2019-01-22T17:19:16.254Z,1548177556.254 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:19:16.275Z,1548177556.275 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:19:16.701Z,1548177556.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:19:16.701Z,1548177556.701 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2019-01-22T17:19:17.129Z,1548177557.129 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:19:17.130Z,1548177557.130 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:19:17.130Z,1548177557.130 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:20:12.323Z,1548177612.323 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:20:12.323Z,1548177612.323 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T17:20:22.648Z,1548177622.648 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:20:22.648Z,1548177622.648 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T17:20:22.648Z,1548177622.648 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:20:22.683Z,1548177622.683 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:20:23.053Z,1548177623.053 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:20:23.873Z,1548177623.873 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:20:23.874Z,1548177623.874 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T17:20:34.298Z,1548177634.298 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:20:34.299Z,1548177634.299 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T17:20:34.299Z,1548177634.299 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:20:34.357Z,1548177634.357 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:20:34.652Z,1548177634.652 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:20:35.466Z,1548177635.466 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:20:35.467Z,1548177635.467 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T17:20:46.311Z,1548177646.311 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:20:46.311Z,1548177646.311 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T17:20:46.311Z,1548177646.311 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:20:46.360Z,1548177646.360 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:20:46.360Z,1548177646.360 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T17:20:46.651Z,1548177646.651 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:22:17.526Z,1548177737.526 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1002176.437459
2019-01-22T17:22:17.527Z,1548177737.527 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2019-01-22T17:22:17.527Z,1548177737.527 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:22:17.539Z,1548177737.539 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:22:17.937Z,1548177737.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:22:17.937Z,1548177737.937 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2019-01-22T17:22:18.388Z,1548177738.388 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:22:18.393Z,1548177738.393 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:22:18.394Z,1548177738.394 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:22:51.908Z,1548177771.908 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-01-22T17:22:51.908Z,1548177771.908 [Default:CheckIn:C.Wait] Stopped
2019-01-22T17:22:51.908Z,1548177771.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-01-22T17:22:51.929Z,1548177771.929 [Default:CheckIn:D] Running Loop=1
2019-01-22T17:22:52.386Z,1548177772.386 [Default:CheckIn:D] Stopped
2019-01-22T17:22:52.386Z,1548177772.386 [Default:CheckIn:E] Running Loop=1
2019-01-22T17:22:52.725Z,1548177772.725 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.642531 min 
2019-01-22T17:22:52.726Z,1548177772.726 [Default:CheckIn:E] Stopped
2019-01-22T17:22:52.726Z,1548177772.726 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-01-22T17:22:52.726Z,1548177772.726 [Default:CheckIn] Stopped
2019-01-22T17:22:52.726Z,1548177772.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-22T17:22:52.726Z,1548177772.726 [Default:CheckIn](INFO): Running loop #4
2019-01-22T17:22:52.726Z,1548177772.726 [Default:CheckIn] Running Loop=4
2019-01-22T17:22:52.726Z,1548177772.726 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-22T17:22:52.727Z,1548177772.727 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-22T17:23:16.630Z,1548177796.630 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:23:16.630Z,1548177796.630 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-22T17:23:27.005Z,1548177807.005 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:23:27.006Z,1548177807.006 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-22T17:23:27.006Z,1548177807.006 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:23:27.050Z,1548177807.050 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:23:27.508Z,1548177807.508 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:23:28.217Z,1548177808.217 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:23:28.218Z,1548177808.218 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-22T17:23:30.799Z,1548177810.799 [CommandLine](IMPORTANT): got command show stack
2019-01-22T17:23:30.799Z,1548177810.799 [CommandLine](IMPORTANT): Behavior Stack:

2019-01-22T17:23:30.800Z,1548177810.800 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-01-22T17:23:30.800Z,1548177810.800 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2019-01-22T17:23:38.607Z,1548177818.607 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:23:38.607Z,1548177818.607 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-22T17:23:38.607Z,1548177818.607 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:23:38.652Z,1548177818.652 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:23:39.002Z,1548177819.002 [CommandLine](IMPORTANT): got command failComponent
2019-01-22T17:23:39.002Z,1548177819.002 [CommandLine](IMPORTANT): Failed components:
2019-01-22T17:23:39.002Z,1548177819.002 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2019-01-22T17:23:39.012Z,1548177819.012 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:23:39.811Z,1548177819.811 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-22T17:23:39.811Z,1548177819.811 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-22T17:23:50.610Z,1548177830.610 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-22T17:23:50.610Z,1548177830.610 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-22T17:23:50.610Z,1548177830.610 [Aanderaa_O2](ERROR): Communications Fault
2019-01-22T17:23:50.684Z,1548177830.684 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-22T17:23:50.684Z,1548177830.684 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-22T17:23:51.010Z,1548177831.010 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:24:00.412Z,1548177840.412 [CommandLine](IMPORTANT): got command show variable vehicleOpen
2019-01-22T17:24:00.750Z,1548177840.750 [CommandLine](IMPORTANT): CBIT.vehicleOpen (bool)
2019-01-22T17:24:11.911Z,1548177851.911 [CommandLine](IMPORTANT): got command get CBIT.vehicleOpen
2019-01-22T17:24:11.912Z,1548177851.912 [CommandLine](IMPORTANT): CBIT.vehicleOpen 0 bool
2019-01-22T17:24:17.787Z,1548177857.787 [CommandLine](IMPORTANT): got command configSet CBIT.vehicleOpen 1.000000 bool persist
2019-01-22T17:24:23.849Z,1548177863.849 [CommandLine](IMPORTANT): got command configSet list
2019-01-22T17:24:23.849Z,1548177863.849 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-01-22T17:24:23.850Z,1548177863.850 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-01-22T17:25:18.755Z,1548177918.755 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1002357.665934
2019-01-22T17:25:18.755Z,1548177918.755 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2019-01-22T17:25:18.755Z,1548177918.755 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T17:25:18.792Z,1548177918.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T17:25:19.141Z,1548177919.141 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T17:25:19.141Z,1548177919.141 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2019-01-22T17:25:19.501Z,1548177919.501 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T17:25:19.502Z,1548177919.502 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T17:25:19.502Z,1548177919.502 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T17:25:20.434Z,1548177920.434 [CommandLine](IMPORTANT): got command restart application
2019-01-22T17:25:21.452Z,1548177921.452 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-22T17:25:21.452Z,1548177921.452 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:21.593Z,1548177921.593 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-01-22T17:25:21.593Z,1548177921.593 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:21.594Z,1548177921.594 [CommandLine](INFO): Join timeout helper Thread ID is 978
2019-01-22T17:25:21.598Z,1548177921.598 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-01-22T17:25:21.598Z,1548177921.598 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:21.599Z,1548177921.599 [NavChartDb](INFO): Join timeout helper Thread ID is 979
2019-01-22T17:25:21.670Z,1548177921.670 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-22T17:25:21.670Z,1548177921.670 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:21.686Z,1548177921.686 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-01-22T17:25:21.687Z,1548177921.687 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:21.688Z,1548177921.688 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 980
2019-01-22T17:25:22.074Z,1548177922.074 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-22T17:25:22.074Z,1548177922.074 [WetLabsBB2FL](INFO): Powering down
2019-01-22T17:25:22.076Z,1548177922.076 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:22.081Z,1548177922.081 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-01-22T17:25:22.081Z,1548177922.081 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:22.081Z,1548177922.081 [CTD_Seabird](INFO): Join timeout helper Thread ID is 981
2019-01-22T17:25:23.013Z,1548177923.013 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-22T17:25:23.013Z,1548177923.013 [CTD_Seabird](INFO): Powering down
2019-01-22T17:25:23.029Z,1548177923.029 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.033Z,1548177923.033 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-01-22T17:25:23.033Z,1548177923.033 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.033Z,1548177923.033 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 982
2019-01-22T17:25:23.426Z,1548177923.426 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-22T17:25:23.427Z,1548177923.427 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-01-22T17:25:23.724Z,1548177923.724 [Rowe_600LCM](INFO): Powering down
2019-01-22T17:25:23.726Z,1548177923.726 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.729Z,1548177923.729 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-01-22T17:25:23.729Z,1548177923.729 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.730Z,1548177923.730 [Radio_Surface](INFO): Join timeout helper Thread ID is 984
2019-01-22T17:25:23.917Z,1548177923.917 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-22T17:25:23.917Z,1548177923.917 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.919Z,1548177923.919 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-01-22T17:25:23.919Z,1548177923.919 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.919Z,1548177923.919 [logger](INFO): Join timeout helper Thread ID is 985
2019-01-22T17:25:23.953Z,1548177923.953 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-22T17:25:23.954Z,1548177923.954 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.965Z,1548177923.965 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-01-22T17:25:23.966Z,1548177923.966 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.966Z,1548177923.966 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-01-22T17:25:23.966Z,1548177923.966 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:23.966Z,1548177923.966 [controlThread](INFO): Join timeout helper Thread ID is 986
2019-01-22T17:25:24.241Z,1548177924.241 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-22T17:25:24.241Z,1548177924.241 [controlThread](DEBUG): Uninitializing ControlThread
2019-01-22T17:25:24.242Z,1548177924.242 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-01-22T17:25:24.362Z,1548177924.362 [NAL9602](INFO): Powering down
2019-01-22T17:25:24.363Z,1548177924.363 [PNI_TCM](INFO): Powering down
2019-01-22T17:25:24.449Z,1548177924.449 [Aanderaa_O2](INFO): Powering down
2019-01-22T17:25:24.452Z,1548177924.452 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-01-22T17:25:24.453Z,1548177924.453 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-01-22T17:25:24.454Z,1548177924.454 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-01-22T17:25:24.454Z,1548177924.454 [MissionManager](INFO): Uninitializing Mission Default
2019-01-22T17:25:24.455Z,1548177924.455 [Default] Stopped
2019-01-22T17:25:24.455Z,1548177924.455 [Default](DEBUG): Aggregate::uninitialize Default
2019-01-22T17:25:24.455Z,1548177924.455 [Default:B.GoToSurface] Stopped
2019-01-22T17:25:24.455Z,1548177924.455 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-22T17:25:24.455Z,1548177924.455 [Default:CheckIn] Stopped
2019-01-22T17:25:24.455Z,1548177924.455 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-22T17:25:24.455Z,1548177924.455 [Default:CheckIn:Read_GPS] Stopped
2019-01-22T17:25:24.459Z,1548177924.459 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-01-22T17:25:24.459Z,1548177924.459 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-01-22T17:25:24.459Z,1548177924.459 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-01-22T17:25:24.459Z,1548177924.459 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-01-22T17:25:24.460Z,1548177924.460 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-01-22T17:25:24.460Z,1548177924.460 [BuoyancyServo](INFO): Powering down
2019-01-22T17:25:24.473Z,1548177924.473 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-01-22T17:25:24.473Z,1548177924.473 [ElevatorServo](INFO): Powering down
2019-01-22T17:25:24.474Z,1548177924.474 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-01-22T17:25:24.474Z,1548177924.474 [MassServo](INFO): Powering down
2019-01-22T17:25:24.475Z,1548177924.475 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-01-22T17:25:24.475Z,1548177924.475 [RudderServo](INFO): Powering down
2019-01-22T17:25:24.476Z,1548177924.476 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-01-22T17:25:24.476Z,1548177924.476 [ThrusterServo](INFO): Powering down
2019-01-22T17:25:24.477Z,1548177924.477 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-01-22T17:25:24.477Z,1548177924.477 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-01-22T17:25:24.477Z,1548177924.477 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-01-22T17:25:24.477Z,1548177924.477 [CBIT](DEBUG): Powering off loads.
2019-01-22T17:25:24.489Z,1548177924.489 [CBIT](DEBUG): Disabling WDT.
2019-01-22T17:25:24.501Z,1548177924.501 [CBIT](DEBUG): Opening all GF detection circuits.
2019-01-22T17:25:24.501Z,1548177924.501 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:24.586Z,1548177924.586 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:24.591Z,1548177924.591 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:24.633Z,1548177924.633 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:24.638Z,1548177924.638 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:24.684Z,1548177924.684 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-22T17:25:24.742Z,1548177924.742 [logger ThreadHandler](INFO): Thread cancelled.