2020-02-20T17:43:53.529Z,1582220633.529 [Supervisor](DEBUG): Initializing supervisor.
2020-02-20T17:43:53.531Z,1582220633.531 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-02-20T17:43:53.532Z,1582220633.532 [SyncHandler](INFO): Protected caller Thread ID is 3806
2020-02-20T17:43:53.532Z,1582220633.532 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-02-20T17:43:53.533Z,1582220633.533 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-02-20T17:43:53.534Z,1582220633.534 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3807
2020-02-20T17:43:53.536Z,1582220633.536 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-02-20T17:43:53.547Z,1582220633.547 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-02-20T17:43:53.548Z,1582220633.548 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-02-20T17:43:53.549Z,1582220633.549 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3808
2020-02-20T17:43:53.550Z,1582220633.550 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-02-20T17:43:53.550Z,1582220633.550 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-02-20T17:43:53.551Z,1582220633.551 [logger ThreadHandler](INFO): Protected caller Thread ID is 3809
2020-02-20T17:43:53.553Z,1582220633.553 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-02-20T17:43:53.553Z,1582220633.553 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-02-20T17:43:53.555Z,1582220633.555 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-02-20T17:43:53.647Z,1582220633.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-02-20T17:43:53.647Z,1582220633.647 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-02-20T17:43:54.230Z,1582220634.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-02-20T17:43:54.231Z,1582220634.231 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-02-20T17:43:54.325Z,1582220634.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-02-20T17:43:54.325Z,1582220634.325 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-02-20T17:43:54.422Z,1582220634.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-02-20T17:43:54.423Z,1582220634.423 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-02-20T17:43:54.501Z,1582220634.501 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-02-20T17:43:54.633Z,1582220634.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-02-20T17:43:54.634Z,1582220634.634 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-02-20T17:43:54.911Z,1582220634.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-02-20T17:43:54.912Z,1582220634.912 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-02-20T17:43:55.946Z,1582220635.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-02-20T17:43:55.946Z,1582220635.946 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-02-20T17:43:56.089Z,1582220636.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-02-20T17:43:56.090Z,1582220636.090 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-02-20T17:43:56.269Z,1582220636.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-02-20T17:43:56.269Z,1582220636.269 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-02-20T17:43:56.713Z,1582220636.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-02-20T17:43:56.714Z,1582220636.714 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-02-20T17:43:56.916Z,1582220636.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-02-20T17:43:56.917Z,1582220636.917 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-02-20T17:43:57.108Z,1582220637.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-02-20T17:43:57.109Z,1582220637.109 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-02-20T17:43:57.574Z,1582220637.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-02-20T17:43:57.575Z,1582220637.575 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-02-20T17:43:57.885Z,1582220637.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-02-20T17:43:57.887Z,1582220637.887 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-02-20T17:43:57.888Z,1582220637.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-02-20T17:43:57.969Z,1582220637.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-02-20T17:43:58.120Z,1582220638.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-02-20T17:43:58.223Z,1582220638.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-02-20T17:43:58.378Z,1582220638.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-02-20T17:43:58.477Z,1582220638.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-02-20T17:43:58.867Z,1582220638.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-02-20T17:43:59.244Z,1582220639.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-02-20T17:43:59.245Z,1582220639.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-02-20T17:43:59.335Z,1582220639.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-02-20T17:43:59.429Z,1582220639.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-02-20T17:43:59.561Z,1582220639.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-02-20T17:43:59.657Z,1582220639.657 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-02-20T17:43:59.657Z,1582220639.657 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-02-20T17:43:59.672Z,1582220639.672 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-02-20T17:43:59.720Z,1582220639.720 [VerticalControl](DEBUG): Construct VerticalControl.
2020-02-20T17:43:59.832Z,1582220639.832 [VerticalControl] Loaded
2020-02-20T17:43:59.832Z,1582220639.832 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-02-20T17:43:59.833Z,1582220639.833 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-02-20T17:43:59.913Z,1582220639.913 [HorizontalControl] Loaded
2020-02-20T17:43:59.914Z,1582220639.914 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-02-20T17:43:59.914Z,1582220639.914 [SpeedControl](DEBUG): Construct SpeedControl.
2020-02-20T17:43:59.920Z,1582220639.920 [SpeedControl] Loaded
2020-02-20T17:43:59.920Z,1582220639.920 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-02-20T17:43:59.921Z,1582220639.921 [LoopControl](DEBUG): Construct LoopControl.
2020-02-20T17:43:59.921Z,1582220639.921 [LoopControl] Loaded
2020-02-20T17:43:59.921Z,1582220639.921 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-02-20T17:43:59.922Z,1582220639.922 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-02-20T17:43:59.922Z,1582220639.922 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-02-20T17:43:59.962Z,1582220639.962 [DepthRateCalculator] Loaded
2020-02-20T17:43:59.963Z,1582220639.963 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-02-20T17:43:59.968Z,1582220639.968 [PitchRateCalculator] Loaded
2020-02-20T17:43:59.968Z,1582220639.968 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-02-20T17:43:59.983Z,1582220639.983 [SpeedCalculator] Loaded
2020-02-20T17:43:59.983Z,1582220639.983 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-02-20T17:44:00.004Z,1582220640.004 [TempGradientCalculator] Loaded
2020-02-20T17:44:00.004Z,1582220640.004 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-02-20T17:44:00.009Z,1582220640.009 [YawRateCalculator] Loaded
2020-02-20T17:44:00.010Z,1582220640.010 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-02-20T17:44:00.039Z,1582220640.039 [ElevatorOffsetCalculator] Loaded
2020-02-20T17:44:00.039Z,1582220640.039 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-02-20T17:44:00.039Z,1582220640.039 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-02-20T17:44:00.040Z,1582220640.040 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-02-20T17:44:00.073Z,1582220640.073 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-02-20T17:44:00.074Z,1582220640.074 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-02-20T17:44:00.154Z,1582220640.154 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-02-20T17:44:00.155Z,1582220640.155 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-02-20T17:44:00.460Z,1582220640.460 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-02-20T17:44:00.460Z,1582220640.460 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-02-20T17:44:00.555Z,1582220640.555 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-02-20T17:44:00.556Z,1582220640.556 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-02-20T17:44:00.843Z,1582220640.843 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-02-20T17:44:00.848Z,1582220640.848 [AHRS_M2](INFO): created writer for : platform_orientation
2020-02-20T17:44:00.849Z,1582220640.849 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-02-20T17:44:00.855Z,1582220640.855 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-02-20T17:44:00.855Z,1582220640.855 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-02-20T17:44:00.860Z,1582220640.860 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-02-20T17:44:00.861Z,1582220640.861 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-02-20T17:44:00.866Z,1582220640.866 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-02-20T17:44:00.937Z,1582220640.937 [AHRS_M2] Loaded
2020-02-20T17:44:00.937Z,1582220640.937 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-02-20T17:44:01.015Z,1582220641.015 [DataOverHttps] Loaded
2020-02-20T17:44:01.016Z,1582220641.016 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-02-20T17:44:01.017Z,1582220641.017 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2020-02-20T17:44:01.017Z,1582220641.017 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3889
2020-02-20T17:44:01.030Z,1582220641.030 [Depth_Keller] Loaded
2020-02-20T17:44:01.031Z,1582220641.031 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-02-20T17:44:01.035Z,1582220641.035 [DropWeight] Loaded
2020-02-20T17:44:01.036Z,1582220641.036 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-02-20T17:44:01.133Z,1582220641.133 [NAL9602] Loaded
2020-02-20T17:44:01.133Z,1582220641.133 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-02-20T17:44:01.170Z,1582220641.170 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-02-20T17:44:01.171Z,1582220641.171 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-02-20T17:44:01.171Z,1582220641.171 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-02-20T17:44:01.172Z,1582220641.172 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-02-20T17:44:01.172Z,1582220641.172 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-02-20T17:44:01.173Z,1582220641.173 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-02-20T17:44:01.173Z,1582220641.173 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-02-20T17:44:01.174Z,1582220641.174 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-02-20T17:44:01.174Z,1582220641.174 [Onboard] Loaded
2020-02-20T17:44:01.174Z,1582220641.174 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-02-20T17:44:01.177Z,1582220641.177 [Radio_Surface] Loaded
2020-02-20T17:44:01.178Z,1582220641.178 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-02-20T17:44:01.179Z,1582220641.179 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0
2020-02-20T17:44:01.179Z,1582220641.179 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3890
2020-02-20T17:44:01.223Z,1582220641.223 [RDI_Pathfinder] Loaded
2020-02-20T17:44:01.223Z,1582220641.223 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-02-20T17:44:01.355Z,1582220641.355 [DAT] Loaded
2020-02-20T17:44:01.355Z,1582220641.355 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-02-20T17:44:02.852Z,1582220642.852 [BPC1] Loaded
2020-02-20T17:44:02.852Z,1582220642.852 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-02-20T17:44:02.852Z,1582220642.852 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-02-20T17:44:02.853Z,1582220642.853 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-02-20T17:44:02.954Z,1582220642.954 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-02-20T17:44:02.955Z,1582220642.955 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-02-20T17:44:02.975Z,1582220642.975 [NavChart] Loaded
2020-02-20T17:44:02.975Z,1582220642.975 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-02-20T17:44:02.979Z,1582220642.979 [UniversalFixResidualReporter] Loaded
2020-02-20T17:44:02.979Z,1582220642.979 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-02-20T17:44:02.979Z,1582220642.979 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-02-20T17:44:02.980Z,1582220642.980 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-02-20T17:44:03.115Z,1582220643.115 [SBIT](DEBUG): Construct Startup Built In Test.
2020-02-20T17:44:03.125Z,1582220643.125 [SBIT] Loaded
2020-02-20T17:44:03.126Z,1582220643.126 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-02-20T17:44:03.126Z,1582220643.126 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-02-20T17:44:03.139Z,1582220643.139 [IBIT] Loaded
2020-02-20T17:44:03.139Z,1582220643.139 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-02-20T17:44:03.142Z,1582220643.142 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-02-20T17:44:03.281Z,1582220643.281 [CBIT] Loaded
2020-02-20T17:44:03.281Z,1582220643.281 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-02-20T17:44:03.281Z,1582220643.281 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-02-20T17:44:03.282Z,1582220643.282 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-02-20T17:44:03.383Z,1582220643.383 [BuoyancyServo] Loaded
2020-02-20T17:44:03.383Z,1582220643.383 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-02-20T17:44:03.395Z,1582220643.395 [ElevatorServo] Loaded
2020-02-20T17:44:03.395Z,1582220643.395 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-02-20T17:44:03.406Z,1582220643.406 [MassServo] Loaded
2020-02-20T17:44:03.406Z,1582220643.406 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-02-20T17:44:03.417Z,1582220643.417 [RudderServo] Loaded
2020-02-20T17:44:03.417Z,1582220643.417 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-02-20T17:44:03.428Z,1582220643.428 [ThrusterServo] Loaded
2020-02-20T17:44:03.428Z,1582220643.428 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-02-20T17:44:03.428Z,1582220643.428 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-02-20T17:44:03.429Z,1582220643.429 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-02-20T17:44:03.496Z,1582220643.496 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-02-20T17:44:03.497Z,1582220643.497 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-02-20T17:44:03.911Z,1582220643.911 [BackSeatDriver] Loaded
2020-02-20T17:44:03.912Z,1582220643.912 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-02-20T17:44:03.922Z,1582220643.922 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40AA34E0
2020-02-20T17:44:03.923Z,1582220643.923 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 3891
2020-02-20T17:44:03.956Z,1582220643.956 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-02-20T17:44:03.961Z,1582220643.961 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-02-20T17:44:03.962Z,1582220643.962 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-02-20T17:44:03.970Z,1582220643.970 [CTD_Seabird](INFO): created writer for : depth
2020-02-20T17:44:03.970Z,1582220643.970 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-02-20T17:44:03.975Z,1582220643.975 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-02-20T17:44:03.976Z,1582220643.976 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-02-20T17:44:03.981Z,1582220643.981 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-02-20T17:44:03.981Z,1582220643.981 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-02-20T17:44:03.986Z,1582220643.986 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-02-20T17:44:03.987Z,1582220643.987 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-02-20T17:44:03.993Z,1582220643.993 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-02-20T17:44:03.993Z,1582220643.993 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-02-20T17:44:03.998Z,1582220643.998 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-02-20T17:44:04.026Z,1582220644.026 [CTD_Seabird] Loaded
2020-02-20T17:44:04.026Z,1582220644.026 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-02-20T17:44:04.027Z,1582220644.027 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0
2020-02-20T17:44:04.027Z,1582220644.027 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3892
2020-02-20T17:44:04.058Z,1582220644.058 [ESPComponent] Loaded
2020-02-20T17:44:04.058Z,1582220644.058 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-02-20T17:44:04.065Z,1582220644.065 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-02-20T17:44:04.065Z,1582220644.065 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-02-20T17:44:04.069Z,1582220644.069 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-02-20T17:44:04.069Z,1582220644.069 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-02-20T17:44:04.074Z,1582220644.074 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-02-20T17:44:04.074Z,1582220644.074 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-02-20T17:44:04.078Z,1582220644.078 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-02-20T17:44:04.079Z,1582220644.079 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-02-20T17:44:04.083Z,1582220644.083 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-02-20T17:44:04.083Z,1582220644.083 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-02-20T17:44:04.088Z,1582220644.088 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-02-20T17:44:04.088Z,1582220644.088 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-02-20T17:44:04.092Z,1582220644.092 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-02-20T17:44:04.092Z,1582220644.092 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-02-20T17:44:04.096Z,1582220644.096 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-02-20T17:44:04.101Z,1582220644.101 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-02-20T17:44:04.101Z,1582220644.101 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-02-20T17:44:04.102Z,1582220644.102 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-02-20T17:44:04.106Z,1582220644.106 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-02-20T17:44:04.106Z,1582220644.106 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-02-20T17:44:04.110Z,1582220644.110 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-02-20T17:44:04.110Z,1582220644.110 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-02-20T17:44:04.115Z,1582220644.115 [WetLabsBB2FL] Loaded
2020-02-20T17:44:04.115Z,1582220644.115 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-02-20T17:44:04.116Z,1582220644.116 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0
2020-02-20T17:44:04.116Z,1582220644.116 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3893
2020-02-20T17:44:04.130Z,1582220644.130 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-02-20T17:44:04.131Z,1582220644.131 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-02-20T17:44:04.131Z,1582220644.131 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-02-20T17:44:04.136Z,1582220644.136 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest
2020-02-20T17:44:04.136Z,1582220644.136 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-02-20T17:44:04.137Z,1582220644.137 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-02-20T17:44:04.141Z,1582220644.141 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-02-20T17:44:04.141Z,1582220644.141 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-02-20T17:44:04.145Z,1582220644.145 [WetLabsUBAT] Loaded
2020-02-20T17:44:04.146Z,1582220644.146 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-02-20T17:44:04.147Z,1582220644.147 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B334E0
2020-02-20T17:44:04.147Z,1582220644.147 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 3894
2020-02-20T17:44:04.147Z,1582220644.147 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-02-20T17:44:04.151Z,1582220644.151 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-02-20T17:44:04.152Z,1582220644.152 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-02-20T17:44:04.158Z,1582220644.158 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-02-20T17:44:04.159Z,1582220644.159 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2020-02-20T17:44:04.160Z,1582220644.160 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3895
2020-02-20T17:44:04.164Z,1582220644.164 [Supervisor](INFO): Main Thread ID is 3805
2020-02-20T17:44:04.165Z,1582220644.165 [Supervisor](DEBUG): Running supervisor.
2020-02-20T17:44:04.165Z,1582220644.165 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3896
2020-02-20T17:44:04.168Z,1582220644.168 [controlThread ThreadHandler](INFO): Handler Thread ID is 3897
2020-02-20T17:44:04.169Z,1582220644.169 [controlThread](DEBUG): Initializing ControlThread
2020-02-20T17:44:04.169Z,1582220644.169 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-02-20T17:44:04.171Z,1582220644.171 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-02-20T17:44:04.172Z,1582220644.172 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-02-20T17:44:04.172Z,1582220644.172 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-02-20T17:44:04.172Z,1582220644.172 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-02-20T17:44:04.173Z,1582220644.173 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-02-20T17:44:04.173Z,1582220644.173 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-02-20T17:44:04.174Z,1582220644.174 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-02-20T17:44:04.174Z,1582220644.174 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-02-20T17:44:04.174Z,1582220644.174 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-02-20T17:44:04.181Z,1582220644.181 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-02-20T17:44:04.182Z,1582220644.182 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-02-20T17:44:04.182Z,1582220644.182 [SBIT](INFO): Initialize SBIT Component.
2020-02-20T17:44:04.182Z,1582220644.182 [SBIT](IMPORTANT): git: 2019-12-09_A
2020-02-20T17:44:04.183Z,1582220644.183 [SBIT](INFO): git hash: 5f7f9b7f448f424256857c85d4e34999b9088e71
2020-02-20T17:44:04.183Z,1582220644.183 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-02-20T17:44:04.183Z,1582220644.183 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2020-02-20T17:44:04.184Z,1582220644.184 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-02-20T17:44:04.185Z,1582220644.185 [IBIT](INFO): Initialize IBIT Component.
2020-02-20T17:44:04.185Z,1582220644.185 [CBIT](DEBUG): Initialize CBIT Component.
2020-02-20T17:44:04.187Z,1582220644.187 [logger ThreadHandler](INFO): Handler Thread ID is 3898
2020-02-20T17:44:04.198Z,1582220644.198 [CBIT](DEBUG): Initialized mux pins.
2020-02-20T17:44:04.198Z,1582220644.198 [CBIT](DEBUG): Initializing the watchdog timer.
2020-02-20T17:44:04.207Z,1582220644.207 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3899
2020-02-20T17:44:04.208Z,1582220644.208 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-02-20T17:44:04.219Z,1582220644.219 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3900
2020-02-20T17:44:04.222Z,1582220644.222 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-02-20T17:44:04.222Z,1582220644.222 [CBIT](DEBUG): Initializing heartbeat.
2020-02-20T17:44:04.231Z,1582220644.231 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 3901
2020-02-20T17:44:04.247Z,1582220644.247 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3902
2020-02-20T17:44:04.247Z,1582220644.247 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-02-20T17:44:04.251Z,1582220644.251 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-02-20T17:44:04.252Z,1582220644.252 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3904
2020-02-20T17:44:04.253Z,1582220644.253 [WetLabsBB2FL](INFO): Powering down
2020-02-20T17:44:04.283Z,1582220644.283 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 3905
2020-02-20T17:44:04.294Z,1582220644.294 [CBIT](DEBUG): Deactivating GF circuits.
2020-02-20T17:44:04.294Z,1582220644.294 [CBIT](DEBUG): Deactivating emergency mode.
2020-02-20T17:44:04.295Z,1582220644.295 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3906
2020-02-20T17:44:04.299Z,1582220644.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-02-20T17:44:04.299Z,1582220644.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-02-20T17:44:04.299Z,1582220644.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-02-20T17:44:04.299Z,1582220644.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-02-20T17:44:04.299Z,1582220644.299 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-02-20T17:44:04.299Z,1582220644.299 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-02-20T17:44:04.300Z,1582220644.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-02-20T17:44:04.300Z,1582220644.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-02-20T17:44:04.300Z,1582220644.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-02-20T17:44:04.300Z,1582220644.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-02-20T17:44:04.300Z,1582220644.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-02-20T17:44:04.300Z,1582220644.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-02-20T17:44:04.301Z,1582220644.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-02-20T17:44:04.301Z,1582220644.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-02-20T17:44:04.301Z,1582220644.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-02-20T17:44:04.301Z,1582220644.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-02-20T17:44:04.330Z,1582220644.330 [CBIT](DEBUG): Backplane powered.
2020-02-20T17:44:04.332Z,1582220644.332 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-02-20T17:44:04.342Z,1582220644.342 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-20T17:44:04.353Z,1582220644.353 [MissionManager](DEBUG):
2020-02-20T17:44:04.354Z,1582220644.354 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-02-20T17:44:04.430Z,1582220644.430 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-02-20T17:44:04.463Z,1582220644.463 [Default:A.Wait](DEBUG): Construct Wait.
2020-02-20T17:44:04.465Z,1582220644.465 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-20T17:44:04.493Z,1582220644.493 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-02-20T17:44:04.495Z,1582220644.495 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-02-20T17:44:04.507Z,1582220644.507 [Default:E.Execute](DEBUG): Construct Execute.
2020-02-20T17:44:04.530Z,1582220644.530 [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
2020-02-20T17:44:04.543Z,1582220644.543 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,ESPComponent,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,
2020-02-20T17:44:04.557Z,1582220644.557 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-20T17:44:04.615Z,1582220644.615 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-02-20T17:44:04.625Z,1582220644.625 [Radio_Surface](INFO): Powering up
2020-02-20T17:44:04.641Z,1582220644.641 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-02-20T17:44:04.641Z,1582220644.641 [DAT](INFO): Powering up
2020-02-20T17:44:04.641Z,1582220644.641 [DAT](DEBUG): Initializing DAT.
2020-02-20T17:44:04.667Z,1582220644.667 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-02-20T17:44:04.702Z,1582220644.702 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-20T17:44:04.750Z,1582220644.750 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-20T17:44:04.763Z,1582220644.763 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-20T17:44:04.765Z,1582220644.765 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-20T17:44:04.779Z,1582220644.779 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-20T17:44:04.783Z,1582220644.783 [MassServo](DEBUG): Initializing EZServoServo.
2020-02-20T17:44:04.795Z,1582220644.795 [MassServo](DEBUG): Initializing MassServo.
2020-02-20T17:44:04.808Z,1582220644.808 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-20T17:44:04.815Z,1582220644.815 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-20T17:44:04.816Z,1582220644.816 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-02-20T17:44:04.827Z,1582220644.827 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-02-20T17:44:04.971Z,1582220644.971 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2020-02-20T17:44:04.971Z,1582220644.971 [DropWeight] Hardware Fault, FailCount= 1
2020-02-20T17:44:04.971Z,1582220644.971 [DropWeight](ERROR): Hardware Fault
2020-02-20T17:44:04.995Z,1582220644.995 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-20T17:44:05.019Z,1582220645.019 [CommandLine](FAULT): Scheduling is paused
2020-02-20T17:44:05.020Z,1582220645.020 [CBIT](INFO): Critical error at 20200220T174404
2020-02-20T17:44:05.020Z,1582220645.020 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-02-20T17:44:05.022Z,1582220645.022 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-02-20T17:44:05.023Z,1582220645.023 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-02-20T17:44:05.703Z,1582220645.703 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-02-20T17:44:05.704Z,1582220645.704 [RudderServo](FAULT): Rudder failed to initialize
2020-02-20T17:44:05.704Z,1582220645.704 [RudderServo] Communications Fault, FailCount= 1
2020-02-20T17:44:05.704Z,1582220645.704 [RudderServo](ERROR): Communications Fault
2020-02-20T17:44:05.816Z,1582220645.816 [CBIT](INFO): Critical error at 20200220T174405
2020-02-20T17:44:05.818Z,1582220645.818 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-02-20T17:44:05.991Z,1582220645.991 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-20T17:44:05.991Z,1582220645.991 [RudderServo](INFO): Powering down
2020-02-20T17:44:06.678Z,1582220646.678 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-20T17:44:06.795Z,1582220646.795 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-20T17:44:06.799Z,1582220646.799 [CBIT](INFO): Clearing failed state for component RudderServo
2020-02-20T17:44:06.799Z,1582220646.799 [RudderServo] No Fault, FailCount= 1
2020-02-20T17:44:11.290Z,1582220651.290 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-02-20T17:44:15.192Z,1582220655.192 [DAT](DEBUG):
2020-02-20T17:44:15.193Z,1582220655.193 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2020-02-20T17:44:16.389Z,1582220656.389 [DAT](DEBUG): MF Frequency Band
2020-02-20T17:44:16.392Z,1582220656.392 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21
2020-02-20T17:44:16.393Z,1582220656.393 [DAT](DEBUG): Feb 20 2020 17:44:52
2020-02-20T17:44:17.251Z,1582220657.251 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:17.253Z,1582220657.253 [DAT](DEBUG): Features enabled [Bearing]
2020-02-20T17:44:17.254Z,1582220657.254 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2020-02-20T17:44:17.267Z,1582220657.267 [DAT](INFO): commRate: 800
2020-02-20T17:44:17.583Z,1582220657.583 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:17.584Z,1582220657.584 [DAT](INFO): entering command mode
2020-02-20T17:44:17.997Z,1582220657.997 [DAT](DEBUG): checking for command mode acknowledgment
2020-02-20T17:44:18.390Z,1582220658.390 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:18.391Z,1582220658.391 [DAT](DEBUG): checking for command mode acknowledgment
2020-02-20T17:44:18.392Z,1582220658.392 [DAT](INFO): command mode acknowledged
2020-02-20T17:44:18.392Z,1582220658.392 [DAT](INFO): setting verbose to 3
2020-02-20T17:44:18.793Z,1582220658.793 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:18.794Z,1582220658.794 [DAT](DEBUG): checking for verbose setting acknowledgment
2020-02-20T17:44:18.795Z,1582220658.795 [DAT](INFO): set verbose to 3
2020-02-20T17:44:19.205Z,1582220659.205 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:19.206Z,1582220659.206 [DAT](INFO): setting transmit power to 8
2020-02-20T17:44:19.611Z,1582220659.611 [DAT](DEBUG): checking for transmit power setting acknowledgment
2020-02-20T17:44:20.003Z,1582220660.003 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:20.004Z,1582220660.004 [DAT](DEBUG): checking for transmit power setting acknowledgment
2020-02-20T17:44:20.005Z,1582220660.005 [DAT](INFO): set transmit power to 8
2020-02-20T17:44:20.408Z,1582220660.408 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:20.409Z,1582220660.409 [DAT](INFO): setting local address to 9
2020-02-20T17:44:20.817Z,1582220660.817 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:20.818Z,1582220660.818 [DAT](DEBUG): checking for local address setting acknowledgment
2020-02-20T17:44:20.964Z,1582220660.964 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005067
2020-02-20T17:44:21.217Z,1582220661.217 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:21.218Z,1582220661.218 [DAT](DEBUG): checking for local address setting acknowledgment
2020-02-20T17:44:21.219Z,1582220661.219 [DAT](INFO): set local address to 9
2020-02-20T17:44:21.620Z,1582220661.620 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:22.024Z,1582220662.024 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:22.833Z,1582220662.833 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:23.237Z,1582220663.237 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:23.654Z,1582220663.654 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:24.449Z,1582220664.449 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:25.259Z,1582220665.259 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:25.687Z,1582220665.687 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:26.064Z,1582220666.064 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:26.870Z,1582220666.870 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-20T17:44:26.870Z,1582220666.870 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-20T17:44:26.870Z,1582220666.870 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-20T17:44:26.899Z,1582220666.899 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-20T17:44:27.347Z,1582220667.347 [RDI_Pathfinder](INFO): Powering down
2020-02-20T17:44:28.115Z,1582220668.115 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-20T17:44:28.115Z,1582220668.115 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-20T17:44:31.300Z,1582220671.300 [NAL9602](INFO): Powering up NAL9602
2020-02-20T17:44:41.022Z,1582220681.022 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-02-20T17:44:41.412Z,1582220681.412 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:41.860Z,1582220681.860 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:42.208Z,1582220682.208 [NAL9602](INFO): NAL9602 initialized
2020-02-20T17:44:42.223Z,1582220682.223 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:42.645Z,1582220682.645 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:43.043Z,1582220683.043 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:43.874Z,1582220683.874 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:44.002Z,1582220684.002 [SBIT](IMPORTANT): Beginning Startup BIT
2020-02-20T17:44:44.026Z,1582220684.026 [CBIT](IMPORTANT): Beginning ground fault scan
2020-02-20T17:44:44.245Z,1582220684.245 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:44.674Z,1582220684.674 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:45.469Z,1582220685.469 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:46.309Z,1582220686.309 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:46.998Z,1582220686.998 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:47.770Z,1582220687.770 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:48.569Z,1582220688.569 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:48.972Z,1582220688.972 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:49.380Z,1582220689.380 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:50.188Z,1582220690.188 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:44:50.991Z,1582220690.991 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-20T17:44:50.991Z,1582220690.991 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-02-20T17:44:50.991Z,1582220690.991 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-20T17:44:51.072Z,1582220691.072 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-20T17:44:51.482Z,1582220691.482 [RDI_Pathfinder](INFO): Powering down
2020-02-20T17:44:52.271Z,1582220692.271 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-20T17:44:52.271Z,1582220692.271 [RDI_Pathfinder] No Fault, FailCount= 2
2020-02-20T17:44:55.086Z,1582220695.086 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.014951
CHAN A1 (24V): -0.001373
CHAN A2 (12V): -0.007488
CHAN A3 (5V): -0.001945
CHAN B0 (3.3V): 0.000352
CHAN B1 (3.15aV): -0.000151
CHAN B2 (3.15bV): -0.000391
CHAN B3 (GND): 0.002340
OPEN: 0.005208
Full Scale Calc: 4.765 mA, -1.589 mA
2020-02-20T17:45:05.231Z,1582220705.231 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-02-20T17:45:05.638Z,1582220705.638 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:06.042Z,1582220706.042 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:06.445Z,1582220706.445 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:07.235Z,1582220707.235 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:08.045Z,1582220708.045 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:08.481Z,1582220708.481 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:08.843Z,1582220708.843 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:09.660Z,1582220709.660 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:10.461Z,1582220710.461 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:10.869Z,1582220710.869 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:11.273Z,1582220711.273 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:11.698Z,1582220711.698 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:12.491Z,1582220712.491 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:12.897Z,1582220712.897 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:13.289Z,1582220713.289 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:13.692Z,1582220713.692 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:14.100Z,1582220714.100 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-20T17:45:14.933Z,1582220714.933 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-20T17:45:14.933Z,1582220714.933 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-02-20T17:45:14.933Z,1582220714.933 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-20T17:45:15.039Z,1582220715.039 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-20T17:45:15.040Z,1582220715.040 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-02-20T17:45:15.387Z,1582220715.387 [RDI_Pathfinder](INFO): Powering down
2020-02-20T17:45:15.449Z,1582220715.449 [CBIT](INFO): Critical error at 20200220T174515
2020-02-20T17:45:25.820Z,1582220725.820 [CommandLine](IMPORTANT): got command quit
2020-02-20T17:45:26.827Z,1582220726.827 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:26.827Z,1582220726.827 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:26.843Z,1582220726.843 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2020-02-20T17:45:26.843Z,1582220726.843 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:26.843Z,1582220726.843 [CommandLine](INFO): Join timeout helper Thread ID is 3938
2020-02-20T17:45:26.858Z,1582220726.858 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-20T17:45:26.859Z,1582220726.859 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:26.859Z,1582220726.859 [NavChartDb](INFO): Join timeout helper Thread ID is 3939
2020-02-20T17:45:27.215Z,1582220727.215 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:27.215Z,1582220727.215 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:27.235Z,1582220727.235 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-02-20T17:45:27.235Z,1582220727.235 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:27.235Z,1582220727.235 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 3940
2020-02-20T17:45:27.587Z,1582220727.587 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:27.588Z,1582220727.588 [WetLabsUBAT](INFO): Powering down
2020-02-20T17:45:27.628Z,1582220727.628 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:27.642Z,1582220727.642 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-02-20T17:45:27.642Z,1582220727.642 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:27.643Z,1582220727.643 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3941
2020-02-20T17:45:27.959Z,1582220727.959 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:27.959Z,1582220727.959 [WetLabsBB2FL](INFO): Powering down
2020-02-20T17:45:27.960Z,1582220727.960 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:27.960Z,1582220727.960 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-02-20T17:45:27.960Z,1582220727.960 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:27.961Z,1582220727.961 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3942
2020-02-20T17:45:28.595Z,1582220728.595 [CTD_Seabird](INFO): Powering down
2020-02-20T17:45:28.607Z,1582220728.607 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:28.607Z,1582220728.607 [CTD_Seabird](INFO): Powering down
2020-02-20T17:45:28.619Z,1582220728.619 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:28.635Z,1582220728.635 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-02-20T17:45:28.635Z,1582220728.635 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:28.636Z,1582220728.636 [BackSeatDriver](INFO): Join timeout helper Thread ID is 3946
2020-02-20T17:45:28.711Z,1582220728.711 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:28.712Z,1582220728.712 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:28.715Z,1582220728.715 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-20T17:45:28.715Z,1582220728.715 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:28.716Z,1582220728.716 [Radio_Surface](INFO): Join timeout helper Thread ID is 3947
2020-02-20T17:45:29.115Z,1582220729.115 [Radio_Surface](INFO): Powering down
2020-02-20T17:45:29.116Z,1582220729.116 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:29.116Z,1582220729.116 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:29.135Z,1582220729.135 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-20T17:45:29.135Z,1582220729.135 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:29.136Z,1582220729.136 [DataOverHttps](INFO): Join timeout helper Thread ID is 3948
2020-02-20T17:45:29.859Z,1582220729.859 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:29.859Z,1582220729.859 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:29.879Z,1582220729.879 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-20T17:45:29.879Z,1582220729.879 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:29.880Z,1582220729.880 [logger](INFO): Join timeout helper Thread ID is 3949
2020-02-20T17:45:29.890Z,1582220729.890 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:29.891Z,1582220729.891 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:29.899Z,1582220729.899 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-20T17:45:29.899Z,1582220729.899 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:29.899Z,1582220729.899 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-20T17:45:29.899Z,1582220729.899 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:29.900Z,1582220729.900 [controlThread](INFO): Join timeout helper Thread ID is 3950
2020-02-20T17:45:29.930Z,1582220729.930 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-20T17:45:29.931Z,1582220729.931 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-20T17:45:29.931Z,1582220729.931 [AHRS_M2](INFO): Powering down
2020-02-20T17:45:30.003Z,1582220730.003 [NAL9602](INFO): Powering down
2020-02-20T17:45:30.004Z,1582220730.004 [RDI_Pathfinder](INFO): Powering down
2020-02-20T17:45:30.005Z,1582220730.005 [DAT](INFO): Powering down
2020-02-20T17:45:30.124Z,1582220730.124 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-20T17:45:30.124Z,1582220730.124 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-20T17:45:30.125Z,1582220730.125 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-20T17:45:30.125Z,1582220730.125 [MissionManager](INFO): Uninitializing Mission Default
2020-02-20T17:45:30.128Z,1582220730.128 [MissionManager](INFO): Uninitializing Mission Startup
2020-02-20T17:45:30.129Z,1582220730.129 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-20T17:45:30.129Z,1582220730.129 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-20T17:45:30.129Z,1582220730.129 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-20T17:45:30.130Z,1582220730.130 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-20T17:45:30.130Z,1582220730.130 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-20T17:45:30.130Z,1582220730.130 [BuoyancyServo](INFO): Powering down
2020-02-20T17:45:30.142Z,1582220730.142 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-20T17:45:30.142Z,1582220730.142 [ElevatorServo](INFO): Powering down
2020-02-20T17:45:30.143Z,1582220730.143 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-02-20T17:45:30.143Z,1582220730.143 [MassServo](INFO): Powering down
2020-02-20T17:45:30.144Z,1582220730.144 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-20T17:45:30.144Z,1582220730.144 [RudderServo](INFO): Powering down
2020-02-20T17:45:30.145Z,1582220730.145 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-20T17:45:30.145Z,1582220730.145 [ThrusterServo](INFO): Powering down
2020-02-20T17:45:30.146Z,1582220730.146 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-20T17:45:30.146Z,1582220730.146 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-20T17:45:30.146Z,1582220730.146 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-20T17:45:30.147Z,1582220730.147 [CBIT](DEBUG): Powering off loads.
2020-02-20T17:45:30.158Z,1582220730.158 [CBIT](DEBUG): Disabling WDT.
2020-02-20T17:45:30.170Z,1582220730.170 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-20T17:45:30.171Z,1582220730.171 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:30.260Z,1582220730.260 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:30.266Z,1582220730.266 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:30.443Z,1582220730.443 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:30.445Z,1582220730.445 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2020-02-20T17:45:30.445Z,1582220730.445 [WetLabsUBAT](INFO): WetLabs destructor: done
2020-02-20T17:45:30.448Z,1582220730.448 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:30.451Z,1582220730.451 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:30.454Z,1582220730.454 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:30.463Z,1582220730.463 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-20T17:45:30.522Z,1582220730.522 [logger ThreadHandler](INFO): Thread cancelled.