2019-02-08T00:14:45.266Z,1549584885.266 [Supervisor](DEBUG): Initializing supervisor. 2019-02-08T00:14:45.270Z,1549584885.270 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-08T00:14:45.270Z,1549584885.270 [SyncHandler](INFO): Protected caller Thread ID is 952 2019-02-08T00:14:45.271Z,1549584885.271 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-08T00:14:45.272Z,1549584885.272 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-08T00:14:45.272Z,1549584885.272 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 953 2019-02-08T00:14:45.276Z,1549584885.276 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-08T00:14:45.289Z,1549584885.289 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-08T00:14:45.290Z,1549584885.290 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-08T00:14:45.290Z,1549584885.290 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 954 2019-02-08T00:14:45.291Z,1549584885.291 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-08T00:14:45.292Z,1549584885.292 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-08T00:14:45.292Z,1549584885.292 [logger ThreadHandler](INFO): Protected caller Thread ID is 955 2019-02-08T00:14:45.294Z,1549584885.294 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-08T00:14:45.295Z,1549584885.295 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-08T00:14:45.297Z,1549584885.297 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-08T00:14:45.681Z,1549584885.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-08T00:14:45.681Z,1549584885.681 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-08T00:14:46.182Z,1549584886.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-08T00:14:46.182Z,1549584886.182 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-08T00:14:46.556Z,1549584886.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-08T00:14:46.557Z,1549584886.557 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-08T00:14:46.765Z,1549584886.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-08T00:14:46.765Z,1549584886.765 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-08T00:14:47.252Z,1549584887.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-08T00:14:47.253Z,1549584887.253 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-08T00:14:47.766Z,1549584887.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-08T00:14:47.767Z,1549584887.767 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-08T00:14:47.874Z,1549584887.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-08T00:14:47.874Z,1549584887.874 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-08T00:14:48.093Z,1549584888.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-08T00:14:48.094Z,1549584888.094 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-08T00:14:48.247Z,1549584888.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-08T00:14:48.247Z,1549584888.247 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-08T00:14:48.329Z,1549584888.329 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-08T00:14:48.556Z,1549584888.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-08T00:14:48.557Z,1549584888.557 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-08T00:14:48.661Z,1549584888.661 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-08T00:14:48.662Z,1549584888.662 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-08T00:14:48.761Z,1549584888.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-08T00:14:48.762Z,1549584888.762 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-08T00:14:48.918Z,1549584888.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-08T00:14:48.919Z,1549584888.919 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-08T00:14:49.873Z,1549584889.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-08T00:14:49.875Z,1549584889.875 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2019-02-08T00:14:49.876Z,1549584889.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2019-02-08T00:14:50.021Z,1549584890.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2019-02-08T00:14:50.259Z,1549584890.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-08T00:14:50.260Z,1549584890.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2019-02-08T00:14:50.444Z,1549584890.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2019-02-08T00:14:50.549Z,1549584890.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2019-02-08T00:14:50.651Z,1549584890.651 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2019-02-08T00:14:50.865Z,1549584890.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2019-02-08T00:14:50.952Z,1549584890.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2019-02-08T00:14:51.054Z,1549584891.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2019-02-08T00:14:51.151Z,1549584891.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2019-02-08T00:14:51.247Z,1549584891.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2019-02-08T00:14:51.534Z,1549584891.534 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2019-02-08T00:14:51.535Z,1549584891.535 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-02-08T00:14:51.539Z,1549584891.539 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-08T00:14:51.951Z,1549584891.951 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-08T00:14:51.000Z,1549584892.000 [SBIT] Loaded 2019-02-08T00:14:51.000Z,1549584892.000 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-08T00:14:52.001Z,1549584892.001 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-08T00:14:52.030Z,1549584892.030 [IBIT] Loaded 2019-02-08T00:14:52.031Z,1549584892.031 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-08T00:14:52.034Z,1549584892.034 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-08T00:14:52.533Z,1549584892.533 [CBIT] Loaded 2019-02-08T00:14:52.534Z,1549584892.534 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-08T00:14:52.534Z,1549584892.534 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-08T00:14:52.535Z,1549584892.535 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-08T00:14:52.663Z,1549584892.663 [BuoyancyServo] Loaded 2019-02-08T00:14:52.664Z,1549584892.664 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-08T00:14:52.679Z,1549584892.679 [ElevatorServo] Loaded 2019-02-08T00:14:52.679Z,1549584892.679 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-08T00:14:52.694Z,1549584892.694 [MassServo] Loaded 2019-02-08T00:14:52.694Z,1549584892.694 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-08T00:14:52.710Z,1549584892.710 [RudderServo] Loaded 2019-02-08T00:14:52.710Z,1549584892.710 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-08T00:14:52.725Z,1549584892.725 [ThrusterServo] Loaded 2019-02-08T00:14:52.726Z,1549584892.726 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-08T00:14:52.726Z,1549584892.726 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-08T00:14:52.727Z,1549584892.727 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-08T00:14:52.836Z,1549584892.836 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-08T00:14:52.836Z,1549584892.836 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-08T00:14:52.898Z,1549584892.898 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-08T00:14:52.898Z,1549584892.898 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-08T00:14:52.959Z,1549584892.959 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-08T00:14:52.960Z,1549584892.960 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-08T00:14:52.977Z,1549584892.977 [NavChart] Loaded 2019-02-08T00:14:52.977Z,1549584892.977 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-08T00:14:52.981Z,1549584892.981 [UniversalFixResidualReporter] Loaded 2019-02-08T00:14:52.981Z,1549584892.981 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-08T00:14:52.982Z,1549584892.982 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-08T00:14:52.983Z,1549584892.983 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-08T00:14:52.997Z,1549584892.997 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-08T00:14:52.997Z,1549584892.997 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-08T00:14:53.240Z,1549584893.240 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-08T00:14:53.241Z,1549584893.241 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-08T00:14:53.588Z,1549584893.588 [AHRS_M2] Loaded 2019-02-08T00:14:53.588Z,1549584893.588 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-02-08T00:14:53.655Z,1549584893.655 [DataOverHttps] Loaded 2019-02-08T00:14:53.655Z,1549584893.655 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2019-02-08T00:14:53.669Z,1549584893.669 [Depth_Keller] Loaded 2019-02-08T00:14:53.670Z,1549584893.670 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-08T00:14:53.675Z,1549584893.675 [DropWeight] Loaded 2019-02-08T00:14:53.675Z,1549584893.675 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-02-08T00:14:53.730Z,1549584893.730 [DUSBL_Hydroid] Loaded 2019-02-08T00:14:53.730Z,1549584893.730 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2019-02-08T00:14:53.823Z,1549584893.823 [NAL9602] Loaded 2019-02-08T00:14:53.824Z,1549584893.824 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-08T00:14:53.829Z,1549584893.829 [Onboard] Loaded 2019-02-08T00:14:53.830Z,1549584893.830 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-08T00:14:53.837Z,1549584893.837 [Radio_Surface] Loaded 2019-02-08T00:14:53.837Z,1549584893.837 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-08T00:14:53.838Z,1549584893.838 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0 2019-02-08T00:14:53.839Z,1549584893.839 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1034 2019-02-08T00:14:53.868Z,1549584893.868 [RDI_Pathfinder] Loaded 2019-02-08T00:14:53.869Z,1549584893.869 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-02-08T00:14:53.892Z,1549584893.892 [RDI_PathfinderUp] Loaded 2019-02-08T00:14:53.892Z,1549584893.892 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2019-02-08T00:14:55.495Z,1549584895.495 [BPC1] Loaded 2019-02-08T00:14:55.495Z,1549584895.495 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-02-08T00:14:55.495Z,1549584895.495 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-08T00:14:55.496Z,1549584895.496 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-08T00:14:55.568Z,1549584895.568 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-08T00:14:55.659Z,1549584895.659 [VerticalControl] Loaded 2019-02-08T00:14:55.659Z,1549584895.659 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-08T00:14:55.660Z,1549584895.660 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-08T00:14:55.721Z,1549584895.721 [HorizontalControl] Loaded 2019-02-08T00:14:55.721Z,1549584895.721 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-08T00:14:55.722Z,1549584895.722 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-08T00:14:55.724Z,1549584895.724 [SpeedControl] Loaded 2019-02-08T00:14:55.725Z,1549584895.725 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-08T00:14:55.725Z,1549584895.725 [LoopControl](DEBUG): Construct LoopControl. 2019-02-08T00:14:55.726Z,1549584895.726 [LoopControl] Loaded 2019-02-08T00:14:55.726Z,1549584895.726 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-08T00:14:55.727Z,1549584895.727 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-08T00:14:55.727Z,1549584895.727 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-08T00:14:55.831Z,1549584895.831 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-08T00:14:55.832Z,1549584895.832 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-08T00:14:56.023Z,1549584896.023 [Aanderaa_O2] Loaded 2019-02-08T00:14:56.023Z,1549584896.023 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-08T00:14:56.110Z,1549584896.110 [CTD_NeilBrown] Loaded 2019-02-08T00:14:56.110Z,1549584896.110 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-02-08T00:14:56.111Z,1549584896.111 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0 2019-02-08T00:14:56.111Z,1549584896.111 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1035 2019-02-08T00:14:56.159Z,1549584896.159 [WetLabsSeaOWL_UV_A] Loaded 2019-02-08T00:14:56.160Z,1549584896.160 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2019-02-08T00:14:56.161Z,1549584896.161 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0 2019-02-08T00:14:56.161Z,1549584896.161 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1036 2019-02-08T00:14:56.162Z,1549584896.162 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-08T00:14:56.162Z,1549584896.162 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-08T00:14:56.227Z,1549584896.227 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-08T00:14:56.228Z,1549584896.228 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-08T00:14:56.270Z,1549584896.270 [DepthRateCalculator] Loaded 2019-02-08T00:14:56.270Z,1549584896.270 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-08T00:14:56.275Z,1549584896.275 [PitchRateCalculator] Loaded 2019-02-08T00:14:56.276Z,1549584896.276 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-08T00:14:56.288Z,1549584896.288 [SpeedCalculator] Loaded 2019-02-08T00:14:56.289Z,1549584896.289 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-08T00:14:56.311Z,1549584896.311 [TempGradientCalculator] Loaded 2019-02-08T00:14:56.311Z,1549584896.311 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-08T00:14:56.317Z,1549584896.317 [YawRateCalculator] Loaded 2019-02-08T00:14:56.317Z,1549584896.317 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-08T00:14:56.348Z,1549584896.348 [ElevatorOffsetCalculator] Loaded 2019-02-08T00:14:56.349Z,1549584896.349 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-08T00:14:56.349Z,1549584896.349 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-08T00:14:56.350Z,1549584896.350 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-08T00:14:56.407Z,1549584896.407 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-08T00:14:56.411Z,1549584896.411 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-08T00:14:56.412Z,1549584896.412 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-08T00:14:56.419Z,1549584896.419 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-08T00:14:56.420Z,1549584896.420 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2019-02-08T00:14:56.420Z,1549584896.420 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1037 2019-02-08T00:14:56.425Z,1549584896.425 [Supervisor](INFO): Main Thread ID is 796 2019-02-08T00:14:56.425Z,1549584896.425 [Supervisor](DEBUG): Running supervisor. 2019-02-08T00:14:56.426Z,1549584896.426 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1038 2019-02-08T00:14:56.429Z,1549584896.429 [controlThread ThreadHandler](INFO): Handler Thread ID is 1039 2019-02-08T00:14:56.429Z,1549584896.429 [controlThread](DEBUG): Initializing ControlThread 2019-02-08T00:14:56.430Z,1549584896.430 [SBIT](INFO): Initialize SBIT Component. 2019-02-08T00:14:56.430Z,1549584896.430 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9 2019-02-08T00:14:56.430Z,1549584896.430 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6 2019-02-08T00:14:56.431Z,1549584896.431 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-08T00:14:56.431Z,1549584896.431 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-02-08T00:14:56.432Z,1549584896.432 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-02-08T00:14:56.433Z,1549584896.433 [IBIT](INFO): Initialize IBIT Component. 2019-02-08T00:14:56.433Z,1549584896.433 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-08T00:14:56.434Z,1549584896.434 [logger ThreadHandler](INFO): Handler Thread ID is 1040 2019-02-08T00:14:56.445Z,1549584896.445 [CBIT](DEBUG): Initialized mux pins. 2019-02-08T00:14:56.445Z,1549584896.445 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-08T00:14:56.453Z,1549584896.453 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1041 2019-02-08T00:14:56.459Z,1549584896.459 [Radio_Surface](INFO): Powering up 2019-02-08T00:14:56.465Z,1549584896.465 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1042 2019-02-08T00:14:56.466Z,1549584896.466 [CTD_NeilBrown](INFO): Powering down 2019-02-08T00:14:56.469Z,1549584896.469 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-02-08T00:14:56.469Z,1549584896.469 [CBIT](DEBUG): Initializing heartbeat. 2019-02-08T00:14:56.501Z,1549584896.501 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1043 2019-02-08T00:14:56.502Z,1549584896.502 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-08T00:14:56.537Z,1549584896.537 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1044 2019-02-08T00:14:56.540Z,1549584896.540 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-08T00:14:56.541Z,1549584896.541 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-08T00:14:56.541Z,1549584896.541 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-08T00:14:56.541Z,1549584896.541 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-02-08T00:14:56.541Z,1549584896.541 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-08T00:14:56.541Z,1549584896.541 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-02-08T00:14:56.542Z,1549584896.542 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-08T00:14:56.542Z,1549584896.542 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-02-08T00:14:56.542Z,1549584896.542 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-08T00:14:56.542Z,1549584896.542 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-02-08T00:14:56.542Z,1549584896.542 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-08T00:14:56.543Z,1549584896.543 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-02-08T00:14:56.543Z,1549584896.543 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-08T00:14:56.543Z,1549584896.543 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-02-08T00:14:56.543Z,1549584896.543 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-08T00:14:56.543Z,1549584896.543 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-02-08T00:14:56.543Z,1549584896.543 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-08T00:14:56.544Z,1549584896.544 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-08T00:14:56.578Z,1549584896.578 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-08T00:14:56.579Z,1549584896.579 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-08T00:14:56.579Z,1549584896.579 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-08T00:14:56.584Z,1549584896.584 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-08T00:14:56.586Z,1549584896.586 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-08T00:14:56.586Z,1549584896.586 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-08T00:14:56.587Z,1549584896.587 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-08T00:14:56.587Z,1549584896.587 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-08T00:14:56.588Z,1549584896.588 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-08T00:14:56.588Z,1549584896.588 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-08T00:14:56.589Z,1549584896.589 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-08T00:14:56.589Z,1549584896.589 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-08T00:14:56.590Z,1549584896.590 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-08T00:14:56.591Z,1549584896.591 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-08T00:14:56.619Z,1549584896.619 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-08T00:14:56.646Z,1549584896.646 [MissionManager](DEBUG): 2019-02-08T00:14:56.647Z,1549584896.647 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-08T00:14:56.723Z,1549584896.723 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-08T00:14:56.724Z,1549584896.724 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-08T00:14:56.742Z,1549584896.742 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-08T00:14:56.791Z,1549584896.791 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-08T00:14:56.794Z,1549584896.794 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-08T00:14:56.820Z,1549584896.820 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-08T00:14:56.823Z,1549584896.823 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-02-08T00:14:56.837Z,1549584896.837 [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-08T00:14:56.861Z,1549584896.861 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-02-08T00:14:56.909Z,1549584896.909 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-08T00:14:56.964Z,1549584896.964 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2019-02-08T00:14:57.005Z,1549584897.005 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:14:57.005Z,1549584897.005 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:14:57.005Z,1549584897.005 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:14:57.173Z,1549584897.173 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-08T00:14:57.381Z,1549584897.381 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:14:57.389Z,1549584897.389 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-08T00:14:57.407Z,1549584897.407 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:14:57.413Z,1549584897.413 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-08T00:14:57.425Z,1549584897.425 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:14:57.433Z,1549584897.433 [MassServo](DEBUG): Initializing MassServo. 2019-02-08T00:14:57.455Z,1549584897.455 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:14:57.461Z,1549584897.461 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-08T00:14:57.474Z,1549584897.474 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:14:57.481Z,1549584897.481 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-08T00:14:57.607Z,1549584897.607 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:14:57.717Z,1549584897.717 [RDI_PathfinderUp](INFO): Powering down 2019-02-08T00:14:57.777Z,1549584897.777 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-08T00:14:57.958Z,1549584897.958 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:14:57.977Z,1549584897.977 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-08T00:14:58.313Z,1549584898.313 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2019-02-08T00:14:58.313Z,1549584898.313 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2019-02-08T00:14:58.313Z,1549584898.313 [BuoyancyServo] Communications Fault, FailCount= 1 2019-02-08T00:14:58.313Z,1549584898.313 [BuoyancyServo](ERROR): Communications Fault 2019-02-08T00:14:58.525Z,1549584898.525 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2019-02-08T00:14:58.535Z,1549584898.535 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:14:58.942Z,1549584898.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:14:59.342Z,1549584899.342 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:14:59.750Z,1549584899.750 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:00.206Z,1549584900.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:00.341Z,1549584900.341 [Aanderaa_O2](INFO): Powering down 2019-02-08T00:15:00.562Z,1549584900.562 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:00.626Z,1549584900.626 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2019-02-08T00:15:00.626Z,1549584900.626 [BuoyancyServo] No Fault, FailCount= 1 2019-02-08T00:15:00.962Z,1549584900.962 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:00.991Z,1549584900.991 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:15:01.110Z,1549584901.110 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-08T00:15:01.426Z,1549584901.426 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:01.774Z,1549584901.774 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:02.174Z,1549584902.174 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:02.574Z,1549584902.574 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:02.974Z,1549584902.974 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:03.374Z,1549584903.374 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:03.774Z,1549584903.774 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:04.174Z,1549584904.174 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:04.574Z,1549584904.574 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:04.963Z,1549584904.963 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:05.366Z,1549584905.366 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:05.750Z,1549584905.750 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:06.150Z,1549584906.150 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:06.566Z,1549584906.566 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:06.942Z,1549584906.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:07.362Z,1549584907.362 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:07.742Z,1549584907.742 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:08.142Z,1549584908.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:08.555Z,1549584908.555 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:08.942Z,1549584908.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:09.342Z,1549584909.342 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:09.754Z,1549584909.754 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:10.142Z,1549584910.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:10.553Z,1549584910.553 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:10.578Z,1549584910.578 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-02-08T00:15:10.942Z,1549584910.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:11.354Z,1549584911.354 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:11.742Z,1549584911.742 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:12.154Z,1549584912.154 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:12.542Z,1549584912.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:12.954Z,1549584912.954 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:13.344Z,1549584913.344 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:13.745Z,1549584913.745 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:14.147Z,1549584914.147 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:14.546Z,1549584914.546 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:14.946Z,1549584914.946 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:15.346Z,1549584915.346 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:15.746Z,1549584915.746 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:16.145Z,1549584916.145 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:16.544Z,1549584916.544 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:16.944Z,1549584916.944 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:17.343Z,1549584917.343 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:17.344Z,1549584917.344 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:15:17.344Z,1549584917.344 [DUSBL_Hydroid] Communications Fault, FailCount= 1 2019-02-08T00:15:17.344Z,1549584917.344 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:15:17.433Z,1549584917.433 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:15:17.746Z,1549584917.746 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:15:17.746Z,1549584917.746 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:15:17.746Z,1549584917.746 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:15:18.146Z,1549584918.146 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:15:18.546Z,1549584918.546 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:15:18.631Z,1549584918.631 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:15:18.631Z,1549584918.631 [DUSBL_Hydroid] No Fault, FailCount= 1 2019-02-08T00:15:18.946Z,1549584918.946 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:19.350Z,1549584919.350 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:19.746Z,1549584919.746 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:20.146Z,1549584920.146 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:20.554Z,1549584920.554 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:20.946Z,1549584920.946 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:20.946Z,1549584920.946 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:15:20.946Z,1549584920.946 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:15:21.346Z,1549584921.346 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:21.746Z,1549584921.746 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:22.146Z,1549584922.146 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:22.546Z,1549584922.546 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:22.946Z,1549584922.946 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:23.354Z,1549584923.354 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:23.742Z,1549584923.742 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:24.154Z,1549584924.154 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:24.155Z,1549584924.155 [NAL9602](INFO): Powering up NAL9602 2019-02-08T00:15:24.547Z,1549584924.547 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:24.942Z,1549584924.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:24.975Z,1549584924.975 [SBIT](IMPORTANT): Beginning Startup BIT 2019-02-08T00:15:24.979Z,1549584924.979 [CBIT](IMPORTANT): Beginning ground fault scan 2019-02-08T00:15:25.354Z,1549584925.354 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:25.750Z,1549584925.750 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:26.143Z,1549584926.143 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:26.542Z,1549584926.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:26.942Z,1549584926.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:27.478Z,1549584927.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:27.886Z,1549584927.886 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:28.286Z,1549584928.286 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:28.686Z,1549584928.686 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:29.078Z,1549584929.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:29.478Z,1549584929.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:29.878Z,1549584929.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:30.278Z,1549584930.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:30.694Z,1549584930.694 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:31.078Z,1549584931.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:31.495Z,1549584931.495 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:31.878Z,1549584931.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:32.278Z,1549584932.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:32.678Z,1549584932.678 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:33.096Z,1549584933.096 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:33.478Z,1549584933.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:33.886Z,1549584933.886 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:34.304Z,1549584934.304 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:34.678Z,1549584934.678 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:35.078Z,1549584935.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:35.079Z,1549584935.079 [NAL9602](INFO): NAL9602 initialized 2019-02-08T00:15:35.486Z,1549584935.486 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:35.878Z,1549584935.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:35.893Z,1549584935.893 [NAL9602](DEBUG): Fix Requested 2019-02-08T00:15:36.014Z,1549584936.014 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.009326 CHAN A1 (24V): -0.193253 CHAN A2 (12V): -0.005852 CHAN A3 (5V): -0.002789 CHAN B0 (3.3V): -0.000168 CHAN B1 (3.15aV): -0.000193 CHAN B2 (3.15bV): -0.000316 CHAN B3 (GND): 0.000897 OPEN: 0.009508 Full Scale Calc: 4.765 mA, -1.589 mA 2019-02-08T00:15:36.278Z,1549584936.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:36.678Z,1549584936.678 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:37.078Z,1549584937.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:37.478Z,1549584937.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:37.878Z,1549584937.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:38.278Z,1549584938.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:38.686Z,1549584938.686 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:39.078Z,1549584939.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:39.478Z,1549584939.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:39.878Z,1549584939.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:40.291Z,1549584940.291 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:40.911Z,1549584940.911 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:41.310Z,1549584941.310 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:41.310Z,1549584941.310 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:15:41.310Z,1549584941.310 [DUSBL_Hydroid] Communications Fault, FailCount= 2 2019-02-08T00:15:41.311Z,1549584941.311 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:15:41.522Z,1549584941.522 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:15:41.702Z,1549584941.702 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:15:41.702Z,1549584941.702 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:15:41.702Z,1549584941.702 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:15:42.103Z,1549584942.103 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:15:42.508Z,1549584942.508 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:15:42.694Z,1549584942.694 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:15:42.695Z,1549584942.695 [DUSBL_Hydroid] No Fault, FailCount= 2 2019-02-08T00:15:42.906Z,1549584942.906 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:43.306Z,1549584943.306 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:43.706Z,1549584943.706 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:44.106Z,1549584944.106 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:44.514Z,1549584944.514 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:44.919Z,1549584944.919 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:15:44.919Z,1549584944.919 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:15:44.919Z,1549584944.919 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:15:45.304Z,1549584945.304 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:45.704Z,1549584945.704 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:46.110Z,1549584946.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:46.510Z,1549584946.510 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:46.906Z,1549584946.906 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:47.310Z,1549584947.310 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:47.710Z,1549584947.710 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:48.110Z,1549584948.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:48.519Z,1549584948.519 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:48.906Z,1549584948.906 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:49.306Z,1549584949.306 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:49.706Z,1549584949.706 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:50.110Z,1549584950.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:50.506Z,1549584950.506 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:50.918Z,1549584950.918 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:51.306Z,1549584951.306 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:51.706Z,1549584951.706 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:52.110Z,1549584952.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:52.506Z,1549584952.506 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:52.902Z,1549584952.902 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:53.306Z,1549584953.306 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:53.714Z,1549584953.714 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:54.102Z,1549584954.102 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:54.514Z,1549584954.514 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:54.906Z,1549584954.906 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:55.310Z,1549584955.310 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:55.715Z,1549584955.715 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:56.102Z,1549584956.102 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:56.502Z,1549584956.502 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:56.902Z,1549584956.902 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:57.302Z,1549584957.302 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:57.702Z,1549584957.702 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:58.106Z,1549584958.106 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:58.506Z,1549584958.506 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:58.902Z,1549584958.902 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:59.306Z,1549584959.306 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:15:59.702Z,1549584959.702 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:00.102Z,1549584960.102 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:00.506Z,1549584960.506 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:00.902Z,1549584960.902 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:01.310Z,1549584961.310 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:01.702Z,1549584961.702 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:02.106Z,1549584962.106 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:02.502Z,1549584962.502 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:02.902Z,1549584962.902 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:03.302Z,1549584963.302 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:03.706Z,1549584963.706 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:04.102Z,1549584964.102 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:04.502Z,1549584964.502 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:04.902Z,1549584964.902 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:05.302Z,1549584965.302 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:05.303Z,1549584965.303 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:16:05.303Z,1549584965.303 [DUSBL_Hydroid] Communications Fault, FailCount= 3 2019-02-08T00:16:05.303Z,1549584965.303 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:16:05.390Z,1549584965.390 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:16:05.702Z,1549584965.702 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:16:05.702Z,1549584965.702 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:16:05.702Z,1549584965.702 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:16:06.106Z,1549584966.106 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:16:06.514Z,1549584966.514 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:16:06.580Z,1549584966.580 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:16:06.580Z,1549584966.580 [DUSBL_Hydroid] No Fault, FailCount= 3 2019-02-08T00:16:06.902Z,1549584966.902 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:07.302Z,1549584967.302 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:07.841Z,1549584967.841 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:08.248Z,1549584968.248 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:08.634Z,1549584968.634 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:09.042Z,1549584969.042 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:09.042Z,1549584969.042 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:16:09.042Z,1549584969.042 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:16:09.438Z,1549584969.438 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:09.834Z,1549584969.834 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:10.240Z,1549584970.240 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:10.727Z,1549584970.727 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:11.138Z,1549584971.138 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:11.534Z,1549584971.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:11.938Z,1549584971.938 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:12.345Z,1549584972.345 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:12.743Z,1549584972.743 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:13.134Z,1549584973.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:13.534Z,1549584973.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:13.934Z,1549584973.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:14.334Z,1549584974.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:14.734Z,1549584974.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:15.134Z,1549584975.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:15.534Z,1549584975.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:15.934Z,1549584975.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:16.334Z,1549584976.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:16.743Z,1549584976.743 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:17.144Z,1549584977.144 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:17.534Z,1549584977.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:17.934Z,1549584977.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:18.334Z,1549584978.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:18.738Z,1549584978.738 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:18.813Z,1549584978.813 [SBIT](IMPORTANT): SBIT PASSED 2019-02-08T00:16:18.849Z,1549584978.849 [CommandLine](IMPORTANT): got command configSet list 2019-02-08T00:16:18.850Z,1549584978.850 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-02-08T00:16:18.851Z,1549584978.851 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool; 2019-02-08T00:16:18.851Z,1549584978.851 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool; 2019-02-08T00:16:19.134Z,1549584979.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:19.192Z,1549584979.192 [MissionManager](IMPORTANT): Started mission Startup 2019-02-08T00:16:19.192Z,1549584979.192 [Startup] Running Loop=1 2019-02-08T00:16:19.192Z,1549584979.192 [Startup](DEBUG): Aggregate::initialize Startup 2019-02-08T00:16:19.201Z,1549584979.201 [Startup:A.GoToSurface] Running Loop=1 2019-02-08T00:16:19.201Z,1549584979.201 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-08T00:16:19.203Z,1549584979.203 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-08T00:16:19.204Z,1549584979.204 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-08T00:16:19.209Z,1549584979.209 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-08T00:16:19.210Z,1549584979.210 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-08T00:16:19.211Z,1549584979.211 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-08T00:16:19.237Z,1549584979.237 [Startup:StartupSatComms] Running Loop=1 2019-02-08T00:16:19.238Z,1549584979.238 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-02-08T00:16:19.238Z,1549584979.238 [Startup:StartupSatComms:A] Running Loop=1 2019-02-08T00:16:19.534Z,1549584979.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:19.622Z,1549584979.622 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-02-08T00:16:19.934Z,1549584979.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:20.334Z,1549584980.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:20.738Z,1549584980.738 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:21.138Z,1549584981.138 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:21.538Z,1549584981.538 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:21.943Z,1549584981.943 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:22.334Z,1549584982.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:22.735Z,1549584982.735 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:23.134Z,1549584983.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:23.534Z,1549584983.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:23.934Z,1549584983.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:24.334Z,1549584984.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:24.734Z,1549584984.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:25.134Z,1549584985.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:25.542Z,1549584985.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:25.946Z,1549584985.946 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:26.342Z,1549584986.342 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:26.734Z,1549584986.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:27.138Z,1549584987.138 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:27.542Z,1549584987.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:27.934Z,1549584987.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:28.338Z,1549584988.338 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:28.738Z,1549584988.738 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:29.134Z,1549584989.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:29.135Z,1549584989.135 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:16:29.135Z,1549584989.135 [DUSBL_Hydroid] Communications Fault, FailCount= 4 2019-02-08T00:16:29.135Z,1549584989.135 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:16:29.229Z,1549584989.229 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:16:29.550Z,1549584989.550 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:16:29.550Z,1549584989.550 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:16:29.550Z,1549584989.550 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:16:29.942Z,1549584989.942 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:16:30.334Z,1549584990.334 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:16:30.414Z,1549584990.414 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:16:30.414Z,1549584990.414 [DUSBL_Hydroid] No Fault, FailCount= 4 2019-02-08T00:16:30.734Z,1549584990.734 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:31.139Z,1549584991.139 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:31.534Z,1549584991.534 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:31.938Z,1549584991.938 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:32.338Z,1549584992.338 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:32.738Z,1549584992.738 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:32.738Z,1549584992.738 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:16:32.739Z,1549584992.739 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:16:33.134Z,1549584993.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:33.542Z,1549584993.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:33.938Z,1549584993.938 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:34.338Z,1549584994.338 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:34.734Z,1549584994.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:35.134Z,1549584995.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:35.544Z,1549584995.544 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:35.943Z,1549584995.943 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:36.338Z,1549584996.338 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:36.738Z,1549584996.738 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:37.134Z,1549584997.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:37.534Z,1549584997.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:37.934Z,1549584997.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:38.334Z,1549584998.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:38.734Z,1549584998.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:39.138Z,1549584999.138 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:39.534Z,1549584999.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:39.935Z,1549584999.935 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:40.335Z,1549585000.335 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:40.734Z,1549585000.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:41.134Z,1549585001.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:41.534Z,1549585001.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:41.934Z,1549585001.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:42.344Z,1549585002.344 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:42.736Z,1549585002.736 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:43.142Z,1549585003.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:43.534Z,1549585003.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:43.938Z,1549585003.938 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:44.338Z,1549585004.338 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:44.734Z,1549585004.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:45.134Z,1549585005.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:45.534Z,1549585005.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:45.938Z,1549585005.938 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:46.334Z,1549585006.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:46.738Z,1549585006.738 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:47.134Z,1549585007.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:47.534Z,1549585007.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:47.938Z,1549585007.938 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:48.334Z,1549585008.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:48.734Z,1549585008.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:49.134Z,1549585009.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:49.538Z,1549585009.538 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:49.939Z,1549585009.939 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:50.338Z,1549585010.338 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:50.734Z,1549585010.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:51.142Z,1549585011.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:51.534Z,1549585011.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:51.934Z,1549585011.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:52.334Z,1549585012.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:52.738Z,1549585012.738 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:53.134Z,1549585013.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:53.135Z,1549585013.135 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:16:53.135Z,1549585013.135 [DUSBL_Hydroid] Communications Fault, FailCount= 5 2019-02-08T00:16:53.135Z,1549585013.135 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:16:53.256Z,1549585013.256 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:16:53.538Z,1549585013.538 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:16:53.538Z,1549585013.538 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:16:53.538Z,1549585013.538 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:16:53.942Z,1549585013.942 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:16:54.334Z,1549585014.334 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:16:54.428Z,1549585014.428 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:16:54.429Z,1549585014.429 [DUSBL_Hydroid] No Fault, FailCount= 5 2019-02-08T00:16:54.730Z,1549585014.730 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:55.134Z,1549585015.134 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:55.534Z,1549585015.534 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:55.934Z,1549585015.934 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:56.334Z,1549585016.334 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:56.734Z,1549585016.734 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:16:56.734Z,1549585016.734 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:16:56.734Z,1549585016.734 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:16:57.138Z,1549585017.138 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:57.542Z,1549585017.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:57.934Z,1549585017.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:58.338Z,1549585018.338 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:58.734Z,1549585018.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:59.138Z,1549585019.138 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:59.539Z,1549585019.539 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:16:59.947Z,1549585019.947 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:00.344Z,1549585020.344 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:00.734Z,1549585020.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:01.138Z,1549585021.138 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:01.538Z,1549585021.538 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:01.934Z,1549585021.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:02.334Z,1549585022.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:02.734Z,1549585022.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:03.134Z,1549585023.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:03.534Z,1549585023.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:03.934Z,1549585023.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:04.334Z,1549585024.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:04.734Z,1549585024.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:05.138Z,1549585025.138 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:05.534Z,1549585025.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:05.938Z,1549585025.938 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:06.334Z,1549585026.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:06.734Z,1549585026.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:07.134Z,1549585027.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:07.534Z,1549585027.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:07.934Z,1549585027.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:08.334Z,1549585028.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:08.738Z,1549585028.738 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:09.139Z,1549585029.139 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:09.547Z,1549585029.547 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:09.934Z,1549585029.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:10.334Z,1549585030.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:10.738Z,1549585030.738 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:11.134Z,1549585031.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:11.534Z,1549585031.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:11.938Z,1549585031.938 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:12.334Z,1549585032.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:12.734Z,1549585032.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:13.134Z,1549585033.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:13.535Z,1549585033.535 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:13.939Z,1549585033.939 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:14.334Z,1549585034.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:14.742Z,1549585034.742 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:15.134Z,1549585035.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:15.534Z,1549585035.534 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:15.934Z,1549585035.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:16.334Z,1549585036.334 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:16.734Z,1549585036.734 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:17.134Z,1549585037.134 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:17.135Z,1549585037.135 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:17:17.135Z,1549585037.135 [DUSBL_Hydroid] Communications Fault, FailCount= 6 2019-02-08T00:17:17.135Z,1549585037.135 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:17:17.208Z,1549585037.208 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:17:17.542Z,1549585037.542 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:17:17.542Z,1549585037.542 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:17:17.542Z,1549585037.542 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:17:17.953Z,1549585037.953 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:17:21.108Z,1549585041.108 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:17:21.228Z,1549585041.228 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-08T00:16:19.2Z 2019-02-08T00:17:21.228Z,1549585041.228 [Startup:StartupSatComms:A] Stopped 2019-02-08T00:17:21.228Z,1549585041.228 [Startup:StartupSatComms:B] Running Loop=1 2019-02-08T00:17:21.278Z,1549585041.278 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:17:21.278Z,1549585041.278 [DUSBL_Hydroid] No Fault, FailCount= 6 2019-02-08T00:17:21.315Z,1549585041.315 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:17:21.316Z,1549585041.316 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:17:21.316Z,1549585041.316 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:17:21.389Z,1549585041.389 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-08T00:17:21.712Z,1549585041.712 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:22.094Z,1549585042.094 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004743 2019-02-08T00:17:22.120Z,1549585042.120 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:22.502Z,1549585042.502 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:22.534Z,1549585042.534 [RDI_Pathfinder](ERROR): Failed to parse: :BI, +0, -4, +0,-32768,A 2019-02-08T00:17:22.898Z,1549585042.898 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:26.072Z,1549585046.072 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:26.228Z,1549585046.228 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190208T000815/Courier0007.lzma 2019-02-08T00:17:26.241Z,1549585046.241 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:26.578Z,1549585046.578 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:26.974Z,1549585046.974 [DataOverHttps](INFO): Moved sent file to Logs/20190208T000815/Courier0007.lzma.bak 2019-02-08T00:17:26.974Z,1549585046.974 [DataOverHttps](INFO): SBD MOMSN=9641494 2019-02-08T00:17:26.982Z,1549585046.982 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:27.443Z,1549585047.443 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:27.778Z,1549585047.778 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:32.628Z,1549585052.628 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:32.958Z,1549585052.958 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:33.314Z,1549585053.314 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:33.719Z,1549585053.719 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:34.118Z,1549585054.118 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:34.510Z,1549585054.510 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:39.135Z,1549585059.135 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:39.279Z,1549585059.279 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20190208T000815/Express0008.lzma 2019-02-08T00:17:39.330Z,1549585059.330 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:39.683Z,1549585059.683 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:40.073Z,1549585060.073 [DataOverHttps](INFO): Moved sent file to Logs/20190208T000815/Express0008.lzma.bak 2019-02-08T00:17:40.073Z,1549585060.073 [DataOverHttps](INFO): SBD MOMSN=9641496 2019-02-08T00:17:40.082Z,1549585060.082 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:40.505Z,1549585060.505 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:40.874Z,1549585060.874 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:44.075Z,1549585064.075 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:44.075Z,1549585064.075 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:17:44.076Z,1549585064.076 [DUSBL_Hydroid] Communications Fault, FailCount= 7 2019-02-08T00:17:44.076Z,1549585064.076 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:17:44.185Z,1549585064.185 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:17:44.244Z,1549585064.244 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:17:44.244Z,1549585064.244 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:17:44.244Z,1549585064.244 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:17:44.622Z,1549585064.622 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:17:45.006Z,1549585065.006 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:17:45.096Z,1549585065.096 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:17:45.096Z,1549585065.096 [DUSBL_Hydroid] No Fault, FailCount= 7 2019-02-08T00:17:45.402Z,1549585065.402 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:17:45.802Z,1549585065.802 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:17:48.994Z,1549585068.994 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:17:48.995Z,1549585068.995 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:17:48.995Z,1549585068.995 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:17:49.286Z,1549585069.286 [DataOverHttps](INFO): Sending 792 bytes from file Logs/20190208T001445/Express0001.lzma 2019-02-08T00:17:49.293Z,1549585069.293 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:49.684Z,1549585069.684 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:50.062Z,1549585070.062 [DataOverHttps](INFO): Moved sent file to Logs/20190208T001445/Express0001.lzma.bak 2019-02-08T00:17:50.062Z,1549585070.062 [DataOverHttps](INFO): SBD MOMSN=9641499 2019-02-08T00:17:50.097Z,1549585070.097 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:50.490Z,1549585070.490 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:50.526Z,1549585070.526 [Startup:StartupSatComms:B] Stopped 2019-02-08T00:17:50.526Z,1549585070.526 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-02-08T00:17:50.526Z,1549585070.526 [Startup:StartupSatComms] Stopped 2019-02-08T00:17:50.526Z,1549585070.526 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-02-08T00:17:50.527Z,1549585070.527 [Startup](INFO): Completed Startup 2019-02-08T00:17:50.527Z,1549585070.527 [MissionManager](INFO): Startup is completed. 2019-02-08T00:17:50.528Z,1549585070.528 [MissionManager](INFO): Uninitializing Mission Startup 2019-02-08T00:17:50.528Z,1549585070.528 [Startup] Stopped 2019-02-08T00:17:50.528Z,1549585070.528 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-02-08T00:17:50.528Z,1549585070.528 [Startup:A.GoToSurface] Stopped 2019-02-08T00:17:50.528Z,1549585070.528 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-08T00:17:50.870Z,1549585070.870 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:50.913Z,1549585070.913 [MissionManager](IMPORTANT): Started mission Default 2019-02-08T00:17:50.913Z,1549585070.913 [Default] Running Loop=1 2019-02-08T00:17:50.913Z,1549585070.913 [Default](DEBUG): Aggregate::initialize Default 2019-02-08T00:17:50.913Z,1549585070.913 [Default:B.GoToSurface] Running Loop=1 2019-02-08T00:17:50.913Z,1549585070.913 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-08T00:17:50.914Z,1549585070.914 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-08T00:17:50.914Z,1549585070.914 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-08T00:17:50.914Z,1549585070.914 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-08T00:17:50.915Z,1549585070.915 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-08T00:17:50.915Z,1549585070.915 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-08T00:17:50.915Z,1549585070.915 [Default:A.Wait] Running Loop=1 2019-02-08T00:17:50.915Z,1549585070.915 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-02-08T00:17:54.044Z,1549585074.044 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:54.183Z,1549585074.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:54.542Z,1549585074.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:54.946Z,1549585074.946 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:55.342Z,1549585075.342 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:55.750Z,1549585075.750 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:56.142Z,1549585076.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:56.542Z,1549585076.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:56.594Z,1549585076.594 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-02-08T00:17:56.594Z,1549585076.594 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-08T00:17:56.595Z,1549585076.595 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-02-08T00:17:56.596Z,1549585076.596 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-08T00:17:56.601Z,1549585076.601 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-02-08T00:17:56.601Z,1549585076.601 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-08T00:17:56.622Z,1549585076.622 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-08T00:17:56.622Z,1549585076.622 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-08T00:17:56.622Z,1549585076.622 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-08T00:17:56.946Z,1549585076.946 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:57.009Z,1549585077.009 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-08T00:17:57.010Z,1549585077.010 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-02-08T00:17:57.010Z,1549585077.010 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-08T00:17:57.010Z,1549585077.010 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-02-08T00:17:57.011Z,1549585077.011 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-08T00:17:57.011Z,1549585077.011 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-02-08T00:17:57.346Z,1549585077.346 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:57.405Z,1549585077.405 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-08T00:17:57.742Z,1549585077.742 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:58.142Z,1549585078.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:58.542Z,1549585078.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:58.942Z,1549585078.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:59.342Z,1549585079.342 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:17:59.746Z,1549585079.746 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:00.146Z,1549585080.146 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:00.542Z,1549585080.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:00.942Z,1549585080.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:01.346Z,1549585081.346 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:01.750Z,1549585081.750 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:02.142Z,1549585082.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:02.546Z,1549585082.546 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:02.946Z,1549585082.946 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:03.352Z,1549585083.352 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:03.750Z,1549585083.750 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:04.146Z,1549585084.146 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:04.212Z,1549585084.212 [Default:A.Wait](INFO): Done Waiting. 2019-02-08T00:18:04.217Z,1549585084.217 [Default:A.Wait] Stopped 2019-02-08T00:18:04.217Z,1549585084.217 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-02-08T00:18:04.542Z,1549585084.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:04.609Z,1549585084.609 [Default:CheckIn] Running Loop=1 2019-02-08T00:18:04.609Z,1549585084.609 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-02-08T00:18:04.609Z,1549585084.609 [Default:CheckIn:Read_GPS] Running Loop=1 2019-02-08T00:18:04.942Z,1549585084.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:04.990Z,1549585084.990 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-02-08T00:18:05.346Z,1549585085.346 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:05.743Z,1549585085.743 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:06.142Z,1549585086.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:06.542Z,1549585086.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:06.942Z,1549585086.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:07.342Z,1549585087.342 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:07.750Z,1549585087.750 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:08.142Z,1549585088.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:08.542Z,1549585088.542 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:08.946Z,1549585088.946 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:09.342Z,1549585089.342 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:09.343Z,1549585089.343 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:18:09.343Z,1549585089.343 [DUSBL_Hydroid] Communications Fault, FailCount= 8 2019-02-08T00:18:09.343Z,1549585089.343 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:18:09.439Z,1549585089.439 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:18:09.440Z,1549585089.440 [CBIT](CRITICAL): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:18:09.746Z,1549585089.746 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:18:09.746Z,1549585089.746 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:18:09.746Z,1549585089.746 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:18:09.857Z,1549585089.857 [CommandLine](FAULT): Scheduling is paused 2019-02-08T00:18:09.858Z,1549585089.858 [CBIT](INFO): Critical error at 20190208T001809 2019-02-08T00:18:09.858Z,1549585089.858 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-02-08T00:18:10.142Z,1549585090.142 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:18:10.542Z,1549585090.542 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:18:40.982Z,1549585120.982 [CommandLine](IMPORTANT): got command failComponent 2019-02-08T00:18:40.983Z,1549585120.983 [CommandLine](IMPORTANT): Failed components: 2019-02-08T00:18:40.983Z,1549585120.983 [CommandLine](IMPORTANT): DUSBL_Hydroid: Communications Fault 2019-02-08T00:18:45.998Z,1549585125.998 [CommandLine](IMPORTANT): got command failComponent none DUSBL_Hydroid 2019-02-08T00:18:45.999Z,1549585125.999 [DUSBL_Hydroid] No Fault, FailCount= 8 2019-02-08T00:18:45.999Z,1549585125.999 [CommandLine](IMPORTANT): DUSBL_Hydroid failureMode is No Fault 2019-02-08T00:18:46.400Z,1549585126.400 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:18:46.400Z,1549585126.400 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:18:46.400Z,1549585126.400 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:18:46.794Z,1549585126.794 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:47.186Z,1549585127.186 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:47.586Z,1549585127.586 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:47.986Z,1549585127.986 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:48.382Z,1549585128.382 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:48.786Z,1549585128.786 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:49.186Z,1549585129.186 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:49.586Z,1549585129.586 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:49.986Z,1549585129.986 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:50.386Z,1549585130.386 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:50.790Z,1549585130.790 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:51.186Z,1549585131.186 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:51.586Z,1549585131.586 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:51.986Z,1549585131.986 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:52.390Z,1549585132.390 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:52.790Z,1549585132.790 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:53.186Z,1549585133.186 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:53.590Z,1549585133.590 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:53.986Z,1549585133.986 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:54.386Z,1549585134.386 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:54.786Z,1549585134.786 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:55.187Z,1549585135.187 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:55.586Z,1549585135.586 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:55.996Z,1549585135.996 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:56.394Z,1549585136.394 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:56.786Z,1549585136.786 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:57.195Z,1549585137.195 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:57.590Z,1549585137.590 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:57.986Z,1549585137.986 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:58.386Z,1549585138.386 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:58.786Z,1549585138.786 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:59.227Z,1549585139.227 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:59.590Z,1549585139.590 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:18:59.986Z,1549585139.986 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:00.386Z,1549585140.386 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:00.786Z,1549585140.786 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:01.208Z,1549585141.208 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:04.468Z,1549585144.468 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:04.639Z,1549585144.639 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:04.990Z,1549585144.990 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:05.386Z,1549585145.386 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:05.786Z,1549585145.786 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:06.190Z,1549585146.190 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:06.586Z,1549585146.586 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:19:06.587Z,1549585146.587 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:19:06.587Z,1549585146.587 [DUSBL_Hydroid] Communications Fault, FailCount= 9 2019-02-08T00:19:06.587Z,1549585146.587 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:19:06.683Z,1549585146.683 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:19:06.684Z,1549585146.684 [CBIT](CRITICAL): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:19:06.990Z,1549585146.990 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:19:06.990Z,1549585146.990 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:19:06.990Z,1549585146.990 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:19:07.056Z,1549585147.056 [CBIT](INFO): Critical error at 20190208T001906 2019-02-08T00:19:07.394Z,1549585147.394 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:19:07.786Z,1549585147.786 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:20:09.705Z,1549585209.705 [CommandLine](IMPORTANT): got command restart application 2019-02-08T00:20:10.712Z,1549585210.712 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:20:10.712Z,1549585210.712 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:10.805Z,1549585210.805 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-02-08T00:20:10.805Z,1549585210.805 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:10.806Z,1549585210.806 [CommandLine](INFO): Join timeout helper Thread ID is 1089 2019-02-08T00:20:10.806Z,1549585210.806 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-02-08T00:20:10.806Z,1549585210.806 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:10.807Z,1549585210.807 [NavChartDb](INFO): Join timeout helper Thread ID is 1090 2019-02-08T00:20:10.865Z,1549585210.865 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:20:10.865Z,1549585210.865 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:10.869Z,1549585210.869 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2019-02-08T00:20:10.869Z,1549585210.869 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:10.869Z,1549585210.869 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1091 2019-02-08T00:20:11.009Z,1549585211.009 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:20:11.009Z,1549585211.009 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-08T00:20:11.010Z,1549585211.010 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.029Z,1549585211.029 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-02-08T00:20:11.029Z,1549585211.029 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.029Z,1549585211.029 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1092 2019-02-08T00:20:11.101Z,1549585211.101 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:20:11.101Z,1549585211.101 [CTD_NeilBrown](INFO): Powering down 2019-02-08T00:20:11.113Z,1549585211.113 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.129Z,1549585211.129 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-02-08T00:20:11.129Z,1549585211.129 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.130Z,1549585211.130 [Radio_Surface](INFO): Join timeout helper Thread ID is 1093 2019-02-08T00:20:11.393Z,1549585211.393 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:20:11.393Z,1549585211.393 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.398Z,1549585211.398 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-02-08T00:20:11.398Z,1549585211.398 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.398Z,1549585211.398 [logger](INFO): Join timeout helper Thread ID is 1094 2019-02-08T00:20:11.399Z,1549585211.399 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:20:11.399Z,1549585211.399 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.400Z,1549585211.400 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-02-08T00:20:11.400Z,1549585211.400 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.401Z,1549585211.401 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-02-08T00:20:11.401Z,1549585211.401 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.401Z,1549585211.401 [controlThread](INFO): Join timeout helper Thread ID is 1095 2019-02-08T00:20:11.625Z,1549585211.625 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:20:11.625Z,1549585211.625 [controlThread](DEBUG): Uninitializing ControlThread 2019-02-08T00:20:11.626Z,1549585211.626 [AHRS_M2](INFO): Powering down 2019-02-08T00:20:11.698Z,1549585211.698 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:20:11.698Z,1549585211.698 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:20:11.699Z,1549585211.699 [NAL9602](INFO): Powering down 2019-02-08T00:20:11.769Z,1549585211.769 [RDI_Pathfinder](INFO): Powering down 2019-02-08T00:20:11.770Z,1549585211.770 [RDI_PathfinderUp](INFO): Powering down 2019-02-08T00:20:11.771Z,1549585211.771 [Aanderaa_O2](INFO): Powering down 2019-02-08T00:20:11.772Z,1549585211.772 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-02-08T00:20:11.773Z,1549585211.773 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-02-08T00:20:11.774Z,1549585211.774 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-02-08T00:20:11.775Z,1549585211.775 [MissionManager](INFO): Uninitializing Mission Default 2019-02-08T00:20:11.775Z,1549585211.775 [Default] Stopped 2019-02-08T00:20:11.775Z,1549585211.775 [Default](DEBUG): Aggregate::uninitialize Default 2019-02-08T00:20:11.775Z,1549585211.775 [Default:B.GoToSurface] Stopped 2019-02-08T00:20:11.775Z,1549585211.775 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-08T00:20:11.775Z,1549585211.775 [Default:CheckIn] Stopped 2019-02-08T00:20:11.775Z,1549585211.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-02-08T00:20:11.775Z,1549585211.775 [Default:CheckIn:Read_GPS] Stopped 2019-02-08T00:20:11.778Z,1549585211.778 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-02-08T00:20:11.778Z,1549585211.778 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-02-08T00:20:11.779Z,1549585211.779 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-02-08T00:20:11.779Z,1549585211.779 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-02-08T00:20:11.779Z,1549585211.779 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-02-08T00:20:11.779Z,1549585211.779 [BuoyancyServo](INFO): Powering down 2019-02-08T00:20:11.793Z,1549585211.793 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-02-08T00:20:11.793Z,1549585211.793 [ElevatorServo](INFO): Powering down 2019-02-08T00:20:11.794Z,1549585211.794 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-08T00:20:11.794Z,1549585211.794 [MassServo](INFO): Powering down 2019-02-08T00:20:11.795Z,1549585211.795 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-02-08T00:20:11.795Z,1549585211.795 [RudderServo](INFO): Powering down 2019-02-08T00:20:11.796Z,1549585211.796 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-02-08T00:20:11.796Z,1549585211.796 [ThrusterServo](INFO): Powering down 2019-02-08T00:20:11.797Z,1549585211.797 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-02-08T00:20:11.797Z,1549585211.797 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-02-08T00:20:11.797Z,1549585211.797 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-02-08T00:20:11.797Z,1549585211.797 [CBIT](DEBUG): Powering off loads. 2019-02-08T00:20:11.809Z,1549585211.809 [CBIT](DEBUG): Disabling WDT. 2019-02-08T00:20:11.821Z,1549585211.821 [CBIT](DEBUG): Opening all GF detection circuits. 2019-02-08T00:20:11.822Z,1549585211.822 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.934Z,1549585211.934 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.981Z,1549585211.981 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:11.984Z,1549585211.984 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:12.006Z,1549585212.006 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:20:12.064Z,1549585212.064 [logger ThreadHandler](INFO): Thread cancelled.