2019-05-19T06:07:12.161Z,1558246032.161 [Supervisor](DEBUG): Initializing supervisor. 2019-05-19T06:07:12.164Z,1558246032.164 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-05-19T06:07:12.164Z,1558246032.164 [SyncHandler](INFO): Protected caller Thread ID is 806 2019-05-19T06:07:12.165Z,1558246032.165 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-05-19T06:07:12.166Z,1558246032.166 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-05-19T06:07:12.166Z,1558246032.166 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2019-05-19T06:07:12.169Z,1558246032.169 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-05-19T06:07:12.180Z,1558246032.180 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-05-19T06:07:12.181Z,1558246032.181 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-05-19T06:07:12.182Z,1558246032.182 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2019-05-19T06:07:12.183Z,1558246032.183 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-05-19T06:07:12.184Z,1558246032.184 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-05-19T06:07:12.184Z,1558246032.184 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2019-05-19T06:07:12.186Z,1558246032.186 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-05-19T06:07:12.186Z,1558246032.186 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-05-19T06:07:12.190Z,1558246032.190 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-05-19T06:07:12.389Z,1558246032.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-05-19T06:07:12.391Z,1558246032.391 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-05-19T06:07:12.472Z,1558246032.472 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-05-19T06:07:12.898Z,1558246032.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-05-19T06:07:12.898Z,1558246032.898 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-05-19T06:07:13.220Z,1558246033.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-05-19T06:07:13.221Z,1558246033.221 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-05-19T06:07:13.317Z,1558246033.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-05-19T06:07:13.317Z,1558246033.317 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-05-19T06:07:13.610Z,1558246033.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-05-19T06:07:13.612Z,1558246033.612 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-05-19T06:07:13.804Z,1558246033.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-05-19T06:07:13.804Z,1558246033.804 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-05-19T06:07:14.265Z,1558246034.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-05-19T06:07:14.267Z,1558246034.267 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-05-19T06:07:14.370Z,1558246034.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-05-19T06:07:14.371Z,1558246034.371 [Supervisor](INFO): Opening Config file at: Config/._vehicle.cfg 2019-05-19T06:07:14.442Z,1558246034.442 [Config/._vehicle](ERROR): Could not parse value: resource 2019-05-19T06:07:14.512Z,1558246034.512 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-05-19T06:07:14.612Z,1558246034.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-05-19T06:07:14.613Z,1558246034.613 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-05-19T06:07:15.180Z,1558246035.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-05-19T06:07:15.181Z,1558246035.181 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-05-19T06:07:15.567Z,1558246035.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-05-19T06:07:15.568Z,1558246035.568 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-05-19T06:07:16.326Z,1558246036.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-05-19T06:07:16.326Z,1558246036.326 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-05-19T06:07:16.470Z,1558246036.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-05-19T06:07:16.471Z,1558246036.471 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-05-19T06:07:16.618Z,1558246036.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-05-19T06:07:16.620Z,1558246036.620 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2019-05-19T06:07:16.624Z,1558246036.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2019-05-19T06:07:16.715Z,1558246036.715 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2019-05-19T06:07:16.801Z,1558246036.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2019-05-19T06:07:16.905Z,1558246036.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2019-05-19T06:07:16.989Z,1558246036.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2019-05-19T06:07:17.085Z,1558246037.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2019-05-19T06:07:17.185Z,1558246037.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2019-05-19T06:07:17.392Z,1558246037.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2019-05-19T06:07:17.469Z,1558246037.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2019-05-19T06:07:17.635Z,1558246037.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2019-05-19T06:07:17.770Z,1558246037.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2019-05-19T06:07:17.887Z,1558246037.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2019-05-19T06:07:18.112Z,1558246038.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-05-19T06:07:18.113Z,1558246038.113 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2019-05-19T06:07:18.116Z,1558246038.116 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-05-19T06:07:18.202Z,1558246038.202 [VerticalControl](DEBUG): Construct VerticalControl. 2019-05-19T06:07:18.312Z,1558246038.312 [VerticalControl] Loaded 2019-05-19T06:07:18.313Z,1558246038.313 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-05-19T06:07:18.313Z,1558246038.313 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-05-19T06:07:18.381Z,1558246038.381 [HorizontalControl] Loaded 2019-05-19T06:07:18.381Z,1558246038.381 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-05-19T06:07:18.382Z,1558246038.382 [SpeedControl](DEBUG): Construct SpeedControl. 2019-05-19T06:07:18.387Z,1558246038.387 [SpeedControl] Loaded 2019-05-19T06:07:18.388Z,1558246038.388 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-05-19T06:07:18.388Z,1558246038.388 [LoopControl](DEBUG): Construct LoopControl. 2019-05-19T06:07:18.389Z,1558246038.389 [LoopControl] Loaded 2019-05-19T06:07:18.389Z,1558246038.389 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-05-19T06:07:18.389Z,1558246038.389 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-05-19T06:07:18.391Z,1558246038.391 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-05-19T06:07:18.415Z,1558246038.415 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-05-19T06:07:18.415Z,1558246038.415 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-05-19T06:07:18.538Z,1558246038.538 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-05-19T06:07:18.539Z,1558246038.539 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-05-19T06:07:18.687Z,1558246038.687 [BuoyancyServo] Loaded 2019-05-19T06:07:18.687Z,1558246038.687 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-05-19T06:07:18.698Z,1558246038.698 [ElevatorServo] Loaded 2019-05-19T06:07:18.698Z,1558246038.698 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-05-19T06:07:18.709Z,1558246038.709 [MassServo] Loaded 2019-05-19T06:07:18.709Z,1558246038.709 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-05-19T06:07:18.720Z,1558246038.720 [RudderServo] Loaded 2019-05-19T06:07:18.721Z,1558246038.721 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-05-19T06:07:18.732Z,1558246038.732 [ThrusterServo] Loaded 2019-05-19T06:07:18.732Z,1558246038.732 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-05-19T06:07:18.732Z,1558246038.732 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-05-19T06:07:18.733Z,1558246038.733 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-05-19T06:07:18.854Z,1558246038.854 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-05-19T06:07:18.855Z,1558246038.855 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-05-19T06:07:18.875Z,1558246038.875 [NavChart] Loaded 2019-05-19T06:07:18.875Z,1558246038.875 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-05-19T06:07:18.879Z,1558246038.879 [UniversalFixResidualReporter] Loaded 2019-05-19T06:07:18.880Z,1558246038.880 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-05-19T06:07:18.880Z,1558246038.880 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-05-19T06:07:18.882Z,1558246038.882 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-05-19T06:07:18.992Z,1558246038.992 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-05-19T06:07:18.993Z,1558246038.993 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-05-19T06:07:19.662Z,1558246039.662 [AHRS_M2] Loaded 2019-05-19T06:07:19.663Z,1558246039.663 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-05-19T06:07:19.806Z,1558246039.806 [DataOverHttps] Loaded 2019-05-19T06:07:19.806Z,1558246039.806 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-05-19T06:07:19.807Z,1558246039.807 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087B4E0 2019-05-19T06:07:19.808Z,1558246039.808 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 894 2019-05-19T06:07:19.821Z,1558246039.821 [Depth_Keller] Loaded 2019-05-19T06:07:19.821Z,1558246039.821 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-05-19T06:07:19.826Z,1558246039.826 [DropWeight] Loaded 2019-05-19T06:07:19.826Z,1558246039.826 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-05-19T06:07:19.883Z,1558246039.883 [DUSBL_Hydroid] Loaded 2019-05-19T06:07:19.883Z,1558246039.883 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2019-05-19T06:07:20.009Z,1558246040.009 [DVL_micro] Loaded 2019-05-19T06:07:20.009Z,1558246040.009 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2019-05-19T06:07:20.104Z,1558246040.104 [NAL9602] Loaded 2019-05-19T06:07:20.104Z,1558246040.104 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-05-19T06:07:20.120Z,1558246040.120 [Onboard] Loaded 2019-05-19T06:07:20.120Z,1558246040.120 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-05-19T06:07:20.126Z,1558246040.126 [PowerOnly] Loaded 2019-05-19T06:07:20.126Z,1558246040.126 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2019-05-19T06:07:20.129Z,1558246040.129 [Radio_Surface] Loaded 2019-05-19T06:07:20.130Z,1558246040.130 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-05-19T06:07:20.131Z,1558246040.131 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AB4E0 2019-05-19T06:07:20.131Z,1558246040.131 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 895 2019-05-19T06:07:20.172Z,1558246040.172 [RDI_Pathfinder] Loaded 2019-05-19T06:07:20.173Z,1558246040.173 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-05-19T06:07:20.301Z,1558246040.301 [DAT] Loaded 2019-05-19T06:07:20.301Z,1558246040.301 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-05-19T06:07:22.100Z,1558246042.100 [BPC1] Loaded 2019-05-19T06:07:22.100Z,1558246042.100 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-05-19T06:07:22.101Z,1558246042.101 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-05-19T06:07:22.101Z,1558246042.101 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-05-19T06:07:22.155Z,1558246042.155 [DepthRateCalculator] Loaded 2019-05-19T06:07:22.155Z,1558246042.155 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-05-19T06:07:22.160Z,1558246042.160 [PitchRateCalculator] Loaded 2019-05-19T06:07:22.161Z,1558246042.161 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-05-19T06:07:22.172Z,1558246042.172 [SpeedCalculator] Loaded 2019-05-19T06:07:22.172Z,1558246042.172 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-05-19T06:07:22.194Z,1558246042.194 [TempGradientCalculator] Loaded 2019-05-19T06:07:22.194Z,1558246042.194 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-05-19T06:07:22.199Z,1558246042.199 [YawRateCalculator] Loaded 2019-05-19T06:07:22.199Z,1558246042.199 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-05-19T06:07:22.228Z,1558246042.228 [ElevatorOffsetCalculator] Loaded 2019-05-19T06:07:22.229Z,1558246042.229 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-05-19T06:07:22.229Z,1558246042.229 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-05-19T06:07:22.230Z,1558246042.230 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-05-19T06:07:22.480Z,1558246042.480 [Aanderaa_O2] Loaded 2019-05-19T06:07:22.480Z,1558246042.480 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-05-19T06:07:22.564Z,1558246042.564 [CTD_NeilBrown] Loaded 2019-05-19T06:07:22.564Z,1558246042.564 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-05-19T06:07:22.566Z,1558246042.566 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409AD4E0 2019-05-19T06:07:22.566Z,1558246042.566 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 896 2019-05-19T06:07:22.575Z,1558246042.575 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-05-19T06:07:22.580Z,1558246042.580 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-05-19T06:07:22.582Z,1558246042.582 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-05-19T06:07:22.587Z,1558246042.587 [CTD_Seabird](INFO): created writer for : depth 2019-05-19T06:07:22.587Z,1558246042.587 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-05-19T06:07:22.592Z,1558246042.592 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-05-19T06:07:22.593Z,1558246042.593 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-05-19T06:07:22.598Z,1558246042.598 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-05-19T06:07:22.598Z,1558246042.598 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-05-19T06:07:22.603Z,1558246042.603 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-05-19T06:07:22.604Z,1558246042.604 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-05-19T06:07:22.609Z,1558246042.609 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-05-19T06:07:22.609Z,1558246042.609 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-05-19T06:07:22.614Z,1558246042.614 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-05-19T06:07:22.641Z,1558246042.641 [CTD_Seabird] Loaded 2019-05-19T06:07:22.641Z,1558246042.641 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-05-19T06:07:22.642Z,1558246042.642 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0 2019-05-19T06:07:22.643Z,1558246042.643 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 897 2019-05-19T06:07:22.657Z,1558246042.657 [PAR_Licor] Loaded 2019-05-19T06:07:22.657Z,1558246042.657 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-05-19T06:07:22.704Z,1558246042.704 [WetLabsBB2FL] Loaded 2019-05-19T06:07:22.704Z,1558246042.704 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-05-19T06:07:22.705Z,1558246042.705 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0 2019-05-19T06:07:22.706Z,1558246042.706 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 898 2019-05-19T06:07:22.706Z,1558246042.706 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-05-19T06:07:22.707Z,1558246042.707 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-05-19T06:07:22.747Z,1558246042.747 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-05-19T06:07:22.748Z,1558246042.748 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-05-19T06:07:23.075Z,1558246043.075 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-05-19T06:07:23.077Z,1558246043.077 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-05-19T06:07:23.231Z,1558246043.231 [SBIT](DEBUG): Construct Startup Built In Test. 2019-05-19T06:07:23.242Z,1558246043.242 [SBIT] Loaded 2019-05-19T06:07:23.243Z,1558246043.243 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-05-19T06:07:23.243Z,1558246043.243 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-05-19T06:07:23.255Z,1558246043.255 [IBIT] Loaded 2019-05-19T06:07:23.256Z,1558246043.256 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-05-19T06:07:23.259Z,1558246043.259 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-05-19T06:07:23.398Z,1558246043.398 [CBIT] Loaded 2019-05-19T06:07:23.398Z,1558246043.398 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-05-19T06:07:23.398Z,1558246043.398 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-05-19T06:07:23.402Z,1558246043.402 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-05-19T06:07:23.403Z,1558246043.403 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-05-19T06:07:23.410Z,1558246043.410 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-05-19T06:07:23.411Z,1558246043.411 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0 2019-05-19T06:07:23.411Z,1558246043.411 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 899 2019-05-19T06:07:23.416Z,1558246043.416 [Supervisor](INFO): Main Thread ID is 802 2019-05-19T06:07:23.416Z,1558246043.416 [Supervisor](DEBUG): Running supervisor. 2019-05-19T06:07:23.416Z,1558246043.416 [CommandLine ThreadHandler](INFO): Handler Thread ID is 900 2019-05-19T06:07:23.419Z,1558246043.419 [controlThread ThreadHandler](INFO): Handler Thread ID is 901 2019-05-19T06:07:23.419Z,1558246043.419 [controlThread](DEBUG): Initializing ControlThread 2019-05-19T06:07:23.420Z,1558246043.420 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-05-19T06:07:23.422Z,1558246043.422 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-05-19T06:07:23.422Z,1558246043.422 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-05-19T06:07:23.423Z,1558246043.423 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-05-19T06:07:23.425Z,1558246043.425 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-05-19T06:07:23.425Z,1558246043.425 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-05-19T06:07:23.432Z,1558246043.432 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-05-19T06:07:23.432Z,1558246043.432 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-05-19T06:07:23.432Z,1558246043.432 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-05-19T06:07:23.433Z,1558246043.433 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-05-19T06:07:23.433Z,1558246043.433 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-05-19T06:07:23.434Z,1558246043.434 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-05-19T06:07:23.437Z,1558246043.437 [SBIT](INFO): Initialize SBIT Component. 2019-05-19T06:07:23.438Z,1558246043.438 [SBIT](IMPORTANT): git: 2019-05-01-10-gcab12ce 2019-05-19T06:07:23.438Z,1558246043.438 [SBIT](INFO): git hash: cab12ce958be6da2bb158d8a74bde728d70637cd 2019-05-19T06:07:23.438Z,1558246043.438 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-05-19T06:07:23.439Z,1558246043.439 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 2019-05-19T06:07:23.440Z,1558246043.440 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2019-05-19T06:07:23.441Z,1558246043.441 [logger ThreadHandler](INFO): Handler Thread ID is 902 2019-05-19T06:07:23.457Z,1558246043.457 [IBIT](INFO): Initialize IBIT Component. 2019-05-19T06:07:23.458Z,1558246043.458 [CBIT](DEBUG): Initialize CBIT Component. 2019-05-19T06:07:23.465Z,1558246043.465 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 903 2019-05-19T06:07:23.466Z,1558246043.466 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-05-19T06:07:23.469Z,1558246043.469 [CBIT](DEBUG): Initialized mux pins. 2019-05-19T06:07:23.469Z,1558246043.469 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2019-05-19T06:07:23.469Z,1558246043.469 [CBIT](DEBUG): Initializing the watchdog timer. 2019-05-19T06:07:23.477Z,1558246043.477 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 904 2019-05-19T06:07:23.489Z,1558246043.489 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 905 2019-05-19T06:07:23.490Z,1558246043.490 [CTD_NeilBrown](INFO): Powering down 2019-05-19T06:07:23.497Z,1558246043.497 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-05-19T06:07:23.497Z,1558246043.497 [CBIT](DEBUG): Initializing heartbeat. 2019-05-19T06:07:23.517Z,1558246043.517 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 906 2019-05-19T06:07:23.518Z,1558246043.518 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-05-19T06:07:23.523Z,1558246043.523 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 908 2019-05-19T06:07:23.524Z,1558246043.524 [WetLabsBB2FL](INFO): Powering down 2019-05-19T06:07:23.542Z,1558246043.542 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-05-19T06:07:23.549Z,1558246043.549 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 909 2019-05-19T06:07:23.559Z,1558246043.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-05-19T06:07:23.559Z,1558246043.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-05-19T06:07:23.559Z,1558246043.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-05-19T06:07:23.559Z,1558246043.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-05-19T06:07:23.559Z,1558246043.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-05-19T06:07:23.559Z,1558246043.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-05-19T06:07:23.560Z,1558246043.560 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-05-19T06:07:23.560Z,1558246043.560 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-05-19T06:07:23.560Z,1558246043.560 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-05-19T06:07:23.560Z,1558246043.560 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-05-19T06:07:23.561Z,1558246043.561 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-05-19T06:07:23.561Z,1558246043.561 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-05-19T06:07:23.561Z,1558246043.561 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-05-19T06:07:23.561Z,1558246043.561 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-05-19T06:07:23.562Z,1558246043.562 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-05-19T06:07:23.562Z,1558246043.562 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-05-19T06:07:23.569Z,1558246043.569 [CBIT](DEBUG): Deactivating GF circuits. 2019-05-19T06:07:23.569Z,1558246043.569 [CBIT](DEBUG): Deactivating emergency mode. 2019-05-19T06:07:23.605Z,1558246043.605 [CBIT](DEBUG): Backplane powered. 2019-05-19T06:07:23.606Z,1558246043.606 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-05-19T06:07:23.634Z,1558246043.634 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-19T06:07:23.653Z,1558246043.653 [MissionManager](DEBUG): 2019-05-19T06:07:23.654Z,1558246043.654 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-05-19T06:07:23.733Z,1558246043.733 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-05-19T06:07:23.734Z,1558246043.734 [Default:A.Wait](DEBUG): Construct Wait. 2019-05-19T06:07:23.736Z,1558246043.736 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-19T06:07:23.764Z,1558246043.764 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-05-19T06:07:23.782Z,1558246043.782 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-05-19T06:07:23.788Z,1558246043.788 [Default:E.Execute](DEBUG): Construct Execute. 2019-05-19T06:07:23.810Z,1558246043.810 [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-05-19T06:07:23.829Z,1558246043.829 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,DVL_micro,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,DAT,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-05-19T06:07:23.835Z,1558246043.835 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-05-19T06:07:23.881Z,1558246043.881 [Radio_Surface](INFO): Powering up 2019-05-19T06:07:23.962Z,1558246043.962 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2019-05-19T06:07:23.989Z,1558246043.989 [DVL_micro](INFO): Initializing 2019-05-19T06:07:24.015Z,1558246044.015 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-05-19T06:07:24.015Z,1558246044.015 [DAT](INFO): Powering up 2019-05-19T06:07:24.015Z,1558246044.015 [DAT](DEBUG): Initializing DAT. 2019-05-19T06:07:24.017Z,1558246044.017 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-05-19T06:07:24.083Z,1558246044.083 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-05-19T06:07:24.149Z,1558246044.149 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-05-19T06:07:24.157Z,1558246044.157 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-05-19T06:07:24.158Z,1558246044.158 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-05-19T06:07:24.165Z,1558246044.165 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-05-19T06:07:24.166Z,1558246044.166 [MassServo](DEBUG): Initializing EZServoServo. 2019-05-19T06:07:24.173Z,1558246044.173 [MassServo](DEBUG): Initializing MassServo. 2019-05-19T06:07:24.174Z,1558246044.174 [RudderServo](DEBUG): Initializing EZServoServo. 2019-05-19T06:07:24.181Z,1558246044.181 [RudderServo](DEBUG): Initializing RudderServo. 2019-05-19T06:07:24.182Z,1558246044.182 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-05-19T06:07:24.189Z,1558246044.189 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-05-19T06:07:24.371Z,1558246044.371 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-05-19T06:07:24.371Z,1558246044.371 [DropWeight] Hardware Fault, FailCount= 1 2019-05-19T06:07:24.371Z,1558246044.371 [DropWeight](ERROR): Hardware Fault 2019-05-19T06:07:24.470Z,1558246044.470 [CommandLine](FAULT): Scheduling is paused 2019-05-19T06:07:24.470Z,1558246044.470 [CBIT](INFO): Critical error at 20190519T060724 2019-05-19T06:07:24.471Z,1558246044.471 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-05-19T06:07:24.474Z,1558246044.474 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-05-19T06:07:24.474Z,1558246044.474 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-05-19T06:07:25.005Z,1558246045.005 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-05-19T06:07:25.005Z,1558246045.005 [RudderServo](FAULT): Rudder failed to initialize 2019-05-19T06:07:25.005Z,1558246045.005 [RudderServo] Communications Fault, FailCount= 1 2019-05-19T06:07:25.005Z,1558246045.005 [RudderServo](ERROR): Communications Fault 2019-05-19T06:07:25.210Z,1558246045.210 [CBIT](INFO): Critical error at 20190519T060724 2019-05-19T06:07:25.213Z,1558246045.213 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-05-19T06:07:25.257Z,1558246045.257 [RDI_Pathfinder](INFO): Powering down 2019-05-19T06:07:25.402Z,1558246045.402 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-05-19T06:07:25.402Z,1558246045.402 [RudderServo](INFO): Powering down 2019-05-19T06:07:26.096Z,1558246046.096 [RudderServo](DEBUG): Initializing EZServoServo. 2019-05-19T06:07:26.214Z,1558246046.214 [RudderServo](DEBUG): Initializing RudderServo. 2019-05-19T06:07:26.218Z,1558246046.218 [CBIT](INFO): Clearing failed state for component RudderServo 2019-05-19T06:07:26.218Z,1558246046.218 [RudderServo] No Fault, FailCount= 1 2019-05-19T06:07:33.798Z,1558246053.798 [CBIT](CRITICAL): Environmental Failure. Press:14.622488 PSI. Humidity:32%. Temp:26 C. ABORTING MISSION 2019-05-19T06:07:34.170Z,1558246054.170 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-05-19T06:07:34.170Z,1558246054.170 [Aanderaa_O2] Communications Fault, FailCount= 1 2019-05-19T06:07:34.170Z,1558246054.170 [Aanderaa_O2](ERROR): Communications Fault 2019-05-19T06:07:34.188Z,1558246054.188 [CBIT](INFO): Critical error at 20190519T060733 2019-05-19T06:07:34.190Z,1558246054.190 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-05-19T06:07:34.558Z,1558246054.558 [DVL_micro](ERROR): No DVL communication! Re-initializing 2019-05-19T06:07:34.558Z,1558246054.558 [DVL_micro] Communications Fault, FailCount= 1 2019-05-19T06:07:34.558Z,1558246054.558 [DVL_micro](ERROR): Communications Fault 2019-05-19T06:07:34.693Z,1558246054.693 [Aanderaa_O2](INFO): Powering down 2019-05-19T06:07:34.806Z,1558246054.806 [CBIT](ERROR): Communications Fault in component: DVL_micro 2019-05-19T06:07:35.021Z,1558246055.021 [DVL_micro](INFO): uninitialize:Powering down 2019-05-19T06:07:35.441Z,1558246055.441 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-05-19T06:07:35.441Z,1558246055.441 [Aanderaa_O2] No Fault, FailCount= 1 2019-05-19T06:07:35.822Z,1558246055.822 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-19T06:07:35.822Z,1558246055.822 [DVL_micro] No Fault, FailCount= 1 2019-05-19T06:07:36.169Z,1558246056.169 [DVL_micro](INFO): Initializing 2019-05-19T06:07:37.389Z,1558246057.389 [RDI_Pathfinder](INFO): Powering down 2019-05-19T06:07:37.390Z,1558246057.390 [DAT](INFO): setting local address to 2 2019-05-19T06:07:37.573Z,1558246057.573 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-05-19T06:07:37.573Z,1558246057.573 [CTD_Seabird] Communications Fault, FailCount= 1 2019-05-19T06:07:37.573Z,1558246057.573 [CTD_Seabird](ERROR): Communications Fault 2019-05-19T06:07:37.793Z,1558246057.793 [DAT](INFO): set local address to 2 2019-05-19T06:07:37.853Z,1558246057.853 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-05-19T06:07:37.977Z,1558246057.977 [CTD_Seabird](INFO): Powering down 2019-05-19T06:07:39.046Z,1558246059.046 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-05-19T06:07:39.046Z,1558246059.046 [CTD_Seabird] No Fault, FailCount= 1 2019-05-19T06:07:39.341Z,1558246059.341 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-05-19T06:07:39.343Z,1558246059.343 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-05-19T06:07:42.217Z,1558246062.217 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2019-05-19T06:07:42.217Z,1558246062.217 [AHRS_M2] Data Fault, FailCount= 1 2019-05-19T06:07:42.217Z,1558246062.217 [AHRS_M2](ERROR): Data Fault 2019-05-19T06:07:42.287Z,1558246062.287 [CBIT](ERROR): Data Fault in component: AHRS_M2 2019-05-19T06:07:42.609Z,1558246062.609 [AHRS_M2](INFO): Powering down 2019-05-19T06:07:43.483Z,1558246063.483 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-05-19T06:07:43.484Z,1558246063.484 [AHRS_M2] No Fault, FailCount= 1 2019-05-19T06:07:43.821Z,1558246063.821 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-05-19T06:07:45.863Z,1558246065.863 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-05-19T06:07:45.863Z,1558246065.863 [Aanderaa_O2] Communications Fault, FailCount= 2 2019-05-19T06:07:45.863Z,1558246065.863 [Aanderaa_O2](ERROR): Communications Fault 2019-05-19T06:07:45.907Z,1558246065.907 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-05-19T06:07:46.389Z,1558246066.389 [Aanderaa_O2](INFO): Powering down 2019-05-19T06:07:46.654Z,1558246066.654 [DVL_micro](ERROR): No DVL communication! Re-initializing 2019-05-19T06:07:46.654Z,1558246066.654 [DVL_micro] Communications Fault, FailCount= 2 2019-05-19T06:07:46.654Z,1558246066.654 [DVL_micro](ERROR): Communications Fault 2019-05-19T06:07:46.731Z,1558246066.731 [CBIT](ERROR): Communications Fault in component: DVL_micro 2019-05-19T06:07:47.153Z,1558246067.153 [DVL_micro](INFO): uninitialize:Powering down 2019-05-19T06:07:47.198Z,1558246067.198 [SBIT](IMPORTANT): Beginning Startup BIT 2019-05-19T06:07:47.203Z,1558246067.203 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-05-19T06:07:47.203Z,1558246067.203 [Aanderaa_O2] No Fault, FailCount= 2 2019-05-19T06:07:47.203Z,1558246067.203 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-19T06:07:47.982Z,1558246067.982 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-19T06:07:47.983Z,1558246067.983 [DVL_micro] No Fault, FailCount= 2 2019-05-19T06:07:48.288Z,1558246068.288 [DVL_micro](INFO): Initializing 2019-05-19T06:07:49.622Z,1558246069.622 [RDI_Pathfinder](INFO): Powering down 2019-05-19T06:07:50.471Z,1558246070.471 [NAL9602](INFO): Powering up NAL9602 2019-05-19T06:07:52.393Z,1558246072.393 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-05-19T06:07:52.393Z,1558246072.393 [CTD_Seabird] Communications Fault, FailCount= 2 2019-05-19T06:07:52.393Z,1558246072.393 [CTD_Seabird](ERROR): Communications Fault 2019-05-19T06:07:52.606Z,1558246072.606 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-05-19T06:07:52.797Z,1558246072.797 [CTD_Seabird](INFO): Powering down 2019-05-19T06:07:54.122Z,1558246074.122 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-05-19T06:07:54.122Z,1558246074.122 [CTD_Seabird] No Fault, FailCount= 2 2019-05-19T06:07:54.137Z,1558246074.137 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-05-19T06:07:54.138Z,1558246074.138 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-05-19T06:07:57.261Z,1558246077.261 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2019-05-19T06:07:57.261Z,1558246077.261 [AHRS_M2] Data Fault, FailCount= 2 2019-05-19T06:07:57.261Z,1558246077.261 [AHRS_M2](ERROR): Data Fault 2019-05-19T06:07:57.336Z,1558246077.336 [CBIT](ERROR): Data Fault in component: AHRS_M2 2019-05-19T06:07:57.653Z,1558246077.653 [AHRS_M2](INFO): Powering down 2019-05-19T06:07:57.762Z,1558246077.762 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-05-19T06:07:57.762Z,1558246077.762 [Aanderaa_O2] Communications Fault, FailCount= 3 2019-05-19T06:07:57.762Z,1558246077.762 [Aanderaa_O2](ERROR): Communications Fault 2019-05-19T06:07:57.796Z,1558246077.796 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-05-19T06:07:57.796Z,1558246077.796 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2019-05-19T06:07:58.197Z,1558246078.197 [Aanderaa_O2](INFO): Powering down 2019-05-19T06:07:58.247Z,1558246078.247 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.014174 CHAN A1 (24V): 0.048325 CHAN A2 (12V): -0.001886 CHAN A3 (5V): -0.001765 CHAN B0 (3.3V): 0.000013 CHAN B1 (3.15aV): -0.000125 CHAN B2 (3.15bV): -0.000413 CHAN B3 (GND): -0.000407 OPEN: 0.005491 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-19T06:07:58.519Z,1558246078.519 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-05-19T06:07:58.519Z,1558246078.519 [AHRS_M2] No Fault, FailCount= 2 2019-05-19T06:07:58.865Z,1558246078.865 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-05-19T06:07:58.878Z,1558246078.878 [DVL_micro](ERROR): No DVL communication! Re-initializing 2019-05-19T06:07:58.878Z,1558246078.878 [DVL_micro] Communications Fault, FailCount= 3 2019-05-19T06:07:58.878Z,1558246078.878 [DVL_micro](ERROR): Communications Fault 2019-05-19T06:07:58.942Z,1558246078.942 [CBIT](ERROR): Communications Fault in component: DVL_micro 2019-05-19T06:07:59.345Z,1558246079.345 [DVL_micro](INFO): uninitialize:Powering down 2019-05-19T06:08:00.150Z,1558246080.150 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-19T06:08:00.150Z,1558246080.150 [DVL_micro] No Fault, FailCount= 3 2019-05-19T06:08:00.489Z,1558246080.489 [DVL_micro](INFO): Initializing 2019-05-19T06:08:01.294Z,1558246081.294 [NAL9602](INFO): NAL9602 initialized 2019-05-19T06:08:01.723Z,1558246081.723 [RDI_Pathfinder](INFO): Powering down 2019-05-19T06:08:02.413Z,1558246082.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:02.433Z,1558246082.433 [NAL9602](DEBUG): Fix Requested 2019-05-19T06:08:02.869Z,1558246082.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:03.789Z,1558246083.789 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:04.333Z,1558246084.333 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:04.797Z,1558246084.797 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:05.281Z,1558246085.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:05.765Z,1558246085.765 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:06.253Z,1558246086.253 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:06.737Z,1558246086.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:07.189Z,1558246087.189 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-05-19T06:08:07.189Z,1558246087.189 [CTD_Seabird] Communications Fault, FailCount= 3 2019-05-19T06:08:07.189Z,1558246087.189 [CTD_Seabird](ERROR): Communications Fault 2019-05-19T06:08:07.229Z,1558246087.229 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:07.378Z,1558246087.378 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-05-19T06:08:07.379Z,1558246087.379 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2019-05-19T06:08:07.593Z,1558246087.593 [CTD_Seabird](INFO): Powering down 2019-05-19T06:08:07.701Z,1558246087.701 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:08.181Z,1558246088.181 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:08.677Z,1558246088.677 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:09.257Z,1558246089.257 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:09.813Z,1558246089.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:10.261Z,1558246090.261 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:10.693Z,1558246090.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:11.161Z,1558246091.161 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:11.166Z,1558246091.166 [DVL_micro](ERROR): No DVL communication! Re-initializing 2019-05-19T06:08:11.166Z,1558246091.166 [DVL_micro] Communications Fault, FailCount= 4 2019-05-19T06:08:11.166Z,1558246091.166 [DVL_micro](ERROR): Communications Fault 2019-05-19T06:08:11.267Z,1558246091.267 [CBIT](ERROR): Communications Fault in component: DVL_micro 2019-05-19T06:08:11.605Z,1558246091.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:11.681Z,1558246091.681 [DVL_micro](INFO): uninitialize:Powering down 2019-05-19T06:08:12.133Z,1558246092.133 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:12.133Z,1558246092.133 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-05-19T06:08:12.133Z,1558246092.133 [AHRS_M2] Communications Fault, FailCount= 3 2019-05-19T06:08:12.133Z,1558246092.133 [AHRS_M2](ERROR): Communications Fault 2019-05-19T06:08:12.274Z,1558246092.274 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-05-19T06:08:12.280Z,1558246092.280 [AHRS_M2](INFO): Powering down 2019-05-19T06:08:12.447Z,1558246092.447 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-19T06:08:12.447Z,1558246092.447 [DVL_micro] No Fault, FailCount= 4 2019-05-19T06:08:12.692Z,1558246092.692 [DVL_micro](INFO): Initializing 2019-05-19T06:08:13.186Z,1558246093.186 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-05-19T06:08:13.186Z,1558246093.186 [AHRS_M2] No Fault, FailCount= 3 2019-05-19T06:08:13.489Z,1558246093.489 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-05-19T06:08:13.910Z,1558246093.910 [RDI_Pathfinder](INFO): Powering down 2019-05-19T06:08:17.045Z,1558246097.045 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:17.477Z,1558246097.477 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:17.885Z,1558246097.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:18.301Z,1558246098.301 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:18.717Z,1558246098.717 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:19.129Z,1558246099.129 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:19.545Z,1558246099.545 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:20.037Z,1558246100.037 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:20.449Z,1558246100.449 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:20.853Z,1558246100.853 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:21.265Z,1558246101.265 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:21.685Z,1558246101.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:22.109Z,1558246102.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:22.521Z,1558246102.521 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:22.937Z,1558246102.937 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:23.329Z,1558246103.329 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:23.338Z,1558246103.338 [DVL_micro](ERROR): No DVL communication! Re-initializing 2019-05-19T06:08:23.338Z,1558246103.338 [DVL_micro] Communications Fault, FailCount= 5 2019-05-19T06:08:23.339Z,1558246103.339 [DVL_micro](ERROR): Communications Fault 2019-05-19T06:08:23.372Z,1558246103.372 [CBIT](ERROR): Communications Fault in component: DVL_micro 2019-05-19T06:08:23.372Z,1558246103.372 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2019-05-19T06:08:23.725Z,1558246103.725 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:23.813Z,1558246103.813 [DVL_micro](INFO): uninitialize:Powering down 2019-05-19T06:08:23.860Z,1558246103.860 [CBIT](INFO): Critical error at 20190519T060823 2019-05-19T06:08:24.193Z,1558246104.193 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:24.601Z,1558246104.601 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:25.021Z,1558246105.021 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:25.433Z,1558246105.433 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:25.849Z,1558246105.849 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:26.265Z,1558246106.265 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:26.673Z,1558246106.673 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-05-19T06:08:26.673Z,1558246106.673 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-05-19T06:08:26.673Z,1558246106.673 [AHRS_M2] Communications Fault, FailCount= 4 2019-05-19T06:08:26.673Z,1558246106.673 [AHRS_M2](ERROR): Communications Fault 2019-05-19T06:08:26.735Z,1558246106.735 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-05-19T06:08:26.741Z,1558246106.741 [AHRS_M2](INFO): Powering down 2019-05-19T06:08:26.830Z,1558246106.830 [CommandLine](IMPORTANT): got command restart system 2019-05-19T06:08:27.604Z,1558246107.604 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-05-19T06:08:27.605Z,1558246107.605 [AHRS_M2] No Fault, FailCount= 4 2019-05-19T06:08:27.954Z,1558246107.954 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-05-19T06:08:29.081Z,1558246109.081 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:29.082Z,1558246109.082 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.273Z,1558246109.273 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-05-19T06:08:29.274Z,1558246109.274 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.276Z,1558246109.276 [CommandLine](INFO): Join timeout helper Thread ID is 948 2019-05-19T06:08:29.277Z,1558246109.277 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-05-19T06:08:29.277Z,1558246109.277 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.278Z,1558246109.278 [NavChartDb](INFO): Join timeout helper Thread ID is 949 2019-05-19T06:08:29.329Z,1558246109.329 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:29.329Z,1558246109.329 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.337Z,1558246109.337 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-05-19T06:08:29.337Z,1558246109.337 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.337Z,1558246109.337 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 950 2019-05-19T06:08:29.569Z,1558246109.569 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:29.570Z,1558246109.570 [WetLabsBB2FL](INFO): Powering down 2019-05-19T06:08:29.570Z,1558246109.570 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.586Z,1558246109.586 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-05-19T06:08:29.586Z,1558246109.586 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.586Z,1558246109.586 [CTD_Seabird](INFO): Join timeout helper Thread ID is 951 2019-05-19T06:08:29.953Z,1558246109.953 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:29.953Z,1558246109.953 [CTD_Seabird](INFO): Powering down 2019-05-19T06:08:29.965Z,1558246109.965 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.965Z,1558246109.965 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-05-19T06:08:29.965Z,1558246109.965 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:29.966Z,1558246109.966 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 952 2019-05-19T06:08:30.045Z,1558246110.045 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:30.045Z,1558246110.045 [CTD_NeilBrown](INFO): Powering down 2019-05-19T06:08:30.057Z,1558246110.057 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.070Z,1558246110.070 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-05-19T06:08:30.070Z,1558246110.070 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.071Z,1558246110.071 [Radio_Surface](INFO): Join timeout helper Thread ID is 953 2019-05-19T06:08:30.173Z,1558246110.173 [Radio_Surface](INFO): Powering down 2019-05-19T06:08:30.174Z,1558246110.174 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:30.174Z,1558246110.174 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.178Z,1558246110.178 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-05-19T06:08:30.178Z,1558246110.178 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.178Z,1558246110.178 [DataOverHttps](INFO): Join timeout helper Thread ID is 954 2019-05-19T06:08:30.575Z,1558246110.575 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:30.578Z,1558246110.578 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.579Z,1558246110.579 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-05-19T06:08:30.579Z,1558246110.579 [logger ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.580Z,1558246110.580 [logger](INFO): Join timeout helper Thread ID is 955 2019-05-19T06:08:30.602Z,1558246110.602 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:30.602Z,1558246110.602 [logger ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.617Z,1558246110.617 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-05-19T06:08:30.618Z,1558246110.618 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.618Z,1558246110.618 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-05-19T06:08:30.618Z,1558246110.618 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:30.618Z,1558246110.618 [controlThread](INFO): Join timeout helper Thread ID is 956 2019-05-19T06:08:30.861Z,1558246110.861 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-05-19T06:08:30.861Z,1558246110.861 [controlThread](DEBUG): Uninitializing ControlThread 2019-05-19T06:08:30.862Z,1558246110.862 [AHRS_M2](INFO): Powering down 2019-05-19T06:08:30.934Z,1558246110.934 [DVL_micro](INFO): uninitialize:Powering down 2019-05-19T06:08:30.935Z,1558246110.935 [NAL9602](INFO): Powering down 2019-05-19T06:08:30.936Z,1558246110.936 [RDI_Pathfinder](INFO): Powering down 2019-05-19T06:08:30.937Z,1558246110.937 [DAT](INFO): Powering down 2019-05-19T06:08:31.058Z,1558246111.058 [Aanderaa_O2](INFO): Powering down 2019-05-19T06:08:31.059Z,1558246111.059 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-05-19T06:08:31.060Z,1558246111.060 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-05-19T06:08:31.061Z,1558246111.061 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-05-19T06:08:31.061Z,1558246111.061 [MissionManager](INFO): Uninitializing Mission Default 2019-05-19T06:08:31.064Z,1558246111.064 [MissionManager](INFO): Uninitializing Mission Startup 2019-05-19T06:08:31.065Z,1558246111.065 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-05-19T06:08:31.066Z,1558246111.066 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-05-19T06:08:31.066Z,1558246111.066 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-05-19T06:08:31.066Z,1558246111.066 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-05-19T06:08:31.067Z,1558246111.067 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-05-19T06:08:31.067Z,1558246111.067 [BuoyancyServo](INFO): Powering down 2019-05-19T06:08:31.081Z,1558246111.081 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-05-19T06:08:31.081Z,1558246111.081 [ElevatorServo](INFO): Powering down 2019-05-19T06:08:31.082Z,1558246111.082 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-05-19T06:08:31.082Z,1558246111.082 [MassServo](INFO): Powering down 2019-05-19T06:08:31.083Z,1558246111.083 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-05-19T06:08:31.083Z,1558246111.083 [RudderServo](INFO): Powering down 2019-05-19T06:08:31.084Z,1558246111.084 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-05-19T06:08:31.084Z,1558246111.084 [ThrusterServo](INFO): Powering down 2019-05-19T06:08:31.085Z,1558246111.085 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-05-19T06:08:31.085Z,1558246111.085 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-05-19T06:08:31.086Z,1558246111.086 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-05-19T06:08:31.086Z,1558246111.086 [CBIT](DEBUG): Powering off loads. 2019-05-19T06:08:31.097Z,1558246111.097 [CBIT](DEBUG): Disabling WDT. 2019-05-19T06:08:31.109Z,1558246111.109 [CBIT](DEBUG): Opening all GF detection circuits. 2019-05-19T06:08:31.110Z,1558246111.110 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:31.554Z,1558246111.554 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:31.566Z,1558246111.566 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:31.596Z,1558246111.596 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:31.599Z,1558246111.599 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:31.603Z,1558246111.603 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:31.641Z,1558246111.641 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-05-19T06:08:31.703Z,1558246111.703 [logger ThreadHandler](INFO): Thread cancelled.