2019-04-24T22:51:25.495Z,1556146285.495 [Supervisor](DEBUG): Initializing supervisor. 2019-04-24T22:51:25.498Z,1556146285.498 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-04-24T22:51:25.498Z,1556146285.498 [SyncHandler](INFO): Protected caller Thread ID is 2762 2019-04-24T22:51:25.499Z,1556146285.499 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-04-24T22:51:25.500Z,1556146285.500 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-04-24T22:51:25.500Z,1556146285.500 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2763 2019-04-24T22:51:25.503Z,1556146285.503 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-04-24T22:51:25.515Z,1556146285.515 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-04-24T22:51:25.516Z,1556146285.516 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-04-24T22:51:25.516Z,1556146285.516 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2764 2019-04-24T22:51:25.517Z,1556146285.517 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-04-24T22:51:25.518Z,1556146285.518 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-04-24T22:51:25.519Z,1556146285.519 [logger ThreadHandler](INFO): Protected caller Thread ID is 2765 2019-04-24T22:51:25.521Z,1556146285.521 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-04-24T22:51:25.521Z,1556146285.521 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-04-24T22:51:25.523Z,1556146285.523 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-04-24T22:51:25.943Z,1556146285.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-04-24T22:51:25.943Z,1556146285.943 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-04-24T22:51:26.087Z,1556146286.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-04-24T22:51:26.088Z,1556146286.088 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-04-24T22:51:26.169Z,1556146286.169 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-04-24T22:51:26.267Z,1556146286.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-04-24T22:51:26.268Z,1556146286.268 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-04-24T22:51:26.363Z,1556146286.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-04-24T22:51:26.363Z,1556146286.363 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-04-24T22:51:26.747Z,1556146286.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-04-24T22:51:26.747Z,1556146286.747 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-04-24T22:51:26.850Z,1556146286.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-04-24T22:51:26.851Z,1556146286.851 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-04-24T22:51:27.308Z,1556146287.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-04-24T22:51:27.308Z,1556146287.308 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-04-24T22:51:27.532Z,1556146287.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-04-24T22:51:27.533Z,1556146287.533 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-04-24T22:51:27.886Z,1556146287.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-04-24T22:51:27.887Z,1556146287.887 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-04-24T22:51:28.098Z,1556146288.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-04-24T22:51:28.099Z,1556146288.099 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-04-24T22:51:28.241Z,1556146288.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-04-24T22:51:28.241Z,1556146288.241 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-04-24T22:51:28.768Z,1556146288.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-04-24T22:51:28.768Z,1556146288.768 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-04-24T22:51:29.100Z,1556146289.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-04-24T22:51:29.101Z,1556146289.101 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-04-24T22:51:29.306Z,1556146289.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-04-24T22:51:29.308Z,1556146289.308 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2019-04-24T22:51:29.309Z,1556146289.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2019-04-24T22:51:29.487Z,1556146289.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2019-04-24T22:51:29.570Z,1556146289.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2019-04-24T22:51:29.684Z,1556146289.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2019-04-24T22:51:29.770Z,1556146289.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2019-04-24T22:51:29.997Z,1556146289.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-04-24T22:51:29.997Z,1556146289.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2019-04-24T22:51:30.093Z,1556146290.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2019-04-24T22:51:30.202Z,1556146290.202 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2019-04-24T22:51:30.326Z,1556146290.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2019-04-24T22:51:30.503Z,1556146290.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2019-04-24T22:51:30.694Z,1556146290.694 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2019-04-24T22:51:30.945Z,1556146290.945 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2019-04-24T22:51:30.946Z,1556146290.946 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-04-24T22:51:31.250Z,1556146291.250 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-04-24T22:51:31.264Z,1556146291.264 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-04-24T22:51:31.265Z,1556146291.265 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-04-24T22:51:31.331Z,1556146291.331 [VerticalControl](DEBUG): Construct VerticalControl. 2019-04-24T22:51:31.448Z,1556146291.448 [VerticalControl] Loaded 2019-04-24T22:51:31.448Z,1556146291.448 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-04-24T22:51:31.449Z,1556146291.449 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-04-24T22:51:31.520Z,1556146291.520 [HorizontalControl] Loaded 2019-04-24T22:51:31.520Z,1556146291.520 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-04-24T22:51:31.521Z,1556146291.521 [SpeedControl](DEBUG): Construct SpeedControl. 2019-04-24T22:51:31.526Z,1556146291.526 [SpeedControl] Loaded 2019-04-24T22:51:31.527Z,1556146291.527 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-04-24T22:51:31.527Z,1556146291.527 [LoopControl](DEBUG): Construct LoopControl. 2019-04-24T22:51:31.528Z,1556146291.528 [LoopControl] Loaded 2019-04-24T22:51:31.528Z,1556146291.528 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-04-24T22:51:31.529Z,1556146291.529 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-04-24T22:51:31.529Z,1556146291.529 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-04-24T22:51:31.628Z,1556146291.628 [BuoyancyServo] Loaded 2019-04-24T22:51:31.628Z,1556146291.628 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-04-24T22:51:31.639Z,1556146291.639 [ElevatorServo] Loaded 2019-04-24T22:51:31.639Z,1556146291.639 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-04-24T22:51:31.650Z,1556146291.650 [MassServo] Loaded 2019-04-24T22:51:31.651Z,1556146291.651 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-04-24T22:51:31.662Z,1556146291.662 [RudderServo] Loaded 2019-04-24T22:51:31.662Z,1556146291.662 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-04-24T22:51:31.673Z,1556146291.673 [ThrusterServo] Loaded 2019-04-24T22:51:31.673Z,1556146291.673 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-04-24T22:51:31.674Z,1556146291.674 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-04-24T22:51:31.674Z,1556146291.674 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-04-24T22:51:31.715Z,1556146291.715 [DepthRateCalculator] Loaded 2019-04-24T22:51:31.715Z,1556146291.715 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-04-24T22:51:31.720Z,1556146291.720 [PitchRateCalculator] Loaded 2019-04-24T22:51:31.720Z,1556146291.720 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-04-24T22:51:31.736Z,1556146291.736 [SpeedCalculator] Loaded 2019-04-24T22:51:31.736Z,1556146291.736 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-04-24T22:51:31.758Z,1556146291.758 [TempGradientCalculator] Loaded 2019-04-24T22:51:31.758Z,1556146291.758 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-04-24T22:51:31.763Z,1556146291.763 [YawRateCalculator] Loaded 2019-04-24T22:51:31.764Z,1556146291.764 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-04-24T22:51:31.794Z,1556146291.794 [ElevatorOffsetCalculator] Loaded 2019-04-24T22:51:31.794Z,1556146291.794 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-04-24T22:51:31.794Z,1556146291.794 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-04-24T22:51:31.795Z,1556146291.795 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-04-24T22:51:31.901Z,1556146291.901 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-04-24T22:51:31.902Z,1556146291.902 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-04-24T22:51:31.923Z,1556146291.923 [NavChart] Loaded 2019-04-24T22:51:31.923Z,1556146291.923 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-04-24T22:51:31.927Z,1556146291.927 [UniversalFixResidualReporter] Loaded 2019-04-24T22:51:31.927Z,1556146291.927 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-04-24T22:51:31.928Z,1556146291.928 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-04-24T22:51:31.928Z,1556146291.928 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-04-24T22:51:32.261Z,1556146292.261 [DataOverHttps] Loaded 2019-04-24T22:51:32.261Z,1556146292.261 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-04-24T22:51:32.262Z,1556146292.262 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408044E0 2019-04-24T22:51:32.263Z,1556146292.263 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2845 2019-04-24T22:51:32.276Z,1556146292.276 [Depth_Keller] Loaded 2019-04-24T22:51:32.277Z,1556146292.277 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-04-24T22:51:32.376Z,1556146292.376 [NAL9602] Loaded 2019-04-24T22:51:32.376Z,1556146292.376 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-04-24T22:51:32.392Z,1556146292.392 [Onboard] Loaded 2019-04-24T22:51:32.392Z,1556146292.392 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-04-24T22:51:32.396Z,1556146292.396 [Radio_Surface] Loaded 2019-04-24T22:51:32.396Z,1556146292.396 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-04-24T22:51:32.397Z,1556146292.397 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408344E0 2019-04-24T22:51:32.397Z,1556146292.397 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2846 2019-04-24T22:51:33.907Z,1556146293.907 [BPC1] Loaded 2019-04-24T22:51:33.907Z,1556146293.907 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-04-24T22:51:33.907Z,1556146293.907 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-04-24T22:51:33.908Z,1556146293.908 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-04-24T22:51:33.978Z,1556146293.978 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-04-24T22:51:33.979Z,1556146293.979 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-04-24T22:51:34.077Z,1556146294.077 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-04-24T22:51:34.078Z,1556146294.078 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-04-24T22:51:34.105Z,1556146294.105 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-04-24T22:51:34.106Z,1556146294.106 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-04-24T22:51:34.356Z,1556146294.356 [CTD_NeilBrown] Loaded 2019-04-24T22:51:34.357Z,1556146294.357 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-04-24T22:51:34.358Z,1556146294.358 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409C04E0 2019-04-24T22:51:34.358Z,1556146294.358 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2847 2019-04-24T22:51:34.367Z,1556146294.367 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-04-24T22:51:34.372Z,1556146294.372 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-04-24T22:51:34.374Z,1556146294.374 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-04-24T22:51:34.379Z,1556146294.379 [CTD_Seabird](INFO): created writer for : depth 2019-04-24T22:51:34.379Z,1556146294.379 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-04-24T22:51:34.384Z,1556146294.384 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-04-24T22:51:34.385Z,1556146294.385 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-04-24T22:51:34.390Z,1556146294.390 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-04-24T22:51:34.390Z,1556146294.390 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-04-24T22:51:34.395Z,1556146294.395 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-04-24T22:51:34.396Z,1556146294.396 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-04-24T22:51:34.401Z,1556146294.401 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-04-24T22:51:34.402Z,1556146294.402 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-04-24T22:51:34.406Z,1556146294.406 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-04-24T22:51:34.435Z,1556146294.435 [CTD_Seabird] Loaded 2019-04-24T22:51:34.435Z,1556146294.435 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-04-24T22:51:34.436Z,1556146294.436 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409F04E0 2019-04-24T22:51:34.436Z,1556146294.436 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2848 2019-04-24T22:51:34.486Z,1556146294.486 [WetLabsBB2FL] Loaded 2019-04-24T22:51:34.487Z,1556146294.487 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-04-24T22:51:34.488Z,1556146294.488 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A204E0 2019-04-24T22:51:34.488Z,1556146294.488 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2849 2019-04-24T22:51:34.489Z,1556146294.489 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-04-24T22:51:34.489Z,1556146294.489 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-04-24T22:51:34.763Z,1556146294.763 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-04-24T22:51:34.763Z,1556146294.763 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-04-24T22:51:34.898Z,1556146294.898 [SBIT](DEBUG): Construct Startup Built In Test. 2019-04-24T22:51:34.909Z,1556146294.909 [SBIT] Loaded 2019-04-24T22:51:34.910Z,1556146294.910 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-04-24T22:51:34.910Z,1556146294.910 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-04-24T22:51:34.922Z,1556146294.922 [IBIT] Loaded 2019-04-24T22:51:34.922Z,1556146294.922 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-04-24T22:51:34.925Z,1556146294.925 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-04-24T22:51:35.066Z,1556146295.066 [CBIT] Loaded 2019-04-24T22:51:35.067Z,1556146295.067 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-04-24T22:51:35.067Z,1556146295.067 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-04-24T22:51:35.071Z,1556146295.071 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-04-24T22:51:35.072Z,1556146295.072 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-04-24T22:51:35.079Z,1556146295.079 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-04-24T22:51:35.080Z,1556146295.080 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF84E0 2019-04-24T22:51:35.080Z,1556146295.080 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2850 2019-04-24T22:51:35.085Z,1556146295.085 [Supervisor](INFO): Main Thread ID is 2761 2019-04-24T22:51:35.085Z,1556146295.085 [Supervisor](DEBUG): Running supervisor. 2019-04-24T22:51:35.085Z,1556146295.085 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2851 2019-04-24T22:51:35.088Z,1556146295.088 [controlThread ThreadHandler](INFO): Handler Thread ID is 2852 2019-04-24T22:51:35.088Z,1556146295.088 [controlThread](DEBUG): Initializing ControlThread 2019-04-24T22:51:35.089Z,1556146295.089 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-04-24T22:51:35.091Z,1556146295.091 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-04-24T22:51:35.091Z,1556146295.091 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-04-24T22:51:35.092Z,1556146295.092 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-04-24T22:51:35.093Z,1556146295.093 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-04-24T22:51:35.094Z,1556146295.094 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-04-24T22:51:35.094Z,1556146295.094 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-04-24T22:51:35.094Z,1556146295.094 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-04-24T22:51:35.095Z,1556146295.095 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-04-24T22:51:35.095Z,1556146295.095 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-04-24T22:51:35.096Z,1556146295.096 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-04-24T22:51:35.097Z,1556146295.097 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-04-24T22:51:35.102Z,1556146295.102 [SBIT](INFO): Initialize SBIT Component. 2019-04-24T22:51:35.103Z,1556146295.103 [SBIT](IMPORTANT): git: 2019-04-10-1-g18bae04 2019-04-24T22:51:35.103Z,1556146295.103 [SBIT](INFO): git hash: 18bae04951e434e73e54670b57def3d69c769fdd 2019-04-24T22:51:35.103Z,1556146295.103 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-04-24T22:51:35.104Z,1556146295.104 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #2 PREEMPT Mon Apr 22 16:23:42 PDT 2019 2019-04-24T22:51:35.106Z,1556146295.106 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-04-24T22:51:35.106Z,1556146295.106 [IBIT](INFO): Initialize IBIT Component. 2019-04-24T22:51:35.107Z,1556146295.107 [CBIT](DEBUG): Initialize CBIT Component. 2019-04-24T22:51:35.108Z,1556146295.108 [logger ThreadHandler](INFO): Handler Thread ID is 2853 2019-04-24T22:51:35.121Z,1556146295.121 [CBIT](DEBUG): Initialized mux pins. 2019-04-24T22:51:35.121Z,1556146295.121 [CBIT](DEBUG): Initializing the watchdog timer. 2019-04-24T22:51:35.126Z,1556146295.126 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2854 2019-04-24T22:51:35.127Z,1556146295.127 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-04-24T22:51:35.134Z,1556146295.134 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2855 2019-04-24T22:51:35.145Z,1556146295.145 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-04-24T22:51:35.145Z,1556146295.145 [CBIT](DEBUG): Initializing heartbeat. 2019-04-24T22:51:35.146Z,1556146295.146 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2856 2019-04-24T22:51:35.154Z,1556146295.154 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2857 2019-04-24T22:51:35.154Z,1556146295.154 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-24T22:51:35.158Z,1556146295.158 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-24T22:51:35.159Z,1556146295.159 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2859 2019-04-24T22:51:35.160Z,1556146295.160 [WetLabsBB2FL](INFO): Powering down 2019-04-24T22:51:35.198Z,1556146295.198 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2860 2019-04-24T22:51:35.201Z,1556146295.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-04-24T22:51:35.201Z,1556146295.201 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-04-24T22:51:35.201Z,1556146295.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-04-24T22:51:35.201Z,1556146295.201 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-04-24T22:51:35.201Z,1556146295.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-04-24T22:51:35.202Z,1556146295.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-04-24T22:51:35.202Z,1556146295.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-04-24T22:51:35.202Z,1556146295.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-04-24T22:51:35.202Z,1556146295.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-04-24T22:51:35.202Z,1556146295.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-04-24T22:51:35.203Z,1556146295.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-04-24T22:51:35.203Z,1556146295.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-04-24T22:51:35.203Z,1556146295.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-04-24T22:51:35.203Z,1556146295.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-04-24T22:51:35.203Z,1556146295.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-04-24T22:51:35.203Z,1556146295.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-04-24T22:51:35.217Z,1556146295.217 [CBIT](DEBUG): Deactivating GF circuits. 2019-04-24T22:51:35.217Z,1556146295.217 [CBIT](DEBUG): Deactivating emergency mode. 2019-04-24T22:51:35.253Z,1556146295.253 [CBIT](DEBUG): Backplane powered. 2019-04-24T22:51:35.254Z,1556146295.254 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-04-24T22:51:35.264Z,1556146295.264 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-04-24T22:51:35.275Z,1556146295.275 [MissionManager](DEBUG): 2019-04-24T22:51:35.275Z,1556146295.275 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-04-24T22:51:35.346Z,1556146295.346 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-04-24T22:51:35.347Z,1556146295.347 [Default:A.Wait](DEBUG): Construct Wait. 2019-04-24T22:51:35.349Z,1556146295.349 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-04-24T22:51:35.387Z,1556146295.387 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-04-24T22:51:35.390Z,1556146295.390 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-04-24T22:51:35.411Z,1556146295.411 [Default:E.Execute](DEBUG): Construct Execute. 2019-04-24T22:51:35.415Z,1556146295.415 [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-04-24T22:51:35.427Z,1556146295.427 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,Depth_Keller,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-04-24T22:51:35.519Z,1556146295.519 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-04-24T22:51:35.525Z,1556146295.525 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-04-24T22:51:35.527Z,1556146295.527 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-04-24T22:51:35.533Z,1556146295.533 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-04-24T22:51:35.534Z,1556146295.534 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-24T22:51:35.537Z,1556146295.537 [Radio_Surface](INFO): Powering up 2019-04-24T22:51:35.541Z,1556146295.541 [MassServo](DEBUG): Initializing MassServo. 2019-04-24T22:51:35.542Z,1556146295.542 [RudderServo](DEBUG): Initializing EZServoServo. 2019-04-24T22:51:35.549Z,1556146295.549 [RudderServo](DEBUG): Initializing RudderServo. 2019-04-24T22:51:35.550Z,1556146295.550 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-04-24T22:51:35.565Z,1556146295.565 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-04-24T22:51:48.205Z,1556146308.205 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-04-24T22:51:48.205Z,1556146308.205 [CTD_Seabird] Communications Fault, FailCount= 1 2019-04-24T22:51:48.205Z,1556146308.205 [CTD_Seabird](ERROR): Communications Fault 2019-04-24T22:51:48.399Z,1556146308.399 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-04-24T22:51:48.609Z,1556146308.609 [CTD_Seabird](INFO): Powering down 2019-04-24T22:51:49.605Z,1556146309.605 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-04-24T22:51:49.605Z,1556146309.605 [CTD_Seabird] No Fault, FailCount= 1 2019-04-24T22:51:49.977Z,1556146309.977 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-24T22:51:49.979Z,1556146309.979 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-24T22:51:59.354Z,1556146319.354 [CommandLine](IMPORTANT): got command configSet PAR_Licor.loadAtStartup 1.000000 bool persist 2019-04-24T22:51:59.355Z,1556146319.355 [CommandLine](IMPORTANT): configSet PAR_Licor.loadAtStartup requires a restart to take effect. 2019-04-24T22:52:02.502Z,1556146322.502 [NAL9602](INFO): Powering up NAL9602 2019-04-24T22:52:03.029Z,1556146323.029 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-04-24T22:52:03.029Z,1556146323.029 [CTD_Seabird] Communications Fault, FailCount= 2 2019-04-24T22:52:03.029Z,1556146323.029 [CTD_Seabird](ERROR): Communications Fault 2019-04-24T22:52:03.341Z,1556146323.341 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-04-24T22:52:03.433Z,1556146323.433 [CTD_Seabird](INFO): Powering down 2019-04-24T22:52:03.758Z,1556146323.758 [SBIT](IMPORTANT): Beginning Startup BIT 2019-04-24T22:52:03.762Z,1556146323.762 [CBIT](IMPORTANT): Beginning ground fault scan 2019-04-24T22:52:04.034Z,1556146324.034 [CommandLine](IMPORTANT): got command restart application 2019-04-24T22:52:04.585Z,1556146324.585 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-04-24T22:52:04.586Z,1556146324.586 [CTD_Seabird] No Fault, FailCount= 2 2019-04-24T22:52:04.777Z,1556146324.777 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-24T22:52:04.785Z,1556146324.785 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-24T22:52:05.037Z,1556146325.037 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2019-04-24T22:52:05.037Z,1556146325.037 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:05.038Z,1556146325.038 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.038Z,1556146325.038 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-04-24T22:52:05.038Z,1556146325.038 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.039Z,1556146325.039 [CommandLine](INFO): Join timeout helper Thread ID is 2870 2019-04-24T22:52:05.049Z,1556146325.049 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-04-24T22:52:05.049Z,1556146325.049 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.050Z,1556146325.050 [NavChartDb](INFO): Join timeout helper Thread ID is 2871 2019-04-24T22:52:05.249Z,1556146325.249 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:05.250Z,1556146325.250 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.253Z,1556146325.253 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-04-24T22:52:05.253Z,1556146325.253 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.254Z,1556146325.254 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2872 2019-04-24T22:52:05.398Z,1556146325.398 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:05.398Z,1556146325.398 [WetLabsBB2FL](INFO): Powering down 2019-04-24T22:52:05.399Z,1556146325.399 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.413Z,1556146325.413 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-04-24T22:52:05.413Z,1556146325.413 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.414Z,1556146325.414 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2873 2019-04-24T22:52:05.781Z,1556146325.781 [CTD_Seabird](INFO): Powering down 2019-04-24T22:52:05.913Z,1556146325.913 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:05.914Z,1556146325.914 [CTD_Seabird](INFO): Powering down 2019-04-24T22:52:05.925Z,1556146325.925 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.941Z,1556146325.941 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-04-24T22:52:05.941Z,1556146325.941 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.942Z,1556146325.942 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2874 2019-04-24T22:52:05.957Z,1556146325.957 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:05.958Z,1556146325.958 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.962Z,1556146325.962 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-04-24T22:52:05.962Z,1556146325.962 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:05.962Z,1556146325.962 [Radio_Surface](INFO): Join timeout helper Thread ID is 2875 2019-04-24T22:52:06.241Z,1556146326.241 [Radio_Surface](INFO): Powering down 2019-04-24T22:52:06.259Z,1556146326.259 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:06.259Z,1556146326.259 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:06.275Z,1556146326.275 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-04-24T22:52:06.275Z,1556146326.275 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:06.275Z,1556146326.275 [DataOverHttps](INFO): Join timeout helper Thread ID is 2876 2019-04-24T22:52:06.637Z,1556146326.637 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:06.638Z,1556146326.638 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:06.639Z,1556146326.639 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-04-24T22:52:06.639Z,1556146326.639 [logger ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:06.639Z,1556146326.639 [logger](INFO): Join timeout helper Thread ID is 2877 2019-04-24T22:52:06.642Z,1556146326.642 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:06.643Z,1556146326.643 [logger ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:06.659Z,1556146326.659 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-04-24T22:52:06.659Z,1556146326.659 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:06.659Z,1556146326.659 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-04-24T22:52:06.659Z,1556146326.659 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:06.659Z,1556146326.659 [controlThread](INFO): Join timeout helper Thread ID is 2878 2019-04-24T22:52:06.818Z,1556146326.818 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-24T22:52:06.818Z,1556146326.818 [controlThread](DEBUG): Uninitializing ControlThread 2019-04-24T22:52:06.819Z,1556146326.819 [NAL9602](INFO): Powering down 2019-04-24T22:52:06.839Z,1556146326.839 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-04-24T22:52:06.840Z,1556146326.840 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-04-24T22:52:06.840Z,1556146326.840 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-04-24T22:52:06.841Z,1556146326.841 [MissionManager](INFO): Uninitializing Mission Default 2019-04-24T22:52:06.843Z,1556146326.843 [MissionManager](INFO): Uninitializing Mission Startup 2019-04-24T22:52:06.844Z,1556146326.844 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-04-24T22:52:06.844Z,1556146326.844 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-04-24T22:52:06.845Z,1556146326.845 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-04-24T22:52:06.845Z,1556146326.845 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-04-24T22:52:06.845Z,1556146326.845 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-04-24T22:52:06.846Z,1556146326.846 [BuoyancyServo](INFO): Powering down 2019-04-24T22:52:06.857Z,1556146326.857 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-04-24T22:52:06.857Z,1556146326.857 [ElevatorServo](INFO): Powering down 2019-04-24T22:52:06.858Z,1556146326.858 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-24T22:52:06.858Z,1556146326.858 [MassServo](INFO): Powering down 2019-04-24T22:52:06.859Z,1556146326.859 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-04-24T22:52:06.859Z,1556146326.859 [RudderServo](INFO): Powering down 2019-04-24T22:52:06.860Z,1556146326.860 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-04-24T22:52:06.860Z,1556146326.860 [ThrusterServo](INFO): Powering down 2019-04-24T22:52:06.861Z,1556146326.861 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-04-24T22:52:06.861Z,1556146326.861 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-04-24T22:52:06.862Z,1556146326.862 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-04-24T22:52:06.862Z,1556146326.862 [CBIT](DEBUG): Powering off loads. 2019-04-24T22:52:06.873Z,1556146326.873 [CBIT](DEBUG): Disabling WDT. 2019-04-24T22:52:06.885Z,1556146326.885 [CBIT](DEBUG): Opening all GF detection circuits. 2019-04-24T22:52:06.886Z,1556146326.886 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:07.321Z,1556146327.321 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:07.327Z,1556146327.327 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:07.357Z,1556146327.357 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:07.360Z,1556146327.360 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:07.363Z,1556146327.363 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:07.393Z,1556146327.393 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-24T22:52:07.448Z,1556146327.448 [logger ThreadHandler](INFO): Thread cancelled.