2019-02-07T23:58:02.851Z,1549583882.851 [Supervisor](DEBUG): Initializing supervisor.
2019-02-07T23:58:02.854Z,1549583882.854 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-07T23:58:02.855Z,1549583882.855 [SyncHandler](INFO): Protected caller Thread ID is 8502
2019-02-07T23:58:02.856Z,1549583882.856 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-07T23:58:02.856Z,1549583882.856 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-07T23:58:02.857Z,1549583882.857 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8503
2019-02-07T23:58:02.861Z,1549583882.861 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-07T23:58:02.872Z,1549583882.872 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-07T23:58:02.873Z,1549583882.873 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-07T23:58:02.874Z,1549583882.874 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8504
2019-02-07T23:58:02.875Z,1549583882.875 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-07T23:58:02.876Z,1549583882.876 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-07T23:58:02.876Z,1549583882.876 [logger ThreadHandler](INFO): Protected caller Thread ID is 8505
2019-02-07T23:58:02.878Z,1549583882.878 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-07T23:58:02.878Z,1549583882.878 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-07T23:58:02.880Z,1549583882.880 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-07T23:58:03.229Z,1549583883.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-07T23:58:03.230Z,1549583883.230 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-07T23:58:03.675Z,1549583883.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-07T23:58:03.676Z,1549583883.676 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-07T23:58:03.999Z,1549583883.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-07T23:58:03.999Z,1549583883.999 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-07T23:58:04.191Z,1549583884.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-07T23:58:04.191Z,1549583884.191 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-07T23:58:04.638Z,1549583884.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-07T23:58:04.639Z,1549583884.639 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-07T23:58:05.119Z,1549583885.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-07T23:58:05.119Z,1549583885.119 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-07T23:58:05.221Z,1549583885.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-07T23:58:05.222Z,1549583885.222 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-07T23:58:05.521Z,1549583885.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-07T23:58:05.522Z,1549583885.522 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-07T23:58:05.666Z,1549583885.666 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-07T23:58:05.666Z,1549583885.666 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-07T23:58:05.746Z,1549583885.746 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-07T23:58:05.954Z,1549583885.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-07T23:58:05.954Z,1549583885.954 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-07T23:58:06.055Z,1549583886.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-07T23:58:06.055Z,1549583886.055 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-07T23:58:06.150Z,1549583886.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-07T23:58:06.151Z,1549583886.151 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-07T23:58:06.297Z,1549583886.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-07T23:58:06.298Z,1549583886.298 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-07T23:58:06.615Z,1549583886.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-07T23:58:06.617Z,1549583886.617 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-07T23:58:06.618Z,1549583886.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-07T23:58:06.751Z,1549583886.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-07T23:58:06.985Z,1549583886.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-07T23:58:06.985Z,1549583886.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-07T23:58:07.150Z,1549583887.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-07T23:58:07.249Z,1549583887.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-07T23:58:07.346Z,1549583887.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-07T23:58:07.539Z,1549583887.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-07T23:58:07.623Z,1549583887.623 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-07T23:58:07.720Z,1549583887.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-07T23:58:07.811Z,1549583887.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-07T23:58:07.892Z,1549583887.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-07T23:58:07.000Z,1549583888.000 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-02-07T23:58:08.000Z,1549583888.000 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-07T23:58:08.004Z,1549583888.004 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-07T23:58:08.152Z,1549583888.152 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-07T23:58:08.183Z,1549583888.183 [SBIT] Loaded
2019-02-07T23:58:08.184Z,1549583888.184 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-07T23:58:08.184Z,1549583888.184 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-07T23:58:08.213Z,1549583888.213 [IBIT] Loaded
2019-02-07T23:58:08.213Z,1549583888.213 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-07T23:58:08.217Z,1549583888.217 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-07T23:58:08.622Z,1549583888.622 [CBIT] Loaded
2019-02-07T23:58:08.622Z,1549583888.622 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-07T23:58:08.622Z,1549583888.622 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-07T23:58:08.623Z,1549583888.623 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-07T23:58:08.756Z,1549583888.756 [BuoyancyServo] Loaded
2019-02-07T23:58:08.756Z,1549583888.756 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-07T23:58:08.771Z,1549583888.771 [ElevatorServo] Loaded
2019-02-07T23:58:08.771Z,1549583888.771 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-07T23:58:08.786Z,1549583888.786 [MassServo] Loaded
2019-02-07T23:58:08.786Z,1549583888.786 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-07T23:58:08.802Z,1549583888.802 [RudderServo] Loaded
2019-02-07T23:58:08.802Z,1549583888.802 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-07T23:58:08.817Z,1549583888.817 [ThrusterServo] Loaded
2019-02-07T23:58:08.817Z,1549583888.817 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-07T23:58:08.817Z,1549583888.817 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-07T23:58:08.818Z,1549583888.818 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-07T23:58:08.929Z,1549583888.929 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-07T23:58:08.929Z,1549583888.929 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-07T23:58:08.991Z,1549583888.991 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-07T23:58:08.991Z,1549583888.991 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-07T23:58:09.052Z,1549583889.052 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-07T23:58:09.052Z,1549583889.052 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-07T23:58:09.069Z,1549583889.069 [NavChart] Loaded
2019-02-07T23:58:09.069Z,1549583889.069 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-07T23:58:09.073Z,1549583889.073 [UniversalFixResidualReporter] Loaded
2019-02-07T23:58:09.074Z,1549583889.074 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-07T23:58:09.074Z,1549583889.074 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-07T23:58:09.075Z,1549583889.075 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-07T23:58:09.089Z,1549583889.089 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-07T23:58:09.090Z,1549583889.090 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-07T23:58:09.348Z,1549583889.348 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-07T23:58:09.349Z,1549583889.349 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-07T23:58:09.709Z,1549583889.709 [AHRS_M2] Loaded
2019-02-07T23:58:09.710Z,1549583889.710 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-02-07T23:58:09.784Z,1549583889.784 [DataOverHttps] Loaded
2019-02-07T23:58:09.785Z,1549583889.785 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-07T23:58:09.798Z,1549583889.798 [Depth_Keller] Loaded
2019-02-07T23:58:09.799Z,1549583889.799 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-07T23:58:09.804Z,1549583889.804 [DropWeight] Loaded
2019-02-07T23:58:09.804Z,1549583889.804 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-07T23:58:09.858Z,1549583889.858 [DUSBL_Hydroid] Loaded
2019-02-07T23:58:09.858Z,1549583889.858 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-07T23:58:09.953Z,1549583889.953 [NAL9602] Loaded
2019-02-07T23:58:09.953Z,1549583889.953 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-07T23:58:09.958Z,1549583889.958 [Onboard] Loaded
2019-02-07T23:58:09.958Z,1549583889.958 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-07T23:58:09.965Z,1549583889.965 [Radio_Surface] Loaded
2019-02-07T23:58:09.966Z,1549583889.966 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-07T23:58:09.967Z,1549583889.967 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0
2019-02-07T23:58:09.967Z,1549583889.967 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8584
2019-02-07T23:58:09.997Z,1549583889.997 [RDI_Pathfinder] Loaded
2019-02-07T23:58:09.997Z,1549583889.997 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-07T23:58:10.020Z,1549583890.020 [RDI_PathfinderUp] Loaded
2019-02-07T23:58:10.020Z,1549583890.020 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-07T23:58:12.190Z,1549583892.190 [BPC1] Loaded
2019-02-07T23:58:12.191Z,1549583892.191 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-07T23:58:12.191Z,1549583892.191 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-07T23:58:12.192Z,1549583892.192 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-07T23:58:12.268Z,1549583892.268 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-07T23:58:12.357Z,1549583892.357 [VerticalControl] Loaded
2019-02-07T23:58:12.357Z,1549583892.357 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-07T23:58:12.358Z,1549583892.358 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-07T23:58:12.418Z,1549583892.418 [HorizontalControl] Loaded
2019-02-07T23:58:12.418Z,1549583892.418 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-07T23:58:12.419Z,1549583892.419 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-07T23:58:12.421Z,1549583892.421 [SpeedControl] Loaded
2019-02-07T23:58:12.421Z,1549583892.421 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-07T23:58:12.422Z,1549583892.422 [LoopControl](DEBUG): Construct LoopControl.
2019-02-07T23:58:12.423Z,1549583892.423 [LoopControl] Loaded
2019-02-07T23:58:12.423Z,1549583892.423 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-07T23:58:12.423Z,1549583892.423 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-07T23:58:12.424Z,1549583892.424 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-07T23:58:12.533Z,1549583892.533 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-07T23:58:12.533Z,1549583892.533 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-07T23:58:12.734Z,1549583892.734 [Aanderaa_O2] Loaded
2019-02-07T23:58:12.734Z,1549583892.734 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-07T23:58:12.823Z,1549583892.823 [CTD_NeilBrown] Loaded
2019-02-07T23:58:12.823Z,1549583892.823 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-07T23:58:12.824Z,1549583892.824 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0
2019-02-07T23:58:12.824Z,1549583892.824 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8585
2019-02-07T23:58:12.872Z,1549583892.872 [WetLabsSeaOWL_UV_A] Loaded
2019-02-07T23:58:12.872Z,1549583892.872 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-07T23:58:12.873Z,1549583892.873 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2019-02-07T23:58:12.874Z,1549583892.874 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8586
2019-02-07T23:58:12.874Z,1549583892.874 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-07T23:58:12.875Z,1549583892.875 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-07T23:58:12.943Z,1549583892.943 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-07T23:58:12.944Z,1549583892.944 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-07T23:58:12.987Z,1549583892.987 [DepthRateCalculator] Loaded
2019-02-07T23:58:12.987Z,1549583892.987 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-07T23:58:12.992Z,1549583892.992 [PitchRateCalculator] Loaded
2019-02-07T23:58:12.993Z,1549583892.993 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-07T23:58:13.005Z,1549583893.005 [SpeedCalculator] Loaded
2019-02-07T23:58:13.005Z,1549583893.005 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-07T23:58:13.027Z,1549583893.027 [TempGradientCalculator] Loaded
2019-02-07T23:58:13.028Z,1549583893.028 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-07T23:58:13.033Z,1549583893.033 [YawRateCalculator] Loaded
2019-02-07T23:58:13.033Z,1549583893.033 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-07T23:58:13.064Z,1549583893.064 [ElevatorOffsetCalculator] Loaded
2019-02-07T23:58:13.064Z,1549583893.064 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-07T23:58:13.065Z,1549583893.065 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-07T23:58:13.065Z,1549583893.065 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-07T23:58:13.094Z,1549583893.094 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-07T23:58:13.097Z,1549583893.097 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-07T23:58:13.098Z,1549583893.098 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-07T23:58:13.105Z,1549583893.105 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-07T23:58:13.106Z,1549583893.106 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2019-02-07T23:58:13.106Z,1549583893.106 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8587
2019-02-07T23:58:13.111Z,1549583893.111 [Supervisor](INFO): Main Thread ID is 8078
2019-02-07T23:58:13.111Z,1549583893.111 [Supervisor](DEBUG): Running supervisor.
2019-02-07T23:58:13.112Z,1549583893.112 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8588
2019-02-07T23:58:13.115Z,1549583893.115 [controlThread ThreadHandler](INFO): Handler Thread ID is 8589
2019-02-07T23:58:13.115Z,1549583893.115 [controlThread](DEBUG): Initializing ControlThread
2019-02-07T23:58:13.116Z,1549583893.116 [SBIT](INFO): Initialize SBIT Component.
2019-02-07T23:58:13.116Z,1549583893.116 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9
2019-02-07T23:58:13.116Z,1549583893.116 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6
2019-02-07T23:58:13.117Z,1549583893.117 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-07T23:58:13.117Z,1549583893.117 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-07T23:58:13.118Z,1549583893.118 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-07T23:58:13.119Z,1549583893.119 [IBIT](INFO): Initialize IBIT Component.
2019-02-07T23:58:13.119Z,1549583893.119 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-07T23:58:13.120Z,1549583893.120 [logger ThreadHandler](INFO): Handler Thread ID is 8590
2019-02-07T23:58:13.134Z,1549583893.134 [CBIT](DEBUG): Initialized mux pins.
2019-02-07T23:58:13.134Z,1549583893.134 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-07T23:58:13.146Z,1549583893.146 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8591
2019-02-07T23:58:13.152Z,1549583893.152 [Radio_Surface](INFO): Powering up
2019-02-07T23:58:13.158Z,1549583893.158 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-07T23:58:13.158Z,1549583893.158 [CBIT](DEBUG): Initializing heartbeat.
2019-02-07T23:58:13.159Z,1549583893.159 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8592
2019-02-07T23:58:13.159Z,1549583893.159 [CTD_NeilBrown](INFO): Powering down
2019-02-07T23:58:13.192Z,1549583893.192 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8593
2019-02-07T23:58:13.193Z,1549583893.193 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-07T23:58:13.223Z,1549583893.223 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8594
2019-02-07T23:58:13.227Z,1549583893.227 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-07T23:58:13.227Z,1549583893.227 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-07T23:58:13.227Z,1549583893.227 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-07T23:58:13.227Z,1549583893.227 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-07T23:58:13.228Z,1549583893.228 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-07T23:58:13.228Z,1549583893.228 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-07T23:58:13.228Z,1549583893.228 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-07T23:58:13.228Z,1549583893.228 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-07T23:58:13.228Z,1549583893.228 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-07T23:58:13.228Z,1549583893.228 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-07T23:58:13.229Z,1549583893.229 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-07T23:58:13.229Z,1549583893.229 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-07T23:58:13.229Z,1549583893.229 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-07T23:58:13.229Z,1549583893.229 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-07T23:58:13.229Z,1549583893.229 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-07T23:58:13.230Z,1549583893.230 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-07T23:58:13.230Z,1549583893.230 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-07T23:58:13.230Z,1549583893.230 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-07T23:58:13.268Z,1549583893.268 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-07T23:58:13.269Z,1549583893.269 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-07T23:58:13.269Z,1549583893.269 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-07T23:58:13.277Z,1549583893.277 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-07T23:58:13.279Z,1549583893.279 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-07T23:58:13.279Z,1549583893.279 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-07T23:58:13.280Z,1549583893.280 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-07T23:58:13.280Z,1549583893.280 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-07T23:58:13.281Z,1549583893.281 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-07T23:58:13.281Z,1549583893.281 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-07T23:58:13.282Z,1549583893.282 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-07T23:58:13.282Z,1549583893.282 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-07T23:58:13.283Z,1549583893.283 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-07T23:58:13.284Z,1549583893.284 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-07T23:58:13.311Z,1549583893.311 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-07T23:58:13.343Z,1549583893.343 [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:58:13.343Z,1549583893.343 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-07T23:58:13.433Z,1549583893.433 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-07T23:58:13.434Z,1549583893.434 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-07T23:58:13.436Z,1549583893.436 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-07T23:58:13.486Z,1549583893.486 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-07T23:58:13.489Z,1549583893.489 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-07T23:58:13.511Z,1549583893.511 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-07T23:58:13.530Z,1549583893.530 [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:58:13.535Z,1549583893.535 [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:58:13.577Z,1549583893.577 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-02-07T23:58:13.658Z,1549583893.658 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-07T23:58:13.691Z,1549583893.691 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-07T23:58:13.715Z,1549583893.715 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:58:13.716Z,1549583893.716 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:58:13.716Z,1549583893.716 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:58:13.886Z,1549583893.886 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-07T23:58:14.097Z,1549583894.097 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:58:14.098Z,1549583894.098 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-07T23:58:14.137Z,1549583894.137 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:58:14.146Z,1549583894.146 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-07T23:58:14.168Z,1549583894.168 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:58:14.174Z,1549583894.174 [MassServo](DEBUG): Initializing MassServo.
2019-02-07T23:58:14.186Z,1549583894.186 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:58:14.194Z,1549583894.194 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-07T23:58:14.207Z,1549583894.207 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:58:14.214Z,1549583894.214 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-07T23:58:14.456Z,1549583894.456 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:14.542Z,1549583894.542 [RDI_PathfinderUp](INFO): Powering down
2019-02-07T23:58:14.954Z,1549583894.954 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-07T23:58:14.954Z,1549583894.954 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-07T23:58:14.954Z,1549583894.954 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-07T23:58:14.954Z,1549583894.954 [BuoyancyServo](ERROR): Communications Fault
2019-02-07T23:58:15.162Z,1549583895.162 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-07T23:58:15.172Z,1549583895.172 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:15.576Z,1549583895.576 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:15.975Z,1549583895.975 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:16.375Z,1549583896.375 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:16.847Z,1549583896.847 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:17.199Z,1549583897.199 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:17.334Z,1549583897.334 [Aanderaa_O2](INFO): Powering down
2019-02-07T23:58:17.455Z,1549583897.455 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-07T23:58:17.455Z,1549583897.455 [BuoyancyServo] No Fault, FailCount= 1
2019-02-07T23:58:17.611Z,1549583897.611 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:17.640Z,1549583897.640 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:58:17.759Z,1549583897.759 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-07T23:58:18.067Z,1549583898.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:18.411Z,1549583898.411 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:18.811Z,1549583898.811 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:19.211Z,1549583899.211 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:19.611Z,1549583899.611 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:20.012Z,1549583900.012 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:20.412Z,1549583900.412 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:20.811Z,1549583900.811 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:21.211Z,1549583901.211 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:21.599Z,1549583901.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:22.003Z,1549583902.003 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:22.388Z,1549583902.388 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:22.800Z,1549583902.800 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:23.179Z,1549583903.179 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:23.588Z,1549583903.588 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:23.990Z,1549583903.990 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:24.379Z,1549583904.379 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:24.792Z,1549583904.792 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:25.179Z,1549583905.179 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:25.590Z,1549583905.590 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:25.979Z,1549583905.979 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:26.391Z,1549583906.391 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:26.779Z,1549583906.779 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:27.191Z,1549583907.191 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:27.216Z,1549583907.216 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-07T23:58:27.579Z,1549583907.579 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:27.991Z,1549583907.991 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:28.379Z,1549583908.379 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:28.791Z,1549583908.791 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:29.180Z,1549583909.180 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:29.582Z,1549583909.582 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:29.984Z,1549583909.984 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:30.384Z,1549583910.384 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:30.783Z,1549583910.783 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:31.183Z,1549583911.183 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:31.583Z,1549583911.583 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:31.983Z,1549583911.983 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:32.381Z,1549583912.381 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:32.781Z,1549583912.781 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:33.180Z,1549583913.180 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:33.583Z,1549583913.583 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:33.983Z,1549583913.983 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:33.984Z,1549583913.984 [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:58:33.984Z,1549583913.984 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2019-02-07T23:58:33.984Z,1549583913.984 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:58:34.080Z,1549583914.080 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:58:34.383Z,1549583914.383 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:58:34.383Z,1549583914.383 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:58:34.383Z,1549583914.383 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:58:34.795Z,1549583914.795 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:58:35.187Z,1549583915.187 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:58:35.275Z,1549583915.275 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:58:35.275Z,1549583915.275 [DUSBL_Hydroid] No Fault, FailCount= 1
2019-02-07T23:58:35.583Z,1549583915.583 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:58:35.983Z,1549583915.983 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:58:36.383Z,1549583916.383 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:58:36.783Z,1549583916.783 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:58:37.183Z,1549583917.183 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:58:37.583Z,1549583917.583 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:58:37.583Z,1549583917.583 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:58:37.583Z,1549583917.583 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:58:37.983Z,1549583917.983 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:38.383Z,1549583918.383 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:38.783Z,1549583918.783 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:39.181Z,1549583919.181 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:39.592Z,1549583919.592 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:39.979Z,1549583919.979 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:39.980Z,1549583919.980 [NAL9602](INFO): Powering up NAL9602
2019-02-07T23:58:40.379Z,1549583920.379 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:40.779Z,1549583920.779 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:41.179Z,1549583921.179 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:41.579Z,1549583921.579 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:41.612Z,1549583921.612 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-07T23:58:41.616Z,1549583921.616 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-07T23:58:41.979Z,1549583921.979 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:42.379Z,1549583922.379 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:42.779Z,1549583922.779 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:43.179Z,1549583923.179 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:43.583Z,1549583923.583 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:44.100Z,1549583924.100 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:44.501Z,1549583924.501 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:44.910Z,1549583924.910 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:45.287Z,1549583925.287 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:45.695Z,1549583925.695 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:46.087Z,1549583926.087 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:46.488Z,1549583926.488 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:46.922Z,1549583926.922 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:47.287Z,1549583927.287 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:47.687Z,1549583927.687 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:48.087Z,1549583928.087 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:48.487Z,1549583928.487 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:48.891Z,1549583928.891 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:49.287Z,1549583929.287 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:49.705Z,1549583929.705 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:50.087Z,1549583930.087 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:50.487Z,1549583930.487 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:50.891Z,1549583930.891 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:50.893Z,1549583930.893 [NAL9602](INFO): NAL9602 initialized
2019-02-07T23:58:51.287Z,1549583931.287 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:51.687Z,1549583931.687 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:51.702Z,1549583931.702 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:58:52.087Z,1549583932.087 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:52.487Z,1549583932.487 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:52.611Z,1549583932.611 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010104
CHAN A1 (24V): -0.025483
CHAN A2 (12V): -0.007142
CHAN A3 (5V): -0.003104
CHAN B0 (3.3V): -0.000708
CHAN B1 (3.15aV): -0.000484
CHAN B2 (3.15bV): -0.000249
CHAN B3 (GND): 0.000586
OPEN: 0.009190
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-07T23:58:52.917Z,1549583932.917 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:53.287Z,1549583933.287 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:53.687Z,1549583933.687 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:54.095Z,1549583934.095 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:54.487Z,1549583934.487 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:54.887Z,1549583934.887 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:55.287Z,1549583935.287 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:55.687Z,1549583935.687 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:56.087Z,1549583936.087 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:56.487Z,1549583936.487 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:56.894Z,1549583936.894 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:57.519Z,1549583937.519 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:57.925Z,1549583937.925 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:58:57.926Z,1549583937.926 [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
«ZÒQf?°h!
2019-02-07T23:58:57.927Z,1549583937.927 [DUSBL_Hydroid] Communications Fault, FailCount= 2
2019-02-07T23:58:57.927Z,1549583937.927 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:58:58.165Z,1549583938.165 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:58:58.324Z,1549583938.324 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:58:58.324Z,1549583938.324 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:58:58.324Z,1549583938.324 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:58:58.711Z,1549583938.711 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:58:59.111Z,1549583939.111 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:58:59.323Z,1549583939.323 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:58:59.324Z,1549583939.324 [DUSBL_Hydroid] No Fault, FailCount= 2
2019-02-07T23:58:59.519Z,1549583939.519 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:58:59.925Z,1549583939.925 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:00.337Z,1549583940.337 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:00.711Z,1549583940.711 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:01.119Z,1549583941.119 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:01.519Z,1549583941.519 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:01.519Z,1549583941.519 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:59:01.519Z,1549583941.519 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:59:01.925Z,1549583941.925 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:02.311Z,1549583942.311 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:02.719Z,1549583942.719 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:03.123Z,1549583943.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:03.519Z,1549583943.519 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:03.943Z,1549583943.943 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:04.325Z,1549583944.325 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:04.719Z,1549583944.719 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:05.123Z,1549583945.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:05.519Z,1549583945.519 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:05.936Z,1549583945.936 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:06.311Z,1549583946.311 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:06.724Z,1549583946.724 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:07.119Z,1549583947.119 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:07.533Z,1549583947.533 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:07.943Z,1549583947.943 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:08.311Z,1549583948.311 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:08.711Z,1549583948.711 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:09.111Z,1549583949.111 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:09.511Z,1549583949.511 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:09.935Z,1549583949.935 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:10.319Z,1549583950.319 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:10.711Z,1549583950.711 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:11.131Z,1549583951.131 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:11.528Z,1549583951.528 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:11.951Z,1549583951.951 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:12.320Z,1549583952.320 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:12.721Z,1549583952.721 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:13.111Z,1549583953.111 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:13.524Z,1549583953.524 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:13.925Z,1549583953.925 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:14.315Z,1549583954.315 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:14.715Z,1549583954.715 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:15.115Z,1549583955.115 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:15.463Z,1549583955.463 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-02-07T23:59:15.479Z,1549583955.479 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-02-07T23:59:15.479Z,1549583955.479 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-02-07T23:59:15.479Z,1549583955.479 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-02-07T23:59:15.480Z,1549583955.480 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-02-07T23:59:15.480Z,1549583955.480 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-02-07T23:59:15.480Z,1549583955.480 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-02-07T23:59:15.481Z,1549583955.481 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (bool)
2019-02-07T23:59:15.481Z,1549583955.481 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-02-07T23:59:15.482Z,1549583955.482 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-02-07T23:59:15.482Z,1549583955.482 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-02-07T23:59:15.531Z,1549583955.531 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:15.643Z,1549583955.643 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-02-07T23:59:15.643Z,1549583955.643 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-02-07T23:59:15.643Z,1549583955.643 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-02-07T23:59:15.665Z,1549583955.665 [CommandLine](IMPORTANT): GobyModem.dusblPingCode (enum)
2019-02-07T23:59:15.665Z,1549583955.665 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-02-07T23:59:15.666Z,1549583955.666 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-02-07T23:59:15.666Z,1549583955.666 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-02-07T23:59:15.666Z,1549583955.666 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-02-07T23:59:15.667Z,1549583955.667 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-02-07T23:59:15.667Z,1549583955.667 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-02-07T23:59:15.667Z,1549583955.667 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-02-07T23:59:15.668Z,1549583955.668 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-02-07T23:59:15.668Z,1549583955.668 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-02-07T23:59:15.716Z,1549583955.716 [CommandLine](IMPORTANT): DUSBL_Hydroid.durationOfLastRun (second)
2019-02-07T23:59:15.722Z,1549583955.722 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_voltage (volt)
2019-02-07T23:59:15.722Z,1549583955.722 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgVoltage (volt)
2019-02-07T23:59:15.723Z,1549583955.723 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_current (milliampere)
2019-02-07T23:59:15.723Z,1549583955.723 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgCurrent (milliampere)
2019-02-07T23:59:15.947Z,1549583955.947 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:16.311Z,1549583956.311 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:16.727Z,1549583956.727 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:17.119Z,1549583957.119 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:17.512Z,1549583957.512 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:17.936Z,1549583957.936 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:18.320Z,1549583958.320 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:18.717Z,1549583958.717 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:19.127Z,1549583959.127 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:19.519Z,1549583959.519 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:19.932Z,1549583959.932 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:20.325Z,1549583960.325 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:20.722Z,1549583960.722 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:21.115Z,1549583961.115 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:21.513Z,1549583961.513 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:21.934Z,1549583961.934 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:21.935Z,1549583961.935 [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
fLÁgÓ
?°h!
2019-02-07T23:59:21.935Z,1549583961.935 [DUSBL_Hydroid] Communications Fault, FailCount= 3
2019-02-07T23:59:21.935Z,1549583961.935 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:59:21.996Z,1549583961.996 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:59:22.328Z,1549583962.328 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:59:22.328Z,1549583962.328 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:59:22.328Z,1549583962.328 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:59:22.715Z,1549583962.715 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:59:23.115Z,1549583963.115 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:59:23.188Z,1549583963.188 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:59:23.188Z,1549583963.188 [DUSBL_Hydroid] No Fault, FailCount= 3
2019-02-07T23:59:23.519Z,1549583963.519 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:23.936Z,1549583963.936 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:24.455Z,1549583964.455 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:24.855Z,1549583964.855 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:25.259Z,1549583965.259 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:25.655Z,1549583965.655 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:25.655Z,1549583965.655 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:59:25.656Z,1549583965.656 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:59:26.071Z,1549583966.071 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:26.455Z,1549583966.455 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:26.855Z,1549583966.855 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:27.267Z,1549583967.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:27.663Z,1549583967.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:27.945Z,1549583967.945 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.baud
2019-02-07T23:59:27.945Z,1549583967.945 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud 57600.000000 bps
2019-02-07T23:59:28.067Z,1549583968.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:28.467Z,1549583968.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:28.867Z,1549583968.867 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:29.263Z,1549583969.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:29.663Z,1549583969.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:30.063Z,1549583970.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:30.467Z,1549583970.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:30.865Z,1549583970.865 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:31.269Z,1549583971.269 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:31.663Z,1549583971.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:32.064Z,1549583972.064 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:32.159Z,1549583972.159 [CommandLine](IMPORTANT): got command show variable loadC4
2019-02-07T23:59:32.463Z,1549583972.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:32.863Z,1549583972.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:33.271Z,1549583973.271 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:33.663Z,1549583973.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:34.067Z,1549583974.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:34.463Z,1549583974.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:34.863Z,1549583974.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:35.271Z,1549583975.271 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:35.663Z,1549583975.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:35.730Z,1549583975.730 [SBIT](IMPORTANT): SBIT PASSED
2019-02-07T23:59:35.763Z,1549583975.763 [CommandLine](IMPORTANT): got command configSet list
2019-02-07T23:59:35.763Z,1549583975.763 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-07T23:59:35.764Z,1549583975.764 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;

2019-02-07T23:59:35.764Z,1549583975.764 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool;

2019-02-07T23:59:36.063Z,1549583976.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:36.134Z,1549583976.134 [MissionManager](IMPORTANT): Started mission Startup
2019-02-07T23:59:36.135Z,1549583976.135 [Startup] Running Loop=1
2019-02-07T23:59:36.135Z,1549583976.135 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-07T23:59:36.135Z,1549583976.135 [Startup:A.GoToSurface] Running Loop=1
2019-02-07T23:59:36.135Z,1549583976.135 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-07T23:59:36.136Z,1549583976.136 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-07T23:59:36.136Z,1549583976.136 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-07T23:59:36.136Z,1549583976.136 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-07T23:59:36.137Z,1549583976.137 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-07T23:59:36.137Z,1549583976.137 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-07T23:59:36.155Z,1549583976.155 [Startup:StartupSatComms] Running Loop=1
2019-02-07T23:59:36.155Z,1549583976.155 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-07T23:59:36.155Z,1549583976.155 [Startup:StartupSatComms:A] Running Loop=1
2019-02-07T23:59:36.467Z,1549583976.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:36.527Z,1549583976.527 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-07T23:59:36.867Z,1549583976.867 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:37.274Z,1549583977.274 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:37.671Z,1549583977.671 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:37.678Z,1549583977.678 [NAL9602](INFO): SBD MO Status=2, MOMSN=2137, MT Status=2, MTMSN=0
2019-02-07T23:59:37.678Z,1549583977.678 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-07T23:59:38.077Z,1549583978.077 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:38.466Z,1549583978.466 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:38.868Z,1549583978.868 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:39.263Z,1549583979.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:39.671Z,1549583979.671 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:40.071Z,1549583980.071 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:40.256Z,1549583980.256 [CommandLine](IMPORTANT): got command show variable loadC4
2019-02-07T23:59:40.467Z,1549583980.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:40.867Z,1549583980.867 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:41.263Z,1549583981.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:41.663Z,1549583981.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:42.067Z,1549583982.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:42.463Z,1549583982.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:42.877Z,1549583982.877 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:43.271Z,1549583983.271 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:43.663Z,1549583983.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:44.067Z,1549583984.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:44.463Z,1549583984.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:44.871Z,1549583984.871 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:45.267Z,1549583985.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:45.663Z,1549583985.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:45.664Z,1549583985.664 [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
«ZÒQf?°h!
2019-02-07T23:59:45.664Z,1549583985.664 [DUSBL_Hydroid] Communications Fault, FailCount= 4
2019-02-07T23:59:45.664Z,1549583985.664 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:59:45.766Z,1549583985.766 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:59:46.067Z,1549583986.067 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:59:46.067Z,1549583986.067 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:59:46.068Z,1549583986.068 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:59:46.471Z,1549583986.471 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:59:46.874Z,1549583986.874 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:59:46.948Z,1549583986.948 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:59:46.948Z,1549583986.948 [DUSBL_Hydroid] No Fault, FailCount= 4
2019-02-07T23:59:47.274Z,1549583987.274 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:47.667Z,1549583987.667 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:48.063Z,1549583988.063 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:48.460Z,1549583988.460 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:48.863Z,1549583988.863 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:49.263Z,1549583989.263 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:59:49.263Z,1549583989.263 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:59:49.264Z,1549583989.264 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:59:49.663Z,1549583989.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:50.060Z,1549583990.060 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:50.463Z,1549583990.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:50.863Z,1549583990.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:51.267Z,1549583991.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:51.664Z,1549583991.664 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:52.063Z,1549583992.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:52.467Z,1549583992.467 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:52.863Z,1549583992.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:53.263Z,1549583993.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:53.663Z,1549583993.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:54.063Z,1549583994.063 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:54.463Z,1549583994.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:54.863Z,1549583994.863 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:55.267Z,1549583995.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:55.675Z,1549583995.675 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:56.067Z,1549583996.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:56.463Z,1549583996.463 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:56.867Z,1549583996.867 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:57.267Z,1549583997.267 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:57.663Z,1549583997.663 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:58.067Z,1549583998.067 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:58.471Z,1549583998.471 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:58.867Z,1549583998.867 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:59.263Z,1549583999.263 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:59:59.667Z,1549583999.667 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:00.063Z,1549584000.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:00.464Z,1549584000.464 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:00.863Z,1549584000.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:01.263Z,1549584001.263 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:01.663Z,1549584001.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:02.067Z,1549584002.067 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:02.463Z,1549584002.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:02.863Z,1549584002.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:03.267Z,1549584003.267 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:03.663Z,1549584003.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:04.063Z,1549584004.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:04.463Z,1549584004.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:04.867Z,1549584004.867 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:05.271Z,1549584005.271 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:05.663Z,1549584005.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:06.063Z,1549584006.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:06.467Z,1549584006.467 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:06.867Z,1549584006.867 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:07.263Z,1549584007.263 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:07.663Z,1549584007.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:08.063Z,1549584008.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:08.467Z,1549584008.467 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:08.863Z,1549584008.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:09.271Z,1549584009.271 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:09.272Z,1549584009.272 [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
«ZÒQf?°h!
2019-02-08T00:00:09.272Z,1549584009.272 [DUSBL_Hydroid] Communications Fault, FailCount= 5
2019-02-08T00:00:09.272Z,1549584009.272 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:00:09.468Z,1549584009.468 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:00:09.667Z,1549584009.667 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:00:09.667Z,1549584009.667 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:00:09.667Z,1549584009.667 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:00:10.071Z,1549584010.071 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:00:10.463Z,1549584010.463 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:00:10.545Z,1549584010.545 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:00:10.545Z,1549584010.545 [DUSBL_Hydroid] No Fault, FailCount= 5
2019-02-08T00:00:10.864Z,1549584010.864 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:00:11.268Z,1549584011.268 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:00:11.663Z,1549584011.663 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:00:12.063Z,1549584012.063 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:00:12.467Z,1549584012.467 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:00:12.863Z,1549584012.863 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:00:12.863Z,1549584012.863 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:00:12.864Z,1549584012.864 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:00:13.263Z,1549584013.263 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:13.667Z,1549584013.667 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:14.063Z,1549584014.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:14.463Z,1549584014.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:14.863Z,1549584014.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:15.263Z,1549584015.263 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:15.663Z,1549584015.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:16.063Z,1549584016.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:16.463Z,1549584016.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:16.872Z,1549584016.872 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:17.267Z,1549584017.267 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:17.663Z,1549584017.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:18.067Z,1549584018.067 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:18.463Z,1549584018.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:18.867Z,1549584018.867 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:19.264Z,1549584019.264 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:19.667Z,1549584019.667 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:20.075Z,1549584020.075 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:20.463Z,1549584020.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:20.867Z,1549584020.867 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:21.263Z,1549584021.263 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:21.663Z,1549584021.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:22.071Z,1549584022.071 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:22.467Z,1549584022.467 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:22.863Z,1549584022.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:23.267Z,1549584023.267 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:23.667Z,1549584023.667 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:24.063Z,1549584024.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:24.463Z,1549584024.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:24.863Z,1549584024.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:25.273Z,1549584025.273 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:25.663Z,1549584025.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:26.071Z,1549584026.071 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:26.467Z,1549584026.467 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:26.863Z,1549584026.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:27.263Z,1549584027.263 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:27.663Z,1549584027.663 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:28.063Z,1549584028.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:28.463Z,1549584028.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:28.867Z,1549584028.867 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:29.268Z,1549584029.268 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:29.671Z,1549584029.671 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:30.071Z,1549584030.071 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:30.467Z,1549584030.467 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:30.863Z,1549584030.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:31.273Z,1549584031.273 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:31.667Z,1549584031.667 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:32.063Z,1549584032.063 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:32.463Z,1549584032.463 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:32.863Z,1549584032.863 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:33.263Z,1549584033.263 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:33.264Z,1549584033.264 [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-08T00:00:33.264Z,1549584033.264 [DUSBL_Hydroid] Communications Fault, FailCount= 6
2019-02-08T00:00:33.264Z,1549584033.264 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:00:33.373Z,1549584033.373 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:00:33.671Z,1549584033.671 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:00:33.671Z,1549584033.671 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:00:33.671Z,1549584033.671 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:00:34.067Z,1549584034.067 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:00:34.463Z,1549584034.463 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:00:34.545Z,1549584034.545 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:00:34.545Z,1549584034.545 [DUSBL_Hydroid] No Fault, FailCount= 6
2019-02-08T00:00:34.887Z,1549584034.887 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:00:38.071Z,1549584038.071 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:00:38.071Z,1549584038.071 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:00:38.072Z,1549584038.072 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:00:38.157Z,1549584038.157 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-07T23:59:36.2Z
2019-02-08T00:00:38.157Z,1549584038.157 [Startup:StartupSatComms:A] Stopped
2019-02-08T00:00:38.157Z,1549584038.157 [Startup:StartupSatComms:B] Running Loop=1
2019-02-08T00:00:38.243Z,1549584038.243 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:38.295Z,1549584038.295 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-08T00:00:38.596Z,1549584038.596 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:38.992Z,1549584038.992 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004945
2019-02-08T00:00:39.012Z,1549584039.012 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:39.408Z,1549584039.408 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:39.783Z,1549584039.783 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:43.019Z,1549584043.019 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:43.022Z,1549584043.022 [NAL9602](INFO): SBD MO Status=2, MOMSN=2137, MT Status=2, MTMSN=0
2019-02-08T00:00:43.022Z,1549584043.022 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-08T00:00:43.174Z,1549584043.174 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20190207T235359/Courier0004.lzma
2019-02-08T00:00:43.183Z,1549584043.183 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:43.553Z,1549584043.553 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:43.949Z,1549584043.949 [DataOverHttps](INFO): Moved sent file to Logs/20190207T235359/Courier0004.lzma.bak
2019-02-08T00:00:43.949Z,1549584043.949 [DataOverHttps](INFO): SBD MOMSN=9641329
2019-02-08T00:00:43.956Z,1549584043.956 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:44.363Z,1549584044.363 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:44.751Z,1549584044.751 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:49.568Z,1549584049.568 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:49.974Z,1549584049.974 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:50.323Z,1549584050.323 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:50.727Z,1549584050.727 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:51.127Z,1549584051.127 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:51.519Z,1549584051.519 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:56.237Z,1549584056.237 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:56.365Z,1549584056.365 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20190207T235359/Express0005.lzma
2019-02-08T00:00:56.407Z,1549584056.407 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:56.759Z,1549584056.759 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:57.145Z,1549584057.145 [DataOverHttps](INFO): Moved sent file to Logs/20190207T235359/Express0005.lzma.bak
2019-02-08T00:00:57.145Z,1549584057.145 [DataOverHttps](INFO): SBD MOMSN=9641332
2019-02-08T00:00:57.179Z,1549584057.179 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:57.566Z,1549584057.566 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:00:57.951Z,1549584057.951 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:01.185Z,1549584061.185 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:01.186Z,1549584061.186 [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
fLÁgÓ
?°h!
2019-02-08T00:01:01.186Z,1549584061.186 [DUSBL_Hydroid] Communications Fault, FailCount= 7
2019-02-08T00:01:01.186Z,1549584061.186 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:01:01.298Z,1549584061.298 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:01:01.374Z,1549584061.374 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:01:01.374Z,1549584061.374 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:01:01.375Z,1549584061.375 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:01:01.729Z,1549584061.729 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:01:02.124Z,1549584062.124 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:01:02.382Z,1549584062.382 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-08T00:01:02.382Z,1549584062.382 [DUSBL_Hydroid] No Fault, FailCount= 7
2019-02-08T00:01:02.520Z,1549584062.520 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:01:02.928Z,1549584062.928 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:01:03.319Z,1549584063.319 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:01:10.204Z,1549584070.204 [DUSBL_Hydroid](INFO): Start
2019-02-08T00:01:10.204Z,1549584070.204 [DUSBL_Hydroid](INFO): Powering up
2019-02-08T00:01:10.204Z,1549584070.204 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-08T00:01:10.252Z,1549584070.252 [NAL9602](INFO): SBD MO Status=2, MOMSN=2137, MT Status=2, MTMSN=0
2019-02-08T00:01:10.252Z,1549584070.252 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-08T00:01:10.522Z,1549584070.522 [DataOverHttps](INFO): Sending 1324 bytes from file Logs/20190207T235802/Express0001.lzma
2019-02-08T00:01:10.539Z,1549584070.539 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:10.887Z,1549584070.887 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:11.280Z,1549584071.280 [DataOverHttps](INFO): Moved sent file to Logs/20190207T235802/Express0001.lzma.bak
2019-02-08T00:01:11.280Z,1549584071.280 [DataOverHttps](INFO): SBD MOMSN=9641339
2019-02-08T00:01:11.288Z,1549584071.288 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:11.692Z,1549584071.692 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:11.767Z,1549584071.767 [Startup:StartupSatComms:B] Stopped
2019-02-08T00:01:11.767Z,1549584071.767 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-08T00:01:11.767Z,1549584071.767 [Startup:StartupSatComms] Stopped
2019-02-08T00:01:11.767Z,1549584071.767 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-08T00:01:11.769Z,1549584071.769 [Startup](INFO): Completed Startup
2019-02-08T00:01:11.769Z,1549584071.769 [MissionManager](INFO): Startup is completed.
2019-02-08T00:01:11.769Z,1549584071.769 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-08T00:01:11.769Z,1549584071.769 [Startup] Stopped
2019-02-08T00:01:11.769Z,1549584071.769 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-08T00:01:11.769Z,1549584071.769 [Startup:A.GoToSurface] Stopped
2019-02-08T00:01:11.769Z,1549584071.769 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-08T00:01:12.104Z,1549584072.104 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:12.178Z,1549584072.178 [MissionManager](IMPORTANT): Started mission Default
2019-02-08T00:01:12.178Z,1549584072.178 [Default] Running Loop=1
2019-02-08T00:01:12.178Z,1549584072.178 [Default](DEBUG): Aggregate::initialize Default
2019-02-08T00:01:12.178Z,1549584072.178 [Default:B.GoToSurface] Running Loop=1
2019-02-08T00:01:12.178Z,1549584072.178 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-08T00:01:12.179Z,1549584072.179 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-08T00:01:12.179Z,1549584072.179 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-08T00:01:12.180Z,1549584072.180 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-08T00:01:12.180Z,1549584072.180 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-08T00:01:12.180Z,1549584072.180 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-08T00:01:12.181Z,1549584072.181 [Default:A.Wait] Running Loop=1
2019-02-08T00:01:12.181Z,1549584072.181 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-08T00:01:17.293Z,1549584077.293 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:17.490Z,1549584077.490 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-08T00:01:17.490Z,1549584077.490 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-08T00:01:17.492Z,1549584077.492 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-08T00:01:17.492Z,1549584077.492 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-08T00:01:17.493Z,1549584077.493 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-08T00:01:17.493Z,1549584077.493 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-08T00:01:17.505Z,1549584077.505 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-08T00:01:17.547Z,1549584077.547 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-08T00:01:17.548Z,1549584077.548 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-08T00:01:17.604Z,1549584077.604 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:17.677Z,1549584077.677 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-08T00:01:17.677Z,1549584077.677 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-08T00:01:17.682Z,1549584077.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-08T00:01:17.682Z,1549584077.682 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-08T00:01:17.682Z,1549584077.682 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-08T00:01:17.682Z,1549584077.682 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-08T00:01:17.980Z,1549584077.980 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:18.042Z,1549584078.042 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-08T00:01:18.387Z,1549584078.387 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:18.763Z,1549584078.763 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:19.163Z,1549584079.163 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:19.563Z,1549584079.563 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:19.963Z,1549584079.963 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:20.363Z,1549584080.363 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:20.763Z,1549584080.763 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:21.171Z,1549584081.171 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:21.563Z,1549584081.563 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:21.963Z,1549584081.963 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:22.363Z,1549584082.363 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:22.763Z,1549584082.763 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:23.163Z,1549584083.163 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:23.563Z,1549584083.563 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:23.963Z,1549584083.963 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:24.363Z,1549584084.363 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:24.763Z,1549584084.763 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:25.163Z,1549584085.163 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:25.231Z,1549584085.231 [Default:A.Wait](INFO): Done Waiting.
2019-02-08T00:01:25.231Z,1549584085.231 [Default:A.Wait] Stopped
2019-02-08T00:01:25.232Z,1549584085.232 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-08T00:01:25.563Z,1549584085.563 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:25.623Z,1549584085.623 [Default:CheckIn] Running Loop=1
2019-02-08T00:01:25.623Z,1549584085.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-08T00:01:25.623Z,1549584085.623 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-08T00:01:25.963Z,1549584085.963 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:26.013Z,1549584086.013 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-08T00:01:26.363Z,1549584086.363 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:26.763Z,1549584086.763 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:27.167Z,1549584087.167 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:27.567Z,1549584087.567 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:27.963Z,1549584087.963 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:28.367Z,1549584088.367 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:28.763Z,1549584088.763 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:29.163Z,1549584089.163 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:29.567Z,1549584089.567 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:29.971Z,1549584089.971 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:30.371Z,1549584090.371 [DUSBL_Hydroid](INFO): Starting
2019-02-08T00:01:30.372Z,1549584090.372 [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
ÒûÆמY?°h!
2019-02-08T00:01:30.372Z,1549584090.372 [DUSBL_Hydroid] Communications Fault, FailCount= 8
2019-02-08T00:01:30.372Z,1549584090.372 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-08T00:01:30.481Z,1549584090.481 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:01:30.482Z,1549584090.482 [CBIT](CRITICAL): Communications Fault in component: DUSBL_Hydroid
2019-02-08T00:01:30.763Z,1549584090.763 [DUSBL_Hydroid](INFO): Stop
2019-02-08T00:01:30.763Z,1549584090.763 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:01:30.763Z,1549584090.763 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:01:30.912Z,1549584090.912 [CommandLine](FAULT): Scheduling is paused
2019-02-08T00:01:30.913Z,1549584090.913 [CBIT](INFO): Critical error at 20190208T000130
2019-02-08T00:01:30.913Z,1549584090.913 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-08T00:01:31.163Z,1549584091.163 [DUSBL_Hydroid](INFO): Stopping
2019-02-08T00:01:31.573Z,1549584091.573 [DUSBL_Hydroid](INFO): Stopped
2019-02-08T00:01:40.769Z,1549584100.769 [NAL9602](INFO): SBD MO Status=2, MOMSN=2137, MT Status=2, MTMSN=0
2019-02-08T00:01:40.769Z,1549584100.769 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-08T00:01:57.308Z,1549584117.308 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-02-08T00:01:57.308Z,1549584117.308 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19020717085808,35.0, -00,1448.9,  0
2019-02-08T00:01:57.313Z,1549584117.313 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-02-08T00:02:27.049Z,1549584147.049 [NAL9602](INFO): SBD MO Status=0, MOMSN=2137, MT Status=0, MTMSN=0
2019-02-08T00:02:27.049Z,1549584147.049 [NAL9602](INFO): No messages in MT queue
2019-02-08T00:02:27.862Z,1549584147.862 [NAL9602](DEBUG): Fix Requested
2019-02-08T00:02:28.249Z,1549584148.249 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,000139.00,3648.16634,N,12147.28450,W,1,5,1.64,-3.7,M,-30.7,M,,*76
2019-02-08T00:02:28.253Z,1549584148.253 [NAL9602](INFO): GPS fix at 20190208T000139: (36.802772, -121.788075)
2019-02-08T00:02:28.333Z,1549584148.333 [Default:CheckIn:Read_GPS] Stopped
2019-02-08T00:02:28.333Z,1549584148.333 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-08T00:02:28.723Z,1549584148.723 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-08T00:02:35.266Z,1549584155.266 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20190207T235802/Courier0004.lzma
2019-02-08T00:02:36.017Z,1549584156.017 [DataOverHttps](INFO): Moved sent file to Logs/20190207T235802/Courier0004.lzma.bak
2019-02-08T00:02:36.017Z,1549584156.017 [DataOverHttps](INFO): SBD MOMSN=9641395
2019-02-08T00:02:45.969Z,1549584165.969 [DataOverHttps](INFO): Sending 334 bytes from file Logs/20190207T235802/Express0005.lzma
2019-02-08T00:02:46.712Z,1549584166.712 [DataOverHttps](INFO): Moved sent file to Logs/20190207T235802/Express0005.lzma.bak
2019-02-08T00:02:46.712Z,1549584166.712 [DataOverHttps](INFO): SBD MOMSN=9641399
2019-02-08T00:02:47.214Z,1549584167.214 [Default:CheckIn:Read_Iridium] Stopped
2019-02-08T00:02:47.215Z,1549584167.215 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-08T00:02:47.215Z,1549584167.215 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-08T00:03:05.511Z,1549584185.511 [NAL9602](INFO): Powering down
2019-02-08T00:03:11.941Z,1549584191.941 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-02-08T00:03:11.941Z,1549584191.941 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.08,       -0.  -0.07,   9.64,  0.00
2019-02-08T00:03:46.572Z,1549584226.572 [CommandLine](IMPORTANT): got command restart application
2019-02-08T00:03:47.581Z,1549584227.581 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:03:47.581Z,1549584227.581 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:47.662Z,1549584227.662 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-08T00:03:47.662Z,1549584227.662 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:47.663Z,1549584227.663 [CommandLine](INFO): Join timeout helper Thread ID is 8642
2019-02-08T00:03:47.682Z,1549584227.682 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-08T00:03:47.682Z,1549584227.682 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:47.683Z,1549584227.683 [NavChartDb](INFO): Join timeout helper Thread ID is 8643
2019-02-08T00:03:47.882Z,1549584227.882 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:03:47.882Z,1549584227.882 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:47.894Z,1549584227.894 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-08T00:03:47.895Z,1549584227.895 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:47.895Z,1549584227.895 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8644
2019-02-08T00:03:47.966Z,1549584227.966 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:03:47.966Z,1549584227.966 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-08T00:03:47.967Z,1549584227.967 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:47.986Z,1549584227.986 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-08T00:03:47.986Z,1549584227.986 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:47.986Z,1549584227.986 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8645
2019-02-08T00:03:48.006Z,1549584228.006 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:03:48.006Z,1549584228.006 [CTD_NeilBrown](INFO): Powering down
2019-02-08T00:03:48.018Z,1549584228.018 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.034Z,1549584228.034 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-08T00:03:48.034Z,1549584228.034 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.034Z,1549584228.034 [Radio_Surface](INFO): Join timeout helper Thread ID is 8646
2019-02-08T00:03:48.350Z,1549584228.350 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:03:48.350Z,1549584228.350 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.363Z,1549584228.363 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-08T00:03:48.363Z,1549584228.363 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.363Z,1549584228.363 [logger](INFO): Join timeout helper Thread ID is 8647
2019-02-08T00:03:48.382Z,1549584228.382 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:03:48.382Z,1549584228.382 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.402Z,1549584228.402 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-08T00:03:48.403Z,1549584228.403 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.403Z,1549584228.403 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-08T00:03:48.403Z,1549584228.403 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.403Z,1549584228.403 [controlThread](INFO): Join timeout helper Thread ID is 8648
2019-02-08T00:03:48.506Z,1549584228.506 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-08T00:03:48.506Z,1549584228.506 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-08T00:03:48.507Z,1549584228.507 [AHRS_M2](INFO): Powering down
2019-02-08T00:03:48.579Z,1549584228.579 [DUSBL_Hydroid](INFO): uninitialize
2019-02-08T00:03:48.579Z,1549584228.579 [DUSBL_Hydroid](INFO): Powering down
2019-02-08T00:03:48.580Z,1549584228.580 [NAL9602](INFO): Powering down
2019-02-08T00:03:48.650Z,1549584228.650 [RDI_Pathfinder](INFO): Powering down
2019-02-08T00:03:48.651Z,1549584228.651 [RDI_PathfinderUp](INFO): Powering down
2019-02-08T00:03:48.652Z,1549584228.652 [Aanderaa_O2](INFO): Powering down
2019-02-08T00:03:48.653Z,1549584228.653 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-08T00:03:48.654Z,1549584228.654 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-08T00:03:48.655Z,1549584228.655 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-08T00:03:48.656Z,1549584228.656 [MissionManager](INFO): Uninitializing Mission Default
2019-02-08T00:03:48.656Z,1549584228.656 [Default] Stopped
2019-02-08T00:03:48.656Z,1549584228.656 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-08T00:03:48.656Z,1549584228.656 [Default:B.GoToSurface] Stopped
2019-02-08T00:03:48.656Z,1549584228.656 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-08T00:03:48.656Z,1549584228.656 [Default:CheckIn] Stopped
2019-02-08T00:03:48.656Z,1549584228.656 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-08T00:03:48.656Z,1549584228.656 [Default:CheckIn:C.Wait] Stopped
2019-02-08T00:03:48.656Z,1549584228.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-08T00:03:48.659Z,1549584228.659 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-08T00:03:48.659Z,1549584228.659 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-08T00:03:48.660Z,1549584228.660 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-08T00:03:48.660Z,1549584228.660 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-08T00:03:48.660Z,1549584228.660 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-08T00:03:48.660Z,1549584228.660 [BuoyancyServo](INFO): Powering down
2019-02-08T00:03:48.674Z,1549584228.674 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-08T00:03:48.674Z,1549584228.674 [ElevatorServo](INFO): Powering down
2019-02-08T00:03:48.675Z,1549584228.675 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-08T00:03:48.675Z,1549584228.675 [MassServo](INFO): Powering down
2019-02-08T00:03:48.676Z,1549584228.676 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-08T00:03:48.676Z,1549584228.676 [RudderServo](INFO): Powering down
2019-02-08T00:03:48.677Z,1549584228.677 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-08T00:03:48.677Z,1549584228.677 [ThrusterServo](INFO): Powering down
2019-02-08T00:03:48.678Z,1549584228.678 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-08T00:03:48.678Z,1549584228.678 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-08T00:03:48.678Z,1549584228.678 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-08T00:03:48.678Z,1549584228.678 [CBIT](DEBUG): Powering off loads.
2019-02-08T00:03:48.690Z,1549584228.690 [CBIT](DEBUG): Disabling WDT.
2019-02-08T00:03:48.702Z,1549584228.702 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-08T00:03:48.703Z,1549584228.703 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.812Z,1549584228.812 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.859Z,1549584228.859 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.861Z,1549584228.861 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.883Z,1549584228.883 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-08T00:03:48.950Z,1549584228.950 [logger ThreadHandler](INFO): Thread cancelled.