2019-02-07T23:41:59.428Z,1549582919.428 [Supervisor](DEBUG): Initializing supervisor.
2019-02-07T23:41:59.430Z,1549582919.430 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-07T23:41:59.431Z,1549582919.431 [SyncHandler](INFO): Protected caller Thread ID is 8079
2019-02-07T23:41:59.431Z,1549582919.431 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-07T23:41:59.432Z,1549582919.432 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-07T23:41:59.433Z,1549582919.433 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8080
2019-02-07T23:41:59.435Z,1549582919.435 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-07T23:41:59.447Z,1549582919.447 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-07T23:41:59.448Z,1549582919.448 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-07T23:41:59.448Z,1549582919.448 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8081
2019-02-07T23:41:59.449Z,1549582919.449 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-07T23:41:59.450Z,1549582919.450 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-07T23:41:59.450Z,1549582919.450 [logger ThreadHandler](INFO): Protected caller Thread ID is 8082
2019-02-07T23:41:59.452Z,1549582919.452 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-07T23:41:59.452Z,1549582919.452 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-07T23:41:59.454Z,1549582919.454 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-07T23:41:59.808Z,1549582919.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-07T23:41:59.809Z,1549582919.809 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-07T23:42:00.262Z,1549582920.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-07T23:42:00.263Z,1549582920.263 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-07T23:42:00.666Z,1549582920.666 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-07T23:42:00.667Z,1549582920.667 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-07T23:42:00.860Z,1549582920.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-07T23:42:00.861Z,1549582920.861 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-07T23:42:01.307Z,1549582921.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-07T23:42:01.307Z,1549582921.307 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-07T23:42:01.787Z,1549582921.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-07T23:42:01.787Z,1549582921.787 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-07T23:42:01.890Z,1549582921.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-07T23:42:01.890Z,1549582921.890 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-07T23:42:02.086Z,1549582922.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-07T23:42:02.086Z,1549582922.086 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-07T23:42:02.231Z,1549582922.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-07T23:42:02.231Z,1549582922.231 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-07T23:42:02.310Z,1549582922.310 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-07T23:42:02.520Z,1549582922.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-07T23:42:02.521Z,1549582922.521 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-07T23:42:02.621Z,1549582922.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-07T23:42:02.621Z,1549582922.621 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-07T23:42:02.717Z,1549582922.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-07T23:42:02.717Z,1549582922.717 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-07T23:42:02.864Z,1549582922.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-07T23:42:02.865Z,1549582922.865 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-07T23:42:03.186Z,1549582923.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-07T23:42:03.188Z,1549582923.188 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-07T23:42:03.188Z,1549582923.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-07T23:42:03.324Z,1549582923.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-07T23:42:03.553Z,1549582923.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-07T23:42:03.554Z,1549582923.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-07T23:42:03.722Z,1549582923.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-07T23:42:03.822Z,1549582923.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-07T23:42:03.919Z,1549582923.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-07T23:42:04.137Z,1549582924.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-07T23:42:04.438Z,1549582924.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-07T23:42:04.598Z,1549582924.598 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-07T23:42:04.697Z,1549582924.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-07T23:42:04.783Z,1549582924.783 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-07T23:42:04.891Z,1549582924.891 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-02-07T23:42:04.892Z,1549582924.892 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-07T23:42:04.896Z,1549582924.896 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-07T23:42:05.031Z,1549582925.031 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-07T23:42:05.061Z,1549582925.061 [SBIT] Loaded
2019-02-07T23:42:05.061Z,1549582925.061 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-07T23:42:05.062Z,1549582925.062 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-07T23:42:05.090Z,1549582925.090 [IBIT] Loaded
2019-02-07T23:42:05.090Z,1549582925.090 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-07T23:42:05.094Z,1549582925.094 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-07T23:42:05.477Z,1549582925.477 [CBIT] Loaded
2019-02-07T23:42:05.478Z,1549582925.478 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-07T23:42:05.478Z,1549582925.478 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-07T23:42:05.479Z,1549582925.479 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-07T23:42:05.580Z,1549582925.580 [BuoyancyServo] Loaded
2019-02-07T23:42:05.581Z,1549582925.581 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-07T23:42:05.595Z,1549582925.595 [ElevatorServo] Loaded
2019-02-07T23:42:05.595Z,1549582925.595 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-07T23:42:05.609Z,1549582925.609 [MassServo] Loaded
2019-02-07T23:42:05.610Z,1549582925.610 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-07T23:42:05.625Z,1549582925.625 [RudderServo] Loaded
2019-02-07T23:42:05.625Z,1549582925.625 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-07T23:42:05.639Z,1549582925.639 [ThrusterServo] Loaded
2019-02-07T23:42:05.640Z,1549582925.640 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-07T23:42:05.640Z,1549582925.640 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-07T23:42:05.641Z,1549582925.641 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-07T23:42:05.746Z,1549582925.746 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-07T23:42:05.746Z,1549582925.746 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-07T23:42:05.805Z,1549582925.805 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-07T23:42:05.805Z,1549582925.805 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-07T23:42:05.863Z,1549582925.863 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-07T23:42:05.864Z,1549582925.864 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-07T23:42:05.880Z,1549582925.880 [NavChart] Loaded
2019-02-07T23:42:05.880Z,1549582925.880 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-07T23:42:05.884Z,1549582925.884 [UniversalFixResidualReporter] Loaded
2019-02-07T23:42:05.884Z,1549582925.884 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-07T23:42:05.885Z,1549582925.885 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-07T23:42:05.885Z,1549582925.885 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-07T23:42:05.898Z,1549582925.898 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-07T23:42:05.899Z,1549582925.899 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-07T23:42:06.134Z,1549582926.134 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-07T23:42:06.135Z,1549582926.135 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-07T23:42:06.470Z,1549582926.470 [AHRS_M2] Loaded
2019-02-07T23:42:06.470Z,1549582926.470 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-02-07T23:42:06.537Z,1549582926.537 [DataOverHttps] Loaded
2019-02-07T23:42:06.537Z,1549582926.537 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-07T23:42:06.551Z,1549582926.551 [Depth_Keller] Loaded
2019-02-07T23:42:06.551Z,1549582926.551 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-07T23:42:06.556Z,1549582926.556 [DropWeight] Loaded
2019-02-07T23:42:06.556Z,1549582926.556 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-07T23:42:06.607Z,1549582926.607 [DUSBL_Hydroid] Loaded
2019-02-07T23:42:06.607Z,1549582926.607 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-07T23:42:06.697Z,1549582926.697 [NAL9602] Loaded
2019-02-07T23:42:06.697Z,1549582926.697 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-07T23:42:06.702Z,1549582926.702 [Onboard] Loaded
2019-02-07T23:42:06.703Z,1549582926.703 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-07T23:42:06.709Z,1549582926.709 [Radio_Surface] Loaded
2019-02-07T23:42:06.710Z,1549582926.710 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-07T23:42:06.711Z,1549582926.711 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0
2019-02-07T23:42:06.711Z,1549582926.711 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8162
2019-02-07T23:42:06.739Z,1549582926.739 [RDI_Pathfinder] Loaded
2019-02-07T23:42:06.740Z,1549582926.740 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-07T23:42:06.761Z,1549582926.761 [RDI_PathfinderUp] Loaded
2019-02-07T23:42:06.762Z,1549582926.762 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-07T23:42:08.308Z,1549582928.308 [BPC1] Loaded
2019-02-07T23:42:08.309Z,1549582928.309 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-07T23:42:08.309Z,1549582928.309 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-07T23:42:08.310Z,1549582928.310 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-07T23:42:08.378Z,1549582928.378 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-07T23:42:08.465Z,1549582928.465 [VerticalControl] Loaded
2019-02-07T23:42:08.465Z,1549582928.465 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-07T23:42:08.466Z,1549582928.466 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-07T23:42:08.523Z,1549582928.523 [HorizontalControl] Loaded
2019-02-07T23:42:08.524Z,1549582928.524 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-07T23:42:08.524Z,1549582928.524 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-07T23:42:08.526Z,1549582928.526 [SpeedControl] Loaded
2019-02-07T23:42:08.527Z,1549582928.527 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-07T23:42:08.527Z,1549582928.527 [LoopControl](DEBUG): Construct LoopControl.
2019-02-07T23:42:08.528Z,1549582928.528 [LoopControl] Loaded
2019-02-07T23:42:08.528Z,1549582928.528 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-07T23:42:08.529Z,1549582928.529 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-07T23:42:08.529Z,1549582928.529 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-07T23:42:08.628Z,1549582928.628 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-07T23:42:08.629Z,1549582928.629 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-07T23:42:08.806Z,1549582928.806 [Aanderaa_O2] Loaded
2019-02-07T23:42:08.806Z,1549582928.806 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-07T23:42:08.889Z,1549582928.889 [CTD_NeilBrown] Loaded
2019-02-07T23:42:08.890Z,1549582928.890 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-07T23:42:08.891Z,1549582928.891 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0
2019-02-07T23:42:08.891Z,1549582928.891 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8163
2019-02-07T23:42:08.936Z,1549582928.936 [WetLabsSeaOWL_UV_A] Loaded
2019-02-07T23:42:08.937Z,1549582928.937 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-07T23:42:08.938Z,1549582928.938 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2019-02-07T23:42:08.938Z,1549582928.938 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8164
2019-02-07T23:42:08.939Z,1549582928.939 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-07T23:42:08.939Z,1549582928.939 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-07T23:42:09.003Z,1549582929.003 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-07T23:42:09.003Z,1549582929.003 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-07T23:42:09.043Z,1549582929.043 [DepthRateCalculator] Loaded
2019-02-07T23:42:09.043Z,1549582929.043 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-07T23:42:09.048Z,1549582929.048 [PitchRateCalculator] Loaded
2019-02-07T23:42:09.049Z,1549582929.049 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-07T23:42:09.061Z,1549582929.061 [SpeedCalculator] Loaded
2019-02-07T23:42:09.061Z,1549582929.061 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-07T23:42:09.083Z,1549582929.083 [TempGradientCalculator] Loaded
2019-02-07T23:42:09.083Z,1549582929.083 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-07T23:42:09.088Z,1549582929.088 [YawRateCalculator] Loaded
2019-02-07T23:42:09.088Z,1549582929.088 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-07T23:42:09.118Z,1549582929.118 [ElevatorOffsetCalculator] Loaded
2019-02-07T23:42:09.118Z,1549582929.118 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-07T23:42:09.119Z,1549582929.119 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-07T23:42:09.119Z,1549582929.119 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-07T23:42:09.255Z,1549582929.255 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-07T23:42:09.264Z,1549582929.264 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-07T23:42:09.265Z,1549582929.265 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-07T23:42:09.282Z,1549582929.282 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-07T23:42:09.290Z,1549582929.290 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2019-02-07T23:42:09.290Z,1549582929.290 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8165
2019-02-07T23:42:09.389Z,1549582929.389 [Supervisor](INFO): Main Thread ID is 8078
2019-02-07T23:42:09.389Z,1549582929.389 [Supervisor](DEBUG): Running supervisor.
2019-02-07T23:42:09.390Z,1549582929.390 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8166
2019-02-07T23:42:09.393Z,1549582929.393 [controlThread ThreadHandler](INFO): Handler Thread ID is 8167
2019-02-07T23:42:09.393Z,1549582929.393 [controlThread](DEBUG): Initializing ControlThread
2019-02-07T23:42:09.394Z,1549582929.394 [SBIT](INFO): Initialize SBIT Component.
2019-02-07T23:42:09.394Z,1549582929.394 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9
2019-02-07T23:42:09.394Z,1549582929.394 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6
2019-02-07T23:42:09.395Z,1549582929.395 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-07T23:42:09.395Z,1549582929.395 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-07T23:42:09.396Z,1549582929.396 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-07T23:42:09.397Z,1549582929.397 [IBIT](INFO): Initialize IBIT Component.
2019-02-07T23:42:09.397Z,1549582929.397 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-07T23:42:09.399Z,1549582929.399 [logger ThreadHandler](INFO): Handler Thread ID is 8168
2019-02-07T23:42:09.410Z,1549582929.410 [CBIT](DEBUG): Initialized mux pins.
2019-02-07T23:42:09.410Z,1549582929.410 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-07T23:42:09.418Z,1549582929.418 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8169
2019-02-07T23:42:09.424Z,1549582929.424 [Radio_Surface](INFO): Powering up
2019-02-07T23:42:09.430Z,1549582929.430 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8170
2019-02-07T23:42:09.431Z,1549582929.431 [CTD_NeilBrown](INFO): Powering down
2019-02-07T23:42:09.434Z,1549582929.434 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-07T23:42:09.434Z,1549582929.434 [CBIT](DEBUG): Initializing heartbeat.
2019-02-07T23:42:09.466Z,1549582929.466 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8171
2019-02-07T23:42:09.467Z,1549582929.467 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-07T23:42:09.502Z,1549582929.502 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8172
2019-02-07T23:42:09.505Z,1549582929.505 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-07T23:42:09.505Z,1549582929.505 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-07T23:42:09.506Z,1549582929.506 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-07T23:42:09.506Z,1549582929.506 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-07T23:42:09.506Z,1549582929.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-07T23:42:09.506Z,1549582929.506 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-07T23:42:09.506Z,1549582929.506 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-07T23:42:09.507Z,1549582929.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-07T23:42:09.507Z,1549582929.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-07T23:42:09.507Z,1549582929.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-07T23:42:09.507Z,1549582929.507 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-07T23:42:09.507Z,1549582929.507 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-07T23:42:09.508Z,1549582929.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-07T23:42:09.508Z,1549582929.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-07T23:42:09.508Z,1549582929.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-07T23:42:09.508Z,1549582929.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-07T23:42:09.508Z,1549582929.508 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-07T23:42:09.508Z,1549582929.508 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-07T23:42:09.543Z,1549582929.543 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-07T23:42:09.544Z,1549582929.544 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-07T23:42:09.544Z,1549582929.544 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-07T23:42:09.549Z,1549582929.549 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-07T23:42:09.551Z,1549582929.551 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-07T23:42:09.551Z,1549582929.551 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-07T23:42:09.552Z,1549582929.552 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-07T23:42:09.553Z,1549582929.553 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-07T23:42:09.553Z,1549582929.553 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-07T23:42:09.553Z,1549582929.553 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-07T23:42:09.554Z,1549582929.554 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-07T23:42:09.555Z,1549582929.555 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-07T23:42:09.555Z,1549582929.555 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-07T23:42:09.556Z,1549582929.556 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-07T23:42:09.583Z,1549582929.583 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-07T23:42:09.611Z,1549582929.611 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-02-07T23:42:09.618Z,1549582929.618 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-07T23:42:09.694Z,1549582929.694 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-07T23:42:09.696Z,1549582929.696 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-07T23:42:09.697Z,1549582929.697 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-07T23:42:09.758Z,1549582929.758 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-07T23:42:09.760Z,1549582929.760 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-07T23:42:09.766Z,1549582929.766 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-07T23:42:09.790Z,1549582929.790 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-02-07T23:42:09.795Z,1549582929.795 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,DUSBL_Hydroid,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-07T23:42:09.822Z,1549582929.822 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-02-07T23:42:09.881Z,1549582929.881 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-07T23:42:09.906Z,1549582929.906 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-07T23:42:09.940Z,1549582929.940 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:09.940Z,1549582929.940 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:42:09.941Z,1549582929.941 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:42:10.082Z,1549582930.082 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-07T23:42:10.303Z,1549582930.303 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:42:10.310Z,1549582930.310 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-07T23:42:10.324Z,1549582930.324 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:42:10.330Z,1549582930.330 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-07T23:42:10.336Z,1549582930.336 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:42:10.346Z,1549582930.346 [MassServo](DEBUG): Initializing MassServo.
2019-02-07T23:42:10.359Z,1549582930.359 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:42:10.366Z,1549582930.366 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-07T23:42:10.376Z,1549582930.376 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:42:10.382Z,1549582930.382 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-07T23:42:10.548Z,1549582930.548 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:10.650Z,1549582930.650 [RDI_PathfinderUp](INFO): Powering down
2019-02-07T23:42:10.699Z,1549582930.699 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-07T23:42:19.200Z,1549582939.200 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:19.266Z,1549582939.266 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-07T23:42:19.673Z,1549582939.673 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:20.019Z,1549582940.019 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:20.154Z,1549582940.154 [Aanderaa_O2](INFO): Powering down
2019-02-07T23:42:20.420Z,1549582940.420 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004446
2019-02-07T23:42:20.423Z,1549582940.423 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:20.878Z,1549582940.878 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-02-07T23:42:20.878Z,1549582940.878 [AHRS_M2] Communications Fault, FailCount= 1
2019-02-07T23:42:20.878Z,1549582940.878 [AHRS_M2](ERROR): Communications Fault
2019-02-07T23:42:20.883Z,1549582940.883 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:21.015Z,1549582941.015 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-02-07T23:42:21.190Z,1549582941.190 [AHRS_M2](INFO): Powering down
2019-02-07T23:42:21.287Z,1549582941.287 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:21.595Z,1549582941.595 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:21.995Z,1549582941.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:22.146Z,1549582942.146 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-02-07T23:42:22.146Z,1549582942.146 [AHRS_M2] No Fault, FailCount= 1
2019-02-07T23:42:22.390Z,1549582942.390 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-02-07T23:42:22.403Z,1549582942.403 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:22.817Z,1549582942.817 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:23.207Z,1549582943.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:23.233Z,1549582943.233 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-07T23:42:23.607Z,1549582943.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:24.007Z,1549582944.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:24.407Z,1549582944.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:24.807Z,1549582944.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:25.207Z,1549582945.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:25.632Z,1549582945.632 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:26.031Z,1549582946.031 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:26.431Z,1549582946.431 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:26.843Z,1549582946.843 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:27.243Z,1549582947.243 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:27.643Z,1549582947.643 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:28.032Z,1549582948.032 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:28.443Z,1549582948.443 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:28.831Z,1549582948.831 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:29.227Z,1549582949.227 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:29.623Z,1549582949.623 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:30.012Z,1549582950.012 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:30.015Z,1549582950.015 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
���]/M
?�h!
2019-02-07T23:42:30.015Z,1549582950.015 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2019-02-07T23:42:30.015Z,1549582950.015 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:42:30.098Z,1549582950.098 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:42:30.417Z,1549582950.417 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:42:30.418Z,1549582950.418 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:42:30.418Z,1549582950.418 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:42:30.816Z,1549582950.816 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:42:31.215Z,1549582951.215 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:42:31.279Z,1549582951.279 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:42:31.279Z,1549582951.279 [DUSBL_Hydroid] No Fault, FailCount= 1
2019-02-07T23:42:31.615Z,1549582951.615 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:32.015Z,1549582952.015 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:32.414Z,1549582952.414 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:32.814Z,1549582952.814 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:33.214Z,1549582953.214 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:33.613Z,1549582953.613 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:33.613Z,1549582953.613 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:42:33.613Z,1549582953.613 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:42:34.012Z,1549582954.012 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:34.435Z,1549582954.435 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:34.835Z,1549582954.835 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:35.219Z,1549582955.219 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:35.615Z,1549582955.615 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:36.015Z,1549582956.015 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:36.419Z,1549582956.419 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:36.815Z,1549582956.815 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:36.816Z,1549582956.816 [NAL9602](INFO): Powering up NAL9602
2019-02-07T23:42:37.223Z,1549582957.223 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:37.615Z,1549582957.615 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:38.015Z,1549582958.015 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:38.048Z,1549582958.048 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-07T23:42:38.057Z,1549582958.057 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-07T23:42:38.415Z,1549582958.415 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:38.815Z,1549582958.815 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:39.215Z,1549582959.215 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:39.615Z,1549582959.615 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:40.015Z,1549582960.015 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:40.521Z,1549582960.521 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:40.911Z,1549582960.911 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:41.308Z,1549582961.308 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:41.725Z,1549582961.725 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:42.128Z,1549582962.128 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:42.511Z,1549582962.511 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:42.911Z,1549582962.911 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:43.319Z,1549582963.319 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:43.711Z,1549582963.711 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:44.111Z,1549582964.111 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:44.519Z,1549582964.519 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:44.927Z,1549582964.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:45.321Z,1549582965.321 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:51.395Z,1549582971.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:51.737Z,1549582971.737 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:52.107Z,1549582972.107 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:52.108Z,1549582972.108 [NAL9602](INFO): NAL9602 initialized
2019-02-07T23:42:52.511Z,1549582972.511 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:52.907Z,1549582972.907 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:52.922Z,1549582972.922 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:42:53.312Z,1549582973.312 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:53.711Z,1549582973.711 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:53.712Z,1549582973.712 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
?�̔��?�h!
2019-02-07T23:42:53.712Z,1549582973.712 [DUSBL_Hydroid] Communications Fault, FailCount= 2
2019-02-07T23:42:53.712Z,1549582973.712 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:42:54.291Z,1549582974.291 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:42:54.415Z,1549582974.415 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:42:54.415Z,1549582974.415 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:42:54.415Z,1549582974.415 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:42:54.647Z,1549582974.647 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009785
CHAN A1 (24V): -0.024852
CHAN A2 (12V): -0.007161
CHAN A3 (5V): -0.002877
CHAN B0 (3.3V): -0.000942
CHAN B1 (3.15aV): 0.000308
CHAN B2 (3.15bV): 0.000407
CHAN B3 (GND): 0.000624
OPEN: 0.009028
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-07T23:42:54.807Z,1549582974.807 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:42:55.207Z,1549582975.207 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:42:55.399Z,1549582975.399 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:42:55.400Z,1549582975.400 [DUSBL_Hydroid] No Fault, FailCount= 2
2019-02-07T23:42:55.625Z,1549582975.625 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:56.020Z,1549582976.020 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:56.407Z,1549582976.407 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:56.807Z,1549582976.807 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:57.207Z,1549582977.207 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:57.611Z,1549582977.611 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:42:57.611Z,1549582977.611 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:42:57.612Z,1549582977.612 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:42:58.007Z,1549582978.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:58.407Z,1549582978.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:58.807Z,1549582978.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:59.207Z,1549582979.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:42:59.607Z,1549582979.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:00.007Z,1549582980.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:00.407Z,1549582980.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:00.811Z,1549582980.811 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:01.207Z,1549582981.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:01.607Z,1549582981.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:02.035Z,1549582982.035 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:02.407Z,1549582982.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:02.807Z,1549582982.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:03.207Z,1549582983.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:03.607Z,1549582983.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:04.007Z,1549582984.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:04.407Z,1549582984.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:04.807Z,1549582984.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:05.207Z,1549582985.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:05.615Z,1549582985.615 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:06.007Z,1549582986.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:06.407Z,1549582986.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:06.807Z,1549582986.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:07.207Z,1549582987.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:07.607Z,1549582987.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:08.007Z,1549582988.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:08.419Z,1549582988.419 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:08.807Z,1549582988.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:09.207Z,1549582989.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:09.607Z,1549582989.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:10.007Z,1549582990.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:10.407Z,1549582990.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:10.807Z,1549582990.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:11.207Z,1549582991.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:11.607Z,1549582991.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:12.007Z,1549582992.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:12.407Z,1549582992.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:12.811Z,1549582992.811 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:13.207Z,1549582993.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:13.611Z,1549582993.611 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:14.011Z,1549582994.011 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:14.407Z,1549582994.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:14.811Z,1549582994.811 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:15.211Z,1549582995.211 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:15.607Z,1549582995.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:16.011Z,1549582996.011 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:16.407Z,1549582996.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:16.811Z,1549582996.811 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:17.207Z,1549582997.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:17.607Z,1549582997.607 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:18.007Z,1549582998.007 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:18.008Z,1549582998.008 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
������
2019-02-07T23:43:18.008Z,1549582998.008 [DUSBL_Hydroid] Communications Fault, FailCount= 3
2019-02-07T23:43:18.008Z,1549582998.008 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:43:18.109Z,1549582998.109 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:43:18.407Z,1549582998.407 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:43:18.407Z,1549582998.407 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:43:18.407Z,1549582998.407 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:43:18.807Z,1549582998.807 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:43:19.207Z,1549582999.207 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:43:19.210Z,1549582999.210 [NAL9602](INFO): SBD MO Status=2, MOMSN=2135, MT Status=2, MTMSN=0
2019-02-07T23:43:19.210Z,1549582999.210 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-07T23:43:19.321Z,1549582999.321 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:43:19.321Z,1549582999.321 [DUSBL_Hydroid] No Fault, FailCount= 3
2019-02-07T23:43:19.607Z,1549582999.607 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:20.007Z,1549583000.007 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:20.411Z,1549583000.411 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:23.764Z,1549583003.764 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:23.764Z,1549583003.764 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:43:23.764Z,1549583003.764 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:43:24.086Z,1549583004.086 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:24.467Z,1549583004.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:24.864Z,1549583004.864 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:25.267Z,1549583005.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:25.663Z,1549583005.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:26.063Z,1549583006.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:26.467Z,1549583006.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:26.863Z,1549583006.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:27.263Z,1549583007.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:27.706Z,1549583007.706 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:28.067Z,1549583008.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:28.463Z,1549583008.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:28.863Z,1549583008.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:29.267Z,1549583009.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:29.718Z,1549583009.718 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:30.063Z,1549583010.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:30.471Z,1549583010.471 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:30.863Z,1549583010.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:31.263Z,1549583011.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:31.667Z,1549583011.667 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:32.063Z,1549583012.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:32.144Z,1549583012.144 [SBIT](IMPORTANT): SBIT PASSED
2019-02-07T23:43:32.243Z,1549583012.243 [CommandLine](IMPORTANT): got command configSet list
2019-02-07T23:43:32.244Z,1549583012.244 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-07T23:43:32.245Z,1549583012.245 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;

