2019-02-07T23:53:59.699Z,1549583639.699 [Supervisor](DEBUG): Initializing supervisor.
2019-02-07T23:53:59.702Z,1549583639.702 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-07T23:53:59.703Z,1549583639.703 [SyncHandler](INFO): Protected caller Thread ID is 8370
2019-02-07T23:53:59.703Z,1549583639.703 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-07T23:53:59.704Z,1549583639.704 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-07T23:53:59.705Z,1549583639.705 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8371
2019-02-07T23:53:59.708Z,1549583639.708 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-07T23:53:59.719Z,1549583639.719 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-07T23:53:59.720Z,1549583639.720 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-07T23:53:59.720Z,1549583639.720 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8372
2019-02-07T23:53:59.721Z,1549583639.721 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-07T23:53:59.722Z,1549583639.722 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-07T23:53:59.723Z,1549583639.723 [logger ThreadHandler](INFO): Protected caller Thread ID is 8373
2019-02-07T23:53:59.724Z,1549583639.724 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-07T23:53:59.725Z,1549583639.725 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-07T23:53:59.727Z,1549583639.727 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-07T23:54:00.077Z,1549583640.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-07T23:54:00.077Z,1549583640.077 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-07T23:54:00.524Z,1549583640.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-07T23:54:00.525Z,1549583640.525 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-07T23:54:00.851Z,1549583640.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-07T23:54:00.852Z,1549583640.852 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-07T23:54:01.071Z,1549583641.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-07T23:54:01.072Z,1549583641.072 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-07T23:54:01.634Z,1549583641.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-07T23:54:01.635Z,1549583641.635 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-07T23:54:02.298Z,1549583642.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-07T23:54:02.299Z,1549583642.299 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-07T23:54:02.401Z,1549583642.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-07T23:54:02.402Z,1549583642.402 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-07T23:54:02.596Z,1549583642.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-07T23:54:02.597Z,1549583642.597 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-07T23:54:02.740Z,1549583642.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-07T23:54:02.741Z,1549583642.741 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-07T23:54:02.820Z,1549583642.820 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-07T23:54:03.029Z,1549583643.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-07T23:54:03.030Z,1549583643.030 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-07T23:54:03.130Z,1549583643.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-07T23:54:03.131Z,1549583643.131 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-07T23:54:03.226Z,1549583643.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-07T23:54:03.227Z,1549583643.227 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-07T23:54:03.373Z,1549583643.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-07T23:54:03.374Z,1549583643.374 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-07T23:54:03.694Z,1549583643.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-07T23:54:03.696Z,1549583643.696 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-07T23:54:03.697Z,1549583643.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-07T23:54:03.832Z,1549583643.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-07T23:54:04.060Z,1549583644.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-07T23:54:04.061Z,1549583644.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-07T23:54:04.229Z,1549583644.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-07T23:54:04.329Z,1549583644.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-07T23:54:04.427Z,1549583644.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-07T23:54:04.622Z,1549583644.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-07T23:54:04.706Z,1549583644.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-07T23:54:04.804Z,1549583644.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-07T23:54:04.896Z,1549583644.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-07T23:54:04.978Z,1549583644.978 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-07T23:54:05.086Z,1549583645.086 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-02-07T23:54:05.086Z,1549583645.086 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-07T23:54:05.090Z,1549583645.090 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-07T23:54:05.224Z,1549583645.224 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-07T23:54:05.253Z,1549583645.253 [SBIT] Loaded
2019-02-07T23:54:05.254Z,1549583645.254 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-07T23:54:05.254Z,1549583645.254 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-07T23:54:05.282Z,1549583645.282 [IBIT] Loaded
2019-02-07T23:54:05.282Z,1549583645.282 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-07T23:54:05.285Z,1549583645.285 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-07T23:54:05.665Z,1549583645.665 [CBIT] Loaded
2019-02-07T23:54:05.666Z,1549583645.666 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-07T23:54:05.666Z,1549583645.666 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-07T23:54:05.667Z,1549583645.667 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-07T23:54:05.791Z,1549583645.791 [BuoyancyServo] Loaded
2019-02-07T23:54:05.792Z,1549583645.792 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-07T23:54:05.806Z,1549583645.806 [ElevatorServo] Loaded
2019-02-07T23:54:05.806Z,1549583645.806 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-07T23:54:05.820Z,1549583645.820 [MassServo] Loaded
2019-02-07T23:54:05.820Z,1549583645.820 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-07T23:54:05.835Z,1549583645.835 [RudderServo] Loaded
2019-02-07T23:54:05.836Z,1549583645.836 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-07T23:54:05.850Z,1549583645.850 [ThrusterServo] Loaded
2019-02-07T23:54:05.850Z,1549583645.850 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-07T23:54:05.850Z,1549583645.850 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-07T23:54:05.851Z,1549583645.851 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-07T23:54:05.954Z,1549583645.954 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-07T23:54:05.954Z,1549583645.954 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-07T23:54:06.012Z,1549583646.012 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-07T23:54:06.012Z,1549583646.012 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-07T23:54:06.070Z,1549583646.070 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-07T23:54:06.070Z,1549583646.070 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-07T23:54:06.086Z,1549583646.086 [NavChart] Loaded
2019-02-07T23:54:06.086Z,1549583646.086 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-07T23:54:06.090Z,1549583646.090 [UniversalFixResidualReporter] Loaded
2019-02-07T23:54:06.091Z,1549583646.091 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-07T23:54:06.091Z,1549583646.091 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-07T23:54:06.092Z,1549583646.092 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-07T23:54:06.105Z,1549583646.105 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-07T23:54:06.105Z,1549583646.105 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-07T23:54:06.337Z,1549583646.337 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-07T23:54:06.338Z,1549583646.338 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-07T23:54:06.793Z,1549583646.793 [AHRS_M2] Loaded
2019-02-07T23:54:06.793Z,1549583646.793 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-02-07T23:54:07.013Z,1549583647.013 [DataOverHttps] Loaded
2019-02-07T23:54:07.013Z,1549583647.013 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-07T23:54:07.037Z,1549583647.037 [Depth_Keller] Loaded
2019-02-07T23:54:07.038Z,1549583647.038 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-07T23:54:07.043Z,1549583647.043 [DropWeight] Loaded
2019-02-07T23:54:07.043Z,1549583647.043 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-07T23:54:07.228Z,1549583647.228 [DUSBL_Hydroid] Loaded
2019-02-07T23:54:07.229Z,1549583647.229 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-07T23:54:07.317Z,1549583647.317 [NAL9602] Loaded
2019-02-07T23:54:07.318Z,1549583647.318 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-07T23:54:07.323Z,1549583647.323 [Onboard] Loaded
2019-02-07T23:54:07.323Z,1549583647.323 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-07T23:54:07.330Z,1549583647.330 [Radio_Surface] Loaded
2019-02-07T23:54:07.330Z,1549583647.330 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-07T23:54:07.355Z,1549583647.355 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0
2019-02-07T23:54:07.355Z,1549583647.355 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8452
2019-02-07T23:54:07.453Z,1549583647.453 [RDI_Pathfinder] Loaded
2019-02-07T23:54:07.454Z,1549583647.454 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-07T23:54:07.475Z,1549583647.475 [RDI_PathfinderUp] Loaded
2019-02-07T23:54:07.476Z,1549583647.476 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-07T23:54:09.020Z,1549583649.020 [BPC1] Loaded
2019-02-07T23:54:09.021Z,1549583649.021 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-07T23:54:09.021Z,1549583649.021 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-07T23:54:09.022Z,1549583649.022 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-07T23:54:09.088Z,1549583649.088 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-07T23:54:09.174Z,1549583649.174 [VerticalControl] Loaded
2019-02-07T23:54:09.174Z,1549583649.174 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-07T23:54:09.175Z,1549583649.175 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-07T23:54:09.232Z,1549583649.232 [HorizontalControl] Loaded
2019-02-07T23:54:09.232Z,1549583649.232 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-07T23:54:09.233Z,1549583649.233 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-07T23:54:09.235Z,1549583649.235 [SpeedControl] Loaded
2019-02-07T23:54:09.235Z,1549583649.235 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-07T23:54:09.236Z,1549583649.236 [LoopControl](DEBUG): Construct LoopControl.
2019-02-07T23:54:09.237Z,1549583649.237 [LoopControl] Loaded
2019-02-07T23:54:09.237Z,1549583649.237 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-07T23:54:09.237Z,1549583649.237 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-07T23:54:09.238Z,1549583649.238 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-07T23:54:09.337Z,1549583649.337 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-07T23:54:09.337Z,1549583649.337 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-07T23:54:09.513Z,1549583649.513 [Aanderaa_O2] Loaded
2019-02-07T23:54:09.513Z,1549583649.513 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-07T23:54:09.596Z,1549583649.596 [CTD_NeilBrown] Loaded
2019-02-07T23:54:09.596Z,1549583649.596 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-07T23:54:09.597Z,1549583649.597 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0
2019-02-07T23:54:09.598Z,1549583649.598 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8453
2019-02-07T23:54:09.643Z,1549583649.643 [WetLabsSeaOWL_UV_A] Loaded
2019-02-07T23:54:09.643Z,1549583649.643 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-07T23:54:09.644Z,1549583649.644 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2019-02-07T23:54:09.644Z,1549583649.644 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8454
2019-02-07T23:54:09.645Z,1549583649.645 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-07T23:54:09.646Z,1549583649.646 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-07T23:54:09.709Z,1549583649.709 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-07T23:54:09.710Z,1549583649.710 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-07T23:54:09.750Z,1549583649.750 [DepthRateCalculator] Loaded
2019-02-07T23:54:09.751Z,1549583649.751 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-07T23:54:09.756Z,1549583649.756 [PitchRateCalculator] Loaded
2019-02-07T23:54:09.756Z,1549583649.756 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-07T23:54:09.768Z,1549583649.768 [SpeedCalculator] Loaded
2019-02-07T23:54:09.768Z,1549583649.768 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-07T23:54:09.789Z,1549583649.789 [TempGradientCalculator] Loaded
2019-02-07T23:54:09.790Z,1549583649.790 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-07T23:54:09.795Z,1549583649.795 [YawRateCalculator] Loaded
2019-02-07T23:54:09.795Z,1549583649.795 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-07T23:54:09.824Z,1549583649.824 [ElevatorOffsetCalculator] Loaded
2019-02-07T23:54:09.825Z,1549583649.825 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-07T23:54:09.825Z,1549583649.825 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-07T23:54:09.826Z,1549583649.826 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-07T23:54:09.852Z,1549583649.852 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-07T23:54:09.856Z,1549583649.856 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-07T23:54:09.857Z,1549583649.857 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-07T23:54:09.863Z,1549583649.863 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-07T23:54:09.864Z,1549583649.864 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2019-02-07T23:54:09.864Z,1549583649.864 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8455
2019-02-07T23:54:09.869Z,1549583649.869 [Supervisor](INFO): Main Thread ID is 8078
2019-02-07T23:54:09.869Z,1549583649.869 [Supervisor](DEBUG): Running supervisor.
2019-02-07T23:54:09.870Z,1549583649.870 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8456
2019-02-07T23:54:09.872Z,1549583649.872 [controlThread ThreadHandler](INFO): Handler Thread ID is 8457
2019-02-07T23:54:09.873Z,1549583649.873 [controlThread](DEBUG): Initializing ControlThread
2019-02-07T23:54:09.873Z,1549583649.873 [SBIT](INFO): Initialize SBIT Component.
2019-02-07T23:54:09.874Z,1549583649.874 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9
2019-02-07T23:54:09.874Z,1549583649.874 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6
2019-02-07T23:54:09.874Z,1549583649.874 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-07T23:54:09.875Z,1549583649.875 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-07T23:54:09.876Z,1549583649.876 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-07T23:54:09.876Z,1549583649.876 [IBIT](INFO): Initialize IBIT Component.
2019-02-07T23:54:09.877Z,1549583649.877 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-07T23:54:09.878Z,1549583649.878 [logger ThreadHandler](INFO): Handler Thread ID is 8458
2019-02-07T23:54:09.890Z,1549583649.890 [CBIT](DEBUG): Initialized mux pins.
2019-02-07T23:54:09.890Z,1549583649.890 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-07T23:54:09.902Z,1549583649.902 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8459
2019-02-07T23:54:09.908Z,1549583649.908 [Radio_Surface](INFO): Powering up
2019-02-07T23:54:09.914Z,1549583649.914 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-07T23:54:09.914Z,1549583649.914 [CBIT](DEBUG): Initializing heartbeat.
2019-02-07T23:54:09.915Z,1549583649.915 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8460
2019-02-07T23:54:09.915Z,1549583649.915 [CTD_NeilBrown](INFO): Powering down
2019-02-07T23:54:09.944Z,1549583649.944 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8461
2019-02-07T23:54:09.945Z,1549583649.945 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-07T23:54:09.982Z,1549583649.982 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8462
2019-02-07T23:54:09.985Z,1549583649.985 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-07T23:54:09.985Z,1549583649.985 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-07T23:54:09.986Z,1549583649.986 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-07T23:54:09.986Z,1549583649.986 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-07T23:54:09.986Z,1549583649.986 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-07T23:54:09.986Z,1549583649.986 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-07T23:54:09.986Z,1549583649.986 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-07T23:54:09.987Z,1549583649.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-07T23:54:09.987Z,1549583649.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-07T23:54:09.987Z,1549583649.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-07T23:54:09.987Z,1549583649.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-07T23:54:09.987Z,1549583649.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-07T23:54:09.988Z,1549583649.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-07T23:54:09.988Z,1549583649.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-07T23:54:09.988Z,1549583649.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-07T23:54:09.988Z,1549583649.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-07T23:54:09.988Z,1549583649.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-07T23:54:09.988Z,1549583649.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-07T23:54:10.023Z,1549583650.023 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-07T23:54:10.024Z,1549583650.024 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-07T23:54:10.024Z,1549583650.024 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-07T23:54:10.029Z,1549583650.029 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-07T23:54:10.031Z,1549583650.031 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-07T23:54:10.031Z,1549583650.031 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-07T23:54:10.032Z,1549583650.032 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-07T23:54:10.032Z,1549583650.032 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-07T23:54:10.033Z,1549583650.033 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-07T23:54:10.033Z,1549583650.033 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-07T23:54:10.034Z,1549583650.034 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-07T23:54:10.034Z,1549583650.034 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-07T23:54:10.035Z,1549583650.035 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-07T23:54:10.036Z,1549583650.036 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-07T23:54:10.058Z,1549583650.058 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-07T23:54:10.091Z,1549583650.091 [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:54:10.092Z,1549583650.092 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-07T23:54:10.179Z,1549583650.179 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-07T23:54:10.180Z,1549583650.180 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-07T23:54:10.182Z,1549583650.182 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-07T23:54:10.226Z,1549583650.226 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-07T23:54:10.229Z,1549583650.229 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-07T23:54:10.258Z,1549583650.258 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-07T23:54:10.262Z,1549583650.262 [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:54:10.274Z,1549583650.274 [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:54:10.282Z,1549583650.282 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-02-07T23:54:10.361Z,1549583650.361 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-07T23:54:10.393Z,1549583650.393 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-07T23:54:10.434Z,1549583650.434 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:10.434Z,1549583650.434 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:54:10.434Z,1549583650.434 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:54:10.590Z,1549583650.590 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-07T23:54:10.818Z,1549583650.818 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:54:10.826Z,1549583650.826 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-07T23:54:10.840Z,1549583650.840 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:54:10.846Z,1549583650.846 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-07T23:54:10.860Z,1549583650.860 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:54:10.866Z,1549583650.866 [MassServo](DEBUG): Initializing MassServo.
2019-02-07T23:54:10.888Z,1549583650.888 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:54:10.894Z,1549583650.894 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-07T23:54:10.900Z,1549583650.900 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:54:10.906Z,1549583650.906 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-07T23:54:11.048Z,1549583651.048 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:11.146Z,1549583651.146 [RDI_PathfinderUp](INFO): Powering down
2019-02-07T23:54:11.202Z,1549583651.202 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-07T23:54:11.407Z,1549583651.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:11.426Z,1549583651.426 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-07T23:54:11.762Z,1549583651.762 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-07T23:54:11.762Z,1549583651.762 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-07T23:54:11.762Z,1549583651.762 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-07T23:54:11.762Z,1549583651.762 [BuoyancyServo](ERROR): Communications Fault
2019-02-07T23:54:11.978Z,1549583651.978 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-07T23:54:11.988Z,1549583651.988 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:12.395Z,1549583652.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:12.791Z,1549583652.791 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:13.191Z,1549583653.191 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:13.659Z,1549583653.659 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:13.794Z,1549583653.794 [Aanderaa_O2](INFO): Powering down
2019-02-07T23:54:14.027Z,1549583654.027 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:14.111Z,1549583654.111 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-07T23:54:14.111Z,1549583654.111 [BuoyancyServo] No Fault, FailCount= 1
2019-02-07T23:54:14.427Z,1549583654.427 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:14.457Z,1549583654.457 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-07T23:54:14.575Z,1549583654.575 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-07T23:54:14.883Z,1549583654.883 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:15.228Z,1549583655.228 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:15.627Z,1549583655.627 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:16.027Z,1549583656.027 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:16.427Z,1549583656.427 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:16.827Z,1549583656.827 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:17.227Z,1549583657.227 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:17.623Z,1549583657.623 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:18.027Z,1549583658.027 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:18.415Z,1549583658.415 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:18.819Z,1549583658.819 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:19.203Z,1549583659.203 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:19.605Z,1549583659.605 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:19.995Z,1549583659.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:20.408Z,1549583660.408 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:20.807Z,1549583660.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:21.202Z,1549583661.202 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:21.596Z,1549583661.596 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:22.006Z,1549583662.006 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:22.395Z,1549583662.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:22.795Z,1549583662.795 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:23.207Z,1549583663.207 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:23.599Z,1549583663.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:23.995Z,1549583663.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:24.023Z,1549583664.023 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-07T23:54:24.407Z,1549583664.407 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:24.795Z,1549583664.795 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:25.203Z,1549583665.203 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:25.601Z,1549583665.601 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:25.000Z,1549583666.000 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:26.399Z,1549583666.399 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:26.799Z,1549583666.799 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:27.198Z,1549583667.198 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:27.603Z,1549583667.603 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:27.998Z,1549583667.998 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:28.397Z,1549583668.397 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:28.797Z,1549583668.797 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:29.199Z,1549583669.199 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:29.602Z,1549583669.602 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:30.003Z,1549583670.003 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:30.024Z,1549583670.024 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-02-07T23:54:30.024Z,1549583670.024 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19020717010, -0.1,   0.0,1448.9,  0
2019-02-07T23:54:30.399Z,1549583670.399 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:30.799Z,1549583670.799 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:30.800Z,1549583670.800 [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
ÒûÆמY?°h!
2019-02-07T23:54:30.800Z,1549583670.800 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2019-02-07T23:54:30.800Z,1549583670.800 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:54:30.883Z,1549583670.883 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:54:31.203Z,1549583671.203 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:54:31.203Z,1549583671.203 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:54:31.203Z,1549583671.203 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:54:31.603Z,1549583671.603 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:54:31.999Z,1549583671.999 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:54:32.084Z,1549583672.084 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:54:32.084Z,1549583672.084 [DUSBL_Hydroid] No Fault, FailCount= 1
2019-02-07T23:54:32.399Z,1549583672.399 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:32.799Z,1549583672.799 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:33.199Z,1549583673.199 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:33.599Z,1549583673.599 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:33.999Z,1549583673.999 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:34.399Z,1549583674.399 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:34.399Z,1549583674.399 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:54:34.399Z,1549583674.399 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:54:34.799Z,1549583674.799 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:35.199Z,1549583675.199 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:35.627Z,1549583675.627 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:35.995Z,1549583675.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:36.395Z,1549583676.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:36.807Z,1549583676.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:37.195Z,1549583677.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:37.610Z,1549583677.610 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:37.614Z,1549583677.614 [NAL9602](INFO): Powering up NAL9602
2019-02-07T23:54:37.995Z,1549583677.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:38.395Z,1549583678.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:38.444Z,1549583678.444 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-07T23:54:38.448Z,1549583678.448 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-07T23:54:38.807Z,1549583678.807 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:39.195Z,1549583679.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:39.599Z,1549583679.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:39.995Z,1549583679.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:40.399Z,1549583680.399 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:40.883Z,1549583680.883 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:41.377Z,1549583681.377 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:41.792Z,1549583681.792 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:42.175Z,1549583682.175 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:42.593Z,1549583682.593 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:42.975Z,1549583682.975 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:43.011Z,1549583683.011 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-02-07T23:54:43.011Z,1549583683.011 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,190207175.0, -0.1,   0.0,1448.9,  0
2019-02-07T23:54:43.371Z,1549583683.371 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:43.775Z,1549583683.775 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:44.175Z,1549583684.175 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:44.571Z,1549583684.571 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:44.975Z,1549583684.975 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:45.375Z,1549583685.375 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:45.772Z,1549583685.772 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:46.175Z,1549583686.175 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:46.575Z,1549583686.575 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:46.971Z,1549583686.971 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:47.383Z,1549583687.383 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:47.775Z,1549583687.775 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:48.171Z,1549583688.171 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:48.575Z,1549583688.575 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:48.576Z,1549583688.576 [NAL9602](INFO): NAL9602 initialized
2019-02-07T23:54:48.992Z,1549583688.992 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:49.156Z,1549583689.156 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009373
CHAN A1 (24V): -0.024721
CHAN A2 (12V): -0.007073
CHAN A3 (5V): -0.001971
CHAN B0 (3.3V): -0.000737
CHAN B1 (3.15aV): -0.000605
CHAN B2 (3.15bV): -0.000075
CHAN B3 (GND): 0.000615
OPEN: 0.009247
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-07T23:54:49.375Z,1549583689.375 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:49.390Z,1549583689.390 [NAL9602](DEBUG): Fix Requested
2019-02-07T23:54:49.775Z,1549583689.775 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:50.179Z,1549583690.179 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:50.580Z,1549583690.580 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:50.975Z,1549583690.975 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:51.375Z,1549583691.375 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:51.771Z,1549583691.771 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:52.183Z,1549583692.183 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:52.583Z,1549583692.583 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:52.980Z,1549583692.980 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:53.393Z,1549583693.393 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:53.993Z,1549583693.993 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:54.403Z,1549583694.403 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:54.404Z,1549583694.404 [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
ÒûÆמY?°h!
2019-02-07T23:54:54.404Z,1549583694.404 [DUSBL_Hydroid] Communications Fault, FailCount= 2
2019-02-07T23:54:54.404Z,1549583694.404 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:54:54.579Z,1549583694.579 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:54:54.815Z,1549583694.815 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:54:54.815Z,1549583694.815 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:54:54.815Z,1549583694.815 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:54:55.195Z,1549583695.195 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:54:55.599Z,1549583695.599 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:54:55.771Z,1549583695.771 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:54:55.771Z,1549583695.771 [DUSBL_Hydroid] No Fault, FailCount= 2
2019-02-07T23:54:55.995Z,1549583695.995 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:56.404Z,1549583696.404 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:56.808Z,1549583696.808 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:57.210Z,1549583697.210 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:57.605Z,1549583697.605 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:57.995Z,1549583697.995 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:54:57.995Z,1549583697.995 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:54:57.995Z,1549583697.995 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:54:58.395Z,1549583698.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:58.811Z,1549583698.811 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:59.195Z,1549583699.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:59.599Z,1549583699.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:54:59.995Z,1549583699.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:00.014Z,1549583700.014 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-02-07T23:55:00.014Z,1549583700.014 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19020717020079,35.0, -0.1,  8.9,  0
2019-02-07T23:55:00.403Z,1549583700.403 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:00.810Z,1549583700.810 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:01.195Z,1549583701.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:01.603Z,1549583701.603 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:02.003Z,1549583702.003 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:02.395Z,1549583702.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:02.791Z,1549583702.791 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:03.203Z,1549583703.203 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:03.599Z,1549583703.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:03.995Z,1549583703.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:04.403Z,1549583704.403 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:04.795Z,1549583704.795 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:05.195Z,1549583705.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:05.599Z,1549583705.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:05.995Z,1549583705.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:06.403Z,1549583706.403 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:06.803Z,1549583706.803 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:07.195Z,1549583707.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:07.599Z,1549583707.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:07.995Z,1549583707.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:08.395Z,1549583708.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:08.791Z,1549583708.791 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:09.203Z,1549583709.203 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:09.621Z,1549583709.621 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:09.995Z,1549583709.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:10.396Z,1549583710.396 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:10.795Z,1549583710.795 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:11.195Z,1549583711.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:11.599Z,1549583711.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:11.999Z,1549583711.999 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:12.395Z,1549583712.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:12.795Z,1549583712.795 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:13.195Z,1549583713.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:13.595Z,1549583713.595 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:13.995Z,1549583713.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:14.395Z,1549583714.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:14.795Z,1549583714.795 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:15.195Z,1549583715.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:15.610Z,1549583715.610 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:15.995Z,1549583715.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:16.395Z,1549583716.395 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:16.795Z,1549583716.795 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:17.195Z,1549583717.195 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:17.599Z,1549583717.599 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:17.995Z,1549583717.995 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:18.023Z,1549583718.023 [RDI_Pathfinder](ERROR): only read 0 of 5 data items
2019-02-07T23:55:18.024Z,1549583718.024 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -,    -2,-32768,A
2019-02-07T23:55:18.391Z,1549583718.391 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:18.392Z,1549583718.392 [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:55:18.392Z,1549583718.392 [DUSBL_Hydroid] Communications Fault, FailCount= 3
2019-02-07T23:55:18.392Z,1549583718.392 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:55:18.481Z,1549583718.481 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:55:18.799Z,1549583718.799 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:55:18.799Z,1549583718.799 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:55:18.799Z,1549583718.799 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:55:19.195Z,1549583719.195 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:55:19.599Z,1549583719.599 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:55:19.677Z,1549583719.677 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:55:19.677Z,1549583719.677 [DUSBL_Hydroid] No Fault, FailCount= 3
2019-02-07T23:55:19.996Z,1549583719.996 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:20.399Z,1549583720.399 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:20.944Z,1549583720.944 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:21.327Z,1549583721.327 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:21.723Z,1549583721.723 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:22.127Z,1549583722.127 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:22.127Z,1549583722.127 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:55:22.127Z,1549583722.127 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:55:22.527Z,1549583722.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:22.927Z,1549583722.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:23.327Z,1549583723.327 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:23.735Z,1549583723.735 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:24.131Z,1549583724.131 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:24.527Z,1549583724.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:24.927Z,1549583724.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:25.324Z,1549583725.324 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:25.723Z,1549583725.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:26.127Z,1549583726.127 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:26.523Z,1549583726.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:26.923Z,1549583726.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:27.327Z,1549583727.327 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:27.723Z,1549583727.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:28.123Z,1549583728.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:28.523Z,1549583728.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:28.923Z,1549583728.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:29.323Z,1549583729.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:29.723Z,1549583729.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:30.123Z,1549583730.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:30.523Z,1549583730.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:30.931Z,1549583730.931 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:31.323Z,1549583731.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:31.723Z,1549583731.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:32.127Z,1549583732.127 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:32.181Z,1549583732.181 [SBIT](IMPORTANT): SBIT PASSED
2019-02-07T23:55:32.271Z,1549583732.271 [CommandLine](IMPORTANT): got command configSet list
2019-02-07T23:55:32.271Z,1549583732.271 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-07T23:55:32.272Z,1549583732.272 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;

2019-02-07T23:55:32.272Z,1549583732.272 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool;

2019-02-07T23:55:32.531Z,1549583732.531 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:32.578Z,1549583732.578 [MissionManager](IMPORTANT): Started mission Startup
2019-02-07T23:55:32.579Z,1549583732.579 [Startup] Running Loop=1
2019-02-07T23:55:32.579Z,1549583732.579 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-07T23:55:32.579Z,1549583732.579 [Startup:A.GoToSurface] Running Loop=1
2019-02-07T23:55:32.579Z,1549583732.579 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-07T23:55:32.579Z,1549583732.579 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-07T23:55:32.580Z,1549583732.580 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-07T23:55:32.580Z,1549583732.580 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-07T23:55:32.581Z,1549583732.581 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-07T23:55:32.581Z,1549583732.581 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-07T23:55:32.604Z,1549583732.604 [Startup:StartupSatComms] Running Loop=1
2019-02-07T23:55:32.604Z,1549583732.604 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-07T23:55:32.604Z,1549583732.604 [Startup:StartupSatComms:A] Running Loop=1
2019-02-07T23:55:32.923Z,1549583732.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:32.971Z,1549583732.971 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-07T23:55:33.323Z,1549583733.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:33.732Z,1549583733.732 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:34.133Z,1549583734.133 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:34.527Z,1549583734.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:34.936Z,1549583734.936 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:35.323Z,1549583735.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:35.727Z,1549583735.727 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:36.123Z,1549583736.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:36.523Z,1549583736.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:36.923Z,1549583736.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:37.323Z,1549583737.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:37.727Z,1549583737.727 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:38.131Z,1549583738.131 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:38.532Z,1549583738.532 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:38.927Z,1549583738.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:39.323Z,1549583739.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:39.723Z,1549583739.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:39.727Z,1549583739.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=2137, MT Status=2, MTMSN=0
2019-02-07T23:55:39.727Z,1549583739.727 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-07T23:55:40.123Z,1549583740.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:40.527Z,1549583740.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:40.923Z,1549583740.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:41.320Z,1549583741.320 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:41.723Z,1549583741.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:42.123Z,1549583742.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:42.523Z,1549583742.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:42.524Z,1549583742.524 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
úœ»]/M
?°h!
2019-02-07T23:55:42.524Z,1549583742.524 [DUSBL_Hydroid] Communications Fault, FailCount= 4
2019-02-07T23:55:42.524Z,1549583742.524 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:55:42.585Z,1549583742.585 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:55:42.936Z,1549583742.936 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:55:42.936Z,1549583742.936 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:55:42.936Z,1549583742.936 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:55:43.323Z,1549583743.323 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:55:43.723Z,1549583743.723 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:55:43.804Z,1549583743.804 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:55:43.804Z,1549583743.804 [DUSBL_Hydroid] No Fault, FailCount= 4
2019-02-07T23:55:44.124Z,1549583744.124 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:44.523Z,1549583744.523 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:44.923Z,1549583744.923 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:45.327Z,1549583745.327 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:45.723Z,1549583745.723 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:46.132Z,1549583746.132 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:55:46.132Z,1549583746.132 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:55:46.132Z,1549583746.132 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:55:46.523Z,1549583746.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:46.927Z,1549583746.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:47.323Z,1549583747.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:47.723Z,1549583747.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:48.123Z,1549583748.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:48.523Z,1549583748.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:48.931Z,1549583748.931 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:49.323Z,1549583749.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:49.723Z,1549583749.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:50.127Z,1549583750.127 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:50.523Z,1549583750.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:50.927Z,1549583750.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:51.323Z,1549583751.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:51.727Z,1549583751.727 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:52.123Z,1549583752.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:52.523Z,1549583752.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:52.923Z,1549583752.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:53.323Z,1549583753.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:53.724Z,1549583753.724 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:54.127Z,1549583754.127 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:54.523Z,1549583754.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:54.931Z,1549583754.931 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:55.323Z,1549583755.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:55.723Z,1549583755.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:56.123Z,1549583756.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:56.523Z,1549583756.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:56.931Z,1549583756.931 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:57.323Z,1549583757.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:57.723Z,1549583757.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:58.123Z,1549583758.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:58.527Z,1549583758.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:58.923Z,1549583758.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:59.323Z,1549583759.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:55:59.723Z,1549583759.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:00.123Z,1549583760.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:00.527Z,1549583760.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:00.927Z,1549583760.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:01.336Z,1549583761.336 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:01.738Z,1549583761.738 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:02.123Z,1549583762.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:02.523Z,1549583762.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:02.927Z,1549583762.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:03.328Z,1549583763.328 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:03.727Z,1549583763.727 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:04.123Z,1549583764.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:04.527Z,1549583764.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:04.923Z,1549583764.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:05.323Z,1549583765.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:05.723Z,1549583765.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:06.127Z,1549583766.127 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:06.523Z,1549583766.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:06.524Z,1549583766.524 [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
«ZÒQf?°h!
2019-02-07T23:56:06.524Z,1549583766.524 [DUSBL_Hydroid] Communications Fault, FailCount= 5
2019-02-07T23:56:06.524Z,1549583766.524 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:56:06.678Z,1549583766.678 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:56:06.923Z,1549583766.923 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:56:06.923Z,1549583766.923 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:56:06.923Z,1549583766.923 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:56:07.327Z,1549583767.327 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:56:07.723Z,1549583767.723 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:56:07.810Z,1549583767.810 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:56:07.810Z,1549583767.810 [DUSBL_Hydroid] No Fault, FailCount= 5
2019-02-07T23:56:08.123Z,1549583768.123 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:08.523Z,1549583768.523 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:08.923Z,1549583768.923 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:09.327Z,1549583769.327 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:09.723Z,1549583769.723 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:10.123Z,1549583770.123 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:10.123Z,1549583770.123 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:56:10.124Z,1549583770.124 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:56:10.531Z,1549583770.531 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:10.931Z,1549583770.931 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:11.323Z,1549583771.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:11.727Z,1549583771.727 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:12.128Z,1549583772.128 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:12.523Z,1549583772.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:12.923Z,1549583772.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:13.327Z,1549583773.327 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:13.727Z,1549583773.727 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:14.127Z,1549583774.127 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:14.523Z,1549583774.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:14.923Z,1549583774.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:15.323Z,1549583775.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:15.723Z,1549583775.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:16.123Z,1549583776.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:16.523Z,1549583776.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:16.927Z,1549583776.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:17.323Z,1549583777.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:17.723Z,1549583777.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:18.131Z,1549583778.131 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:18.531Z,1549583778.531 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:18.931Z,1549583778.931 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:19.327Z,1549583779.327 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:19.724Z,1549583779.724 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:20.123Z,1549583780.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:20.527Z,1549583780.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:20.923Z,1549583780.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:21.327Z,1549583781.327 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:21.723Z,1549583781.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:22.123Z,1549583782.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:22.527Z,1549583782.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:22.931Z,1549583782.931 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:23.323Z,1549583783.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:23.724Z,1549583783.724 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:24.123Z,1549583784.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:24.523Z,1549583784.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:24.927Z,1549583784.927 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:25.323Z,1549583785.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:25.723Z,1549583785.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:26.123Z,1549583786.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:26.523Z,1549583786.523 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:26.923Z,1549583786.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:27.327Z,1549583787.327 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:27.723Z,1549583787.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:28.123Z,1549583788.123 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:28.527Z,1549583788.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:28.923Z,1549583788.923 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:29.323Z,1549583789.323 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:29.723Z,1549583789.723 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:30.127Z,1549583790.127 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:30.128Z,1549583790.128 [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
fLÁgÓ
?°h!
2019-02-07T23:56:30.128Z,1549583790.128 [DUSBL_Hydroid] Communications Fault, FailCount= 6
2019-02-07T23:56:30.128Z,1549583790.128 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:56:30.218Z,1549583790.218 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:56:30.523Z,1549583790.523 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:56:30.523Z,1549583790.523 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:56:30.523Z,1549583790.523 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:56:30.933Z,1549583790.933 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:56:31.345Z,1549583791.345 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:56:31.427Z,1549583791.427 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:56:31.427Z,1549583791.427 [DUSBL_Hydroid] No Fault, FailCount= 6
2019-02-07T23:56:34.495Z,1549583794.495 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:34.495Z,1549583794.495 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:56:34.496Z,1549583794.496 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:56:34.696Z,1549583794.696 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-07T23:55:32.6Z
2019-02-07T23:56:34.696Z,1549583794.696 [Startup:StartupSatComms:A] Stopped
2019-02-07T23:56:34.697Z,1549583794.697 [Startup:StartupSatComms:B] Running Loop=1
2019-02-07T23:56:34.775Z,1549583794.775 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:34.855Z,1549583794.855 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-07T23:56:35.131Z,1549583795.131 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:35.523Z,1549583795.523 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004882
2019-02-07T23:56:35.527Z,1549583795.527 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:35.931Z,1549583795.931 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:36.327Z,1549583796.327 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:39.501Z,1549583799.501 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:39.659Z,1549583799.659 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190207T234912/Courier0007.lzma
2019-02-07T23:56:39.666Z,1549583799.666 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:40.011Z,1549583800.011 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:40.411Z,1549583800.411 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:40.807Z,1549583800.807 [DataOverHttps](INFO): Moved sent file to Logs/20190207T234912/Courier0007.lzma.bak
2019-02-07T23:56:40.807Z,1549583800.807 [DataOverHttps](INFO): SBD MOMSN=9641301
2019-02-07T23:56:40.814Z,1549583800.814 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:41.225Z,1549583801.225 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:41.645Z,1549583801.645 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:44.846Z,1549583804.846 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:44.989Z,1549583804.989 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:45.380Z,1549583805.380 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:45.780Z,1549583805.780 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:46.175Z,1549583806.175 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:46.575Z,1549583806.575 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:49.804Z,1549583809.804 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:50.023Z,1549583810.023 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20190207T234912/Express0008.lzma
2019-02-07T23:56:50.059Z,1549583810.059 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:50.416Z,1549583810.416 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:50.798Z,1549583810.798 [DataOverHttps](INFO): Moved sent file to Logs/20190207T234912/Express0008.lzma.bak
2019-02-07T23:56:50.799Z,1549583810.799 [DataOverHttps](INFO): SBD MOMSN=9641303
2019-02-07T23:56:50.830Z,1549583810.830 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:51.215Z,1549583811.215 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:51.614Z,1549583811.614 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:54.819Z,1549583814.819 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:56:54.820Z,1549583814.820 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: !U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
!U1,V,O
?«Ì”Öß?°h!
2019-02-07T23:56:54.820Z,1549583814.820 [DUSBL_Hydroid] Communications Fault, FailCount= 7
2019-02-07T23:56:54.820Z,1549583814.820 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:56:54.941Z,1549583814.941 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:56:55.008Z,1549583815.008 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:56:55.008Z,1549583815.008 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:56:55.008Z,1549583815.008 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:56:55.363Z,1549583815.363 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:56:55.772Z,1549583815.772 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:56:55.854Z,1549583815.854 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-02-07T23:56:55.854Z,1549583815.854 [DUSBL_Hydroid] No Fault, FailCount= 7
2019-02-07T23:56:56.172Z,1549583816.172 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:56.575Z,1549583816.575 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:59.777Z,1549583819.777 [DUSBL_Hydroid](INFO): Start
2019-02-07T23:56:59.777Z,1549583819.777 [DUSBL_Hydroid](INFO): Powering up
2019-02-07T23:56:59.778Z,1549583819.778 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-07T23:56:59.936Z,1549583819.936 [DataOverHttps](INFO): Sending 852 bytes from file Logs/20190207T235359/Express0001.lzma
2019-02-07T23:56:59.950Z,1549583819.950 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:00.316Z,1549583820.316 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:00.706Z,1549583820.706 [DataOverHttps](INFO): Moved sent file to Logs/20190207T235359/Express0001.lzma.bak
2019-02-07T23:57:00.707Z,1549583820.707 [DataOverHttps](INFO): SBD MOMSN=9641306
2019-02-07T23:57:00.719Z,1549583820.719 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:01.119Z,1549583821.119 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:01.180Z,1549583821.180 [Startup:StartupSatComms:B] Stopped
2019-02-07T23:57:01.180Z,1549583821.180 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-07T23:57:01.180Z,1549583821.180 [Startup:StartupSatComms] Stopped
2019-02-07T23:57:01.180Z,1549583821.180 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-07T23:57:01.181Z,1549583821.181 [Startup](INFO): Completed Startup
2019-02-07T23:57:01.181Z,1549583821.181 [MissionManager](INFO): Startup is completed.
2019-02-07T23:57:01.182Z,1549583821.182 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-07T23:57:01.182Z,1549583821.182 [Startup] Stopped
2019-02-07T23:57:01.182Z,1549583821.182 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-07T23:57:01.182Z,1549583821.182 [Startup:A.GoToSurface] Stopped
2019-02-07T23:57:01.182Z,1549583821.182 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-07T23:57:01.515Z,1549583821.515 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:01.582Z,1549583821.582 [MissionManager](IMPORTANT): Started mission Default
2019-02-07T23:57:01.582Z,1549583821.582 [Default] Running Loop=1
2019-02-07T23:57:01.582Z,1549583821.582 [Default](DEBUG): Aggregate::initialize Default
2019-02-07T23:57:01.582Z,1549583821.582 [Default:B.GoToSurface] Running Loop=1
2019-02-07T23:57:01.583Z,1549583821.583 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-07T23:57:01.583Z,1549583821.583 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-07T23:57:01.583Z,1549583821.583 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-07T23:57:01.583Z,1549583821.583 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-07T23:57:01.584Z,1549583821.584 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-07T23:57:01.584Z,1549583821.584 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-07T23:57:01.584Z,1549583821.584 [Default:A.Wait] Running Loop=1
2019-02-07T23:57:01.584Z,1549583821.584 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-07T23:57:04.726Z,1549583824.726 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:05.005Z,1549583825.005 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:05.355Z,1549583825.355 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:05.772Z,1549583825.772 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:06.155Z,1549583826.155 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:06.555Z,1549583826.555 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:06.955Z,1549583826.955 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:07.355Z,1549583827.355 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:07.755Z,1549583827.755 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:08.175Z,1549583828.175 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:08.555Z,1549583828.555 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:08.968Z,1549583828.968 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:09.359Z,1549583829.359 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:09.763Z,1549583829.763 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:10.155Z,1549583830.155 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:10.195Z,1549583830.195 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-07T23:57:10.195Z,1549583830.195 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-07T23:57:10.196Z,1549583830.196 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-07T23:57:10.196Z,1549583830.196 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-07T23:57:10.198Z,1549583830.198 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-07T23:57:10.198Z,1549583830.198 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-07T23:57:10.227Z,1549583830.227 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-07T23:57:10.227Z,1549583830.227 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-07T23:57:10.227Z,1549583830.227 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-07T23:57:10.555Z,1549583830.555 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:10.668Z,1549583830.668 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-07T23:57:10.668Z,1549583830.668 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-07T23:57:10.669Z,1549583830.669 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-07T23:57:10.669Z,1549583830.669 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-07T23:57:10.669Z,1549583830.669 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-07T23:57:10.669Z,1549583830.669 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-07T23:57:10.955Z,1549583830.955 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:11.028Z,1549583831.028 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-07T23:57:11.355Z,1549583831.355 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:11.770Z,1549583831.770 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:12.156Z,1549583832.156 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:12.575Z,1549583832.575 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:12.968Z,1549583832.968 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:13.355Z,1549583833.355 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:13.755Z,1549583833.755 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:14.168Z,1549583834.168 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:14.555Z,1549583834.555 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:14.635Z,1549583834.635 [Default:A.Wait](INFO): Done Waiting.
2019-02-07T23:57:14.635Z,1549583834.635 [Default:A.Wait] Stopped
2019-02-07T23:57:14.635Z,1549583834.635 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-07T23:57:14.967Z,1549583834.967 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:15.029Z,1549583835.029 [Default:CheckIn] Running Loop=1
2019-02-07T23:57:15.029Z,1549583835.029 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-07T23:57:15.029Z,1549583835.029 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-07T23:57:15.355Z,1549583835.355 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:15.419Z,1549583835.419 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-07T23:57:15.764Z,1549583835.764 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:16.163Z,1549583836.163 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:16.555Z,1549583836.555 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:16.967Z,1549583836.967 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:17.355Z,1549583837.355 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:17.756Z,1549583837.756 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:18.158Z,1549583838.158 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:18.561Z,1549583838.561 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:18.960Z,1549583838.960 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:19.359Z,1549583839.359 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:19.760Z,1549583839.760 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:20.166Z,1549583840.166 [DUSBL_Hydroid](INFO): Starting
2019-02-07T23:57:20.167Z,1549583840.167 [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
ÒûÆמY?°h!
2019-02-07T23:57:20.167Z,1549583840.167 [DUSBL_Hydroid] Communications Fault, FailCount= 8
2019-02-07T23:57:20.167Z,1549583840.167 [DUSBL_Hydroid](ERROR): Communications Fault
2019-02-07T23:57:20.270Z,1549583840.270 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:57:20.271Z,1549583840.271 [CBIT](CRITICAL): Communications Fault in component: DUSBL_Hydroid
2019-02-07T23:57:20.558Z,1549583840.558 [DUSBL_Hydroid](INFO): Stop
2019-02-07T23:57:20.558Z,1549583840.558 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:57:20.558Z,1549583840.558 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:57:20.700Z,1549583840.700 [CommandLine](FAULT): Scheduling is paused
2019-02-07T23:57:20.701Z,1549583840.701 [CBIT](INFO): Critical error at 20190207T235720
2019-02-07T23:57:20.701Z,1549583840.701 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-07T23:57:20.957Z,1549583840.957 [DUSBL_Hydroid](INFO): Stopping
2019-02-07T23:57:21.357Z,1549583841.357 [DUSBL_Hydroid](INFO): Stopped
2019-02-07T23:57:59.032Z,1549583879.032 [CommandLine](IMPORTANT): got command restart application
2019-02-07T23:58:00.041Z,1549583880.041 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:58:00.041Z,1549583880.041 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.214Z,1549583880.214 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-07T23:58:00.214Z,1549583880.214 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.215Z,1549583880.215 [CommandLine](INFO): Join timeout helper Thread ID is 8495
2019-02-07T23:58:00.215Z,1549583880.215 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-07T23:58:00.215Z,1549583880.215 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.216Z,1549583880.216 [NavChartDb](INFO): Join timeout helper Thread ID is 8496
2019-02-07T23:58:00.578Z,1549583880.578 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:58:00.578Z,1549583880.578 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.590Z,1549583880.590 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-07T23:58:00.590Z,1549583880.590 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.590Z,1549583880.590 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8497
2019-02-07T23:58:00.751Z,1549583880.751 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:58:00.751Z,1549583880.751 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-07T23:58:00.752Z,1549583880.752 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.754Z,1549583880.754 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-07T23:58:00.755Z,1549583880.755 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.755Z,1549583880.755 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8498
2019-02-07T23:58:00.958Z,1549583880.958 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:58:00.958Z,1549583880.958 [CTD_NeilBrown](INFO): Powering down
2019-02-07T23:58:00.970Z,1549583880.970 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.986Z,1549583880.986 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-07T23:58:00.986Z,1549583880.986 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:00.986Z,1549583880.986 [Radio_Surface](INFO): Join timeout helper Thread ID is 8499
2019-02-07T23:58:01.142Z,1549583881.142 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:58:01.142Z,1549583881.142 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.151Z,1549583881.151 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-07T23:58:01.151Z,1549583881.151 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.151Z,1549583881.151 [logger](INFO): Join timeout helper Thread ID is 8500
2019-02-07T23:58:01.161Z,1549583881.161 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:58:01.161Z,1549583881.161 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.178Z,1549583881.178 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-07T23:58:01.179Z,1549583881.179 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.179Z,1549583881.179 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-07T23:58:01.179Z,1549583881.179 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.179Z,1549583881.179 [controlThread](INFO): Join timeout helper Thread ID is 8501
2019-02-07T23:58:01.438Z,1549583881.438 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-07T23:58:01.438Z,1549583881.438 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-07T23:58:01.439Z,1549583881.439 [AHRS_M2](INFO): Powering down
2019-02-07T23:58:01.511Z,1549583881.511 [DUSBL_Hydroid](INFO): uninitialize
2019-02-07T23:58:01.511Z,1549583881.511 [DUSBL_Hydroid](INFO): Powering down
2019-02-07T23:58:01.512Z,1549583881.512 [NAL9602](INFO): Powering down
2019-02-07T23:58:01.582Z,1549583881.582 [RDI_Pathfinder](INFO): Powering down
2019-02-07T23:58:01.583Z,1549583881.583 [RDI_PathfinderUp](INFO): Powering down
2019-02-07T23:58:01.584Z,1549583881.584 [Aanderaa_O2](INFO): Powering down
2019-02-07T23:58:01.585Z,1549583881.585 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-07T23:58:01.586Z,1549583881.586 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-07T23:58:01.587Z,1549583881.587 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-07T23:58:01.588Z,1549583881.588 [MissionManager](INFO): Uninitializing Mission Default
2019-02-07T23:58:01.588Z,1549583881.588 [Default] Stopped
2019-02-07T23:58:01.588Z,1549583881.588 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-07T23:58:01.588Z,1549583881.588 [Default:B.GoToSurface] Stopped
2019-02-07T23:58:01.588Z,1549583881.588 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-07T23:58:01.588Z,1549583881.588 [Default:CheckIn] Stopped
2019-02-07T23:58:01.588Z,1549583881.588 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-07T23:58:01.588Z,1549583881.588 [Default:CheckIn:Read_GPS] Stopped
2019-02-07T23:58:01.591Z,1549583881.591 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-07T23:58:01.591Z,1549583881.591 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-07T23:58:01.591Z,1549583881.591 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-07T23:58:01.592Z,1549583881.592 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-07T23:58:01.592Z,1549583881.592 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-07T23:58:01.592Z,1549583881.592 [BuoyancyServo](INFO): Powering down
2019-02-07T23:58:01.606Z,1549583881.606 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-07T23:58:01.606Z,1549583881.606 [ElevatorServo](INFO): Powering down
2019-02-07T23:58:01.607Z,1549583881.607 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-07T23:58:01.607Z,1549583881.607 [MassServo](INFO): Powering down
2019-02-07T23:58:01.608Z,1549583881.608 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-07T23:58:01.608Z,1549583881.608 [RudderServo](INFO): Powering down
2019-02-07T23:58:01.609Z,1549583881.609 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-07T23:58:01.609Z,1549583881.609 [ThrusterServo](INFO): Powering down
2019-02-07T23:58:01.609Z,1549583881.609 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-07T23:58:01.610Z,1549583881.610 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-07T23:58:01.610Z,1549583881.610 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-07T23:58:01.610Z,1549583881.610 [CBIT](DEBUG): Powering off loads.
2019-02-07T23:58:01.622Z,1549583881.622 [CBIT](DEBUG): Disabling WDT.
2019-02-07T23:58:01.634Z,1549583881.634 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-07T23:58:01.634Z,1549583881.634 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.729Z,1549583881.729 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.776Z,1549583881.776 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.778Z,1549583881.778 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.803Z,1549583881.803 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-07T23:58:01.858Z,1549583881.858 [logger ThreadHandler](INFO): Thread cancelled.