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.