2019-02-07T23:43:32.245Z,1549583012.245 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool;

2019-02-07T23:43:32.484Z,1549583012.484 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:32.557Z,1549583012.557 [MissionManager](IMPORTANT): Started mission Startup
2019-02-07T23:43:32.557Z,1549583012.557 [Startup] Running Loop=1
2019-02-07T23:43:32.562Z,1549583012.562 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-07T23:43:32.562Z,1549583012.562 [Startup:A.GoToSurface] Running Loop=1
2019-02-07T23:43:32.562Z,1549583012.562 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-07T23:43:32.563Z,1549583012.563 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-07T23:43:32.563Z,1549583012.563 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-07T23:43:32.563Z,1549583012.563 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-07T23:43:32.564Z,1549583012.564 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-07T23:43:32.564Z,1549583012.564 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-07T23:43:32.587Z,1549583012.587 [Startup:StartupSatComms] Running Loop=1
2019-02-07T23:43:32.587Z,1549583012.587 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-07T23:43:32.587Z,1549583012.587 [Startup:StartupSatComms:A] Running Loop=1
2019-02-07T23:43:32.871Z,1549583012.871 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:32.943Z,1549583012.943 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-07T23:43:33.264Z,1549583013.264 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:33.663Z,1549583013.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:34.087Z,1549583014.087 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:34.463Z,1549583014.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:34.867Z,1549583014.867 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:35.267Z,1549583015.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:35.663Z,1549583015.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:36.063Z,1549583016.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:36.467Z,1549583016.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:36.863Z,1549583016.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:37.263Z,1549583017.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:37.663Z,1549583017.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:38.063Z,1549583018.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:38.463Z,1549583018.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:38.863Z,1549583018.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:39.263Z,1549583019.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:39.667Z,1549583019.667 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:40.063Z,1549583020.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:40.463Z,1549583020.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:40.863Z,1549583020.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:41.267Z,1549583021.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:41.667Z,1549583021.667 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:42.079Z,1549583022.079 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:42.472Z,1549583022.472 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:42.863Z,1549583022.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:43.267Z,1549583023.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:43.667Z,1549583023.667 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:44.063Z,1549583024.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:44.064Z,1549583024.064 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
���]/M
?�h!
2019-02-07T23:43:44.064Z,1549583024.064 [DUSBL_Hydroid] Communications Fault, FailCount= 4
2019-02-07T23:43:44.064Z,1549583024.064 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:43:44.215Z,1549583024.215 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:43:44.483Z,1549583024.483 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:43:44.483Z,1549583024.483 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:43:44.483Z,1549583024.483 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:43:44.863Z,1549583024.863 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:43:45.263Z,1549583025.263 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:43:45.352Z,1549583025.352 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:43:45.352Z,1549583025.352 [DUSBL_Hydroid] No Fault, FailCount= 4
2019-02-07T23:43:45.663Z,1549583025.663 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:46.063Z,1549583026.063 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:46.463Z,1549583026.463 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:46.863Z,1549583026.863 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:47.263Z,1549583027.263 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:47.673Z,1549583027.673 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:43:47.673Z,1549583027.673 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:43:47.673Z,1549583027.673 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:43:48.071Z,1549583028.071 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:48.463Z,1549583028.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:48.863Z,1549583028.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:49.267Z,1549583029.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:49.663Z,1549583029.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:50.067Z,1549583030.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:50.467Z,1549583030.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:50.872Z,1549583030.872 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:51.271Z,1549583031.271 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:51.663Z,1549583031.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:52.063Z,1549583032.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:52.463Z,1549583032.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:52.465Z,1549583032.465 [NAL9602](INFO): SBD MO Status=2, MOMSN=2135, MT Status=2, MTMSN=0
2019-02-07T23:43:52.466Z,1549583032.466 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-07T23:43:52.863Z,1549583032.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:53.271Z,1549583033.271 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:53.663Z,1549583033.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:54.067Z,1549583034.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:54.463Z,1549583034.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:54.867Z,1549583034.867 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:55.267Z,1549583035.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:55.663Z,1549583035.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:58.957Z,1549583038.957 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:59.297Z,1549583039.297 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:43:59.652Z,1549583039.652 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:00.048Z,1549583040.048 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:00.447Z,1549583040.447 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:00.847Z,1549583040.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:01.247Z,1549583041.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:01.647Z,1549583041.647 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:02.043Z,1549583042.043 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:02.447Z,1549583042.447 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:02.847Z,1549583042.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:03.251Z,1549583043.251 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:03.643Z,1549583043.643 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:04.047Z,1549583044.047 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:04.447Z,1549583044.447 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:04.847Z,1549583044.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:05.247Z,1549583045.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:05.647Z,1549583045.647 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:06.043Z,1549583046.043 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:06.451Z,1549583046.451 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:06.855Z,1549583046.855 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:07.247Z,1549583047.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:07.647Z,1549583047.647 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:08.051Z,1549583048.051 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:08.052Z,1549583048.052 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2019-02-07T23:44:08.052Z,1549583048.052 [DUSBL_Hydroid] Communications Fault, FailCount= 5
2019-02-07T23:44:08.052Z,1549583048.052 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:44:08.197Z,1549583048.197 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:44:08.457Z,1549583048.457 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:44:08.457Z,1549583048.457 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:44:08.458Z,1549583048.458 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:44:08.861Z,1549583048.861 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:44:09.257Z,1549583049.257 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:44:09.327Z,1549583049.327 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:44:09.327Z,1549583049.327 [DUSBL_Hydroid] No Fault, FailCount= 5
2019-02-07T23:44:09.662Z,1549583049.662 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:10.047Z,1549583050.047 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:10.451Z,1549583050.451 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:10.847Z,1549583050.847 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:11.251Z,1549583051.251 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:11.647Z,1549583051.647 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:11.648Z,1549583051.648 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:44:11.648Z,1549583051.648 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:44:12.047Z,1549583052.047 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:12.447Z,1549583052.447 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:12.847Z,1549583052.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:12.850Z,1549583052.850 [NAL9602](INFO): SBD MO Status=0, MOMSN=2135, MT Status=0, MTMSN=0
2019-02-07T23:44:12.850Z,1549583052.850 [NAL9602](INFO): No messages in MT queue
2019-02-07T23:44:13.247Z,1549583053.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:13.661Z,1549583053.661 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:13.678Z,1549583053.678 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:14.055Z,1549583054.055 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:14.062Z,1549583054.062 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234325.00,,,,,1,3,5.29,,,,,,*6B
2019-02-07T23:44:14.062Z,1549583054.062 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:14.063Z,1549583054.063 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:14.447Z,1549583054.447 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:14.847Z,1549583054.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:15.247Z,1549583055.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:15.647Z,1549583055.647 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:16.047Z,1549583056.047 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:16.062Z,1549583056.062 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:16.451Z,1549583056.451 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:16.453Z,1549583056.453 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234328.00,,,,,1,3,5.29,,,,,,*66
2019-02-07T23:44:16.454Z,1549583056.454 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:16.454Z,1549583056.454 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:16.847Z,1549583056.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:17.247Z,1549583057.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:17.652Z,1549583057.652 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:18.055Z,1549583058.055 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:18.455Z,1549583058.455 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:18.848Z,1549583058.848 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:18.866Z,1549583058.866 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:19.247Z,1549583059.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:19.249Z,1549583059.249 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234330.00,,,,,1,3,5.29,,,,,,*6F
2019-02-07T23:44:19.250Z,1549583059.250 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:19.250Z,1549583059.250 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:19.648Z,1549583059.648 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:20.047Z,1549583060.047 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:20.447Z,1549583060.447 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:20.847Z,1549583060.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:21.255Z,1549583061.255 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:21.651Z,1549583061.651 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:22.047Z,1549583062.047 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:22.062Z,1549583062.062 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:22.455Z,1549583062.455 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:22.457Z,1549583062.457 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234334.00,,,,,1,3,5.30,,,,,,*63
2019-02-07T23:44:22.458Z,1549583062.458 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:22.458Z,1549583062.458 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:22.847Z,1549583062.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:23.247Z,1549583063.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:23.647Z,1549583063.647 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:24.047Z,1549583064.047 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:24.447Z,1549583064.447 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:24.847Z,1549583064.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:24.862Z,1549583064.862 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:25.247Z,1549583065.247 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:25.249Z,1549583065.249 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234337.00,,,,,1,3,5.30,,,,,,*60
2019-02-07T23:44:25.250Z,1549583065.250 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:25.250Z,1549583065.250 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:25.647Z,1549583065.647 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:26.047Z,1549583066.047 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:26.447Z,1549583066.447 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:26.855Z,1549583066.855 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:27.255Z,1549583067.255 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:27.648Z,1549583067.648 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:28.051Z,1549583068.051 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:28.074Z,1549583068.074 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:28.448Z,1549583068.448 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:28.451Z,1549583068.451 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234341.00,,,,,1,3,5.31,,,,,,*60
2019-02-07T23:44:28.451Z,1549583068.451 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:28.452Z,1549583068.452 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:28.847Z,1549583068.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:29.251Z,1549583069.251 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:29.647Z,1549583069.647 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:30.047Z,1549583070.047 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:30.451Z,1549583070.451 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:30.847Z,1549583070.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:34.035Z,1549583074.035 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:34.035Z,1549583074.035 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
fL�g�
?�h!
2019-02-07T23:44:34.035Z,1549583074.035 [DUSBL_Hydroid] Communications Fault, FailCount= 6
2019-02-07T23:44:34.035Z,1549583074.035 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:44:34.050Z,1549583074.050 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:34.152Z,1549583074.152 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-07T23:43:32.6Z
2019-02-07T23:44:34.152Z,1549583074.152 [Startup:StartupSatComms:A] Stopped
2019-02-07T23:44:34.153Z,1549583074.153 [Startup:StartupSatComms:B] Running Loop=1
2019-02-07T23:44:34.198Z,1549583074.198 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:44:34.259Z,1549583074.259 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:44:34.259Z,1549583074.259 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:44:34.259Z,1549583074.259 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:44:34.333Z,1549583074.333 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234347.00,,,,,1,3,5.32,,,,,,*65
2019-02-07T23:44:34.333Z,1549583074.333 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:34.333Z,1549583074.333 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:34.363Z,1549583074.363 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-07T23:44:34.624Z,1549583074.624 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:44:35.023Z,1549583075.023 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:44:35.097Z,1549583075.097 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:44:35.097Z,1549583075.097 [DUSBL_Hydroid] No Fault, FailCount= 6
2019-02-07T23:44:35.427Z,1549583075.427 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:35.465Z,1549583075.465 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +6,    +4,    +1,-32768,A
2019-02-07T23:44:35.815Z,1549583075.815 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:38.996Z,1549583078.996 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:44:38.996Z,1549583078.996 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:44:38.997Z,1549583078.997 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:44:39.292Z,1549583079.292 [DataOverHttps](INFO): Sending 869 bytes from file Logs/20190207T234159/Express0001.lzma
2019-02-07T23:44:39.339Z,1549583079.339 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:39.697Z,1549583079.697 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:39.710Z,1549583079.710 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:40.079Z,1549583080.079 [DataOverHttps](INFO): Moved sent file to Logs/20190207T234159/Express0001.lzma.bak
2019-02-07T23:44:40.080Z,1549583080.080 [DataOverHttps](INFO): SBD MOMSN=9641211
2019-02-07T23:44:40.112Z,1549583080.112 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:40.114Z,1549583080.114 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234352.00,,,,,1,3,5.33,,,,,,*60
2019-02-07T23:44:40.114Z,1549583080.114 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:40.114Z,1549583080.114 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:40.502Z,1549583080.502 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:40.569Z,1549583080.569 [Startup:StartupSatComms:B] Stopped
2019-02-07T23:44:40.569Z,1549583080.569 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-07T23:44:40.569Z,1549583080.569 [Startup:StartupSatComms] Stopped
2019-02-07T23:44:40.569Z,1549583080.569 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-07T23:44:40.570Z,1549583080.570 [Startup](INFO): Completed Startup
2019-02-07T23:44:40.570Z,1549583080.570 [MissionManager](INFO): Startup is completed.
2019-02-07T23:44:40.570Z,1549583080.570 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-07T23:44:40.570Z,1549583080.570 [Startup] Stopped
2019-02-07T23:44:40.571Z,1549583080.571 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-07T23:44:40.571Z,1549583080.571 [Startup:A.GoToSurface] Stopped
2019-02-07T23:44:40.571Z,1549583080.571 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-07T23:44:40.887Z,1549583080.887 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:40.940Z,1549583080.940 [MissionManager](IMPORTANT): Started mission Default
2019-02-07T23:44:40.940Z,1549583080.940 [Default] Running Loop=1
2019-02-07T23:44:40.941Z,1549583080.941 [Default](DEBUG): Aggregate::initialize Default
2019-02-07T23:44:40.941Z,1549583080.941 [Default:B.GoToSurface] Running Loop=1
2019-02-07T23:44:40.941Z,1549583080.941 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-07T23:44:40.941Z,1549583080.941 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-07T23:44:40.941Z,1549583080.941 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-07T23:44:40.942Z,1549583080.942 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-07T23:44:40.942Z,1549583080.942 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-07T23:44:40.943Z,1549583080.943 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-07T23:44:40.943Z,1549583080.943 [Default:A.Wait] Running Loop=1
2019-02-07T23:44:40.943Z,1549583080.943 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-07T23:44:44.063Z,1549583084.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:44.214Z,1549583084.214 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:44.579Z,1549583084.579 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:44.594Z,1549583084.594 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:44.972Z,1549583084.972 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:44.974Z,1549583084.974 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234357.00,,,,,1,3,5.34,,,,,,*62
2019-02-07T23:44:44.974Z,1549583084.974 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:44.975Z,1549583084.975 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:45.363Z,1549583085.363 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:45.763Z,1549583085.763 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:46.197Z,1549583086.197 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:46.563Z,1549583086.563 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:46.967Z,1549583086.967 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:46.982Z,1549583086.982 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:47.373Z,1549583087.373 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:47.375Z,1549583087.375 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234400.00,,,,,1,3,5.34,,,,,,*67
2019-02-07T23:44:47.375Z,1549583087.375 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:47.376Z,1549583087.376 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:47.771Z,1549583087.771 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:48.195Z,1549583088.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:48.575Z,1549583088.575 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:48.971Z,1549583088.971 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:49.367Z,1549583089.367 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:49.763Z,1549583089.763 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:50.192Z,1549583090.192 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:50.206Z,1549583090.206 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:50.575Z,1549583090.575 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:50.577Z,1549583090.577 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234403.00,,,,,1,3,5.35,,,,,,*65
2019-02-07T23:44:50.578Z,1549583090.578 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:50.578Z,1549583090.578 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:50.963Z,1549583090.963 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:51.367Z,1549583091.367 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:51.767Z,1549583091.767 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:52.195Z,1549583092.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:52.579Z,1549583092.579 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:52.963Z,1549583092.963 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:52.982Z,1549583092.982 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:53.367Z,1549583093.367 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:53.369Z,1549583093.369 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234406.00,,,,,1,3,5.35,,,,,,*60
2019-02-07T23:44:53.370Z,1549583093.370 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:53.370Z,1549583093.370 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:53.763Z,1549583093.763 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:54.182Z,1549583094.182 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:54.255Z,1549583094.255 [Default:A.Wait](INFO): Done Waiting.
2019-02-07T23:44:54.256Z,1549583094.256 [Default:A.Wait] Stopped
2019-02-07T23:44:54.256Z,1549583094.256 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-07T23:44:54.563Z,1549583094.563 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:54.627Z,1549583094.627 [Default:CheckIn] Running Loop=1
2019-02-07T23:44:54.628Z,1549583094.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-07T23:44:54.628Z,1549583094.628 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-07T23:44:54.963Z,1549583094.963 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:55.022Z,1549583095.022 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-07T23:44:55.363Z,1549583095.363 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:55.763Z,1549583095.763 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:56.174Z,1549583096.174 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:56.190Z,1549583096.190 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:56.567Z,1549583096.567 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:56.570Z,1549583096.570 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234409.00,,,,,1,3,5.36,,,,,,*6C
2019-02-07T23:44:56.571Z,1549583096.571 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:56.571Z,1549583096.571 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:56.967Z,1549583096.967 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:57.363Z,1549583097.363 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:57.763Z,1549583097.763 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:58.181Z,1549583098.181 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:58.567Z,1549583098.567 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:58.963Z,1549583098.963 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:58.978Z,1549583098.978 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:44:59.363Z,1549583099.363 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:44:59.364Z,1549583099.364 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
?�̔��?�h!
2019-02-07T23:44:59.364Z,1549583099.364 [DUSBL_Hydroid] Communications Fault, FailCount= 7
2019-02-07T23:44:59.364Z,1549583099.364 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:44:59.366Z,1549583099.366 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234412.00,,,,,1,3,5.36,,,,,,*66
2019-02-07T23:44:59.366Z,1549583099.366 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:59.367Z,1549583099.367 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:44:59.462Z,1549583099.462 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:44:59.771Z,1549583099.771 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:44:59.771Z,1549583099.771 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:44:59.771Z,1549583099.771 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:45:00.173Z,1549583100.173 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:45:00.563Z,1549583100.563 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:45:00.640Z,1549583100.640 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:45:00.640Z,1549583100.640 [DUSBL_Hydroid] No Fault, FailCount= 7
2019-02-07T23:45:00.963Z,1549583100.963 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:45:01.363Z,1549583101.363 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:45:01.767Z,1549583101.767 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:45:02.189Z,1549583102.189 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:45:02.202Z,1549583102.202 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:02.563Z,1549583102.563 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:45:02.565Z,1549583102.565 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234415.00,,,,,1,3,5.37,,,,,,*60
2019-02-07T23:45:02.566Z,1549583102.566 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:02.566Z,1549583102.566 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:02.963Z,1549583102.963 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:45:02.963Z,1549583102.963 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:45:02.963Z,1549583102.963 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:45:03.363Z,1549583103.363 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:03.764Z,1549583103.764 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:04.181Z,1549583104.181 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:04.580Z,1549583104.580 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:04.979Z,1549583104.979 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:04.994Z,1549583104.994 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:05.367Z,1549583105.367 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:05.369Z,1549583105.369 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234418.00,,,,,1,3,5.37,,,,,,*6D
2019-02-07T23:45:05.370Z,1549583105.370 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:05.370Z,1549583105.370 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:05.771Z,1549583105.771 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:06.184Z,1549583106.184 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:06.571Z,1549583106.571 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:06.963Z,1549583106.963 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:07.363Z,1549583107.363 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:07.764Z,1549583107.764 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:08.175Z,1549583108.175 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:08.204Z,1549583108.204 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:08.567Z,1549583108.567 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:08.569Z,1549583108.569 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234421.00,,,,,1,3,5.38,,,,,,*68
2019-02-07T23:45:08.571Z,1549583108.571 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:08.571Z,1549583108.571 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:08.976Z,1549583108.976 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:09.367Z,1549583109.367 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:09.763Z,1549583109.763 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:09.792Z,1549583109.792 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-07T23:45:09.792Z,1549583109.792 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-07T23:45:09.793Z,1549583109.793 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-07T23:45:09.793Z,1549583109.793 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-07T23:45:09.807Z,1549583109.807 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-07T23:45:09.807Z,1549583109.807 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-07T23:45:09.836Z,1549583109.836 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-07T23:45:09.836Z,1549583109.836 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-07T23:45:09.836Z,1549583109.836 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-07T23:45:10.191Z,1549583110.191 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:10.270Z,1549583110.270 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-07T23:45:10.271Z,1549583110.271 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-07T23:45:10.271Z,1549583110.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-07T23:45:10.271Z,1549583110.271 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-07T23:45:10.272Z,1549583110.272 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-07T23:45:10.272Z,1549583110.272 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-07T23:45:10.563Z,1549583110.563 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:10.605Z,1549583110.605 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-07T23:45:10.963Z,1549583110.963 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:10.978Z,1549583110.978 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:11.375Z,1549583111.375 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:11.377Z,1549583111.377 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234424.00,,,,,1,3,5.39,,,,,,*6C
2019-02-07T23:45:11.378Z,1549583111.378 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:11.378Z,1549583111.378 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:11.779Z,1549583111.779 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:12.179Z,1549583112.179 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:12.575Z,1549583112.575 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:12.975Z,1549583112.975 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:13.375Z,1549583113.375 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:13.772Z,1549583113.772 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:14.188Z,1549583114.188 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:14.202Z,1549583114.202 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:14.567Z,1549583114.567 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:14.569Z,1549583114.569 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234427.00,,,,,1,3,5.39,,,,,,*6F
2019-02-07T23:45:14.570Z,1549583114.570 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:14.570Z,1549583114.570 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:14.976Z,1549583114.976 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:15.363Z,1549583115.363 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:15.775Z,1549583115.775 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:16.195Z,1549583116.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:19.403Z,1549583119.403 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:19.679Z,1549583119.679 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:19.696Z,1549583119.696 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:20.031Z,1549583120.031 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:20.034Z,1549583120.034 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234432.00,,,,,1,3,5.40,,,,,,*65
2019-02-07T23:45:20.034Z,1549583120.034 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:20.035Z,1549583120.035 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:20.427Z,1549583120.427 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:20.827Z,1549583120.827 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:21.231Z,1549583121.231 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:21.627Z,1549583121.627 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:22.031Z,1549583122.031 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:22.046Z,1549583122.046 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:22.435Z,1549583122.435 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:22.438Z,1549583122.438 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234435.00,,,,,1,3,5.40,,,,,,*62
2019-02-07T23:45:22.438Z,1549583122.438 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:22.439Z,1549583122.439 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:22.827Z,1549583122.827 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:23.227Z,1549583123.227 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:45:23.228Z,1549583123.228 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
fL�g�
?�h!
2019-02-07T23:45:23.228Z,1549583123.228 [DUSBL_Hydroid] Communications Fault, FailCount= 8
2019-02-07T23:45:23.228Z,1549583123.228 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:45:23.330Z,1549583123.330 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:45:23.330Z,1549583123.330 [CBIT](CRITICAL): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:45:23.631Z,1549583123.631 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:45:23.631Z,1549583123.631 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:45:23.631Z,1549583123.631 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:45:23.758Z,1549583123.758 [CommandLine](FAULT): Scheduling is paused
2019-02-07T23:45:23.759Z,1549583123.759 [CBIT](INFO): Critical error at 20190207T234523
2019-02-07T23:45:23.759Z,1549583123.759 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-07T23:45:24.027Z,1549583124.027 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:45:24.427Z,1549583124.427 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:45:25.242Z,1549583125.242 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:25.639Z,1549583125.639 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234438.00,,,,,1,3,5.41,,,,,,*6E
2019-02-07T23:45:25.639Z,1549583125.639 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:25.640Z,1549583125.640 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:28.046Z,1549583128.046 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:28.428Z,1549583128.428 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234441.00,,,,,1,3,5.42,,,,,,*63
2019-02-07T23:45:28.429Z,1549583128.429 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:28.429Z,1549583128.429 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:31.250Z,1549583131.250 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:31.632Z,1549583131.632 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234444.00,,,,,1,3,5.42,,,,,,*66
2019-02-07T23:45:31.633Z,1549583131.633 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:31.633Z,1549583131.633 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:34.046Z,1549583134.046 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:34.433Z,1549583134.433 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234447.00,,,,,1,3,5.43,,,,,,*64
2019-02-07T23:45:34.433Z,1549583134.433 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:34.433Z,1549583134.433 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:37.242Z,1549583137.242 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:37.629Z,1549583137.629 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234450.00,,,,,1,3,5.43,,,,,,*62
2019-02-07T23:45:37.629Z,1549583137.629 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:37.629Z,1549583137.629 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:40.038Z,1549583140.038 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:40.441Z,1549583140.441 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234453.00,,,,,1,3,5.44,,,,,,*66
2019-02-07T23:45:40.441Z,1549583140.441 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:40.446Z,1549583140.446 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:43.245Z,1549583143.245 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:43.632Z,1549583143.632 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234456.00,,,,,1,3,5.44,,,,,,*63
2019-02-07T23:45:43.633Z,1549583143.633 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:43.633Z,1549583143.633 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:46.042Z,1549583146.042 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:46.428Z,1549583146.428 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234459.00,,,,,1,3,5.45,,,,,,*6D
2019-02-07T23:45:46.429Z,1549583146.429 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:46.429Z,1549583146.429 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:49.242Z,1549583149.242 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:49.628Z,1549583149.628 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234502.00,,,,,1,3,5.45,,,,,,*62
2019-02-07T23:45:49.629Z,1549583149.629 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:49.629Z,1549583149.629 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:54.558Z,1549583154.558 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:56.934Z,1549583156.934 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:45:57.316Z,1549583157.316 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234510.00,,,,,1,3,5.47,,,,,,*63
2019-02-07T23:45:57.317Z,1549583157.317 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:45:57.317Z,1549583157.317 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:00.142Z,1549583160.142 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:46:00.521Z,1549583160.521 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234513.00,,,,,1,3,5.47,,,,,,*60
2019-02-07T23:46:00.522Z,1549583160.522 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:00.522Z,1549583160.522 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:02.930Z,1549583162.930 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:46:03.318Z,1549583163.318 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234516.00,,,,,1,3,5.48,,,,,,*6A
2019-02-07T23:46:03.318Z,1549583163.318 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:03.319Z,1549583163.319 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:06.134Z,1549583166.134 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:46:06.518Z,1549583166.518 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234519.00,,,,,1,4,4.04,,,,,,*6B
2019-02-07T23:46:06.518Z,1549583166.518 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:06.519Z,1549583166.519 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:08.934Z,1549583168.934 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:46:09.332Z,1549583169.332 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234522.00,,,,,1,4,4.05,,,,,,*62
2019-02-07T23:46:09.333Z,1549583169.333 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:09.333Z,1549583169.333 [NAL9602](ERROR): Invalid hemisphere value reported: 44
2019-02-07T23:46:12.130Z,1549583172.130 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:46:12.517Z,1549583172.517 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,234525.00,3648.15750,N,12147.28506,W,1,4,4.05,10.8,M,-30.7,M,,*6B
2019-02-07T23:46:12.520Z,1549583172.520 [NAL9602](INFO): GPS fix at 20190207T234525: (36.802625, -121.788084)
2019-02-07T23:46:12.605Z,1549583172.605 [Default:CheckIn:Read_GPS] Stopped
2019-02-07T23:46:12.605Z,1549583172.605 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-07T23:46:13.036Z,1549583173.036 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-07T23:46:19.534Z,1549583179.534 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20190207T234159/Courier0004.lzma
2019-02-07T23:46:20.301Z,1549583180.301 [DataOverHttps](INFO): Moved sent file to Logs/20190207T234159/Courier0004.lzma.bak
2019-02-07T23:46:20.302Z,1549583180.302 [DataOverHttps](INFO): SBD MOMSN=9641243
2019-02-07T23:46:30.414Z,1549583190.414 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20190207T234159/Express0005.lzma
2019-02-07T23:46:31.196Z,1549583191.196 [DataOverHttps](INFO): Moved sent file to Logs/20190207T234159/Express0005.lzma.bak
2019-02-07T23:46:31.196Z,1549583191.196 [DataOverHttps](INFO): SBD MOMSN=9641247
2019-02-07T23:46:31.697Z,1549583191.697 [Default:CheckIn:Read_Iridium] Stopped
2019-02-07T23:46:31.697Z,1549583191.697 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-07T23:46:31.706Z,1549583191.706 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-07T23:46:49.571Z,1549583209.571 [NAL9602](INFO): Powering down
2019-02-07T23:49:09.368Z,1549583349.368 [CommandLine](IMPORTANT): got command restart application
2019-02-07T23:49:10.377Z,1549583350.377 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:49:10.377Z,1549583350.377 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.390Z,1549583350.390 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-07T23:49:10.390Z,1549583350.390 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.391Z,1549583350.391 [CommandLine](INFO): Join timeout helper Thread ID is 8219
2019-02-07T23:49:10.393Z,1549583350.393 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-07T23:49:10.393Z,1549583350.393 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.399Z,1549583350.399 [NavChartDb](INFO): Join timeout helper Thread ID is 8220
2019-02-07T23:49:10.498Z,1549583350.498 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:49:10.498Z,1549583350.498 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.506Z,1549583350.506 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-07T23:49:10.506Z,1549583350.506 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.506Z,1549583350.506 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8221
2019-02-07T23:49:10.682Z,1549583350.682 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:49:10.682Z,1549583350.682 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-07T23:49:10.683Z,1549583350.683 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.698Z,1549583350.698 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-07T23:49:10.698Z,1549583350.698 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.698Z,1549583350.698 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8222
2019-02-07T23:49:10.850Z,1549583350.850 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:49:10.850Z,1549583350.850 [CTD_NeilBrown](INFO): Powering down
2019-02-07T23:49:10.862Z,1549583350.862 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.866Z,1549583350.866 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-07T23:49:10.866Z,1549583350.866 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:10.866Z,1549583350.866 [Radio_Surface](INFO): Join timeout helper Thread ID is 8223
2019-02-07T23:49:11.014Z,1549583351.014 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:49:11.014Z,1549583351.014 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.023Z,1549583351.023 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-07T23:49:11.023Z,1549583351.023 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.023Z,1549583351.023 [logger](INFO): Join timeout helper Thread ID is 8224
2019-02-07T23:49:11.042Z,1549583351.042 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:49:11.042Z,1549583351.042 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.062Z,1549583351.062 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-07T23:49:11.063Z,1549583351.063 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.063Z,1549583351.063 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-07T23:49:11.063Z,1549583351.063 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.063Z,1549583351.063 [controlThread](INFO): Join timeout helper Thread ID is 8225
2019-02-07T23:49:11.166Z,1549583351.166 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:49:11.167Z,1549583351.167 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-07T23:49:11.168Z,1549583351.168 [AHRS_M2](INFO): Powering down
2019-02-07T23:49:11.239Z,1549583351.239 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:49:11.239Z,1549583351.239 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:49:11.240Z,1549583351.240 [NAL9602](INFO): Powering down
2019-02-07T23:49:11.310Z,1549583351.310 [RDI_Pathfinder](INFO): Powering down
2019-02-07T23:49:11.311Z,1549583351.311 [RDI_PathfinderUp](INFO): Powering down
2019-02-07T23:49:11.312Z,1549583351.312 [Aanderaa_O2](INFO): Powering down
2019-02-07T23:49:11.313Z,1549583351.313 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-07T23:49:11.314Z,1549583351.314 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-07T23:49:11.315Z,1549583351.315 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-07T23:49:11.316Z,1549583351.316 [MissionManager](INFO): Uninitializing Mission Default
2019-02-07T23:49:11.316Z,1549583351.316 [Default] Stopped
2019-02-07T23:49:11.316Z,1549583351.316 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-07T23:49:11.316Z,1549583351.316 [Default:B.GoToSurface] Stopped
2019-02-07T23:49:11.316Z,1549583351.316 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-07T23:49:11.316Z,1549583351.316 [Default:CheckIn] Stopped
2019-02-07T23:49:11.316Z,1549583351.316 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-07T23:49:11.316Z,1549583351.316 [Default:CheckIn:C.Wait] Stopped
2019-02-07T23:49:11.316Z,1549583351.316 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-07T23:49:11.319Z,1549583351.319 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-07T23:49:11.319Z,1549583351.319 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-07T23:49:11.320Z,1549583351.320 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-07T23:49:11.320Z,1549583351.320 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-07T23:49:11.320Z,1549583351.320 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-07T23:49:11.320Z,1549583351.320 [BuoyancyServo](INFO): Powering down
2019-02-07T23:49:11.334Z,1549583351.334 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-07T23:49:11.334Z,1549583351.334 [ElevatorServo](INFO): Powering down
2019-02-07T23:49:11.335Z,1549583351.335 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-07T23:49:11.335Z,1549583351.335 [MassServo](INFO): Powering down
2019-02-07T23:49:11.336Z,1549583351.336 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-07T23:49:11.336Z,1549583351.336 [RudderServo](INFO): Powering down
2019-02-07T23:49:11.337Z,1549583351.337 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-07T23:49:11.337Z,1549583351.337 [ThrusterServo](INFO): Powering down
2019-02-07T23:49:11.338Z,1549583351.338 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-07T23:49:11.338Z,1549583351.338 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-07T23:49:11.338Z,1549583351.338 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-07T23:49:11.338Z,1549583351.338 [CBIT](DEBUG): Powering off loads.
2019-02-07T23:49:11.350Z,1549583351.350 [CBIT](DEBUG): Disabling WDT.
2019-02-07T23:49:11.362Z,1549583351.362 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-07T23:49:11.363Z,1549583351.363 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.473Z,1549583351.473 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.520Z,1549583351.520 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.523Z,1549583351.523 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.546Z,1549583351.546 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:49:11.614Z,1549583351.614 [logger ThreadHandler](INFO): Thread cancelled.