2018-08-20T19:01:31.795Z,1534791691.795 [Supervisor](DEBUG): Initializing supervisor.
2018-08-20T19:01:31.798Z,1534791691.798 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-08-20T19:01:31.798Z,1534791691.798 [SyncHandler](INFO): Protected caller Thread ID is 797
2018-08-20T19:01:31.799Z,1534791691.799 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-08-20T19:01:31.800Z,1534791691.800 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-08-20T19:01:31.800Z,1534791691.800 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 798
2018-08-20T19:01:31.803Z,1534791691.803 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-08-20T19:01:31.814Z,1534791691.814 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-08-20T19:01:31.815Z,1534791691.815 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-08-20T19:01:31.816Z,1534791691.816 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 799
2018-08-20T19:01:31.817Z,1534791691.817 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-08-20T19:01:31.817Z,1534791691.817 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-08-20T19:01:31.818Z,1534791691.818 [logger ThreadHandler](INFO): Protected caller Thread ID is 800
2018-08-20T19:01:31.820Z,1534791691.820 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-08-20T19:01:31.820Z,1534791691.820 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-08-20T19:01:31.824Z,1534791691.824 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-08-20T19:01:32.188Z,1534791692.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-08-20T19:01:32.188Z,1534791692.188 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-08-20T19:01:32.400Z,1534791692.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-08-20T19:01:32.400Z,1534791692.400 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-08-20T19:01:32.696Z,1534791692.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-08-20T19:01:32.698Z,1534791692.698 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-08-20T19:01:32.842Z,1534791692.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-08-20T19:01:32.844Z,1534791692.844 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-08-20T19:01:32.944Z,1534791692.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-08-20T19:01:32.946Z,1534791692.946 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-08-20T19:01:33.433Z,1534791693.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-08-20T19:01:33.435Z,1534791693.435 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-08-20T19:01:33.875Z,1534791693.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-08-20T19:01:33.877Z,1534791693.877 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-08-20T19:01:34.351Z,1534791694.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-08-20T19:01:34.351Z,1534791694.351 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-08-20T19:01:34.458Z,1534791694.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-08-20T19:01:34.459Z,1534791694.459 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-08-20T19:01:34.558Z,1534791694.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-08-20T19:01:34.559Z,1534791694.559 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-08-20T19:01:34.772Z,1534791694.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-08-20T19:01:34.774Z,1534791694.774 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-08-20T19:01:34.992Z,1534791694.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-08-20T19:01:34.992Z,1534791694.992 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-08-20T19:01:35.355Z,1534791695.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-08-20T19:01:35.355Z,1534791695.355 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-08-20T19:01:35.505Z,1534791695.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-08-20T19:01:35.505Z,1534791695.505 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-08-20T19:01:36.558Z,1534791696.558 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-08-20T19:01:36.560Z,1534791696.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-08-20T19:01:36.667Z,1534791696.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-08-20T19:01:36.789Z,1534791696.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-08-20T19:01:36.899Z,1534791696.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-08-20T19:01:36.991Z,1534791696.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-08-20T19:01:37.153Z,1534791697.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-08-20T19:01:37.337Z,1534791697.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-08-20T19:01:37.413Z,1534791697.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-08-20T19:01:37.650Z,1534791697.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-08-20T19:01:37.650Z,1534791697.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-08-20T19:01:37.735Z,1534791697.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-08-20T19:01:37.828Z,1534791697.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-08-20T19:01:37.932Z,1534791697.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-08-20T19:01:38.064Z,1534791698.064 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-08-20T19:01:38.072Z,1534791698.072 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-08-20T19:01:38.353Z,1534791698.353 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-08-20T19:01:38.354Z,1534791698.354 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-08-20T19:01:38.410Z,1534791698.410 [DepthRateCalculator] Loaded
2018-08-20T19:01:38.410Z,1534791698.410 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-08-20T19:01:38.416Z,1534791698.416 [PitchRateCalculator] Loaded
2018-08-20T19:01:38.416Z,1534791698.416 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-08-20T19:01:38.432Z,1534791698.432 [SpeedCalculator] Loaded
2018-08-20T19:01:38.433Z,1534791698.433 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-08-20T19:01:38.454Z,1534791698.454 [TempGradientCalculator] Loaded
2018-08-20T19:01:38.454Z,1534791698.454 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-08-20T19:01:38.470Z,1534791698.470 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-08-20T19:01:38.470Z,1534791698.470 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-08-20T19:01:38.476Z,1534791698.476 [YawRateCalculator] Loaded
2018-08-20T19:01:38.476Z,1534791698.476 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-08-20T19:01:38.516Z,1534791698.516 [ElevatorOffsetCalculator] Loaded
2018-08-20T19:01:38.516Z,1534791698.516 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-08-20T19:01:38.517Z,1534791698.517 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-08-20T19:01:38.519Z,1534791698.519 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-08-20T19:01:38.639Z,1534791698.639 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-08-20T19:01:38.640Z,1534791698.640 [StratificationFrontDetector](DEBUG): (re)initializing
2018-08-20T19:01:38.640Z,1534791698.640 [StratificationFrontDetector] Loaded
2018-08-20T19:01:38.640Z,1534791698.640 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-08-20T19:01:38.641Z,1534791698.641 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-08-20T19:01:38.642Z,1534791698.642 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-08-20T19:01:38.775Z,1534791698.775 [BuoyancyServo] Loaded
2018-08-20T19:01:38.776Z,1534791698.776 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-08-20T19:01:38.791Z,1534791698.791 [ElevatorServo] Loaded
2018-08-20T19:01:38.791Z,1534791698.791 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-08-20T19:01:38.806Z,1534791698.806 [MassServo] Loaded
2018-08-20T19:01:38.806Z,1534791698.806 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-08-20T19:01:38.821Z,1534791698.821 [RudderServo] Loaded
2018-08-20T19:01:38.822Z,1534791698.822 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-08-20T19:01:38.836Z,1534791698.836 [ThrusterServo] Loaded
2018-08-20T19:01:38.836Z,1534791698.836 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-08-20T19:01:38.837Z,1534791698.837 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-08-20T19:01:38.839Z,1534791698.839 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-08-20T19:01:38.862Z,1534791698.862 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-08-20T19:01:38.863Z,1534791698.863 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-08-20T19:01:38.988Z,1534791698.988 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-08-20T19:01:38.989Z,1534791698.989 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-08-20T19:01:39.554Z,1534791699.554 [DataOverHttps] Loaded
2018-08-20T19:01:39.554Z,1534791699.554 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-08-20T19:01:39.568Z,1534791699.568 [Depth_Keller] Loaded
2018-08-20T19:01:39.568Z,1534791699.568 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-08-20T19:01:39.574Z,1534791699.574 [DropWeight] Loaded
2018-08-20T19:01:39.574Z,1534791699.574 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-08-20T19:01:39.675Z,1534791699.675 [NAL9602] Loaded
2018-08-20T19:01:39.675Z,1534791699.675 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-08-20T19:01:39.691Z,1534791699.691 [Onboard] Loaded
2018-08-20T19:01:39.691Z,1534791699.691 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-08-20T19:01:39.702Z,1534791699.702 [Radio_Surface] Loaded
2018-08-20T19:01:39.702Z,1534791699.702 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-08-20T19:01:39.703Z,1534791699.703 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408854E0
2018-08-20T19:01:39.704Z,1534791699.704 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-08-20T19:01:39.857Z,1534791699.857 [PNI_TCM] Loaded
2018-08-20T19:01:39.857Z,1534791699.857 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-08-20T19:01:39.918Z,1534791699.918 [Rowe_600LCM] Loaded
2018-08-20T19:01:39.918Z,1534791699.918 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-08-20T19:01:39.919Z,1534791699.919 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0
2018-08-20T19:01:39.920Z,1534791699.920 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 883
2018-08-20T19:01:42.320Z,1534791702.320 [BPC1] Loaded
2018-08-20T19:01:42.320Z,1534791702.320 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-08-20T19:01:42.321Z,1534791702.321 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-08-20T19:01:42.322Z,1534791702.322 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-08-20T19:01:42.476Z,1534791702.476 [SBIT](DEBUG): Construct Startup Built In Test.
2018-08-20T19:01:42.498Z,1534791702.498 [SBIT] Loaded
2018-08-20T19:01:42.499Z,1534791702.499 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-08-20T19:01:42.499Z,1534791702.499 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-08-20T19:01:42.511Z,1534791702.511 [IBIT] Loaded
2018-08-20T19:01:42.511Z,1534791702.511 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-08-20T19:01:42.515Z,1534791702.515 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-08-20T19:01:42.666Z,1534791702.666 [CBIT] Loaded
2018-08-20T19:01:42.666Z,1534791702.666 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-08-20T19:01:42.667Z,1534791702.667 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-08-20T19:01:42.668Z,1534791702.668 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-08-20T19:01:42.856Z,1534791702.856 [Aanderaa_O2] Loaded
2018-08-20T19:01:42.857Z,1534791702.857 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-08-20T19:01:42.935Z,1534791702.935 [CTD_NeilBrown] Loaded
2018-08-20T19:01:42.936Z,1534791702.936 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-08-20T19:01:42.937Z,1534791702.937 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409794E0
2018-08-20T19:01:42.937Z,1534791702.937 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 884
2018-08-20T19:01:42.989Z,1534791702.989 [CTD_Seabird] Loaded
2018-08-20T19:01:42.989Z,1534791702.989 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-08-20T19:01:42.990Z,1534791702.990 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409A94E0
2018-08-20T19:01:42.990Z,1534791702.990 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 885
2018-08-20T19:01:43.005Z,1534791703.005 [PAR_Licor] Loaded
2018-08-20T19:01:43.005Z,1534791703.005 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-08-20T19:01:43.055Z,1534791703.055 [WetLabsBB2FL] Loaded
2018-08-20T19:01:43.055Z,1534791703.055 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-08-20T19:01:43.056Z,1534791703.056 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409D94E0
2018-08-20T19:01:43.056Z,1534791703.056 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 886
2018-08-20T19:01:43.057Z,1534791703.057 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-08-20T19:01:43.058Z,1534791703.058 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-08-20T19:01:43.146Z,1534791703.146 [VerticalControl](DEBUG): Construct VerticalControl.
2018-08-20T19:01:43.231Z,1534791703.231 [VerticalControl] Loaded
2018-08-20T19:01:43.231Z,1534791703.231 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-08-20T19:01:43.232Z,1534791703.232 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-08-20T19:01:43.290Z,1534791703.290 [HorizontalControl] Loaded
2018-08-20T19:01:43.290Z,1534791703.290 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-08-20T19:01:43.291Z,1534791703.291 [SpeedControl](DEBUG): Construct SpeedControl.
2018-08-20T19:01:43.292Z,1534791703.292 [SpeedControl] Loaded
2018-08-20T19:01:43.292Z,1534791703.292 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-08-20T19:01:43.293Z,1534791703.293 [LoopControl](DEBUG): Construct LoopControl.
2018-08-20T19:01:43.294Z,1534791703.294 [LoopControl] Loaded
2018-08-20T19:01:43.294Z,1534791703.294 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-08-20T19:01:43.295Z,1534791703.295 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-08-20T19:01:43.296Z,1534791703.296 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-08-20T19:01:43.334Z,1534791703.334 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-08-20T19:01:43.335Z,1534791703.335 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-08-20T19:01:43.442Z,1534791703.442 [DeadReckonUsingSpeedCalculator] Loaded
2018-08-20T19:01:43.442Z,1534791703.442 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-08-20T19:01:43.459Z,1534791703.459 [NavChart] Loaded
2018-08-20T19:01:43.459Z,1534791703.459 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-08-20T19:01:43.463Z,1534791703.463 [UniversalFixResidualReporter] Loaded
2018-08-20T19:01:43.464Z,1534791703.464 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-08-20T19:01:43.464Z,1534791703.464 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-08-20T19:01:43.468Z,1534791703.468 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-08-20T19:01:43.469Z,1534791703.469 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-08-20T19:01:43.475Z,1534791703.475 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-08-20T19:01:43.476Z,1534791703.476 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A654E0
2018-08-20T19:01:43.477Z,1534791703.477 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 887
2018-08-20T19:01:43.481Z,1534791703.481 [Supervisor](INFO): Main Thread ID is 796
2018-08-20T19:01:43.481Z,1534791703.481 [Supervisor](DEBUG): Running supervisor.
2018-08-20T19:01:43.482Z,1534791703.482 [CommandLine ThreadHandler](INFO): Handler Thread ID is 888
2018-08-20T19:01:43.484Z,1534791703.484 [controlThread ThreadHandler](INFO): Handler Thread ID is 889
2018-08-20T19:01:43.485Z,1534791703.485 [controlThread](DEBUG): Initializing ControlThread
2018-08-20T19:01:43.486Z,1534791703.486 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-20T19:01:43.486Z,1534791703.486 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-08-20T19:01:43.487Z,1534791703.487 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-08-20T19:01:43.487Z,1534791703.487 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-08-20T19:01:43.487Z,1534791703.487 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-08-20T19:01:43.488Z,1534791703.488 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-08-20T19:01:43.489Z,1534791703.489 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-08-20T19:01:43.497Z,1534791703.497 [SBIT](INFO): Initialize SBIT Component.
2018-08-20T19:01:43.497Z,1534791703.497 [SBIT](IMPORTANT): git: 2018-08-20
2018-08-20T19:01:43.498Z,1534791703.498 [SBIT](INFO): git hash: 695b514009632b0c9b638e65628c1e5f155de51b
2018-08-20T19:01:43.498Z,1534791703.498 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-08-20T19:01:43.499Z,1534791703.499 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-08-20T19:01:43.500Z,1534791703.500 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-08-20T19:01:43.501Z,1534791703.501 [IBIT](INFO): Initialize IBIT Component.
2018-08-20T19:01:43.502Z,1534791703.502 [CBIT](DEBUG): Initialize CBIT Component.
2018-08-20T19:01:43.502Z,1534791703.502 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-08-20T19:01:43.503Z,1534791703.503 [logger ThreadHandler](INFO): Handler Thread ID is 890
2018-08-20T19:01:43.522Z,1534791703.522 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 891
2018-08-20T19:01:43.528Z,1534791703.528 [Radio_Surface](INFO): Powering up
2018-08-20T19:01:43.534Z,1534791703.534 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 892
2018-08-20T19:01:43.535Z,1534791703.535 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:01:43.535Z,1534791703.535 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:01:43.614Z,1534791703.614 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-08-20T19:01:43.616Z,1534791703.616 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-08-20T19:01:43.617Z,1534791703.617 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-08-20T19:01:43.617Z,1534791703.617 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-08-20T19:01:43.618Z,1534791703.618 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:01:43.619Z,1534791703.619 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:01:43.619Z,1534791703.619 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:01:43.620Z,1534791703.620 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-08-20T19:01:43.649Z,1534791703.649 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-08-20T19:01:43.650Z,1534791703.650 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-08-20T19:01:43.661Z,1534791703.661 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 893
2018-08-20T19:01:43.662Z,1534791703.662 [CTD_NeilBrown](INFO): Powering down
2018-08-20T19:01:43.798Z,1534791703.798 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 894
2018-08-20T19:01:43.799Z,1534791703.799 [CTD_Seabird](INFO): Initializing
2018-08-20T19:01:43.799Z,1534791703.799 [CTD_Seabird](INFO): Checking LCM
2018-08-20T19:01:43.801Z,1534791703.801 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 895
2018-08-20T19:01:43.811Z,1534791703.811 [WetLabsBB2FL](INFO): Powering down
2018-08-20T19:01:43.832Z,1534791703.832 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-20T19:01:43.916Z,1534791703.916 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896
2018-08-20T19:01:43.920Z,1534791703.920 [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>


2018-08-20T19:01:43.925Z,1534791703.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-08-20T19:01:43.925Z,1534791703.925 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-08-20T19:01:43.925Z,1534791703.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-08-20T19:01:43.925Z,1534791703.925 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-08-20T19:01:43.925Z,1534791703.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-08-20T19:01:43.926Z,1534791703.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-08-20T19:01:43.926Z,1534791703.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-08-20T19:01:43.926Z,1534791703.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-08-20T19:01:43.926Z,1534791703.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-08-20T19:01:43.927Z,1534791703.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-08-20T19:01:43.927Z,1534791703.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-08-20T19:01:43.927Z,1534791703.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-08-20T19:01:43.927Z,1534791703.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-08-20T19:01:43.927Z,1534791703.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-08-20T19:01:43.928Z,1534791703.928 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-08-20T19:01:43.928Z,1534791703.928 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-08-20T19:01:43.931Z,1534791703.931 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-08-20T19:01:44.022Z,1534791704.022 [CTD_Seabird](INFO): LCM OK
2018-08-20T19:01:44.022Z,1534791704.022 [CTD_Seabird](INFO): Powering up
2018-08-20T19:01:44.043Z,1534791704.043 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:01:44.050Z,1534791704.050 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:01:44.053Z,1534791704.053 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-08-20T19:01:44.066Z,1534791704.066 [Default:A.Wait](DEBUG): Construct Wait.
2018-08-20T19:01:44.068Z,1534791704.068 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-20T19:01:44.095Z,1534791704.095 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-08-20T19:01:44.097Z,1534791704.097 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-08-20T19:01:44.119Z,1534791704.119 [Default:E.Execute](DEBUG): Construct Execute.
2018-08-20T19:01:44.143Z,1534791704.143 [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 300</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>


2018-08-20T19:01:44.148Z,1534791704.148 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-08-20T19:01:44.174Z,1534791704.174 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-08-20T19:01:44.209Z,1534791704.209 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-08-20T19:01:44.475Z,1534791704.475 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-08-20T19:01:44.582Z,1534791704.582 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T19:01:44.822Z,1534791704.822 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-20T19:01:44.830Z,1534791704.830 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-20T19:01:44.857Z,1534791704.857 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-08-20T19:01:44.862Z,1534791704.862 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-08-20T19:01:44.869Z,1534791704.869 [MassServo](DEBUG): Initializing EZServoServo.
2018-08-20T19:01:44.886Z,1534791704.886 [MassServo](DEBUG): Initializing MassServo.
2018-08-20T19:01:44.892Z,1534791704.892 [RudderServo](DEBUG): Initializing EZServoServo.
2018-08-20T19:01:44.898Z,1534791704.898 [RudderServo](DEBUG): Initializing RudderServo.
2018-08-20T19:01:44.920Z,1534791704.920 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-08-20T19:01:44.926Z,1534791704.926 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-08-20T19:01:45.250Z,1534791705.250 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T19:01:45.487Z,1534791705.487 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T19:01:45.980Z,1534791705.980 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T19:01:46.420Z,1534791706.420 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T19:01:46.822Z,1534791706.822 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T19:01:47.222Z,1534791707.222 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T19:01:47.722Z,1534791707.722 [Aanderaa_O2](INFO): Powering down
2018-08-20T19:01:47.816Z,1534791707.816 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-20T19:01:48.023Z,1534791708.023 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:01:48.214Z,1534791708.214 [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 &
2018-08-20T19:01:48.420Z,1534791708.420 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:01:48.421Z,1534791708.421 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:01:48.421Z,1534791708.421 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:01:49.302Z,1534791709.302 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-20T19:01:49.508Z,1534791709.508 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-08-20T19:01:49.510Z,1534791709.510 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-08-20T19:01:50.320Z,1534791710.320 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-20T19:01:50.320Z,1534791710.320 [CTD_Seabird](INFO): Powering down
2018-08-20T19:02:07.227Z,1534791727.227 [SBIT](IMPORTANT): Beginning Startup BIT
2018-08-20T19:02:07.241Z,1534791727.241 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-20T19:02:09.693Z,1534791729.693 [NAL9602](INFO): Powering up NAL9602
2018-08-20T19:02:18.237Z,1534791738.237 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006163
CHAN A1 (24V): -0.009329
CHAN A2 (12V): -0.002018
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001703
CHAN B1 (3.15aV): 0.000160
CHAN B2 (3.15bV): -0.000349
CHAN B3 (GND): 0.000130
OPEN: -0.000476
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-20T19:02:20.868Z,1534791740.868 [NAL9602](INFO): NAL9602 initialized
2018-08-20T19:03:01.092Z,1534791781.092 [SBIT](IMPORTANT): SBIT PASSED
2018-08-20T19:03:01.181Z,1534791781.181 [CommandLine](IMPORTANT): got command configSet list
2018-08-20T19:03:01.181Z,1534791781.181 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-08-20T19:03:01.183Z,1534791781.183 [CommandLine](IMPORTANT): CBIT.stopDepth=215 meter;

2018-08-20T19:03:01.473Z,1534791781.473 [MissionManager](IMPORTANT): Started mission Startup
2018-08-20T19:03:01.486Z,1534791781.486 [Startup] Running Loop=1
2018-08-20T19:03:01.487Z,1534791781.487 [Startup](DEBUG): Aggregate::initialize Startup
2018-08-20T19:03:01.487Z,1534791781.487 [Startup:A.GoToSurface] Running Loop=1
2018-08-20T19:03:01.487Z,1534791781.487 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-20T19:03:01.488Z,1534791781.488 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-20T19:03:01.488Z,1534791781.488 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-20T19:03:01.489Z,1534791781.489 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-20T19:03:01.490Z,1534791781.490 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-20T19:03:01.494Z,1534791781.494 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-20T19:03:01.520Z,1534791781.520 [Startup:StartupSatComms] Running Loop=1
2018-08-20T19:03:01.520Z,1534791781.520 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-08-20T19:03:01.521Z,1534791781.521 [Startup:StartupSatComms:A] Running Loop=1
2018-08-20T19:03:01.888Z,1534791781.888 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-08-20T19:03:44.666Z,1534791824.666 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:03:44.666Z,1534791824.666 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T19:03:44.666Z,1534791824.666 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:03:44.679Z,1534791824.679 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:03:45.070Z,1534791825.070 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:03:45.281Z,1534791825.281 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:03:46.658Z,1534791826.658 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:03:46.658Z,1534791826.658 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T19:03:46.894Z,1534791826.894 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:03:46.895Z,1534791826.895 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:03:46.895Z,1534791826.895 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:03:46.895Z,1534791826.895 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:03:51.144Z,1534791831.144 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:03:51.216Z,1534791831.216 [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 &
2018-08-20T19:03:51.218Z,1534791831.218 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:03:51.219Z,1534791831.219 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:03:51.220Z,1534791831.220 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:04:01.845Z,1534791841.845 [Startup:StartupSatComms:A](INFO): Timed out from 2018-08-20T19:03:01.5Z
2018-08-20T19:04:01.845Z,1534791841.845 [Startup:StartupSatComms:A] Stopped
2018-08-20T19:04:01.845Z,1534791841.845 [Startup:StartupSatComms:B] Running Loop=1
2018-08-20T19:04:02.257Z,1534791842.257 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-20T19:04:12.516Z,1534791852.516 [CommandLine](IMPORTANT): got command gfscan
2018-08-20T19:04:12.683Z,1534791852.683 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-20T19:04:23.490Z,1534791863.490 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006030
CHAN A1 (24V): -0.009132
CHAN A2 (12V): -0.002494
CHAN A3 (5V): -0.001999
CHAN B0 (3.3V): 0.000099
CHAN B1 (3.15aV): -0.000844
CHAN B2 (3.15bV): 0.000048
CHAN B3 (GND): 0.000058
OPEN: -0.000423
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-20T19:04:41.779Z,1534791881.779 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006187
2018-08-20T19:04:46.128Z,1534791886.128 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:04:46.128Z,1534791886.128 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-08-20T19:04:46.129Z,1534791886.129 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:04:46.172Z,1534791886.172 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:04:46.193Z,1534791886.193 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T182810/Courier0010.lzma
2018-08-20T19:04:46.326Z,1534791886.326 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:04:46.326Z,1534791886.326 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-08-20T19:04:46.659Z,1534791886.659 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:04:46.660Z,1534791886.660 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:04:46.660Z,1534791886.660 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:04:46.985Z,1534791886.985 [DataOverHttps](INFO): Moved sent file to Logs/20180820T182810/Courier0010.lzma.bak
2018-08-20T19:04:46.986Z,1534791886.986 [DataOverHttps](INFO): SBD MOMSN=8430321
2018-08-20T19:04:56.568Z,1534791896.568 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20180820T182810/Express0011.lzma
2018-08-20T19:04:57.366Z,1534791897.366 [DataOverHttps](INFO): Moved sent file to Logs/20180820T182810/Express0011.lzma.bak
2018-08-20T19:04:57.366Z,1534791897.366 [DataOverHttps](INFO): SBD MOMSN=8430323
2018-08-20T19:05:01.882Z,1534791901.882 [Startup:StartupSatComms:B](INFO): Timed out from 2018-08-20T19:04:01.8Z
2018-08-20T19:05:01.882Z,1534791901.882 [Startup:StartupSatComms:B] Stopped
2018-08-20T19:05:01.883Z,1534791901.883 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-08-20T19:05:01.883Z,1534791901.883 [Startup:StartupSatComms] Stopped
2018-08-20T19:05:01.883Z,1534791901.883 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-08-20T19:05:01.884Z,1534791901.884 [Startup](INFO): Completed Startup
2018-08-20T19:05:01.885Z,1534791901.885 [MissionManager](INFO): Startup is completed.
2018-08-20T19:05:01.885Z,1534791901.885 [MissionManager](INFO): Uninitializing Mission Startup
2018-08-20T19:05:01.885Z,1534791901.885 [Startup] Stopped
2018-08-20T19:05:01.885Z,1534791901.885 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-08-20T19:05:01.885Z,1534791901.885 [Startup:A.GoToSurface] Stopped
2018-08-20T19:05:01.885Z,1534791901.885 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-20T19:05:02.249Z,1534791902.249 [MissionManager](IMPORTANT): Started mission Default
2018-08-20T19:05:02.249Z,1534791902.249 [Default] Running Loop=1
2018-08-20T19:05:02.249Z,1534791902.249 [Default](DEBUG): Aggregate::initialize Default
2018-08-20T19:05:02.249Z,1534791902.249 [Default:B.GoToSurface] Running Loop=1
2018-08-20T19:05:02.249Z,1534791902.249 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-20T19:05:02.255Z,1534791902.255 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-20T19:05:02.255Z,1534791902.255 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-20T19:05:02.256Z,1534791902.256 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-20T19:05:02.256Z,1534791902.256 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-20T19:05:02.257Z,1534791902.257 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-20T19:05:02.257Z,1534791902.257 [Default:A.Wait] Running Loop=1
2018-08-20T19:05:02.257Z,1534791902.257 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-08-20T19:05:07.586Z,1534791907.586 [DataOverHttps](INFO): Sending 670 bytes from file Logs/20180820T190131/Express0001.lzma
2018-08-20T19:05:08.357Z,1534791908.357 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Express0001.lzma.bak
2018-08-20T19:05:08.357Z,1534791908.357 [DataOverHttps](INFO): SBD MOMSN=8430332
2018-08-20T19:05:15.624Z,1534791915.624 [Default:A.Wait](INFO): Done Waiting.
2018-08-20T19:05:15.624Z,1534791915.624 [Default:A.Wait] Stopped
2018-08-20T19:05:15.624Z,1534791915.624 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T19:05:16.012Z,1534791916.012 [Default:CheckIn] Running Loop=1
2018-08-20T19:05:16.013Z,1534791916.013 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T19:05:16.013Z,1534791916.013 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T19:05:16.419Z,1534791916.419 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-08-20T19:05:47.546Z,1534791947.546 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:05:47.546Z,1534791947.546 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-20T19:05:47.546Z,1534791947.546 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:05:47.669Z,1534791947.669 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:05:47.950Z,1534791947.950 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:05:48.210Z,1534791948.210 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:05:49.735Z,1534791949.735 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:05:49.735Z,1534791949.735 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-20T19:05:49.826Z,1534791949.826 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:05:49.827Z,1534791949.827 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:05:49.827Z,1534791949.827 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:05:49.827Z,1534791949.827 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:05:53.950Z,1534791953.950 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:05:54.112Z,1534791954.112 [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 &
2018-08-20T19:05:54.114Z,1534791954.114 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:05:54.115Z,1534791954.115 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:05:54.116Z,1534791954.116 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:07:23.885Z,1534792043.885 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-08-20T19:07:26.325Z,1534792046.325 [BPC1](ERROR): Bank A: No match for stick 1 serial number:0FD1 in the onboard configuration file.
2018-08-20T19:07:26.325Z,1534792046.325 [BPC1](ERROR): Failed to parse Bank A battery data
2018-08-20T19:07:46.784Z,1534792066.784 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:07:46.785Z,1534792066.785 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-08-20T19:07:46.785Z,1534792066.785 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:07:46.833Z,1534792066.833 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:07:47.172Z,1534792067.172 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:07:47.172Z,1534792067.172 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-08-20T19:07:47.548Z,1534792067.548 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:07:47.549Z,1534792067.549 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:07:47.549Z,1534792067.549 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:07:49.874Z,1534792069.874 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:07:49.874Z,1534792069.874 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-20T19:07:49.874Z,1534792069.874 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:07:50.007Z,1534792070.007 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:07:50.278Z,1534792070.278 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:07:50.515Z,1534792070.515 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:07:51.989Z,1534792071.989 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:07:51.989Z,1534792071.989 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-20T19:07:52.130Z,1534792072.130 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:07:52.131Z,1534792072.131 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:07:52.131Z,1534792072.131 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:07:52.131Z,1534792072.131 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:07:56.258Z,1534792076.258 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:07:56.429Z,1534792076.429 [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 &
2018-08-20T19:07:56.430Z,1534792076.430 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:07:56.430Z,1534792076.430 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:07:56.431Z,1534792076.431 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:09:52.230Z,1534792192.230 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:09:52.230Z,1534792192.230 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-20T19:09:52.230Z,1534792192.230 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:09:52.351Z,1534792192.351 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:09:52.634Z,1534792192.634 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:09:52.887Z,1534792192.887 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:09:54.324Z,1534792194.324 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:09:54.324Z,1534792194.324 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-20T19:09:54.502Z,1534792194.502 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:09:54.503Z,1534792194.503 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:09:54.503Z,1534792194.503 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:09:54.503Z,1534792194.503 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:09:58.625Z,1534792198.625 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:09:58.829Z,1534792198.829 [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 &
2018-08-20T19:09:58.831Z,1534792198.831 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:09:58.832Z,1534792198.832 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:09:58.833Z,1534792198.833 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:10:16.185Z,1534792216.185 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T19:05:16.0Z
2018-08-20T19:10:16.185Z,1534792216.185 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T19:10:16.185Z,1534792216.185 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T19:10:16.599Z,1534792216.599 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-20T19:10:20.953Z,1534792220.953 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T190131/Courier0004.lzma
2018-08-20T19:10:21.713Z,1534792221.713 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Courier0004.lzma.bak
2018-08-20T19:10:21.713Z,1534792221.713 [DataOverHttps](INFO): SBD MOMSN=8430347
2018-08-20T19:10:31.308Z,1534792231.308 [DataOverHttps](INFO): Sending 452 bytes from file Logs/20180820T190131/Express0005.lzma
2018-08-20T19:10:32.105Z,1534792232.105 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Express0005.lzma.bak
2018-08-20T19:10:32.105Z,1534792232.105 [DataOverHttps](INFO): SBD MOMSN=8430349
2018-08-20T19:10:32.963Z,1534792232.963 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T19:10:32.963Z,1534792232.963 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T19:10:32.963Z,1534792232.963 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T19:10:47.849Z,1534792247.849 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:10:47.849Z,1534792247.849 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-08-20T19:10:47.849Z,1534792247.849 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:10:47.882Z,1534792247.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:10:48.257Z,1534792248.257 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:10:48.257Z,1534792248.257 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-08-20T19:10:48.643Z,1534792248.643 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:10:48.644Z,1534792248.644 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:10:48.644Z,1534792248.644 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:11:54.514Z,1534792314.514 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:11:54.514Z,1534792314.514 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-20T19:11:54.514Z,1534792314.514 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:11:54.611Z,1534792314.611 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:11:54.611Z,1534792314.611 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-20T19:11:54.918Z,1534792314.918 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:11:55.171Z,1534792315.171 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:12:22.124Z,1534792342.124 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T19:12:22.124Z,1534792342.124 [NAL9602] Data Fault, FailCount= 1
2018-08-20T19:12:22.124Z,1534792342.124 [NAL9602](ERROR): Data Fault
2018-08-20T19:12:22.211Z,1534792342.211 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T19:12:22.524Z,1534792342.524 [NAL9602](INFO): Powering down
2018-08-20T19:12:23.399Z,1534792343.399 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T19:12:23.399Z,1534792343.399 [NAL9602] No Fault, FailCount= 1
2018-08-20T19:12:52.532Z,1534792372.532 [NAL9602](INFO): Powering up NAL9602
2018-08-20T19:13:03.332Z,1534792383.332 [NAL9602](INFO): NAL9602 initialized
2018-08-20T19:13:48.976Z,1534792428.976 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:13:48.976Z,1534792428.976 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-08-20T19:13:48.976Z,1534792428.976 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:13:49.006Z,1534792429.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:13:49.414Z,1534792429.414 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:13:49.414Z,1534792429.414 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-08-20T19:13:49.769Z,1534792429.769 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:13:49.770Z,1534792429.770 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:13:49.770Z,1534792429.770 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:15:33.393Z,1534792533.393 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T19:15:33.394Z,1534792533.394 [Default:CheckIn:C.Wait] Stopped
2018-08-20T19:15:33.395Z,1534792533.395 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T19:15:33.395Z,1534792533.395 [Default:CheckIn:D] Running Loop=1
2018-08-20T19:15:33.817Z,1534792533.817 [Default:CheckIn:D] Stopped
2018-08-20T19:15:33.817Z,1534792533.817 [Default:CheckIn:E] Running Loop=1
2018-08-20T19:15:34.215Z,1534792534.215 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.526098 min 
2018-08-20T19:15:34.217Z,1534792534.217 [Default:CheckIn:E] Stopped
2018-08-20T19:15:34.217Z,1534792534.217 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T19:15:34.217Z,1534792534.217 [Default:CheckIn] Stopped
2018-08-20T19:15:34.217Z,1534792534.217 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T19:15:34.217Z,1534792534.217 [Default:CheckIn](INFO): Running loop #2
2018-08-20T19:15:34.217Z,1534792534.217 [Default:CheckIn] Running Loop=2
2018-08-20T19:15:34.218Z,1534792534.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T19:15:34.218Z,1534792534.218 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T19:16:49.818Z,1534792609.818 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:16:49.818Z,1534792609.818 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-08-20T19:16:49.818Z,1534792609.818 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:16:49.857Z,1534792609.857 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:16:50.236Z,1534792610.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:16:50.236Z,1534792610.236 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-08-20T19:16:50.603Z,1534792610.603 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:16:50.604Z,1534792610.604 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:16:50.604Z,1534792610.604 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:19:50.654Z,1534792790.654 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:19:50.654Z,1534792790.654 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-08-20T19:19:50.654Z,1534792790.654 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:19:50.680Z,1534792790.680 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:19:51.075Z,1534792791.075 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:19:51.076Z,1534792791.076 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-08-20T19:19:51.447Z,1534792791.447 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:19:51.447Z,1534792791.447 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:19:51.448Z,1534792791.448 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:20:34.262Z,1534792834.262 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T19:15:34.2Z
2018-08-20T19:20:34.262Z,1534792834.262 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T19:20:34.262Z,1534792834.262 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T19:20:37.800Z,1534792837.800 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T190131/Courier0007.lzma
2018-08-20T19:20:38.438Z,1534792838.438 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Courier0007.lzma.bak
2018-08-20T19:20:38.438Z,1534792838.438 [DataOverHttps](INFO): SBD MOMSN=8430359
2018-08-20T19:20:47.253Z,1534792847.253 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20180820T190131/Express0008.lzma
2018-08-20T19:20:48.052Z,1534792848.052 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Express0008.lzma.bak
2018-08-20T19:20:48.053Z,1534792848.053 [DataOverHttps](INFO): SBD MOMSN=8430361
2018-08-20T19:20:48.813Z,1534792848.813 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T19:20:48.813Z,1534792848.813 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T19:20:48.813Z,1534792848.813 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T19:21:54.607Z,1534792914.607 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:21:54.607Z,1534792914.607 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-20T19:21:54.930Z,1534792914.930 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:21:54.931Z,1534792914.931 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:21:54.931Z,1534792914.931 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:21:54.931Z,1534792914.931 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:21:59.133Z,1534792919.133 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:21:59.204Z,1534792919.204 [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 &
2018-08-20T19:21:59.205Z,1534792919.205 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:21:59.206Z,1534792919.206 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:21:59.207Z,1534792919.207 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:22:44.969Z,1534792964.969 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 31 sticks.
2018-08-20T19:22:44.969Z,1534792964.969 [BPC1] Data Fault, FailCount= 1
2018-08-20T19:22:44.969Z,1534792964.969 [BPC1](ERROR): Data Fault
2018-08-20T19:22:45.025Z,1534792965.025 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-20T19:22:46.232Z,1534792966.232 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-20T19:22:46.232Z,1534792966.232 [BPC1] No Fault, FailCount= 1
2018-08-20T19:22:52.853Z,1534792972.853 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:22:52.854Z,1534792972.854 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-08-20T19:22:52.854Z,1534792972.854 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:22:52.869Z,1534792972.869 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:22:53.037Z,1534792973.037 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:22:53.037Z,1534792973.037 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-08-20T19:22:53.368Z,1534792973.368 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:22:53.368Z,1534792973.368 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:22:53.369Z,1534792973.369 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:23:05.700Z,1534792985.700 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T19:23:05.700Z,1534792985.700 [NAL9602] Data Fault, FailCount= 2
2018-08-20T19:23:05.700Z,1534792985.700 [NAL9602](ERROR): Data Fault
2018-08-20T19:23:05.789Z,1534792985.789 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T19:23:06.100Z,1534792986.100 [NAL9602](INFO): Powering down
2018-08-20T19:23:06.993Z,1534792986.993 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T19:23:06.993Z,1534792986.993 [NAL9602] No Fault, FailCount= 2
2018-08-20T19:23:36.500Z,1534793016.500 [NAL9602](INFO): Powering up NAL9602
2018-08-20T19:23:47.700Z,1534793027.700 [NAL9602](INFO): NAL9602 initialized
2018-08-20T19:23:55.658Z,1534793035.658 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:23:55.659Z,1534793035.659 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T19:23:55.659Z,1534793035.659 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:23:55.746Z,1534793035.746 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:23:56.062Z,1534793036.062 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:23:56.312Z,1534793036.312 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:23:57.118Z,1534793037.118 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-08-20T19:23:57.118Z,1534793037.118 [Rowe_600LCM] Hardware Fault, FailCount= 1
2018-08-20T19:23:57.118Z,1534793037.118 [Rowe_600LCM](ERROR): Hardware Fault
2018-08-20T19:23:57.723Z,1534793037.723 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:23:57.723Z,1534793037.723 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T19:23:57.926Z,1534793037.926 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:23:57.927Z,1534793037.927 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:23:57.927Z,1534793037.927 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:23:57.927Z,1534793037.927 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:24:02.072Z,1534793042.072 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:24:02.181Z,1534793042.181 [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 &
2018-08-20T19:24:02.182Z,1534793042.182 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:24:02.183Z,1534793042.183 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:24:02.183Z,1534793042.183 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:25:49.303Z,1534793149.303 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T19:25:49.303Z,1534793149.303 [Default:CheckIn:C.Wait] Stopped
2018-08-20T19:25:49.303Z,1534793149.303 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T19:25:49.304Z,1534793149.304 [Default:CheckIn:D] Running Loop=1
2018-08-20T19:25:49.701Z,1534793149.701 [Default:CheckIn:D] Stopped
2018-08-20T19:25:49.702Z,1534793149.702 [Default:CheckIn:E] Running Loop=1
2018-08-20T19:25:50.097Z,1534793150.097 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.790869 min 
2018-08-20T19:25:50.097Z,1534793150.097 [Default:CheckIn:E] Stopped
2018-08-20T19:25:50.098Z,1534793150.098 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T19:25:50.098Z,1534793150.098 [Default:CheckIn] Stopped
2018-08-20T19:25:50.098Z,1534793150.098 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T19:25:50.098Z,1534793150.098 [Default:CheckIn](INFO): Running loop #3
2018-08-20T19:25:50.098Z,1534793150.098 [Default:CheckIn] Running Loop=3
2018-08-20T19:25:50.099Z,1534793150.099 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T19:25:50.099Z,1534793150.099 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T19:25:54.457Z,1534793154.457 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:25:54.457Z,1534793154.457 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-08-20T19:25:54.458Z,1534793154.458 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:25:54.494Z,1534793154.494 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:25:54.645Z,1534793154.645 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:25:54.645Z,1534793154.645 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-08-20T19:25:54.972Z,1534793154.972 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:25:54.973Z,1534793154.973 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:25:54.973Z,1534793154.973 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:25:58.662Z,1534793158.662 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:25:58.662Z,1534793158.662 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-20T19:25:58.662Z,1534793158.662 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:25:58.980Z,1534793158.980 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:25:59.066Z,1534793159.066 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:25:59.174Z,1534793159.174 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:26:00.595Z,1534793160.595 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:26:00.595Z,1534793160.595 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-20T19:26:00.790Z,1534793160.790 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:26:00.791Z,1534793160.791 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:26:00.791Z,1534793160.791 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:26:00.791Z,1534793160.791 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:26:04.984Z,1534793164.984 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:26:05.113Z,1534793165.113 [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 &
2018-08-20T19:26:05.114Z,1534793165.114 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:26:05.115Z,1534793165.115 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:26:05.115Z,1534793165.115 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:28:01.354Z,1534793281.354 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:28:01.354Z,1534793281.354 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-20T19:28:01.354Z,1534793281.354 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:28:01.722Z,1534793281.722 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:28:01.758Z,1534793281.758 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:28:01.897Z,1534793281.897 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:28:02.705Z,1534793282.705 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-08-20T19:28:02.705Z,1534793282.705 [Rowe_600LCM] Hardware Fault, FailCount= 3
2018-08-20T19:28:02.705Z,1534793282.705 [Rowe_600LCM](ERROR): Hardware Fault
2018-08-20T19:28:03.344Z,1534793283.344 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:28:03.344Z,1534793283.344 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-20T19:28:03.514Z,1534793283.514 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:28:03.515Z,1534793283.515 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:28:03.515Z,1534793283.515 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:28:03.515Z,1534793283.515 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:28:07.676Z,1534793287.676 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:28:07.821Z,1534793287.821 [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 &
2018-08-20T19:28:07.822Z,1534793287.822 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:28:07.823Z,1534793287.823 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:28:07.823Z,1534793287.823 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:28:55.284Z,1534793335.284 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:28:55.284Z,1534793335.284 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-08-20T19:28:55.284Z,1534793335.284 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:28:55.316Z,1534793335.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:28:55.692Z,1534793335.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:28:55.692Z,1534793335.692 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-08-20T19:28:56.087Z,1534793336.087 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:28:56.088Z,1534793336.088 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:28:56.088Z,1534793336.088 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:30:03.566Z,1534793403.566 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:30:03.566Z,1534793403.566 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-20T19:30:03.566Z,1534793403.566 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:30:03.682Z,1534793403.682 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:30:03.970Z,1534793403.970 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:30:04.214Z,1534793404.214 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:30:05.655Z,1534793405.655 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:30:05.655Z,1534793405.655 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-20T19:30:05.830Z,1534793405.830 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:30:05.831Z,1534793405.831 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:30:05.831Z,1534793405.831 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:30:05.831Z,1534793405.831 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:30:09.954Z,1534793409.954 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:30:10.073Z,1534793410.073 [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 &
2018-08-20T19:30:10.074Z,1534793410.074 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:30:10.074Z,1534793410.074 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:30:10.075Z,1534793410.075 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:30:50.452Z,1534793450.452 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T19:25:50.1Z
2018-08-20T19:30:50.452Z,1534793450.452 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T19:30:50.453Z,1534793450.453 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T19:30:55.758Z,1534793455.758 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T190131/Courier0010.lzma
2018-08-20T19:30:56.545Z,1534793456.545 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Courier0010.lzma.bak
2018-08-20T19:30:56.545Z,1534793456.545 [DataOverHttps](INFO): SBD MOMSN=8430367
2018-08-20T19:31:06.139Z,1534793466.139 [DataOverHttps](INFO): Sending 336 bytes from file Logs/20180820T190131/Express0011.lzma
2018-08-20T19:31:06.905Z,1534793466.905 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Express0011.lzma.bak
2018-08-20T19:31:06.905Z,1534793466.905 [DataOverHttps](INFO): SBD MOMSN=8430369
2018-08-20T19:31:07.721Z,1534793467.721 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T19:31:07.721Z,1534793467.721 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T19:31:07.721Z,1534793467.721 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T19:31:56.191Z,1534793516.191 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:31:56.191Z,1534793516.191 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-08-20T19:31:56.191Z,1534793516.191 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:31:56.219Z,1534793516.219 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:31:56.647Z,1534793516.647 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:31:56.647Z,1534793516.647 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-08-20T19:31:56.999Z,1534793516.999 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:31:56.999Z,1534793516.999 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:31:56.000Z,1534793517.000 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:32:06.526Z,1534793526.526 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:32:06.526Z,1534793526.526 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-20T19:32:06.526Z,1534793526.526 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:32:06.660Z,1534793526.660 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:32:06.661Z,1534793526.661 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-20T19:32:06.931Z,1534793526.931 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:32:07.216Z,1534793527.216 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:33:49.324Z,1534793629.324 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T19:33:49.324Z,1534793629.324 [NAL9602] Data Fault, FailCount= 3
2018-08-20T19:33:49.324Z,1534793629.324 [NAL9602](ERROR): Data Fault
2018-08-20T19:33:49.423Z,1534793629.423 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T19:33:49.720Z,1534793629.720 [NAL9602](INFO): Powering down
2018-08-20T19:33:50.575Z,1534793630.575 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T19:33:50.575Z,1534793630.575 [NAL9602] No Fault, FailCount= 3
2018-08-20T19:34:20.112Z,1534793660.112 [NAL9602](INFO): Powering up NAL9602
2018-08-20T19:34:31.316Z,1534793671.316 [NAL9602](INFO): NAL9602 initialized
2018-08-20T19:34:57.368Z,1534793697.368 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:34:57.368Z,1534793697.368 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-08-20T19:34:57.368Z,1534793697.368 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:34:57.397Z,1534793697.397 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:34:57.776Z,1534793697.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:34:57.776Z,1534793697.776 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-08-20T19:34:58.187Z,1534793698.187 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:34:58.187Z,1534793698.187 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:34:58.188Z,1534793698.188 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:36:08.206Z,1534793768.206 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T19:36:08.207Z,1534793768.207 [Default:CheckIn:C.Wait] Stopped
2018-08-20T19:36:08.207Z,1534793768.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T19:36:08.207Z,1534793768.207 [Default:CheckIn:D] Running Loop=1
2018-08-20T19:36:08.606Z,1534793768.606 [Default:CheckIn:D] Stopped
2018-08-20T19:36:08.607Z,1534793768.607 [Default:CheckIn:E] Running Loop=1
2018-08-20T19:36:09.016Z,1534793769.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.105953 min 
2018-08-20T19:36:09.016Z,1534793769.016 [Default:CheckIn:E] Stopped
2018-08-20T19:36:09.016Z,1534793769.016 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T19:36:09.017Z,1534793769.017 [Default:CheckIn] Stopped
2018-08-20T19:36:09.017Z,1534793769.017 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T19:36:09.017Z,1534793769.017 [Default:CheckIn](INFO): Running loop #4
2018-08-20T19:36:09.017Z,1534793769.017 [Default:CheckIn] Running Loop=4
2018-08-20T19:36:09.017Z,1534793769.017 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T19:36:09.017Z,1534793769.017 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T19:37:58.216Z,1534793878.216 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:37:58.216Z,1534793878.216 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-08-20T19:37:58.216Z,1534793878.216 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:37:58.230Z,1534793878.230 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:37:58.642Z,1534793878.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:37:58.642Z,1534793878.642 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-08-20T19:37:59.003Z,1534793879.003 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:37:59.004Z,1534793879.004 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:37:59.004Z,1534793879.004 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:40:59.104Z,1534794059.104 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:40:59.104Z,1534794059.104 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-08-20T19:40:59.104Z,1534794059.104 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:40:59.142Z,1534794059.142 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:40:59.542Z,1534794059.542 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:40:59.543Z,1534794059.543 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-08-20T19:40:59.909Z,1534794059.909 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:40:59.909Z,1534794059.909 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:40:59.910Z,1534794059.910 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:41:09.100Z,1534794069.100 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T19:36:09.0Z
2018-08-20T19:41:09.100Z,1534794069.100 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T19:41:09.100Z,1534794069.100 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T19:41:12.978Z,1534794072.978 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T190131/Courier0013.lzma
2018-08-20T19:41:13.752Z,1534794073.752 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Courier0013.lzma.bak
2018-08-20T19:41:13.753Z,1534794073.753 [DataOverHttps](INFO): SBD MOMSN=8430380
2018-08-20T19:41:22.629Z,1534794082.629 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20180820T190131/Express0014.lzma
2018-08-20T19:41:23.420Z,1534794083.420 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Express0014.lzma.bak
2018-08-20T19:41:23.421Z,1534794083.421 [DataOverHttps](INFO): SBD MOMSN=8430382
2018-08-20T19:41:24.197Z,1534794084.197 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T19:41:24.197Z,1534794084.197 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T19:41:24.197Z,1534794084.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T19:42:06.692Z,1534794126.692 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:42:06.692Z,1534794126.692 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-20T19:42:07.018Z,1534794127.018 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:42:07.018Z,1534794127.018 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:42:07.019Z,1534794127.019 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:42:07.019Z,1534794127.019 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:42:11.240Z,1534794131.240 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:42:11.310Z,1534794131.310 [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 &
2018-08-20T19:42:11.311Z,1534794131.311 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:42:11.312Z,1534794131.312 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:42:11.313Z,1534794131.313 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:43:47.073Z,1534794227.073 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 15 sticks.
2018-08-20T19:43:47.073Z,1534794227.073 [BPC1] Data Fault, FailCount= 2
2018-08-20T19:43:47.073Z,1534794227.073 [BPC1](ERROR): Data Fault
2018-08-20T19:43:47.142Z,1534794227.142 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-20T19:43:48.307Z,1534794228.307 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-20T19:43:48.307Z,1534794228.307 [BPC1] No Fault, FailCount= 2
2018-08-20T19:44:00.295Z,1534794240.295 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:44:00.295Z,1534794240.295 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-08-20T19:44:00.296Z,1534794240.296 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:44:00.332Z,1534794240.332 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:44:00.700Z,1534794240.700 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:44:00.700Z,1534794240.700 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-08-20T19:44:01.069Z,1534794241.069 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:44:01.075Z,1534794241.075 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:44:01.076Z,1534794241.076 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:44:07.806Z,1534794247.806 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:44:07.806Z,1534794247.806 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T19:44:07.806Z,1534794247.806 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:44:07.934Z,1534794247.934 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:44:08.222Z,1534794248.222 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:44:08.478Z,1534794248.478 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:44:09.923Z,1534794249.923 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:44:09.923Z,1534794249.923 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T19:44:10.094Z,1534794250.094 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:44:10.095Z,1534794250.095 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:44:10.095Z,1534794250.095 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:44:10.095Z,1534794250.095 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:44:14.236Z,1534794254.236 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:44:14.418Z,1534794254.418 [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 &
2018-08-20T19:44:14.419Z,1534794254.419 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:44:14.420Z,1534794254.420 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:44:14.421Z,1534794254.421 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:44:33.788Z,1534794273.788 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T19:44:33.788Z,1534794273.788 [NAL9602] Data Fault, FailCount= 4
2018-08-20T19:44:33.788Z,1534794273.788 [NAL9602](ERROR): Data Fault
2018-08-20T19:44:33.902Z,1534794273.902 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T19:44:34.184Z,1534794274.184 [NAL9602](INFO): Powering down
2018-08-20T19:44:35.073Z,1534794275.073 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T19:44:35.073Z,1534794275.073 [NAL9602] No Fault, FailCount= 4
2018-08-20T19:45:04.584Z,1534794304.584 [NAL9602](INFO): Powering up NAL9602
2018-08-20T19:45:15.388Z,1534794315.388 [NAL9602](INFO): NAL9602 initialized
2018-08-20T19:46:10.122Z,1534794370.122 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:46:10.122Z,1534794370.122 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-20T19:46:10.122Z,1534794370.122 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:46:10.253Z,1534794370.253 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:46:10.542Z,1534794370.542 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:46:10.796Z,1534794370.796 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:46:12.224Z,1534794372.224 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:46:12.224Z,1534794372.224 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-20T19:46:12.410Z,1534794372.410 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:46:12.411Z,1534794372.411 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:46:12.411Z,1534794372.411 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:46:12.411Z,1534794372.411 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:46:16.549Z,1534794376.549 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:46:16.716Z,1534794376.716 [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 &
2018-08-20T19:46:16.717Z,1534794376.717 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:46:16.718Z,1534794376.718 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:46:16.719Z,1534794376.719 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:46:24.605Z,1534794384.605 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T19:46:24.605Z,1534794384.605 [Default:CheckIn:C.Wait] Stopped
2018-08-20T19:46:24.605Z,1534794384.605 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T19:46:24.610Z,1534794384.610 [Default:CheckIn:D] Running Loop=1
2018-08-20T19:46:25.023Z,1534794385.023 [Default:CheckIn:D] Stopped
2018-08-20T19:46:25.023Z,1534794385.023 [Default:CheckIn:E] Running Loop=1
2018-08-20T19:46:25.414Z,1534794385.414 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.379557 min 
2018-08-20T19:46:25.415Z,1534794385.415 [Default:CheckIn:E] Stopped
2018-08-20T19:46:25.415Z,1534794385.415 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T19:46:25.415Z,1534794385.415 [Default:CheckIn] Stopped
2018-08-20T19:46:25.415Z,1534794385.415 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T19:46:25.415Z,1534794385.415 [Default:CheckIn](INFO): Running loop #5
2018-08-20T19:46:25.415Z,1534794385.415 [Default:CheckIn] Running Loop=5
2018-08-20T19:46:25.416Z,1534794385.416 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T19:46:25.416Z,1534794385.416 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T19:47:01.360Z,1534794421.360 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:47:01.360Z,1534794421.360 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-08-20T19:47:01.360Z,1534794421.360 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:47:01.376Z,1534794421.376 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:47:01.795Z,1534794421.795 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:47:01.795Z,1534794421.795 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-08-20T19:47:02.168Z,1534794422.168 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:47:02.169Z,1534794422.169 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:47:02.169Z,1534794422.169 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:48:12.454Z,1534794492.454 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:48:12.454Z,1534794492.454 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-08-20T19:48:12.454Z,1534794492.454 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:48:12.549Z,1534794492.549 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:48:12.858Z,1534794492.858 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:48:13.105Z,1534794493.105 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:48:14.550Z,1534794494.550 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:48:14.550Z,1534794494.550 [Rowe_600LCM] No Fault, FailCount= 3
2018-08-20T19:48:14.718Z,1534794494.718 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:48:14.718Z,1534794494.718 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:48:14.719Z,1534794494.719 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:48:14.719Z,1534794494.719 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:48:18.845Z,1534794498.845 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:48:19.013Z,1534794499.013 [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 &
2018-08-20T19:48:19.014Z,1534794499.014 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:48:19.015Z,1534794499.015 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:48:19.015Z,1534794499.015 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:50:02.438Z,1534794602.438 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:50:02.438Z,1534794602.438 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-08-20T19:50:02.438Z,1534794602.438 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:50:02.462Z,1534794602.462 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:50:02.864Z,1534794602.864 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:50:02.864Z,1534794602.864 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-08-20T19:50:03.249Z,1534794603.249 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:50:03.249Z,1534794603.249 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:50:03.250Z,1534794603.250 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:50:15.330Z,1534794615.330 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:50:15.330Z,1534794615.330 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-08-20T19:50:15.330Z,1534794615.330 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:50:15.658Z,1534794615.658 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:50:15.734Z,1534794615.734 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:50:15.847Z,1534794615.847 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:50:17.263Z,1534794617.263 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T19:50:17.263Z,1534794617.263 [Rowe_600LCM] No Fault, FailCount= 4
2018-08-20T19:50:17.462Z,1534794617.462 [Rowe_600LCM](INFO): Initializing
2018-08-20T19:50:17.463Z,1534794617.463 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T19:50:17.463Z,1534794617.463 [Rowe_600LCM](INFO): LCM OK
2018-08-20T19:50:17.463Z,1534794617.463 [Rowe_600LCM](INFO): Powering up
2018-08-20T19:50:21.648Z,1534794621.648 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T19:50:21.781Z,1534794621.781 [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 &
2018-08-20T19:50:21.782Z,1534794621.782 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T19:50:21.783Z,1534794621.783 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T19:50:21.783Z,1534794621.783 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T19:51:25.525Z,1534794685.525 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-20T19:46:25.4Z
2018-08-20T19:51:25.525Z,1534794685.525 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T19:51:25.525Z,1534794685.525 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T19:51:30.610Z,1534794690.610 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180820T190131/Courier0016.lzma
2018-08-20T19:51:31.393Z,1534794691.393 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Courier0016.lzma.bak
2018-08-20T19:51:31.393Z,1534794691.393 [DataOverHttps](INFO): SBD MOMSN=8430388
2018-08-20T19:51:41.187Z,1534794701.187 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20180820T190131/Express0017.lzma
2018-08-20T19:51:41.998Z,1534794701.998 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Express0017.lzma.bak
2018-08-20T19:51:41.999Z,1534794701.999 [DataOverHttps](INFO): SBD MOMSN=8430390
2018-08-20T19:51:42.787Z,1534794702.787 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T19:51:42.787Z,1534794702.787 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T19:51:42.787Z,1534794702.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T19:52:18.038Z,1534794738.038 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T19:52:18.038Z,1534794738.038 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-08-20T19:52:18.038Z,1534794738.038 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T19:52:18.137Z,1534794738.137 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T19:52:18.139Z,1534794738.139 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-08-20T19:52:18.442Z,1534794738.442 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T19:52:18.665Z,1534794738.665 [Rowe_600LCM](INFO): Powering down
2018-08-20T19:52:21.548Z,1534794741.548 [CommandLine](IMPORTANT): got command burn 5.000000
2018-08-20T19:52:26.562Z,1534794746.562 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-08-20T19:53:03.287Z,1534794783.287 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:53:03.288Z,1534794783.288 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-08-20T19:53:03.288Z,1534794783.288 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:53:03.335Z,1534794783.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:53:03.727Z,1534794783.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:53:03.727Z,1534794783.727 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-08-20T19:53:04.114Z,1534794784.114 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:53:04.115Z,1534794784.115 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:53:04.115Z,1534794784.115 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:55:16.899Z,1534794916.899 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-08-20T19:55:16.899Z,1534794916.899 [NAL9602] Data Fault, FailCount= 5
2018-08-20T19:55:16.900Z,1534794916.900 [NAL9602](ERROR): Data Fault
2018-08-20T19:55:16.966Z,1534794916.966 [CBIT](ERROR): Data Fault in component: NAL9602
2018-08-20T19:55:16.967Z,1534794916.967 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-08-20T19:55:17.291Z,1534794917.291 [NAL9602](INFO): Powering down
2018-08-20T19:55:17.372Z,1534794917.372 [CommandLine](FAULT): Scheduling is paused
2018-08-20T19:55:17.372Z,1534794917.372 [CBIT](INFO): Critical error at 20180820T195516
2018-08-20T19:55:17.373Z,1534794917.373 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-08-20T19:56:04.166Z,1534794964.166 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:56:04.166Z,1534794964.166 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-08-20T19:56:04.166Z,1534794964.166 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:56:04.188Z,1534794964.188 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:56:04.582Z,1534794964.582 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:56:04.582Z,1534794964.582 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-08-20T19:56:04.961Z,1534794964.961 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:56:04.966Z,1534794964.966 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:56:04.966Z,1534794964.966 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T19:56:42.959Z,1534795002.959 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-08-20T19:56:42.959Z,1534795002.959 [Default:CheckIn:C.Wait] Stopped
2018-08-20T19:56:42.959Z,1534795002.959 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T19:56:42.959Z,1534795002.959 [Default:CheckIn:D] Running Loop=1
2018-08-20T19:56:43.358Z,1534795003.358 [Default:CheckIn:D] Stopped
2018-08-20T19:56:43.358Z,1534795003.358 [Default:CheckIn:E] Running Loop=1
2018-08-20T19:56:43.761Z,1534795003.761 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.685148 min 
2018-08-20T19:56:43.761Z,1534795003.761 [Default:CheckIn:E] Stopped
2018-08-20T19:56:43.761Z,1534795003.761 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-08-20T19:56:43.762Z,1534795003.762 [Default:CheckIn] Stopped
2018-08-20T19:56:43.763Z,1534795003.763 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T19:56:43.763Z,1534795003.763 [Default:CheckIn](INFO): Running loop #6
2018-08-20T19:56:43.763Z,1534795003.763 [Default:CheckIn] Running Loop=6
2018-08-20T19:56:43.763Z,1534795003.763 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-20T19:56:43.763Z,1534795003.763 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-20T19:57:16.943Z,1534795036.943 [CBIT](INFO): Clearing failed state for component NAL9602
2018-08-20T19:57:16.943Z,1534795036.943 [NAL9602] No Fault, FailCount= 5
2018-08-20T19:57:17.288Z,1534795037.288 [NAL9602](INFO): Powering up NAL9602
2018-08-20T19:57:28.488Z,1534795048.488 [NAL9602](INFO): NAL9602 initialized
2018-08-20T19:59:05.353Z,1534795145.353 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-20T19:59:05.353Z,1534795145.353 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-08-20T19:59:05.353Z,1534795145.353 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-20T19:59:05.367Z,1534795145.367 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-20T19:59:05.766Z,1534795145.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-20T19:59:05.766Z,1534795145.766 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-08-20T19:59:06.149Z,1534795146.149 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-20T19:59:06.150Z,1534795146.150 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-20T19:59:06.150Z,1534795146.150 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-20T20:01:28.601Z,1534795288.601 [NAL9602](INFO): GPS fix at 20180820T200127: (36.802361, -121.787443)
2018-08-20T20:01:28.680Z,1534795288.680 [Default:CheckIn:Read_GPS] Stopped
2018-08-20T20:01:28.681Z,1534795288.681 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-20T20:01:33.247Z,1534795293.247 [CommandLine](IMPORTANT): got command ibit
2018-08-20T20:01:35.027Z,1534795295.027 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-08-20T20:01:35.027Z,1534795295.027 [IBIT](IMPORTANT): Beginning control surface checks.
2018-08-20T20:01:35.031Z,1534795295.031 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-20T20:01:35.060Z,1534795295.060 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20180820T190131/Courier0019.lzma
2018-08-20T20:01:35.840Z,1534795295.840 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Courier0019.lzma.bak
2018-08-20T20:01:35.840Z,1534795295.840 [DataOverHttps](INFO): SBD MOMSN=8430399
2018-08-20T20:01:35.852Z,1534795295.852 [NAL9602](INFO): GPS fix at 20180820T200134: (36.802365, -121.787459)
2018-08-20T20:01:41.296Z,1534795301.296 [NAL9602](INFO): GPS fix at 20180820T200139: (36.802361, -121.787442)
2018-08-20T20:01:45.607Z,1534795305.607 [DataOverHttps](INFO): Sending 452 bytes from file Logs/20180820T190131/Express0020.lzma
2018-08-20T20:01:46.401Z,1534795306.401 [DataOverHttps](INFO): Moved sent file to Logs/20180820T190131/Express0020.lzma.bak
2018-08-20T20:01:46.401Z,1534795306.401 [DataOverHttps](INFO): SBD MOMSN=8430403
2018-08-20T20:01:46.412Z,1534795306.412 [NAL9602](INFO): GPS fix at 20180820T200145: (36.802353, -121.787425)
2018-08-20T20:01:53.422Z,1534795313.422 [NAL9602](INFO): GPS fix at 20180820T200151: (36.802350, -121.787419)
2018-08-20T20:01:54.859Z,1534795314.859 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006180
CHAN A1 (24V): 4.657929
CHAN A2 (12V): -0.002975
CHAN A3 (5V): -0.001533
CHAN B0 (3.3V): -0.000416
CHAN B1 (3.15aV): 0.000455
CHAN B2 (3.15bV): -0.000167
CHAN B3 (GND): 0.000073
OPEN: -0.000457
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-20T20:02:00.004Z,1534795320.004 [NAL9602](INFO): GPS fix at 20180820T200158: (36.802343, -121.787400)
2018-08-20T20:02:05.551Z,1534795325.551 [NAL9602](INFO): GPS fix at 20180820T200204: (36.802321, -121.787304)
2018-08-20T20:02:11.977Z,1534795331.977 [NAL9602](INFO): GPS fix at 20180820T200210: (36.802338, -121.787219)
2018-08-20T20:02:17.988Z,1534795337.988 [NAL9602](INFO): GPS fix at 20180820T200216: (36.802338, -121.787205)
2018-08-20T20:02:18.106Z,1534795338.106 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T20:02:18.106Z,1534795338.106 [Rowe_600LCM] No Fault, FailCount= 5
2018-08-20T20:02:18.118Z,1534795338.118 [Rowe_600LCM](INFO): Initializing
2018-08-20T20:02:18.118Z,1534795338.118 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T20:02:18.119Z,1534795338.119 [Rowe_600LCM](INFO): LCM OK
2018-08-20T20:02:18.119Z,1534795338.119 [Rowe_600LCM](INFO): Powering up
2018-08-20T20:02:22.553Z,1534795342.553 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T20:02:22.757Z,1534795342.757 [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 &
2018-08-20T20:02:22.758Z,1534795342.758 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T20:02:22.766Z,1534795342.766 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T20:02:22.767Z,1534795342.767 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T20:02:27.595Z,1534795347.595 [NAL9602](INFO): GPS fix at 20180820T200226: (36.802365, -121.787155)
2018-08-20T20:02:34.059Z,1534795354.059 [NAL9602](INFO): GPS fix at 20180820T200232: (36.802384, -121.787118)
2018-08-20T20:02:34.115Z,1534795354.115 [CommandLine](INFO): End of History
2018-08-20T20:02:34.222Z,1534795354.222 [CommandLine](INFO): End of History
2018-08-20T20:02:34.330Z,1534795354.330 [CommandLine](INFO): End of History
2018-08-20T20:02:34.436Z,1534795354.436 [CommandLine](INFO): End of History
2018-08-20T20:02:34.535Z,1534795354.535 [CommandLine](INFO): End of History
2018-08-20T20:02:34.600Z,1534795354.600 [CommandLine](INFO): End of History
2018-08-20T20:02:34.668Z,1534795354.668 [CommandLine](INFO): End of History
2018-08-20T20:02:34.732Z,1534795354.732 [CommandLine](INFO): End of History
2018-08-20T20:02:34.802Z,1534795354.802 [CommandLine](INFO): End of History
2018-08-20T20:02:34.872Z,1534795354.872 [CommandLine](INFO): End of History
2018-08-20T20:02:34.935Z,1534795354.935 [CommandLine](INFO): End of History
2018-08-20T20:02:41.224Z,1534795361.224 [NAL9602](INFO): GPS fix at 20180820T200239: (36.802406, -121.787100)
2018-08-20T20:02:47.792Z,1534795367.792 [NAL9602](INFO): GPS fix at 20180820T200246: (36.802415, -121.787085)
2018-08-20T20:02:54.299Z,1534795374.299 [NAL9602](INFO): GPS fix at 20180820T200252: (36.802430, -121.787086)
2018-08-20T20:03:00.856Z,1534795380.856 [NAL9602](INFO): GPS fix at 20180820T200259: (36.802454, -121.787080)
2018-08-20T20:03:07.370Z,1534795387.370 [NAL9602](INFO): GPS fix at 20180820T200305: (36.802468, -121.787083)
2018-08-20T20:03:13.948Z,1534795393.948 [NAL9602](INFO): GPS fix at 20180820T200312: (36.802493, -121.787065)
2018-08-20T20:03:20.457Z,1534795400.457 [NAL9602](INFO): GPS fix at 20180820T200318: (36.802523, -121.787023)
2018-08-20T20:03:27.084Z,1534795407.084 [NAL9602](INFO): GPS fix at 20180820T200325: (36.802556, -121.786999)
2018-08-20T20:03:32.037Z,1534795412.037 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2018-08-20T20:03:32.559Z,1534795412.559 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2018-08-20T20:03:33.023Z,1534795413.023 [NAL9602](INFO): GPS fix at 20180820T200331: (36.802611, -121.786967)
2018-08-20T20:03:33.151Z,1534795413.151 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-08-20T20:03:33.152Z,1534795413.152 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-08-20T20:03:33.152Z,1534795413.152 [IBIT](IMPORTANT): Pressure:12.048186 PSI
2018-08-20T20:03:33.153Z,1534795413.153 [IBIT](IMPORTANT): Humidity:15.468197 %
2018-08-20T20:03:33.786Z,1534795413.786 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-08-20T20:03:33.790Z,1534795413.790 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2018-08-20T20:03:33.791Z,1534795413.791 [IBIT](IMPORTANT): massDefault: -0.075000 cm
2018-08-20T20:03:33.791Z,1534795413.791 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2018-08-20T20:03:33.791Z,1534795413.791 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-08-20T20:03:33.792Z,1534795413.792 [IBIT](IMPORTANT): IBIT FAILED
2018-08-20T20:03:34.400Z,1534795414.400 [Default:CheckIn:Read_Iridium] Stopped
2018-08-20T20:03:34.400Z,1534795414.400 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-20T20:03:34.400Z,1534795414.400 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-20T20:03:47.615Z,1534795427.615 [CommandLine](IMPORTANT): got command ibit
2018-08-20T20:03:47.811Z,1534795427.811 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-08-20T20:03:47.811Z,1534795427.811 [IBIT](IMPORTANT): Beginning control surface checks.
2018-08-20T20:03:47.819Z,1534795427.819 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-20T20:03:49.333Z,1534795429.333 [NAL9602](INFO): GPS fix at 20180820T200347: (36.802620, -121.786970)
2018-08-20T20:03:52.524Z,1534795432.524 [NAL9602](INFO): GPS fix at 20180820T200351: (36.802634, -121.786965)
2018-08-20T20:03:55.338Z,1534795435.338 [NAL9602](INFO): GPS fix at 20180820T200353: (36.802652, -121.786955)
2018-08-20T20:03:58.523Z,1534795438.523 [NAL9602](INFO): GPS fix at 20180820T200357: (36.802696, -121.786945)
2018-08-20T20:03:58.646Z,1534795438.646 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006657
CHAN A1 (24V): 4.657929
CHAN A2 (12V): -0.001820
CHAN A3 (5V): -0.001401
CHAN B0 (3.3V): -0.000009
CHAN B1 (3.15aV): -0.000049
CHAN B2 (3.15bV): 0.000964
CHAN B3 (GND): -0.000445
OPEN: -0.000553
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-20T20:04:01.328Z,1534795441.328 [NAL9602](INFO): GPS fix at 20180820T200359: (36.802719, -121.786940)
2018-08-20T20:04:04.529Z,1534795444.529 [NAL9602](INFO): GPS fix at 20180820T200403: (36.802754, -121.786942)
2018-08-20T20:04:07.337Z,1534795447.337 [NAL9602](INFO): GPS fix at 20180820T200405: (36.802757, -121.786945)
2018-08-20T20:04:10.533Z,1534795450.533 [NAL9602](INFO): GPS fix at 20180820T200409: (36.802760, -121.786946)
2018-08-20T20:04:13.324Z,1534795453.324 [NAL9602](INFO): GPS fix at 20180820T200411: (36.802760, -121.786945)
2018-08-20T20:04:16.530Z,1534795456.530 [NAL9602](INFO): GPS fix at 20180820T200415: (36.802760, -121.786941)
2018-08-20T20:04:18.506Z,1534795458.506 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T20:04:18.506Z,1534795458.506 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-08-20T20:04:18.506Z,1534795458.506 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T20:04:18.661Z,1534795458.661 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T20:04:18.920Z,1534795458.920 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T20:04:19.194Z,1534795459.194 [Rowe_600LCM](INFO): Powering down
2018-08-20T20:04:19.324Z,1534795459.324 [NAL9602](INFO): GPS fix at 20180820T200417: (36.802760, -121.786938)
2018-08-20T20:04:20.687Z,1534795460.687 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T20:04:20.687Z,1534795460.687 [Rowe_600LCM] No Fault, FailCount= 1
2018-08-20T20:04:20.810Z,1534795460.810 [Rowe_600LCM](INFO): Initializing
2018-08-20T20:04:20.811Z,1534795460.811 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T20:04:20.812Z,1534795460.812 [Rowe_600LCM](INFO): LCM OK
2018-08-20T20:04:20.812Z,1534795460.812 [Rowe_600LCM](INFO): Powering up
2018-08-20T20:04:22.523Z,1534795462.523 [NAL9602](INFO): GPS fix at 20180820T200421: (36.802760, -121.786938)
2018-08-20T20:04:25.034Z,1534795465.034 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T20:04:25.165Z,1534795465.165 [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 &
2018-08-20T20:04:25.166Z,1534795465.166 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T20:04:25.167Z,1534795465.167 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T20:04:25.167Z,1534795465.167 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T20:04:25.331Z,1534795465.331 [NAL9602](INFO): GPS fix at 20180820T200423: (36.802759, -121.786939)
2018-08-20T20:04:28.528Z,1534795468.528 [NAL9602](INFO): GPS fix at 20180820T200427: (36.802757, -121.786942)
2018-08-20T20:04:31.327Z,1534795471.327 [NAL9602](INFO): GPS fix at 20180820T200429: (36.802756, -121.786946)
2018-08-20T20:04:34.525Z,1534795474.525 [NAL9602](INFO): GPS fix at 20180820T200433: (36.802755, -121.786953)
2018-08-20T20:04:39.520Z,1534795479.520 [NAL9602](INFO): GPS fix at 20180820T200438: (36.802754, -121.786957)
2018-08-20T20:04:42.321Z,1534795482.321 [NAL9602](INFO): GPS fix at 20180820T200440: (36.802755, -121.786959)
2018-08-20T20:04:45.518Z,1534795485.518 [NAL9602](INFO): GPS fix at 20180820T200444: (36.802756, -121.786959)
2018-08-20T20:04:48.316Z,1534795488.316 [NAL9602](INFO): GPS fix at 20180820T200446: (36.802757, -121.786957)
2018-08-20T20:04:49.140Z,1534795489.140 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 15 sticks.
2018-08-20T20:04:49.140Z,1534795489.140 [BPC1] Data Fault, FailCount= 3
2018-08-20T20:04:49.140Z,1534795489.140 [BPC1](ERROR): Data Fault
2018-08-20T20:04:49.241Z,1534795489.241 [CBIT](ERROR): Data Fault in component: BPC1
2018-08-20T20:04:50.410Z,1534795490.410 [CBIT](INFO): Clearing failed state for component BPC1
2018-08-20T20:04:50.411Z,1534795490.411 [BPC1] No Fault, FailCount= 3
2018-08-20T20:04:51.522Z,1534795491.522 [NAL9602](INFO): GPS fix at 20180820T200450: (36.802759, -121.786956)
2018-08-20T20:04:54.324Z,1534795494.324 [NAL9602](INFO): GPS fix at 20180820T200452: (36.802760, -121.786955)
2018-08-20T20:04:57.524Z,1534795497.524 [NAL9602](INFO): GPS fix at 20180820T200456: (36.802761, -121.786954)
2018-08-20T20:05:00.317Z,1534795500.317 [NAL9602](INFO): GPS fix at 20180820T200458: (36.802761, -121.786953)
2018-08-20T20:05:03.524Z,1534795503.524 [NAL9602](INFO): GPS fix at 20180820T200502: (36.802762, -121.786952)
2018-08-20T20:05:06.316Z,1534795506.316 [NAL9602](INFO): GPS fix at 20180820T200504: (36.802762, -121.786952)
2018-08-20T20:05:09.516Z,1534795509.516 [NAL9602](INFO): GPS fix at 20180820T200508: (36.802764, -121.786952)
2018-08-20T20:05:12.321Z,1534795512.321 [NAL9602](INFO): GPS fix at 20180820T200510: (36.802765, -121.786953)
2018-08-20T20:05:15.520Z,1534795515.520 [NAL9602](INFO): GPS fix at 20180820T200514: (36.802765, -121.786951)
2018-08-20T20:05:18.323Z,1534795518.323 [NAL9602](INFO): GPS fix at 20180820T200516: (36.802765, -121.786950)
2018-08-20T20:05:21.525Z,1534795521.525 [NAL9602](INFO): GPS fix at 20180820T200520: (36.802764, -121.786949)
2018-08-20T20:05:24.316Z,1534795524.316 [NAL9602](INFO): GPS fix at 20180820T200522: (36.802763, -121.786949)
2018-08-20T20:05:27.532Z,1534795527.532 [NAL9602](INFO): GPS fix at 20180820T200526: (36.802762, -121.786950)
2018-08-20T20:05:30.326Z,1534795530.326 [NAL9602](INFO): GPS fix at 20180820T200528: (36.802763, -121.786950)
2018-08-20T20:05:33.533Z,1534795533.533 [NAL9602](INFO): GPS fix at 20180820T200532: (36.802764, -121.786950)
2018-08-20T20:05:38.667Z,1534795538.667 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2018-08-20T20:05:38.855Z,1534795538.855 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2018-08-20T20:05:39.100Z,1534795539.100 [NAL9602](INFO): GPS fix at 20180820T200537: (36.802764, -121.786948)
2018-08-20T20:05:39.183Z,1534795539.183 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-08-20T20:05:39.183Z,1534795539.183 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-08-20T20:05:39.184Z,1534795539.184 [IBIT](IMPORTANT): Pressure:12.121350 PSI
2018-08-20T20:05:39.184Z,1534795539.184 [IBIT](IMPORTANT): Humidity:15.443780 %
2018-08-20T20:05:39.597Z,1534795539.597 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-08-20T20:05:39.598Z,1534795539.598 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2018-08-20T20:05:39.598Z,1534795539.598 [IBIT](IMPORTANT): massDefault: -0.075000 cm
2018-08-20T20:05:39.599Z,1534795539.599 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2018-08-20T20:05:39.600Z,1534795539.600 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-08-20T20:05:39.600Z,1534795539.600 [IBIT](IMPORTANT): IBIT FAILED
2018-08-20T20:05:59.940Z,1534795559.940 [CommandLine](IMPORTANT): got command ibit
2018-08-20T20:06:00.031Z,1534795560.031 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-08-20T20:06:00.031Z,1534795560.031 [IBIT](IMPORTANT): Beginning control surface checks.
2018-08-20T20:06:00.034Z,1534795560.034 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-20T20:06:01.497Z,1534795561.497 [NAL9602](INFO): GPS fix at 20180820T200600: (36.802764, -121.786949)
2018-08-20T20:06:04.307Z,1534795564.307 [NAL9602](INFO): GPS fix at 20180820T200602: (36.802764, -121.786952)
2018-08-20T20:06:07.497Z,1534795567.497 [NAL9602](INFO): GPS fix at 20180820T200607: (36.802762, -121.786957)
2018-08-20T20:06:10.313Z,1534795570.313 [NAL9602](INFO): GPS fix at 20180820T200609: (36.802762, -121.786958)
2018-08-20T20:06:10.809Z,1534795570.809 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006576
CHAN A1 (24V): -0.009947
CHAN A2 (12V): -0.002288
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001638
CHAN B1 (3.15aV): -0.000637
CHAN B2 (3.15bV): -0.000707
CHAN B3 (GND): 0.000468
OPEN: -0.000473
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-20T20:06:13.497Z,1534795573.497 [NAL9602](INFO): GPS fix at 20180820T200613: (36.802762, -121.786958)
2018-08-20T20:06:16.301Z,1534795576.301 [NAL9602](INFO): GPS fix at 20180820T200615: (36.802763, -121.786958)
2018-08-20T20:06:19.497Z,1534795579.497 [NAL9602](INFO): GPS fix at 20180820T200619: (36.802763, -121.786961)
2018-08-20T20:06:21.090Z,1534795581.090 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-08-20T20:06:21.090Z,1534795581.090 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-08-20T20:06:21.090Z,1534795581.090 [Rowe_600LCM](ERROR): Communications Fault
2018-08-20T20:06:21.280Z,1534795581.280 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-08-20T20:06:21.494Z,1534795581.494 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T20:06:21.992Z,1534795581.992 [Rowe_600LCM](INFO): Powering down
2018-08-20T20:06:22.292Z,1534795582.292 [NAL9602](INFO): GPS fix at 20180820T200621: (36.802763, -121.786962)
2018-08-20T20:06:22.802Z,1534795582.802 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-08-20T20:06:22.803Z,1534795582.803 [Rowe_600LCM] Hardware Fault, FailCount= 2
2018-08-20T20:06:22.803Z,1534795582.803 [Rowe_600LCM](ERROR): Hardware Fault
2018-08-20T20:06:23.219Z,1534795583.219 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-08-20T20:06:23.219Z,1534795583.219 [Rowe_600LCM] No Fault, FailCount= 2
2018-08-20T20:06:23.610Z,1534795583.610 [Rowe_600LCM](INFO): Initializing
2018-08-20T20:06:23.611Z,1534795583.611 [Rowe_600LCM](INFO): Checking LCM
2018-08-20T20:06:23.611Z,1534795583.611 [Rowe_600LCM](INFO): LCM OK
2018-08-20T20:06:23.611Z,1534795583.611 [Rowe_600LCM](INFO): Powering up
2018-08-20T20:06:25.520Z,1534795585.520 [NAL9602](INFO): GPS fix at 20180820T200625: (36.802765, -121.786964)
2018-08-20T20:06:27.752Z,1534795587.752 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-08-20T20:06:27.834Z,1534795587.834 [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 &
2018-08-20T20:06:27.835Z,1534795587.835 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-08-20T20:06:27.836Z,1534795587.836 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-08-20T20:06:27.836Z,1534795587.836 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-08-20T20:06:28.293Z,1534795588.293 [NAL9602](INFO): GPS fix at 20180820T200627: (36.802765, -121.786965)
2018-08-20T20:06:31.492Z,1534795591.492 [NAL9602](INFO): GPS fix at 20180820T200631: (36.802767, -121.786966)
2018-08-20T20:06:34.699Z,1534795594.699 [NAL9602](INFO): GPS fix at 20180820T200634: (36.802768, -121.786965)
2018-08-20T20:06:39.944Z,1534795599.944 [NAL9602](INFO): GPS fix at 20180820T200639: (36.802770, -121.786961)
2018-08-20T20:06:42.336Z,1534795602.336 [NAL9602](INFO): GPS fix at 20180820T200641: (36.802771, -121.786959)
2018-08-20T20:06:45.550Z,1534795605.550 [NAL9602](INFO): GPS fix at 20180820T200645: (36.802773, -121.786958)
2018-08-20T20:06:48.336Z,1534795608.336 [NAL9602](INFO): GPS fix at 20180820T200647: (36.802774, -121.786957)
2018-08-20T20:06:51.541Z,1534795611.541 [NAL9602](INFO): GPS fix at 20180820T200651: (36.802776, -121.786955)
2018-08-20T20:06:54.336Z,1534795614.336 [NAL9602](INFO): GPS fix at 20180820T200653: (36.802777, -121.786954)
2018-08-20T20:06:57.549Z,1534795617.549 [NAL9602](INFO): GPS fix at 20180820T200657: (36.802779, -121.786951)
2018-08-20T20:07:00.336Z,1534795620.336 [NAL9602](INFO): GPS fix at 20180820T200659: (36.802780, -121.786949)
2018-08-20T20:07:03.545Z,1534795623.545 [NAL9602](INFO): GPS fix at 20180820T200703: (36.802780, -121.786947)
2018-08-20T20:07:06.336Z,1534795626.336 [NAL9602](INFO): GPS fix at 20180820T200705: (36.802781, -121.786947)
2018-08-20T20:07:09.544Z,1534795629.544 [NAL9602](INFO): GPS fix at 20180820T200709: (36.802781, -121.786946)
2018-08-20T20:07:12.336Z,1534795632.336 [NAL9602](INFO): GPS fix at 20180820T200711: (36.802781, -121.786945)
2018-08-20T20:07:15.540Z,1534795635.540 [NAL9602](INFO): GPS fix at 20180820T200715: (36.802781, -121.786942)
2018-08-20T20:07:18.336Z,1534795638.336 [NAL9602](INFO): GPS fix at 20180820T200717: (36.802781, -121.786940)
2018-08-20T20:07:21.540Z,1534795641.540 [NAL9602](INFO): GPS fix at 20180820T200721: (36.802780, -121.786939)
2018-08-20T20:07:24.336Z,1534795644.336 [NAL9602](INFO): GPS fix at 20180820T200723: (36.802780, -121.786938)
2018-08-20T20:07:27.540Z,1534795647.540 [NAL9602](INFO): GPS fix at 20180820T200727: (36.802780, -121.786940)
2018-08-20T20:07:30.337Z,1534795650.337 [NAL9602](INFO): GPS fix at 20180820T200729: (36.802779, -121.786939)
2018-08-20T20:07:33.536Z,1534795653.536 [NAL9602](INFO): GPS fix at 20180820T200733: (36.802777, -121.786938)
2018-08-20T20:07:39.226Z,1534795659.226 [NAL9602](INFO): GPS fix at 20180820T200739: (36.802774, -121.786936)
2018-08-20T20:07:41.597Z,1534795661.597 [NAL9602](INFO): GPS fix at 20180820T200741: (36.802774, -121.786937)
2018-08-20T20:07:44.399Z,1534795664.399 [NAL9602](INFO): GPS fix at 20180820T200744: (36.802774, -121.786936)
2018-08-20T20:07:47.593Z,1534795667.593 [NAL9602](INFO): GPS fix at 20180820T200747: (36.802774, -121.786936)
2018-08-20T20:07:50.392Z,1534795670.392 [NAL9602](INFO): GPS fix at 20180820T200750: (36.802773, -121.786936)
2018-08-20T20:07:50.873Z,1534795670.873 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2018-08-20T20:07:51.307Z,1534795671.307 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2018-08-20T20:07:51.307Z,1534795671.307 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2018-08-20T20:07:51.308Z,1534795671.308 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2018-08-20T20:07:51.696Z,1534795671.696 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-08-20T20:07:51.697Z,1534795671.697 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-08-20T20:07:51.697Z,1534795671.697 [IBIT](IMPORTANT): Pressure:12.038712 PSI
2018-08-20T20:07:51.698Z,1534795671.698 [IBIT](IMPORTANT): Humidity:15.394945 %
2018-08-20T20:07:52.114Z,1534795672.114 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-08-20T20:07:52.114Z,1534795672.114 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2018-08-20T20:07:52.114Z,1534795672.114 [IBIT](IMPORTANT): massDefault: -0.075000 cm
2018-08-20T20:07:52.115Z,1534795672.115 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2018-08-20T20:07:52.115Z,1534795672.115 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-08-20T20:07:52.115Z,1534795672.115 [IBIT](IMPORTANT): IBIT FAILED
2018-08-20T20:07:59.941Z,1534795679.941 [CommandLine](IMPORTANT): got command restart system
2018-08-20T20:08:02.582Z,1534795682.582 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:02.583Z,1534795682.583 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:02.738Z,1534795682.738 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-08-20T20:08:02.738Z,1534795682.738 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:02.739Z,1534795682.739 [CommandLine](INFO): Join timeout helper Thread ID is 1138
2018-08-20T20:08:02.740Z,1534795682.740 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-08-20T20:08:02.740Z,1534795682.740 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:02.740Z,1534795682.740 [NavChartDb](INFO): Join timeout helper Thread ID is 1139
2018-08-20T20:08:02.858Z,1534795682.858 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:02.859Z,1534795682.859 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:02.878Z,1534795682.878 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-08-20T20:08:02.878Z,1534795682.878 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:02.879Z,1534795682.879 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1140
2018-08-20T20:08:03.161Z,1534795683.161 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:03.161Z,1534795683.161 [WetLabsBB2FL](INFO): Powering down
2018-08-20T20:08:03.162Z,1534795683.162 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:03.166Z,1534795683.166 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-08-20T20:08:03.166Z,1534795683.166 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:03.167Z,1534795683.167 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1141
2018-08-20T20:08:03.490Z,1534795683.490 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:03.658Z,1534795683.658 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-08-20T20:08:03.658Z,1534795683.658 [CTD_Seabird](INFO): Powering down
2018-08-20T20:08:03.660Z,1534795683.660 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:03.670Z,1534795683.670 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-08-20T20:08:03.670Z,1534795683.670 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:03.671Z,1534795683.671 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1143
2018-08-20T20:08:03.870Z,1534795683.870 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:03.871Z,1534795683.871 [CTD_NeilBrown](INFO): Powering down
2018-08-20T20:08:03.872Z,1534795683.872 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:03.875Z,1534795683.875 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-08-20T20:08:03.875Z,1534795683.875 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:03.876Z,1534795683.876 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1144
2018-08-20T20:08:04.634Z,1534795684.634 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:04.634Z,1534795684.634 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-08-20T20:08:04.738Z,1534795684.738 [Rowe_600LCM](INFO): Powering down
2018-08-20T20:08:04.740Z,1534795684.740 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:04.751Z,1534795684.751 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-08-20T20:08:04.751Z,1534795684.751 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:04.751Z,1534795684.751 [Radio_Surface](INFO): Join timeout helper Thread ID is 1146
2018-08-20T20:08:04.754Z,1534795684.754 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:04.755Z,1534795684.755 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:04.771Z,1534795684.771 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-08-20T20:08:04.771Z,1534795684.771 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:04.771Z,1534795684.771 [logger](INFO): Join timeout helper Thread ID is 1147
2018-08-20T20:08:04.788Z,1534795684.788 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:04.790Z,1534795684.790 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:04.815Z,1534795684.815 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-08-20T20:08:04.815Z,1534795684.815 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:04.815Z,1534795684.815 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-08-20T20:08:04.815Z,1534795684.815 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:04.815Z,1534795684.815 [controlThread](INFO): Join timeout helper Thread ID is 1148
2018-08-20T20:08:04.851Z,1534795684.851 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-20T20:08:04.851Z,1534795684.851 [controlThread](DEBUG): Uninitializing ControlThread
2018-08-20T20:08:04.852Z,1534795684.852 [NAL9602](INFO): Powering down
2018-08-20T20:08:04.853Z,1534795684.853 [PNI_TCM](INFO): Powering down
2018-08-20T20:08:04.942Z,1534795684.942 [Aanderaa_O2](INFO): Powering down
2018-08-20T20:08:04.944Z,1534795684.944 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-20T20:08:04.945Z,1534795684.945 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-08-20T20:08:04.946Z,1534795684.946 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-08-20T20:08:04.947Z,1534795684.947 [MissionManager](INFO): Uninitializing Mission Default
2018-08-20T20:08:04.947Z,1534795684.947 [Default] Stopped
2018-08-20T20:08:04.947Z,1534795684.947 [Default](DEBUG): Aggregate::uninitialize Default
2018-08-20T20:08:04.947Z,1534795684.947 [Default:B.GoToSurface] Stopped
2018-08-20T20:08:04.947Z,1534795684.947 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-20T20:08:04.947Z,1534795684.947 [Default:CheckIn] Stopped
2018-08-20T20:08:04.948Z,1534795684.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-20T20:08:04.948Z,1534795684.948 [Default:CheckIn:C.Wait] Stopped
2018-08-20T20:08:04.948Z,1534795684.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-20T20:08:04.952Z,1534795684.952 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-08-20T20:08:04.952Z,1534795684.952 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-08-20T20:08:04.953Z,1534795684.953 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-08-20T20:08:04.953Z,1534795684.953 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-08-20T20:08:04.953Z,1534795684.953 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-20T20:08:04.954Z,1534795684.954 [BuoyancyServo](INFO): Powering down
2018-08-20T20:08:04.966Z,1534795684.966 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-08-20T20:08:04.966Z,1534795684.966 [ElevatorServo](INFO): Powering down
2018-08-20T20:08:04.967Z,1534795684.967 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-08-20T20:08:04.967Z,1534795684.967 [MassServo](INFO): Powering down
2018-08-20T20:08:04.968Z,1534795684.968 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-08-20T20:08:04.969Z,1534795684.969 [RudderServo](INFO): Powering down
2018-08-20T20:08:04.970Z,1534795684.970 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-08-20T20:08:04.970Z,1534795684.970 [ThrusterServo](INFO): Powering down
2018-08-20T20:08:04.971Z,1534795684.971 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-08-20T20:08:04.971Z,1534795684.971 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-08-20T20:08:04.972Z,1534795684.972 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-08-20T20:08:04.973Z,1534795684.973 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:05.090Z,1534795685.090 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:05.096Z,1534795685.096 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:05.134Z,1534795685.134 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:05.138Z,1534795685.138 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:05.141Z,1534795685.141 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:05.168Z,1534795685.168 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-20T20:08:05.240Z,1534795685.240 [logger ThreadHandler](INFO): Thread cancelled.