2020-08-26T19:32:20.920Z,1598470340.920 [Supervisor](DEBUG): Initializing supervisor.
2020-08-26T19:32:20.923Z,1598470340.923 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-08-26T19:32:20.924Z,1598470340.924 [SyncHandler](INFO): Protected caller Thread ID is 4932
2020-08-26T19:32:20.924Z,1598470340.924 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-08-26T19:32:20.925Z,1598470340.925 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-08-26T19:32:20.925Z,1598470340.925 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4933
2020-08-26T19:32:20.929Z,1598470340.929 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-08-26T19:32:21.051Z,1598470341.051 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-08-26T19:32:21.052Z,1598470341.052 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-08-26T19:32:21.052Z,1598470341.052 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4936
2020-08-26T19:32:21.053Z,1598470341.053 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-08-26T19:32:21.054Z,1598470341.054 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-08-26T19:32:21.054Z,1598470341.054 [logger ThreadHandler](INFO): Protected caller Thread ID is 4937
2020-08-26T19:32:21.056Z,1598470341.056 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-08-26T19:32:21.056Z,1598470341.056 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-08-26T19:32:21.058Z,1598470341.058 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-08-26T19:32:21.154Z,1598470341.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-08-26T19:32:21.154Z,1598470341.154 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-08-26T19:32:21.812Z,1598470341.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-08-26T19:32:21.813Z,1598470341.813 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-08-26T19:32:22.002Z,1598470342.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-08-26T19:32:22.003Z,1598470342.003 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-08-26T19:32:22.140Z,1598470342.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-08-26T19:32:22.140Z,1598470342.140 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-08-26T19:32:22.242Z,1598470342.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-08-26T19:32:22.243Z,1598470342.243 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-08-26T19:32:22.324Z,1598470342.324 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-08-26T19:32:22.498Z,1598470342.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-08-26T19:32:22.498Z,1598470342.498 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-08-26T19:32:22.819Z,1598470342.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-08-26T19:32:22.820Z,1598470342.820 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-08-26T19:32:23.302Z,1598470343.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-08-26T19:32:23.302Z,1598470343.302 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-08-26T19:32:23.482Z,1598470343.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-08-26T19:32:23.482Z,1598470343.482 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-08-26T19:32:23.673Z,1598470343.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-08-26T19:32:23.673Z,1598470343.673 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-08-26T19:32:24.208Z,1598470344.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-08-26T19:32:24.208Z,1598470344.208 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-08-26T19:32:24.422Z,1598470344.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-08-26T19:32:24.423Z,1598470344.423 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-08-26T19:32:24.659Z,1598470344.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-08-26T19:32:24.660Z,1598470344.660 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-08-26T19:32:25.098Z,1598470345.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-08-26T19:32:25.099Z,1598470345.099 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-08-26T19:32:25.460Z,1598470345.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-08-26T19:32:25.462Z,1598470345.462 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-08-26T19:32:25.463Z,1598470345.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-08-26T19:32:25.575Z,1598470345.575 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-08-26T19:32:25.777Z,1598470345.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-08-26T19:32:26.005Z,1598470346.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-08-26T19:32:26.288Z,1598470346.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-08-26T19:32:26.394Z,1598470346.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-08-26T19:32:26.710Z,1598470346.710 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-08-26T19:32:26.940Z,1598470346.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-08-26T19:32:26.940Z,1598470346.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-08-26T19:32:27.071Z,1598470347.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-08-26T19:32:27.170Z,1598470347.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-08-26T19:32:27.300Z,1598470347.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-08-26T19:32:27.437Z,1598470347.437 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/LOGIN/
2020-08-26T19:32:27.437Z,1598470347.437 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-08-26T19:32:27.451Z,1598470347.451 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-08-26T19:32:27.527Z,1598470347.527 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-08-26T19:32:27.527Z,1598470347.527 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-08-26T19:32:27.573Z,1598470347.573 [VerticalControl](DEBUG): Construct VerticalControl.
2020-08-26T19:32:27.680Z,1598470347.680 [VerticalControl] Loaded
2020-08-26T19:32:27.681Z,1598470347.681 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-08-26T19:32:27.681Z,1598470347.681 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-08-26T19:32:27.795Z,1598470347.795 [HorizontalControl] Loaded
2020-08-26T19:32:27.795Z,1598470347.795 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-08-26T19:32:27.796Z,1598470347.796 [SpeedControl](DEBUG): Construct SpeedControl.
2020-08-26T19:32:27.801Z,1598470347.801 [SpeedControl] Loaded
2020-08-26T19:32:27.801Z,1598470347.801 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-08-26T19:32:27.802Z,1598470347.802 [LoopControl](DEBUG): Construct LoopControl.
2020-08-26T19:32:27.802Z,1598470347.802 [LoopControl] Loaded
2020-08-26T19:32:27.803Z,1598470347.803 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-08-26T19:32:27.803Z,1598470347.803 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-08-26T19:32:27.803Z,1598470347.803 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-08-26T19:32:27.844Z,1598470347.844 [DepthRateCalculator] Loaded
2020-08-26T19:32:27.845Z,1598470347.845 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-08-26T19:32:27.850Z,1598470347.850 [PitchRateCalculator] Loaded
2020-08-26T19:32:27.850Z,1598470347.850 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-08-26T19:32:27.865Z,1598470347.865 [SpeedCalculator] Loaded
2020-08-26T19:32:27.865Z,1598470347.865 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-08-26T19:32:27.885Z,1598470347.885 [TempGradientCalculator] Loaded
2020-08-26T19:32:27.885Z,1598470347.885 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-08-26T19:32:27.890Z,1598470347.890 [YawRateCalculator] Loaded
2020-08-26T19:32:27.890Z,1598470347.890 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-08-26T19:32:27.918Z,1598470347.918 [ElevatorOffsetCalculator] Loaded
2020-08-26T19:32:27.918Z,1598470347.918 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-08-26T19:32:27.919Z,1598470347.919 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-08-26T19:32:27.919Z,1598470347.919 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-08-26T19:32:27.946Z,1598470347.946 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-08-26T19:32:27.946Z,1598470347.946 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-08-26T19:32:28.032Z,1598470348.032 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-08-26T19:32:28.033Z,1598470348.033 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-08-26T19:32:28.352Z,1598470348.352 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-08-26T19:32:28.353Z,1598470348.353 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-08-26T19:32:28.470Z,1598470348.470 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-08-26T19:32:28.471Z,1598470348.471 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-08-26T19:32:28.817Z,1598470348.817 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-08-26T19:32:28.822Z,1598470348.822 [AHRS_M2](INFO): created writer for : platform_orientation
2020-08-26T19:32:28.832Z,1598470348.832 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-08-26T19:32:28.837Z,1598470348.837 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-08-26T19:32:28.838Z,1598470348.838 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-08-26T19:32:28.851Z,1598470348.851 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-08-26T19:32:28.852Z,1598470348.852 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-08-26T19:32:28.857Z,1598470348.857 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-08-26T19:32:28.925Z,1598470348.925 [AHRS_M2] Loaded
2020-08-26T19:32:28.925Z,1598470348.925 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-08-26T19:32:28.000Z,1598470349.000 [DataOverHttps] Loaded
2020-08-26T19:32:29.000Z,1598470349.000 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-08-26T19:32:29.001Z,1598470349.001 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2020-08-26T19:32:29.002Z,1598470349.002 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5063
2020-08-26T19:32:29.031Z,1598470349.031 [Depth_Keller] Loaded
2020-08-26T19:32:29.031Z,1598470349.031 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-08-26T19:32:29.140Z,1598470349.140 [NAL9602] Loaded
2020-08-26T19:32:29.141Z,1598470349.141 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-08-26T19:32:29.239Z,1598470349.239 [Onboard] Loaded
2020-08-26T19:32:29.239Z,1598470349.239 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-08-26T19:32:29.240Z,1598470349.240 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0
2020-08-26T19:32:29.240Z,1598470349.240 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5066
2020-08-26T19:32:29.259Z,1598470349.259 [Radio_Surface] Loaded
2020-08-26T19:32:29.259Z,1598470349.259 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-08-26T19:32:29.260Z,1598470349.260 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0
2020-08-26T19:32:29.260Z,1598470349.260 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5067
2020-08-26T19:32:29.406Z,1598470349.406 [DAT] Loaded
2020-08-26T19:32:29.406Z,1598470349.406 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-08-26T19:32:31.060Z,1598470351.060 [BPC1] Loaded
2020-08-26T19:32:31.061Z,1598470351.061 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-08-26T19:32:31.092Z,1598470351.092 [AMEcho] Loaded
2020-08-26T19:32:31.092Z,1598470351.092 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2020-08-26T19:32:31.092Z,1598470351.092 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-08-26T19:32:31.093Z,1598470351.093 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-08-26T19:32:31.553Z,1598470351.553 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-08-26T19:32:31.553Z,1598470351.553 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-08-26T19:32:31.573Z,1598470351.573 [NavChart] Loaded
2020-08-26T19:32:31.573Z,1598470351.573 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-08-26T19:32:31.577Z,1598470351.577 [UniversalFixResidualReporter] Loaded
2020-08-26T19:32:31.577Z,1598470351.577 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-08-26T19:32:31.578Z,1598470351.578 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-08-26T19:32:31.578Z,1598470351.578 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-08-26T19:32:31.746Z,1598470351.746 [SBIT](DEBUG): Construct Startup Built In Test.
2020-08-26T19:32:31.757Z,1598470351.757 [SBIT] Loaded
2020-08-26T19:32:31.757Z,1598470351.757 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-08-26T19:32:31.758Z,1598470351.758 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-08-26T19:32:31.770Z,1598470351.770 [IBIT] Loaded
2020-08-26T19:32:31.770Z,1598470351.770 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-08-26T19:32:31.773Z,1598470351.773 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-08-26T19:32:31.907Z,1598470351.907 [CBIT] Loaded
2020-08-26T19:32:31.907Z,1598470351.907 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-08-26T19:32:31.908Z,1598470351.908 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-08-26T19:32:31.908Z,1598470351.908 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-08-26T19:32:32.026Z,1598470352.026 [BuoyancyServo] Loaded
2020-08-26T19:32:32.027Z,1598470352.027 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-08-26T19:32:32.091Z,1598470352.091 [ElevatorServo] Loaded
2020-08-26T19:32:32.091Z,1598470352.091 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-08-26T19:32:32.118Z,1598470352.118 [MassServo] Loaded
2020-08-26T19:32:32.119Z,1598470352.119 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-08-26T19:32:32.146Z,1598470352.146 [RudderServo] Loaded
2020-08-26T19:32:32.146Z,1598470352.146 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-08-26T19:32:32.172Z,1598470352.172 [ThrusterServo] Loaded
2020-08-26T19:32:32.172Z,1598470352.172 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-08-26T19:32:32.173Z,1598470352.173 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-08-26T19:32:32.173Z,1598470352.173 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-08-26T19:32:32.192Z,1598470352.192 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-08-26T19:32:32.192Z,1598470352.192 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-08-26T19:32:32.468Z,1598470352.468 [BackSeatDriver] Loaded
2020-08-26T19:32:32.468Z,1598470352.468 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-08-26T19:32:32.469Z,1598470352.469 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0
2020-08-26T19:32:32.470Z,1598470352.470 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 5086
2020-08-26T19:32:32.497Z,1598470352.497 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-08-26T19:32:32.503Z,1598470352.503 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-08-26T19:32:32.503Z,1598470352.503 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-08-26T19:32:32.508Z,1598470352.508 [CTD_Seabird](INFO): created writer for : depth
2020-08-26T19:32:32.508Z,1598470352.508 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-08-26T19:32:32.514Z,1598470352.514 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-08-26T19:32:32.515Z,1598470352.515 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-08-26T19:32:32.520Z,1598470352.520 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-08-26T19:32:32.520Z,1598470352.520 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-08-26T19:32:32.525Z,1598470352.525 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-08-26T19:32:32.526Z,1598470352.526 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-08-26T19:32:32.531Z,1598470352.531 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-08-26T19:32:32.532Z,1598470352.532 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-08-26T19:32:32.537Z,1598470352.537 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-08-26T19:32:32.564Z,1598470352.564 [CTD_Seabird] Loaded
2020-08-26T19:32:32.564Z,1598470352.564 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-08-26T19:32:32.565Z,1598470352.565 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B334E0
2020-08-26T19:32:32.565Z,1598470352.565 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5087
2020-08-26T19:32:32.587Z,1598470352.587 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-08-26T19:32:32.587Z,1598470352.587 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-08-26T19:32:32.591Z,1598470352.591 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-08-26T19:32:32.591Z,1598470352.591 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-08-26T19:32:32.595Z,1598470352.595 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-08-26T19:32:32.595Z,1598470352.595 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-08-26T19:32:32.599Z,1598470352.599 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-08-26T19:32:32.600Z,1598470352.600 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-08-26T19:32:32.603Z,1598470352.603 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-08-26T19:32:32.604Z,1598470352.604 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-08-26T19:32:32.608Z,1598470352.608 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-08-26T19:32:32.608Z,1598470352.608 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-08-26T19:32:32.612Z,1598470352.612 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-08-26T19:32:32.612Z,1598470352.612 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-08-26T19:32:32.616Z,1598470352.616 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T19:32:32.621Z,1598470352.621 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T19:32:32.621Z,1598470352.621 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T19:32:32.621Z,1598470352.621 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T19:32:32.625Z,1598470352.625 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T19:32:32.626Z,1598470352.626 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T19:32:32.630Z,1598470352.630 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T19:32:32.630Z,1598470352.630 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-26T19:32:32.634Z,1598470352.634 [WetLabsBB2FL] Loaded
2020-08-26T19:32:32.634Z,1598470352.634 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-08-26T19:32:32.635Z,1598470352.635 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2020-08-26T19:32:32.635Z,1598470352.635 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5088
2020-08-26T19:32:32.636Z,1598470352.636 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-08-26T19:32:32.640Z,1598470352.640 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-08-26T19:32:32.641Z,1598470352.641 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-08-26T19:32:32.648Z,1598470352.648 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-08-26T19:32:32.649Z,1598470352.649 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0
2020-08-26T19:32:32.649Z,1598470352.649 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5089
2020-08-26T19:32:32.653Z,1598470352.653 [Supervisor](INFO): Main Thread ID is 4929
2020-08-26T19:32:32.654Z,1598470352.654 [Supervisor](DEBUG): Running supervisor.
2020-08-26T19:32:32.654Z,1598470352.654 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5090
2020-08-26T19:32:32.656Z,1598470352.656 [controlThread ThreadHandler](INFO): Handler Thread ID is 5091
2020-08-26T19:32:32.657Z,1598470352.657 [controlThread](DEBUG): Initializing ControlThread
2020-08-26T19:32:32.658Z,1598470352.658 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-08-26T19:32:32.659Z,1598470352.659 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-08-26T19:32:32.660Z,1598470352.660 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-08-26T19:32:32.660Z,1598470352.660 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-08-26T19:32:32.661Z,1598470352.661 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-08-26T19:32:32.661Z,1598470352.661 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-08-26T19:32:32.661Z,1598470352.661 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-08-26T19:32:32.662Z,1598470352.662 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-08-26T19:32:32.662Z,1598470352.662 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-08-26T19:32:32.663Z,1598470352.663 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-08-26T19:32:32.665Z,1598470352.665 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-08-26T19:32:32.666Z,1598470352.666 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-08-26T19:32:32.666Z,1598470352.666 [SBIT](INFO): Initialize SBIT Component.
2020-08-26T19:32:32.667Z,1598470352.667 [SBIT](IMPORTANT): git: 2020-08-18a-33-g45ef95c
2020-08-26T19:32:32.667Z,1598470352.667 [SBIT](INFO): git hash: 45ef95c9c37b919513b8f2d02e2e9e14cb3befbe
2020-08-26T19:32:32.667Z,1598470352.667 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-08-26T19:32:32.668Z,1598470352.668 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-08-26T19:32:32.669Z,1598470352.669 [SBIT](INFO): Beginning SBIT in 65.000000 seconds.
2020-08-26T19:32:32.670Z,1598470352.670 [IBIT](INFO): Initialize IBIT Component.
2020-08-26T19:32:32.671Z,1598470352.671 [CBIT](DEBUG): Initialize CBIT Component.
2020-08-26T19:32:32.672Z,1598470352.672 [logger ThreadHandler](INFO): Handler Thread ID is 5092
2020-08-26T19:32:32.684Z,1598470352.684 [CBIT](DEBUG): Initialized mux pins.
2020-08-26T19:32:32.684Z,1598470352.684 [CBIT](DEBUG): Initializing the watchdog timer.
2020-08-26T19:32:32.692Z,1598470352.692 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5093
2020-08-26T19:32:32.693Z,1598470352.693 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-08-26T19:32:32.704Z,1598470352.704 [Onboard ThreadHandler](INFO): Handler Thread ID is 5094
2020-08-26T19:32:32.708Z,1598470352.708 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2020-08-26T19:32:32.709Z,1598470352.709 [CBIT](DEBUG): Initializing heartbeat.
2020-08-26T19:32:32.764Z,1598470352.764 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5095
2020-08-26T19:32:32.779Z,1598470352.779 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 5098
2020-08-26T19:32:32.780Z,1598470352.780 [CBIT](DEBUG): Deactivating GF circuits.
2020-08-26T19:32:32.780Z,1598470352.780 [CBIT](DEBUG): Deactivating emergency mode.
2020-08-26T19:32:32.792Z,1598470352.792 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5099
2020-08-26T19:32:32.793Z,1598470352.793 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-08-26T19:32:32.797Z,1598470352.797 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5101
2020-08-26T19:32:32.798Z,1598470352.798 [WetLabsBB2FL](INFO): Powering down
2020-08-26T19:32:32.816Z,1598470352.816 [CBIT](DEBUG): Backplane powered.
2020-08-26T19:32:32.818Z,1598470352.818 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-08-26T19:32:32.829Z,1598470352.829 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-26T19:32:32.849Z,1598470352.849 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5102
2020-08-26T19:32:32.852Z,1598470352.852 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-08-26T19:32:32.852Z,1598470352.852 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-08-26T19:32:32.852Z,1598470352.852 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-08-26T19:32:32.852Z,1598470352.852 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-08-26T19:32:32.853Z,1598470352.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-08-26T19:32:32.853Z,1598470352.853 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-08-26T19:32:32.853Z,1598470352.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-08-26T19:32:32.853Z,1598470352.853 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-08-26T19:32:32.853Z,1598470352.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-08-26T19:32:32.854Z,1598470352.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-08-26T19:32:32.854Z,1598470352.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-08-26T19:32:32.854Z,1598470352.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-08-26T19:32:32.854Z,1598470352.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-08-26T19:32:32.854Z,1598470352.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-08-26T19:32:32.854Z,1598470352.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-08-26T19:32:32.855Z,1598470352.855 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-08-26T19:32:32.880Z,1598470352.880 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2020-08-26T19:32:32.881Z,1598470352.881 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-08-26T19:32:32.952Z,1598470352.952 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-08-26T19:32:32.953Z,1598470352.953 [Default:A.Wait](DEBUG): Construct Wait.
2020-08-26T19:32:32.954Z,1598470352.954 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-26T19:32:32.990Z,1598470352.990 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-08-26T19:32:32.993Z,1598470352.993 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-08-26T19:32:33.015Z,1598470353.015 [Default:E.Execute](DEBUG): Construct Execute.
2020-08-26T19:32:33.018Z,1598470353.018 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-08-26T19:32:33.027Z,1598470353.027 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,AMEcho,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-08-26T19:32:33.045Z,1598470353.045 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-26T19:32:33.168Z,1598470353.168 [Radio_Surface](INFO): Powering up
2020-08-26T19:32:33.182Z,1598470353.182 [DAT](INFO): Start
2020-08-26T19:32:33.205Z,1598470353.205 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-08-26T19:32:33.205Z,1598470353.205 [DAT](INFO): Powering up
2020-08-26T19:32:33.205Z,1598470353.205 [DAT](DEBUG): Initializing DAT.
2020-08-26T19:32:33.252Z,1598470353.252 [AMEcho](INFO): Powering up
2020-08-26T19:32:33.256Z,1598470353.256 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-08-26T19:32:33.316Z,1598470353.316 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-08-26T19:32:33.328Z,1598470353.328 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-08-26T19:32:33.330Z,1598470353.330 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-08-26T19:32:33.340Z,1598470353.340 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-08-26T19:32:33.341Z,1598470353.341 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-26T19:32:33.352Z,1598470353.352 [MassServo](DEBUG): Initializing MassServo.
2020-08-26T19:32:33.353Z,1598470353.353 [RudderServo](DEBUG): Initializing EZServoServo.
2020-08-26T19:32:33.364Z,1598470353.364 [RudderServo](DEBUG): Initializing RudderServo.
2020-08-26T19:32:33.365Z,1598470353.365 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-08-26T19:32:33.376Z,1598470353.376 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-08-26T19:32:33.379Z,1598470353.379 [CommandLine](FAULT): Scheduling is paused
2020-08-26T19:32:33.379Z,1598470353.379 [CBIT](INFO): Critical error at 20200826T193232
2020-08-26T19:32:33.379Z,1598470353.379 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-08-26T19:32:33.457Z,1598470353.457 [DAT](INFO): Starting
2020-08-26T19:32:33.624Z,1598470353.624 [AMEcho](INFO): Powering down
2020-08-26T19:32:33.841Z,1598470353.841 [DAT](INFO): Starting
2020-08-26T19:32:34.245Z,1598470354.245 [DAT](INFO): Starting
2020-08-26T19:32:34.649Z,1598470354.649 [DAT](INFO): Starting
2020-08-26T19:32:35.053Z,1598470355.053 [DAT](INFO): Starting
2020-08-26T19:32:35.457Z,1598470355.457 [DAT](INFO): Starting
2020-08-26T19:32:35.861Z,1598470355.861 [DAT](INFO): Starting
2020-08-26T19:32:36.333Z,1598470356.333 [DAT](INFO): Starting
2020-08-26T19:32:36.693Z,1598470356.693 [DAT](INFO): Starting
2020-08-26T19:32:37.109Z,1598470357.109 [DAT](INFO): Starting
2020-08-26T19:32:37.566Z,1598470357.566 [DAT](INFO): Starting
2020-08-26T19:32:37.941Z,1598470357.941 [DAT](INFO): Starting
2020-08-26T19:32:38.342Z,1598470358.342 [DAT](INFO): Starting
2020-08-26T19:32:38.749Z,1598470358.749 [DAT](INFO): Starting
2020-08-26T19:32:39.171Z,1598470359.171 [DAT](INFO): Starting
2020-08-26T19:32:39.545Z,1598470359.545 [DAT](INFO): Starting
2020-08-26T19:32:39.824Z,1598470359.824 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-08-26T19:32:39.961Z,1598470359.961 [DAT](INFO): Starting
2020-08-26T19:32:40.362Z,1598470360.362 [DAT](INFO): Starting
2020-08-26T19:32:40.769Z,1598470360.769 [DAT](INFO): Starting
2020-08-26T19:32:41.161Z,1598470361.161 [DAT](INFO): Starting
2020-08-26T19:32:41.569Z,1598470361.569 [DAT](INFO): Starting
2020-08-26T19:32:41.957Z,1598470361.957 [DAT](INFO): Starting
2020-08-26T19:32:42.357Z,1598470362.357 [DAT](INFO): Starting
2020-08-26T19:32:42.765Z,1598470362.765 [DAT](INFO): Starting
2020-08-26T19:32:43.161Z,1598470363.161 [DAT](INFO): Starting
2020-08-26T19:32:43.597Z,1598470363.597 [DAT](INFO): Starting
2020-08-26T19:32:43.598Z,1598470363.598 [DAT](INFO): DAT read: 

2020-08-26T19:32:43.601Z,1598470363.601 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2020-08-26T19:32:43.969Z,1598470363.969 [DAT](INFO): Starting
2020-08-26T19:32:44.373Z,1598470364.373 [DAT](INFO): Starting
2020-08-26T19:32:44.797Z,1598470364.797 [DAT](INFO): Starting
2020-08-26T19:32:44.798Z,1598470364.798 [DAT](INFO): DAT read: MF Frequency Band

2020-08-26T19:32:44.799Z,1598470364.799 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.12.21

2020-08-26T19:32:44.804Z,1598470364.804 [DAT](INFO): DAT read: Aug 26 2020 19:32:13 

2020-08-26T19:32:45.181Z,1598470365.181 [DAT](INFO): Starting
2020-08-26T19:32:45.585Z,1598470365.585 [DAT](INFO): Starting
2020-08-26T19:32:45.586Z,1598470365.586 [DAT](INFO): DAT read: Features enabled [Bearing]

2020-08-26T19:32:45.587Z,1598470365.587 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2020-08-26T19:32:45.588Z,1598470365.588 [DAT](INFO): commRate: 800
2020-08-26T19:32:45.588Z,1598470365.588 [DAT](INFO): commRate: 800
2020-08-26T19:32:45.989Z,1598470365.989 [DAT](INFO): Starting
2020-08-26T19:32:45.990Z,1598470365.990 [DAT](INFO): entering command mode
2020-08-26T19:32:46.397Z,1598470366.397 [DAT](INFO): Starting
2020-08-26T19:32:46.397Z,1598470366.397 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:46.730Z,1598470366.730 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004957
2020-08-26T19:32:46.793Z,1598470366.793 [DAT](INFO): Starting
2020-08-26T19:32:46.793Z,1598470366.793 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:47.201Z,1598470367.201 [DAT](INFO): Starting
2020-08-26T19:32:47.201Z,1598470367.201 [DAT](INFO): DAT read: 

2020-08-26T19:32:47.202Z,1598470367.202 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:47.613Z,1598470367.613 [DAT](INFO): Starting
2020-08-26T19:32:47.613Z,1598470367.613 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:48.009Z,1598470368.009 [DAT](INFO): Starting
2020-08-26T19:32:48.009Z,1598470368.009 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:48.413Z,1598470368.413 [DAT](INFO): Starting
2020-08-26T19:32:48.413Z,1598470368.413 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:48.817Z,1598470368.817 [DAT](INFO): Starting
2020-08-26T19:32:48.817Z,1598470368.817 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:49.221Z,1598470369.221 [DAT](INFO): Starting
2020-08-26T19:32:49.221Z,1598470369.221 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:49.662Z,1598470369.662 [DAT](INFO): Starting
2020-08-26T19:32:49.662Z,1598470369.662 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:50.029Z,1598470370.029 [DAT](INFO): Starting
2020-08-26T19:32:50.029Z,1598470370.029 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:50.433Z,1598470370.433 [DAT](INFO): Starting
2020-08-26T19:32:50.433Z,1598470370.433 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:50.837Z,1598470370.837 [DAT](INFO): Starting
2020-08-26T19:32:50.837Z,1598470370.837 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:51.257Z,1598470371.257 [DAT](INFO): Starting
2020-08-26T19:32:51.257Z,1598470371.257 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:51.645Z,1598470371.645 [DAT](INFO): Starting
2020-08-26T19:32:51.645Z,1598470371.645 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:52.049Z,1598470372.049 [DAT](INFO): Starting
2020-08-26T19:32:52.049Z,1598470372.049 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:52.453Z,1598470372.453 [DAT](INFO): Starting
2020-08-26T19:32:52.453Z,1598470372.453 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:52.882Z,1598470372.882 [DAT](INFO): Starting
2020-08-26T19:32:52.883Z,1598470372.883 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:53.261Z,1598470373.261 [DAT](INFO): Starting
2020-08-26T19:32:53.261Z,1598470373.261 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:53.665Z,1598470373.665 [DAT](INFO): Starting
2020-08-26T19:32:53.665Z,1598470373.665 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:54.069Z,1598470374.069 [DAT](INFO): Starting
2020-08-26T19:32:54.069Z,1598470374.069 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:54.473Z,1598470374.473 [DAT](INFO): Starting
2020-08-26T19:32:54.473Z,1598470374.473 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:54.877Z,1598470374.877 [DAT](INFO): Starting
2020-08-26T19:32:54.877Z,1598470374.877 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:55.281Z,1598470375.281 [DAT](INFO): Starting
2020-08-26T19:32:55.281Z,1598470375.281 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:55.705Z,1598470375.705 [DAT](INFO): Starting
2020-08-26T19:32:55.706Z,1598470375.706 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:56.125Z,1598470376.125 [DAT](INFO): Starting
2020-08-26T19:32:56.126Z,1598470376.126 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:56.505Z,1598470376.505 [DAT](INFO): Starting
2020-08-26T19:32:56.506Z,1598470376.506 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:56.913Z,1598470376.913 [DAT](INFO): Starting
2020-08-26T19:32:56.913Z,1598470376.913 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:57.317Z,1598470377.317 [DAT](INFO): Starting
2020-08-26T19:32:57.317Z,1598470377.317 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:57.721Z,1598470377.721 [DAT](INFO): Starting
2020-08-26T19:32:57.721Z,1598470377.721 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:58.125Z,1598470378.125 [DAT](INFO): Starting
2020-08-26T19:32:58.125Z,1598470378.125 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:58.537Z,1598470378.537 [DAT](INFO): Starting
2020-08-26T19:32:58.537Z,1598470378.537 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:58.937Z,1598470378.937 [DAT](INFO): Starting
2020-08-26T19:32:58.937Z,1598470378.937 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:59.337Z,1598470379.337 [NAL9602](INFO): Powering up NAL9602
2020-08-26T19:32:59.338Z,1598470379.338 [DAT](INFO): Starting
2020-08-26T19:32:59.339Z,1598470379.339 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:32:59.741Z,1598470379.741 [DAT](INFO): Starting
2020-08-26T19:32:59.742Z,1598470379.742 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:33:00.145Z,1598470380.145 [DAT](INFO): Starting
2020-08-26T19:33:00.145Z,1598470380.145 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:33:00.549Z,1598470380.549 [DAT](INFO): Starting
2020-08-26T19:33:00.549Z,1598470380.549 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:33:00.953Z,1598470380.953 [DAT](INFO): Starting
2020-08-26T19:33:00.953Z,1598470380.953 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:33:01.357Z,1598470381.357 [DAT](INFO): Starting
2020-08-26T19:33:01.357Z,1598470381.357 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-26T19:33:01.357Z,1598470381.357 [DAT](FAULT): failed to enter command mode
2020-08-26T19:33:01.763Z,1598470381.763 [DAT](INFO): Starting
2020-08-26T19:33:01.764Z,1598470381.764 [DAT](INFO): entering command mode
2020-08-26T19:33:02.191Z,1598470382.191 [DAT](INFO): Starting
2020-08-26T19:33:02.191Z,1598470382.191 [DAT](INFO): DAT read: user:1>

2020-08-26T19:33:02.192Z,1598470382.192 [DAT](DEBUG): read user prompt 1: user:1>

2020-08-26T19:33:02.193Z,1598470382.193 [DAT](INFO): DAT read: Command '+++' not found

2020-08-26T19:33:02.193Z,1598470382.193 [DAT](INFO): DAT read: Error

2020-08-26T19:33:02.193Z,1598470382.193 [DAT](INFO): setting verbose to 3
2020-08-26T19:33:02.565Z,1598470382.565 [DAT](INFO): Starting
2020-08-26T19:33:02.566Z,1598470382.566 [DAT](INFO): DAT read: user:2>

2020-08-26T19:33:02.567Z,1598470382.567 [DAT](INFO): DAT read: Verbose         | 3           

2020-08-26T19:33:02.567Z,1598470382.567 [DAT](INFO): set verbose to 3
2020-08-26T19:33:02.567Z,1598470382.567 [DAT](INFO): setting DatVerbose to 27440
2020-08-26T19:33:02.973Z,1598470382.973 [DAT](INFO): Starting
2020-08-26T19:33:02.974Z,1598470382.974 [DAT](INFO): DAT read: user:3>

2020-08-26T19:33:02.975Z,1598470382.975 [DAT](INFO): DAT read: DatVerbose      | 27440       

2020-08-26T19:33:02.975Z,1598470382.975 [DAT](INFO): set DatVerbose to 27440
2020-08-26T19:33:02.975Z,1598470382.975 [DAT](INFO): setting transmit power to 8
2020-08-26T19:33:03.394Z,1598470383.394 [DAT](INFO): Starting
2020-08-26T19:33:03.394Z,1598470383.394 [DAT](INFO): DAT read: user:4>

2020-08-26T19:33:03.395Z,1598470383.395 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2020-08-26T19:33:03.396Z,1598470383.396 [DAT](INFO): set transmit power to 8
2020-08-26T19:33:03.400Z,1598470383.400 [DAT](INFO): setting local address to 9
2020-08-26T19:33:03.785Z,1598470383.785 [DAT](INFO): Starting
2020-08-26T19:33:03.785Z,1598470383.785 [DAT](INFO): DAT read: user:5>

2020-08-26T19:33:03.786Z,1598470383.786 [DAT](INFO): DAT read: LocalAddr       | 9           

2020-08-26T19:33:03.787Z,1598470383.787 [DAT](INFO): set local address to 9
2020-08-26T19:33:03.787Z,1598470383.787 [DAT](INFO): Runnable
2020-08-26T19:33:04.185Z,1598470384.185 [DAT](INFO): Pause
2020-08-26T19:33:04.589Z,1598470384.589 [DAT](INFO): Paused
2020-08-26T19:33:04.993Z,1598470384.993 [DAT](INFO): Paused
2020-08-26T19:33:05.401Z,1598470385.401 [DAT](INFO): Paused
2020-08-26T19:33:05.805Z,1598470385.805 [DAT](INFO): Paused
2020-08-26T19:33:06.205Z,1598470386.205 [DAT](INFO): Paused
2020-08-26T19:33:06.609Z,1598470386.609 [DAT](INFO): Paused
2020-08-26T19:33:07.013Z,1598470387.013 [DAT](INFO): Paused
2020-08-26T19:33:07.417Z,1598470387.417 [DAT](INFO): Paused
2020-08-26T19:33:07.821Z,1598470387.821 [DAT](INFO): Paused
2020-08-26T19:33:08.229Z,1598470388.229 [DAT](INFO): Paused
2020-08-26T19:33:08.629Z,1598470388.629 [DAT](INFO): Paused
2020-08-26T19:33:09.033Z,1598470389.033 [DAT](INFO): Paused
2020-08-26T19:33:09.437Z,1598470389.437 [DAT](INFO): Paused
2020-08-26T19:33:09.928Z,1598470389.928 [DAT](INFO): Paused
2020-08-26T19:33:10.265Z,1598470390.265 [NAL9602](INFO): NAL9602 initialized
2020-08-26T19:33:10.266Z,1598470390.266 [DAT](INFO): Paused
2020-08-26T19:33:10.665Z,1598470390.665 [DAT](INFO): Paused
2020-08-26T19:33:11.080Z,1598470391.080 [DAT](INFO): Paused
2020-08-26T19:33:11.488Z,1598470391.488 [DAT](INFO): Paused
2020-08-26T19:33:11.908Z,1598470391.908 [DAT](INFO): Paused
2020-08-26T19:33:12.302Z,1598470392.302 [DAT](INFO): Paused
2020-08-26T19:33:12.784Z,1598470392.784 [DAT](INFO): Paused
2020-08-26T19:33:13.120Z,1598470393.120 [DAT](INFO): Paused
2020-08-26T19:33:13.548Z,1598470393.548 [DAT](INFO): Paused
2020-08-26T19:33:13.947Z,1598470393.947 [DAT](INFO): Paused
2020-08-26T19:33:14.377Z,1598470394.377 [DAT](INFO): Paused
2020-08-26T19:33:14.746Z,1598470394.746 [DAT](INFO): Paused
2020-08-26T19:33:15.149Z,1598470395.149 [DAT](INFO): Paused
2020-08-26T19:33:15.553Z,1598470395.553 [DAT](INFO): Paused
2020-08-26T19:33:15.958Z,1598470395.958 [DAT](INFO): Paused
2020-08-26T19:33:16.361Z,1598470396.361 [DAT](INFO): Paused
2020-08-26T19:33:16.765Z,1598470396.765 [DAT](INFO): Paused
2020-08-26T19:33:17.173Z,1598470397.173 [DAT](INFO): Paused
2020-08-26T19:33:17.592Z,1598470397.592 [DAT](INFO): Paused
2020-08-26T19:33:17.988Z,1598470397.988 [DAT](INFO): Paused
2020-08-26T19:33:18.416Z,1598470398.416 [DAT](INFO): Paused
2020-08-26T19:33:18.796Z,1598470398.796 [DAT](INFO): Paused
2020-08-26T19:33:19.189Z,1598470399.189 [DAT](INFO): Paused
2020-08-26T19:33:19.594Z,1598470399.594 [DAT](INFO): Paused
2020-08-26T19:33:19.997Z,1598470399.997 [DAT](INFO): Paused
2020-08-26T19:33:20.402Z,1598470400.402 [DAT](INFO): Paused
2020-08-26T19:33:20.806Z,1598470400.806 [DAT](INFO): Paused
2020-08-26T19:33:21.216Z,1598470401.216 [DAT](INFO): Paused
2020-08-26T19:33:21.621Z,1598470401.621 [DAT](INFO): Paused
2020-08-26T19:33:22.014Z,1598470402.014 [DAT](INFO): Paused
2020-08-26T19:33:22.421Z,1598470402.421 [DAT](INFO): Paused
2020-08-26T19:33:22.836Z,1598470402.836 [DAT](INFO): Paused
2020-08-26T19:33:23.240Z,1598470403.240 [DAT](INFO): Paused
2020-08-26T19:33:23.664Z,1598470403.664 [DAT](INFO): Paused
2020-08-26T19:33:24.060Z,1598470404.060 [DAT](INFO): Paused
2020-08-26T19:33:24.442Z,1598470404.442 [DAT](INFO): Paused
2020-08-26T19:33:24.845Z,1598470404.845 [DAT](INFO): Paused
2020-08-26T19:33:25.249Z,1598470405.249 [DAT](INFO): Paused
2020-08-26T19:33:25.657Z,1598470405.657 [DAT](INFO): Paused
2020-08-26T19:33:26.057Z,1598470406.057 [DAT](INFO): Paused
2020-08-26T19:33:26.461Z,1598470406.461 [DAT](INFO): Paused
2020-08-26T19:33:26.866Z,1598470406.866 [DAT](INFO): Paused
2020-08-26T19:33:27.276Z,1598470407.276 [DAT](INFO): Paused
2020-08-26T19:33:27.673Z,1598470407.673 [DAT](INFO): Paused
2020-08-26T19:33:28.092Z,1598470408.092 [DAT](INFO): Paused
2020-08-26T19:33:28.492Z,1598470408.492 [DAT](INFO): Paused
2020-08-26T19:33:28.952Z,1598470408.952 [DAT](INFO): Paused
2020-08-26T19:33:29.303Z,1598470409.303 [DAT](INFO): Paused
2020-08-26T19:33:29.693Z,1598470409.693 [DAT](INFO): Paused
2020-08-26T19:33:30.097Z,1598470410.097 [DAT](INFO): Paused
2020-08-26T19:33:30.503Z,1598470410.503 [DAT](INFO): Paused
2020-08-26T19:33:30.909Z,1598470410.909 [DAT](INFO): Paused
2020-08-26T19:33:31.313Z,1598470411.313 [DAT](INFO): Paused
2020-08-26T19:33:31.714Z,1598470411.714 [DAT](INFO): Paused
2020-08-26T19:33:32.121Z,1598470412.121 [DAT](INFO): Paused
2020-08-26T19:33:32.521Z,1598470412.521 [DAT](INFO): Paused
2020-08-26T19:33:32.968Z,1598470412.968 [DAT](INFO): Paused
2020-08-26T19:33:33.368Z,1598470413.368 [DAT](INFO): Paused
2020-08-26T19:33:33.772Z,1598470413.772 [DAT](INFO): Paused
2020-08-26T19:33:34.176Z,1598470414.176 [DAT](INFO): Paused
2020-08-26T19:33:34.570Z,1598470414.570 [DAT](INFO): Paused
2020-08-26T19:33:34.973Z,1598470414.973 [DAT](INFO): Paused
2020-08-26T19:33:35.377Z,1598470415.377 [DAT](INFO): Paused
2020-08-26T19:33:35.781Z,1598470415.781 [DAT](INFO): Paused
2020-08-26T19:33:36.225Z,1598470416.225 [DAT](INFO): Paused
2020-08-26T19:33:36.589Z,1598470416.589 [DAT](INFO): Paused
2020-08-26T19:33:36.994Z,1598470416.994 [DAT](INFO): Paused
2020-08-26T19:33:37.397Z,1598470417.397 [DAT](INFO): Paused
2020-08-26T19:33:37.802Z,1598470417.802 [DAT](INFO): Paused
2020-08-26T19:33:38.220Z,1598470418.220 [DAT](INFO): Paused
2020-08-26T19:33:38.234Z,1598470418.234 [SBIT](IMPORTANT): Beginning Startup BIT
2020-08-26T19:33:38.242Z,1598470418.242 [CBIT](IMPORTANT): Beginning ground fault scan
2020-08-26T19:33:38.624Z,1598470418.624 [DAT](INFO): Paused
2020-08-26T19:33:39.043Z,1598470419.043 [DAT](INFO): Paused
2020-08-26T19:33:39.428Z,1598470419.428 [DAT](INFO): Paused
2020-08-26T19:33:39.835Z,1598470419.835 [DAT](INFO): Paused
2020-08-26T19:33:40.253Z,1598470420.253 [DAT](INFO): Paused
2020-08-26T19:33:40.634Z,1598470420.634 [DAT](INFO): Paused
2020-08-26T19:33:40.812Z,1598470420.812 [CommandLine](IMPORTANT): got command send depth meter modem:6:_.depth
2020-08-26T19:33:41.037Z,1598470421.037 [DAT](INFO): Paused
2020-08-26T19:33:41.442Z,1598470421.442 [DAT](INFO): Resume
2020-08-26T19:33:41.442Z,1598470421.442 [DAT](DEBUG): sending wake-up to local modem
2020-08-26T19:33:41.848Z,1598470421.848 [DAT](INFO): Resuming
2020-08-26T19:33:41.848Z,1598470421.848 [DAT](DEBUG): confirming wake-up of local modem
2020-08-26T19:33:42.270Z,1598470422.270 [DAT](INFO): Runnable
2020-08-26T19:33:42.270Z,1598470422.270 [DAT](INFO): ************** SENDING_FILL_BUFFER **************

2020-08-26T19:33:42.271Z,1598470422.271 [DAT](INFO): modem://6: set _.depth -0.241123 meter
2020-08-26T19:33:42.271Z,1598470422.271 [DAT](INFO): #Outgoing data=1
2020-08-26T19:33:42.276Z,1598470422.276 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-26T19:33:42.682Z,1598470422.682 [DAT](INFO): Runnable
2020-08-26T19:33:43.062Z,1598470423.062 [DAT](INFO): Pause
2020-08-26T19:33:43.484Z,1598470423.484 [DAT](INFO): Paused
2020-08-26T19:33:43.879Z,1598470423.879 [DAT](INFO): Paused
2020-08-26T19:33:44.296Z,1598470424.296 [DAT](INFO): Paused
2020-08-26T19:33:44.688Z,1598470424.688 [DAT](INFO): Paused
2020-08-26T19:33:45.102Z,1598470425.102 [DAT](INFO): Paused
2020-08-26T19:33:45.489Z,1598470425.489 [DAT](INFO): Paused
2020-08-26T19:33:45.885Z,1598470425.885 [DAT](INFO): Paused
2020-08-26T19:33:46.290Z,1598470426.290 [DAT](INFO): Paused
2020-08-26T19:33:46.706Z,1598470426.706 [DAT](INFO): Paused
2020-08-26T19:33:47.118Z,1598470427.118 [DAT](INFO): Paused
2020-08-26T19:33:47.502Z,1598470427.502 [DAT](INFO): Paused
2020-08-26T19:33:47.905Z,1598470427.905 [DAT](INFO): Paused
2020-08-26T19:33:48.321Z,1598470428.321 [DAT](INFO): Paused
2020-08-26T19:33:48.732Z,1598470428.732 [DAT](INFO): Paused
2020-08-26T19:33:49.132Z,1598470429.132 [DAT](INFO): Paused
2020-08-26T19:33:49.256Z,1598470429.256 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010715
CHAN A1 (24V): -0.000860
CHAN A2 (12V): -0.007071
CHAN A3 (5V): -0.002033
CHAN B0 (3.3V): 0.000160
CHAN B1 (3.15aV): -0.000570
CHAN B2 (3.15bV): 0.000080
CHAN B3 (GND): 0.001883
OPEN: 0.005559
Full Scale Calc: 4.765 mA, -1.589 mA
2020-08-26T19:33:49.540Z,1598470429.540 [DAT](INFO): Paused
2020-08-26T19:33:49.944Z,1598470429.944 [DAT](INFO): Paused
2020-08-26T19:33:50.333Z,1598470430.333 [DAT](INFO): Paused
2020-08-26T19:33:50.750Z,1598470430.750 [DAT](INFO): Paused
2020-08-26T19:33:51.138Z,1598470431.138 [DAT](INFO): Paused
2020-08-26T19:33:51.541Z,1598470431.541 [DAT](INFO): Paused
2020-08-26T19:33:51.945Z,1598470431.945 [DAT](INFO): Paused
2020-08-26T19:33:52.350Z,1598470432.350 [DAT](INFO): Paused
2020-08-26T19:33:52.753Z,1598470432.753 [DAT](INFO): Paused
2020-08-26T19:33:53.161Z,1598470433.161 [DAT](INFO): Paused
2020-08-26T19:33:53.582Z,1598470433.582 [DAT](INFO): Paused
2020-08-26T19:33:53.976Z,1598470433.976 [DAT](INFO): Paused
2020-08-26T19:33:54.380Z,1598470434.380 [DAT](INFO): Paused
2020-08-26T19:33:54.800Z,1598470434.800 [DAT](INFO): Paused
2020-08-26T19:33:55.188Z,1598470435.188 [DAT](INFO): Paused
2020-08-26T19:33:55.589Z,1598470435.589 [DAT](INFO): Paused
2020-08-26T19:33:55.998Z,1598470435.998 [DAT](INFO): Paused
2020-08-26T19:33:56.406Z,1598470436.406 [DAT](INFO): Paused
2020-08-26T19:33:56.802Z,1598470436.802 [DAT](INFO): Paused
2020-08-26T19:33:57.206Z,1598470437.206 [DAT](INFO): Paused
2020-08-26T19:33:57.605Z,1598470437.605 [DAT](INFO): Paused
2020-08-26T19:33:58.018Z,1598470438.018 [DAT](INFO): Paused
2020-08-26T19:33:58.418Z,1598470438.418 [DAT](INFO): Paused
2020-08-26T19:33:58.823Z,1598470438.823 [DAT](INFO): Paused
2020-08-26T19:33:59.248Z,1598470439.248 [DAT](INFO): Paused
2020-08-26T19:33:59.636Z,1598470439.636 [DAT](INFO): Paused
2020-08-26T19:34:00.048Z,1598470440.048 [DAT](INFO): Paused
2020-08-26T19:34:00.448Z,1598470440.448 [DAT](INFO): Paused
2020-08-26T19:34:00.846Z,1598470440.846 [DAT](INFO): Paused
2020-08-26T19:34:01.241Z,1598470441.241 [DAT](INFO): Paused
2020-08-26T19:34:01.649Z,1598470441.649 [DAT](INFO): Paused
2020-08-26T19:34:02.062Z,1598470442.062 [DAT](INFO): Paused
2020-08-26T19:34:02.480Z,1598470442.480 [DAT](INFO): Paused
2020-08-26T19:34:02.858Z,1598470442.858 [DAT](INFO): Paused
2020-08-26T19:34:03.266Z,1598470443.266 [DAT](INFO): Paused
2020-08-26T19:34:03.665Z,1598470443.665 [DAT](INFO): Paused
2020-08-26T19:34:04.077Z,1598470444.077 [DAT](INFO): Paused
2020-08-26T19:34:04.492Z,1598470444.492 [DAT](INFO): Paused
2020-08-26T19:34:04.912Z,1598470444.912 [DAT](INFO): Paused
2020-08-26T19:34:05.300Z,1598470445.300 [DAT](INFO): Paused
2020-08-26T19:34:05.696Z,1598470445.696 [DAT](INFO): Paused
2020-08-26T19:34:06.094Z,1598470446.093 [DAT](INFO): Paused
2020-08-26T19:34:06.503Z,1598470446.503 [DAT](INFO): Paused
2020-08-26T19:34:06.897Z,1598470446.897 [DAT](INFO): Paused
2020-08-26T19:34:07.305Z,1598470447.305 [DAT](INFO): Paused
2020-08-26T19:34:07.729Z,1598470447.729 [DAT](INFO): Paused
2020-08-26T19:34:08.110Z,1598470448.110 [DAT](INFO): Paused
2020-08-26T19:34:08.513Z,1598470448.513 [DAT](INFO): Paused
2020-08-26T19:34:08.917Z,1598470448.917 [DAT](INFO): Paused
2020-08-26T19:34:09.321Z,1598470449.321 [DAT](INFO): Paused
2020-08-26T19:34:09.756Z,1598470449.756 [DAT](INFO): Paused
2020-08-26T19:34:10.148Z,1598470450.148 [DAT](INFO): Paused
2020-08-26T19:34:10.548Z,1598470450.548 [DAT](INFO): Paused
2020-08-26T19:34:10.951Z,1598470450.951 [DAT](INFO): Paused
2020-08-26T19:34:11.341Z,1598470451.341 [DAT](INFO): Paused
2020-08-26T19:34:11.745Z,1598470451.745 [DAT](INFO): Paused
2020-08-26T19:34:12.156Z,1598470452.156 [DAT](INFO): Paused
2020-08-26T19:34:12.558Z,1598470452.558 [DAT](INFO): Paused
2020-08-26T19:34:12.957Z,1598470452.957 [DAT](INFO): Paused
2020-08-26T19:34:13.362Z,1598470453.362 [DAT](INFO): Paused
2020-08-26T19:34:13.765Z,1598470453.765 [DAT](INFO): Paused
2020-08-26T19:34:14.176Z,1598470454.176 [DAT](INFO): Paused
2020-08-26T19:34:14.574Z,1598470454.574 [DAT](INFO): Paused
2020-08-26T19:34:14.996Z,1598470454.996 [DAT](INFO): Paused
2020-08-26T19:34:15.398Z,1598470455.398 [DAT](INFO): Paused
2020-08-26T19:34:15.796Z,1598470455.796 [DAT](INFO): Paused
2020-08-26T19:34:16.200Z,1598470456.200 [DAT](INFO): Paused
2020-08-26T19:34:16.593Z,1598470456.593 [DAT](INFO): Paused
2020-08-26T19:34:16.998Z,1598470456.998 [DAT](INFO): Paused
2020-08-26T19:34:17.402Z,1598470457.402 [DAT](INFO): Paused
2020-08-26T19:34:17.805Z,1598470457.805 [DAT](INFO): Paused
2020-08-26T19:34:18.209Z,1598470458.209 [DAT](INFO): Paused
2020-08-26T19:34:18.622Z,1598470458.622 [DAT](INFO): Paused
2020-08-26T19:34:19.027Z,1598470459.027 [DAT](INFO): Paused
2020-08-26T19:34:19.422Z,1598470459.422 [DAT](INFO): Paused
2020-08-26T19:34:19.838Z,1598470459.838 [DAT](INFO): Paused
2020-08-26T19:34:20.248Z,1598470460.248 [DAT](INFO): Paused
2020-08-26T19:34:20.664Z,1598470460.664 [DAT](INFO): Paused
2020-08-26T19:34:21.048Z,1598470461.048 [DAT](INFO): Paused
2020-08-26T19:34:21.460Z,1598470461.460 [DAT](INFO): Paused
2020-08-26T19:34:21.846Z,1598470461.846 [DAT](INFO): Paused
2020-08-26T19:34:22.249Z,1598470462.249 [DAT](INFO): Paused
2020-08-26T19:34:22.665Z,1598470462.665 [DAT](INFO): Paused
2020-08-26T19:34:23.058Z,1598470463.058 [DAT](INFO): Paused
2020-08-26T19:34:23.478Z,1598470463.478 [DAT](INFO): Paused
2020-08-26T19:34:23.898Z,1598470463.898 [DAT](INFO): Paused
2020-08-26T19:34:24.302Z,1598470464.302 [DAT](INFO): Paused
2020-08-26T19:34:24.694Z,1598470464.694 [DAT](INFO): Paused
2020-08-26T19:34:25.077Z,1598470465.077 [DAT](INFO): Paused
2020-08-26T19:34:25.499Z,1598470465.499 [DAT](INFO): Paused
2020-08-26T19:34:25.896Z,1598470465.896 [DAT](INFO): Paused
2020-08-26T19:34:26.304Z,1598470466.304 [DAT](INFO): Paused
2020-08-26T19:34:26.704Z,1598470466.704 [DAT](INFO): Paused
2020-08-26T19:34:27.098Z,1598470467.098 [DAT](INFO): Paused
2020-08-26T19:34:27.501Z,1598470467.501 [DAT](INFO): Paused
2020-08-26T19:34:27.910Z,1598470467.910 [DAT](INFO): Paused
2020-08-26T19:34:28.313Z,1598470468.313 [DAT](INFO): Paused
2020-08-26T19:34:28.717Z,1598470468.717 [DAT](INFO): Paused
2020-08-26T19:34:29.122Z,1598470469.122 [DAT](INFO): Paused
2020-08-26T19:34:29.540Z,1598470469.540 [DAT](INFO): Paused
2020-08-26T19:34:29.930Z,1598470469.930 [DAT](INFO): Paused
2020-08-26T19:34:30.334Z,1598470470.334 [DAT](INFO): Paused
2020-08-26T19:34:30.748Z,1598470470.748 [DAT](INFO): Paused
2020-08-26T19:34:31.152Z,1598470471.152 [DAT](INFO): Paused
2020-08-26T19:34:31.560Z,1598470471.560 [DAT](INFO): Paused
2020-08-26T19:34:31.964Z,1598470471.964 [DAT](INFO): Paused
2020-08-26T19:34:31.987Z,1598470471.987 [SBIT](IMPORTANT): SBIT PASSED
2020-08-26T19:34:31.987Z,1598470471.987 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-08-26T19:34:31.992Z,1598470471.992 [SBIT](IMPORTANT): BackSeatDriver.loadAtStartup=1 bool;

2020-08-26T19:34:31.992Z,1598470471.992 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere;

2020-08-26T19:34:31.992Z,1598470471.992 [SBIT](IMPORTANT): DAT.sbdAddress=6 enum;

2020-08-26T19:34:31.992Z,1598470471.992 [SBIT](IMPORTANT): DAT.surfaceThreshold=-1 meter;

2020-08-26T19:34:31.992Z,1598470471.992 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2020-08-26T19:34:31.993Z,1598470471.993 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-08-26T19:34:31.993Z,1598470471.993 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-08-26T19:34:31.993Z,1598470471.993 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-08-26T19:34:31.993Z,1598470471.993 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-08-26T19:34:31.993Z,1598470471.993 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2020-08-26T19:34:31.993Z,1598470471.993 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=344.685708 cubic_centimeter;

2020-08-26T19:34:31.993Z,1598470471.993 [SBIT](IMPORTANT): VerticalControl.massDefault=11.293600 millimeter;

2020-08-26T19:34:31.993Z,1598470471.993 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2020-08-26T19:34:32.366Z,1598470472.366 [DAT](INFO): Paused
2020-08-26T19:34:32.402Z,1598470472.402 [MissionManager](IMPORTANT): Started mission Startup
2020-08-26T19:34:32.402Z,1598470472.402 [Startup] Running Loop=1
2020-08-26T19:34:32.402Z,1598470472.402 [Startup](DEBUG): Aggregate::initialize Startup
2020-08-26T19:34:32.403Z,1598470472.403 [Startup:A.GoToSurface] Running Loop=1
2020-08-26T19:34:32.403Z,1598470472.403 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-08-26T19:34:32.403Z,1598470472.403 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-08-26T19:34:32.404Z,1598470472.404 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-08-26T19:34:32.404Z,1598470472.404 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-08-26T19:34:32.405Z,1598470472.405 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-08-26T19:34:32.405Z,1598470472.405 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-08-26T19:34:32.406Z,1598470472.406 [Startup:StartupSatComms] Running Loop=1
2020-08-26T19:34:32.406Z,1598470472.406 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-08-26T19:34:32.406Z,1598470472.406 [Startup:StartupSatComms:A] Running Loop=1
2020-08-26T19:34:32.758Z,1598470472.758 [DAT](INFO): Paused
2020-08-26T19:34:32.774Z,1598470472.774 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-08-26T19:34:33.174Z,1598470473.174 [DAT](INFO): Paused
2020-08-26T19:34:33.238Z,1598470473.238 [AMEcho](INFO): Powering up
2020-08-26T19:34:33.565Z,1598470473.565 [DAT](INFO): Paused
2020-08-26T19:34:33.974Z,1598470473.974 [DAT](INFO): Paused
2020-08-26T19:34:34.373Z,1598470474.373 [DAT](INFO): Paused
2020-08-26T19:34:34.817Z,1598470474.817 [DAT](INFO): Paused
2020-08-26T19:34:35.182Z,1598470475.182 [DAT](INFO): Paused
2020-08-26T19:34:35.586Z,1598470475.586 [DAT](INFO): Paused
2020-08-26T19:34:35.000Z,1598470476.000 [DAT](INFO): Paused
2020-08-26T19:34:36.408Z,1598470476.408 [DAT](INFO): Paused
2020-08-26T19:34:36.823Z,1598470476.823 [DAT](INFO): Paused
2020-08-26T19:34:37.224Z,1598470477.224 [DAT](INFO): Paused
2020-08-26T19:34:37.635Z,1598470477.635 [DAT](INFO): Paused
2020-08-26T19:34:38.031Z,1598470478.031 [DAT](INFO): Paused
2020-08-26T19:34:38.425Z,1598470478.425 [DAT](INFO): Paused
2020-08-26T19:34:38.822Z,1598470478.822 [DAT](INFO): Paused
2020-08-26T19:34:39.227Z,1598470479.227 [DAT](INFO): Paused
2020-08-26T19:34:39.639Z,1598470479.639 [DAT](INFO): Paused
2020-08-26T19:34:40.029Z,1598470480.029 [DAT](INFO): Paused
2020-08-26T19:34:40.442Z,1598470480.442 [DAT](INFO): Paused
2020-08-26T19:34:40.853Z,1598470480.853 [DAT](INFO): Paused
2020-08-26T19:34:41.256Z,1598470481.256 [DAT](INFO): Paused
2020-08-26T19:34:41.660Z,1598470481.660 [DAT](INFO): Paused
2020-08-26T19:34:42.064Z,1598470482.064 [DAT](INFO): Paused
2020-08-26T19:34:42.464Z,1598470482.464 [DAT](INFO): Paused
2020-08-26T19:34:42.886Z,1598470482.886 [DAT](INFO): Paused
2020-08-26T19:34:43.274Z,1598470483.274 [DAT](INFO): Paused
2020-08-26T19:34:43.670Z,1598470483.670 [DAT](INFO): Paused
2020-08-26T19:34:44.075Z,1598470484.075 [DAT](INFO): Paused
2020-08-26T19:34:44.487Z,1598470484.487 [DAT](INFO): Paused
2020-08-26T19:34:44.878Z,1598470484.878 [DAT](INFO): Paused
2020-08-26T19:34:45.281Z,1598470485.281 [DAT](INFO): Paused
2020-08-26T19:34:45.708Z,1598470485.708 [DAT](INFO): Paused
2020-08-26T19:34:46.132Z,1598470486.132 [DAT](INFO): Paused
2020-08-26T19:34:46.504Z,1598470486.504 [DAT](INFO): Paused
2020-08-26T19:34:46.920Z,1598470486.920 [DAT](INFO): Paused
2020-08-26T19:34:47.316Z,1598470487.316 [DAT](INFO): Paused
2020-08-26T19:34:47.715Z,1598470487.715 [DAT](INFO): Paused
2020-08-26T19:34:48.134Z,1598470488.134 [DAT](INFO): Paused
2020-08-26T19:34:48.534Z,1598470488.534 [DAT](INFO): Paused
2020-08-26T19:34:48.925Z,1598470488.925 [DAT](INFO): Paused
2020-08-26T19:34:49.321Z,1598470489.321 [DAT](INFO): Paused
2020-08-26T19:34:49.726Z,1598470489.726 [DAT](INFO): Paused
2020-08-26T19:34:50.141Z,1598470490.141 [DAT](INFO): Paused
2020-08-26T19:34:50.547Z,1598470490.547 [DAT](INFO): Paused
2020-08-26T19:34:50.942Z,1598470490.942 [DAT](INFO): Paused
2020-08-26T19:34:51.360Z,1598470491.360 [DAT](INFO): Paused
2020-08-26T19:34:51.768Z,1598470491.768 [DAT](INFO): Paused
2020-08-26T19:34:52.172Z,1598470492.172 [DAT](INFO): Paused
2020-08-26T19:34:52.572Z,1598470492.572 [DAT](INFO): Paused
2020-08-26T19:34:52.977Z,1598470492.977 [DAT](INFO): Paused
2020-08-26T19:34:53.378Z,1598470493.378 [DAT](INFO): Paused
2020-08-26T19:34:53.770Z,1598470493.770 [DAT](INFO): Paused
2020-08-26T19:34:54.178Z,1598470494.178 [DAT](INFO): Paused
2020-08-26T19:34:54.574Z,1598470494.574 [DAT](INFO): Paused
2020-08-26T19:34:54.988Z,1598470494.988 [DAT](INFO): Paused
2020-08-26T19:34:55.406Z,1598470495.406 [DAT](INFO): Paused
2020-08-26T19:34:55.804Z,1598470495.804 [DAT](INFO): Paused
2020-08-26T19:34:56.236Z,1598470496.236 [DAT](INFO): Paused
2020-08-26T19:34:56.608Z,1598470496.608 [DAT](INFO): Paused
2020-08-26T19:34:57.012Z,1598470497.012 [DAT](INFO): Paused
2020-08-26T19:34:57.416Z,1598470497.416 [DAT](INFO): Paused
2020-08-26T19:34:57.816Z,1598470497.816 [DAT](INFO): Paused
2020-08-26T19:34:58.210Z,1598470498.210 [DAT](INFO): Paused
2020-08-26T19:34:58.613Z,1598470498.613 [DAT](INFO): Paused
2020-08-26T19:34:59.029Z,1598470499.029 [DAT](INFO): Paused
2020-08-26T19:34:59.422Z,1598470499.422 [DAT](INFO): Paused
2020-08-26T19:34:59.826Z,1598470499.826 [DAT](INFO): Paused
2020-08-26T19:35:00.229Z,1598470500.229 [DAT](INFO): Paused
2020-08-26T19:35:00.633Z,1598470500.633 [DAT](INFO): Paused
2020-08-26T19:35:01.047Z,1598470501.047 [DAT](INFO): Paused
2020-08-26T19:35:01.449Z,1598470501.449 [DAT](INFO): Paused
2020-08-26T19:35:01.860Z,1598470501.860 [DAT](INFO): Paused
2020-08-26T19:35:02.268Z,1598470502.268 [DAT](INFO): Paused
2020-08-26T19:35:02.680Z,1598470502.680 [DAT](INFO): Paused
2020-08-26T19:35:03.068Z,1598470503.068 [DAT](INFO): Paused
2020-08-26T19:35:03.470Z,1598470503.470 [DAT](INFO): Paused
2020-08-26T19:35:03.874Z,1598470503.874 [DAT](INFO): Paused
2020-08-26T19:35:04.270Z,1598470504.270 [DAT](INFO): Paused
2020-08-26T19:35:04.686Z,1598470504.686 [DAT](INFO): Paused
2020-08-26T19:35:05.097Z,1598470505.097 [DAT](INFO): Paused
2020-08-26T19:35:05.482Z,1598470505.482 [DAT](INFO): Paused
2020-08-26T19:35:05.895Z,1598470505.895 [DAT](INFO): Paused
2020-08-26T19:35:06.297Z,1598470506.297 [DAT](INFO): Paused
2020-08-26T19:35:06.694Z,1598470506.694 [DAT](INFO): Paused
2020-08-26T19:35:07.120Z,1598470507.120 [DAT](INFO): Paused
2020-08-26T19:35:07.528Z,1598470507.528 [DAT](INFO): Paused
2020-08-26T19:35:07.937Z,1598470507.937 [DAT](INFO): Paused
2020-08-26T19:35:08.324Z,1598470508.324 [DAT](INFO): Paused
2020-08-26T19:35:08.727Z,1598470508.727 [DAT](INFO): Paused
2020-08-26T19:35:09.118Z,1598470509.118 [DAT](INFO): Paused
2020-08-26T19:35:09.526Z,1598470509.526 [DAT](INFO): Paused
2020-08-26T19:35:09.947Z,1598470509.947 [DAT](INFO): Paused
2020-08-26T19:35:10.334Z,1598470510.334 [DAT](INFO): Paused
2020-08-26T19:35:10.742Z,1598470510.742 [DAT](INFO): Paused
2020-08-26T19:35:11.141Z,1598470511.141 [DAT](INFO): Paused
2020-08-26T19:35:11.542Z,1598470511.542 [DAT](INFO): Paused
2020-08-26T19:35:11.954Z,1598470511.954 [DAT](INFO): Paused
2020-08-26T19:35:12.376Z,1598470512.376 [DAT](INFO): Paused
2020-08-26T19:35:12.780Z,1598470512.780 [DAT](INFO): Paused
2020-08-26T19:35:13.184Z,1598470513.184 [DAT](INFO): Paused
2020-08-26T19:35:13.612Z,1598470513.612 [DAT](INFO): Paused
2020-08-26T19:35:13.965Z,1598470513.965 [DAT](INFO): Paused
2020-08-26T19:35:14.379Z,1598470514.379 [DAT](INFO): Paused
2020-08-26T19:35:14.783Z,1598470514.783 [DAT](INFO): Paused
2020-08-26T19:35:15.177Z,1598470515.177 [DAT](INFO): Paused
2020-08-26T19:35:15.582Z,1598470515.582 [DAT](INFO): Paused
2020-08-26T19:35:16.010Z,1598470516.010 [DAT](INFO): Paused
2020-08-26T19:35:16.389Z,1598470516.389 [DAT](INFO): Paused
2020-08-26T19:35:16.798Z,1598470516.798 [DAT](INFO): Paused
2020-08-26T19:35:17.197Z,1598470517.197 [DAT](INFO): Paused
2020-08-26T19:35:17.612Z,1598470517.612 [DAT](INFO): Paused
2020-08-26T19:35:18.016Z,1598470518.016 [DAT](INFO): Paused
2020-08-26T19:35:18.424Z,1598470518.424 [DAT](INFO): Paused
2020-08-26T19:35:18.828Z,1598470518.828 [DAT](INFO): Paused
2020-08-26T19:35:19.226Z,1598470519.226 [DAT](INFO): Paused
2020-08-26T19:35:19.621Z,1598470519.621 [DAT](INFO): Paused
2020-08-26T19:35:20.025Z,1598470520.025 [DAT](INFO): Paused
2020-08-26T19:35:20.430Z,1598470520.430 [DAT](INFO): Paused
2020-08-26T19:35:20.846Z,1598470520.846 [DAT](INFO): Paused
2020-08-26T19:35:21.237Z,1598470521.237 [DAT](INFO): Paused
2020-08-26T19:35:21.642Z,1598470521.642 [DAT](INFO): Paused
2020-08-26T19:35:22.045Z,1598470522.045 [DAT](INFO): Paused
2020-08-26T19:35:22.449Z,1598470522.449 [DAT](INFO): Paused
2020-08-26T19:35:22.868Z,1598470522.868 [DAT](INFO): Paused
2020-08-26T19:35:23.276Z,1598470523.276 [DAT](INFO): Paused
2020-08-26T19:35:23.695Z,1598470523.695 [DAT](INFO): Paused
2020-08-26T19:35:24.084Z,1598470524.084 [DAT](INFO): Paused
2020-08-26T19:35:24.469Z,1598470524.469 [DAT](INFO): Paused
2020-08-26T19:35:24.873Z,1598470524.873 [DAT](INFO): Paused
2020-08-26T19:35:25.282Z,1598470525.282 [DAT](INFO): Paused
2020-08-26T19:35:25.706Z,1598470525.706 [DAT](INFO): Paused
2020-08-26T19:35:26.106Z,1598470526.106 [DAT](INFO): Paused
2020-08-26T19:35:26.494Z,1598470526.494 [DAT](INFO): Paused
2020-08-26T19:35:26.893Z,1598470526.893 [DAT](INFO): Paused
2020-08-26T19:35:27.303Z,1598470527.303 [DAT](INFO): Paused
2020-08-26T19:35:27.706Z,1598470527.706 [DAT](INFO): Paused
2020-08-26T19:35:28.124Z,1598470528.124 [DAT](INFO): Paused
2020-08-26T19:35:28.532Z,1598470528.532 [DAT](INFO): Paused
2020-08-26T19:35:28.940Z,1598470528.940 [DAT](INFO): Paused
2020-08-26T19:35:29.330Z,1598470529.330 [DAT](INFO): Paused
2020-08-26T19:35:29.721Z,1598470529.721 [DAT](INFO): Paused
2020-08-26T19:35:30.137Z,1598470530.137 [DAT](INFO): Paused
2020-08-26T19:35:30.532Z,1598470530.532 [DAT](INFO): Paused
2020-08-26T19:35:30.946Z,1598470530.946 [DAT](INFO): Paused
2020-08-26T19:35:31.342Z,1598470531.342 [DAT](INFO): Paused
2020-08-26T19:35:31.746Z,1598470531.746 [DAT](INFO): Paused
2020-08-26T19:35:32.145Z,1598470532.145 [DAT](INFO): Paused
2020-08-26T19:35:32.550Z,1598470532.550 [DAT](INFO): Paused
2020-08-26T19:35:32.561Z,1598470532.561 [Startup:StartupSatComms:A](INFO): Timed out from 2020-08-26T19:34:32.4Z
2020-08-26T19:35:32.561Z,1598470532.561 [Startup:StartupSatComms:A] Stopped
2020-08-26T19:35:32.561Z,1598470532.561 [Startup:StartupSatComms:B] Running Loop=1
2020-08-26T19:35:32.953Z,1598470532.953 [DAT](INFO): Paused
2020-08-26T19:35:32.960Z,1598470532.960 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-08-26T19:35:32.960Z,1598470532.960 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-08-26T19:35:32.962Z,1598470532.962 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-08-26T19:35:32.971Z,1598470532.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-08-26T19:35:33.524Z,1598470533.524 [DAT](INFO): Paused
2020-08-26T19:35:33.561Z,1598470533.561 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-08-26T19:35:33.561Z,1598470533.561 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-08-26T19:35:33.904Z,1598470533.904 [DAT](INFO): Resume
2020-08-26T19:35:33.904Z,1598470533.904 [DAT](DEBUG): sending wake-up to local modem
2020-08-26T19:35:34.312Z,1598470534.312 [DAT](INFO): Resuming
2020-08-26T19:35:34.312Z,1598470534.312 [DAT](DEBUG): confirming wake-up of local modem
2020-08-26T19:35:34.712Z,1598470534.712 [DAT](INFO): Runnable
2020-08-26T19:35:34.713Z,1598470534.713 [DAT](INFO): DAT read: user:6>Rx Time:19:34:41.4305

2020-08-26T19:35:34.714Z,1598470534.714 [DAT](INFO): DAT read: 

2020-08-26T19:35:34.715Z,1598470534.715 [DAT](INFO): DAT read: $Packet for address 1

2020-08-26T19:35:34.715Z,1598470534.715 [DAT](INFO): received a packet notification
2020-08-26T19:35:34.728Z,1598470534.728 [DAT](INFO): ************** SENDING_TRANSMIT **************

2020-08-26T19:35:34.728Z,1598470534.728 [DAT](INFO): setting remote address to 6
2020-08-26T19:35:35.111Z,1598470535.111 [DAT](INFO): Runnable
2020-08-26T19:35:35.111Z,1598470535.111 [DAT](INFO): DAT read: 

2020-08-26T19:35:35.112Z,1598470535.112 [DAT](INFO): DAT read: RemoteAddr      | 6           

2020-08-26T19:35:35.113Z,1598470535.113 [DAT](INFO): set remote address to 6
2020-08-26T19:35:35.113Z,1598470535.113 [DAT](INFO): ************** SENDING_TRANSMIT **************

2020-08-26T19:35:35.114Z,1598470535.114 [DAT](INFO): entering online mode
2020-08-26T19:35:35.509Z,1598470535.509 [DAT](INFO): Runnable
2020-08-26T19:35:35.510Z,1598470535.510 [DAT](INFO): DAT read: user:7>

2020-08-26T19:35:35.511Z,1598470535.511 [DAT](INFO): DAT read: 

2020-08-26T19:35:35.512Z,1598470535.512 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2020-08-26T19:35:35.512Z,1598470535.512 [DAT](INFO): commRate: 800
2020-08-26T19:35:35.512Z,1598470535.512 [DAT](INFO): online mode acknowledged
2020-08-26T19:35:35.513Z,1598470535.513 [DAT](INFO): ************** SENDING_TRANSMIT **************

2020-08-26T19:35:35.513Z,1598470535.513 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2020-08-26T19:35:35.914Z,1598470535.914 [DAT](INFO): Runnable
2020-08-26T19:35:35.914Z,1598470535.914 [DAT](INFO): ************** SENDING_TRANSMIT_VERIFY **************

2020-08-26T19:35:36.317Z,1598470536.317 [DAT](INFO): Runnable
2020-08-26T19:35:36.318Z,1598470536.318 [DAT](INFO): ************** SENDING_TRANSMIT_VERIFY **************

2020-08-26T19:35:36.535Z,1598470536.535 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200826T185927/Courier0010.lzma
2020-08-26T19:35:36.721Z,1598470536.721 [DAT](INFO): Runnable
2020-08-26T19:35:36.722Z,1598470536.722 [DAT](INFO): ************** SENDING_TRANSMIT_VERIFY **************

2020-08-26T19:35:37.130Z,1598470537.130 [DAT](INFO): Runnable
2020-08-26T19:35:37.130Z,1598470537.130 [DAT](INFO): ************** SENDING_TRANSMIT_VERIFY **************

2020-08-26T19:35:37.539Z,1598470537.539 [DAT](INFO): Runnable
2020-08-26T19:35:37.539Z,1598470537.539 [DAT](INFO): ************** SENDING_TRANSMIT_VERIFY **************

2020-08-26T19:35:37.542Z,1598470537.542 [DataOverHttps](INFO): Moved sent file to Logs/20200826T185927/Courier0010.lzma.bak
2020-08-26T19:35:37.542Z,1598470537.542 [DataOverHttps](INFO): SBD MOMSN=12622302
2020-08-26T19:35:37.937Z,1598470537.937 [DAT](INFO): Runnable
2020-08-26T19:35:37.938Z,1598470537.938 [DAT](INFO): ************** SENDING_TRANSMIT_VERIFY **************

2020-08-26T19:35:38.342Z,1598470538.342 [DAT](INFO): Runnable
2020-08-26T19:35:38.342Z,1598470538.342 [DAT](INFO): ************** SENDING_TRANSMIT_VERIFY **************

2020-08-26T19:35:38.785Z,1598470538.785 [DAT](INFO): Runnable
2020-08-26T19:35:38.786Z,1598470538.786 [DAT](INFO): DAT read: Forwarding Delay UpTx time:19:35:11.3151

2020-08-26T19:35:38.786Z,1598470538.786 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2020-08-26T19:35:38.786Z,1598470538.786 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:39.204Z,1598470539.204 [DAT](INFO): Runnable
2020-08-26T19:35:39.205Z,1598470539.205 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:39.632Z,1598470539.632 [DAT](INFO): Runnable
2020-08-26T19:35:39.632Z,1598470539.632 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:39.988Z,1598470539.988 [DAT](INFO): Runnable
2020-08-26T19:35:39.989Z,1598470539.989 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:40.381Z,1598470540.381 [DAT](INFO): Runnable
2020-08-26T19:35:40.382Z,1598470540.382 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:40.786Z,1598470540.786 [DAT](INFO): Runnable
2020-08-26T19:35:40.786Z,1598470540.786 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:41.192Z,1598470541.192 [DAT](INFO): Runnable
2020-08-26T19:35:41.193Z,1598470541.193 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:41.597Z,1598470541.597 [DAT](INFO): Runnable
2020-08-26T19:35:41.598Z,1598470541.598 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:41.998Z,1598470541.998 [DAT](INFO): Runnable
2020-08-26T19:35:41.998Z,1598470541.998 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:42.430Z,1598470542.430 [DAT](INFO): Runnable
2020-08-26T19:35:42.430Z,1598470542.430 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:42.805Z,1598470542.805 [DAT](INFO): Runnable
2020-08-26T19:35:42.806Z,1598470542.806 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:43.210Z,1598470543.210 [DAT](INFO): Runnable
2020-08-26T19:35:43.210Z,1598470543.210 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:43.606Z,1598470543.606 [DAT](INFO): Runnable
2020-08-26T19:35:43.606Z,1598470543.606 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:44.047Z,1598470544.047 [DAT](INFO): Runnable
2020-08-26T19:35:44.047Z,1598470544.047 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:44.476Z,1598470544.476 [DAT](INFO): Runnable
2020-08-26T19:35:44.477Z,1598470544.477 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:44.839Z,1598470544.839 [DAT](INFO): Runnable
2020-08-26T19:35:44.839Z,1598470544.839 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:45.240Z,1598470545.240 [DAT](INFO): Runnable
2020-08-26T19:35:45.240Z,1598470545.240 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:45.632Z,1598470545.632 [DAT](INFO): Runnable
2020-08-26T19:35:45.632Z,1598470545.632 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:46.043Z,1598470546.043 [DAT](INFO): Runnable
2020-08-26T19:35:46.044Z,1598470546.044 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:46.441Z,1598470546.441 [DAT](INFO): Runnable
2020-08-26T19:35:46.442Z,1598470546.442 [DAT](INFO): DAT read: Rx Time:19:35:18.1311

2020-08-26T19:35:46.443Z,1598470546.443 [DAT](INFO): received an acoustic signal
2020-08-26T19:35:46.443Z,1598470546.443 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:46.850Z,1598470546.850 [DAT](INFO): Runnable
2020-08-26T19:35:46.851Z,1598470546.851 [DAT](INFO): DAT read: 

2020-08-26T19:35:46.856Z,1598470546.856 [DAT](INFO): DAT read: $Packet for address 1

2020-08-26T19:35:46.856Z,1598470546.856 [DAT](INFO): received a packet notification
2020-08-26T19:35:46.872Z,1598470546.872 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:47.259Z,1598470547.259 [DAT](INFO): Runnable
2020-08-26T19:35:47.264Z,1598470547.264 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:47.653Z,1598470547.653 [DAT](INFO): Runnable
2020-08-26T19:35:47.654Z,1598470547.654 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:48.054Z,1598470548.054 [DAT](INFO): Runnable
2020-08-26T19:35:48.054Z,1598470548.054 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:48.463Z,1598470548.463 [DAT](INFO): Runnable
2020-08-26T19:35:48.463Z,1598470548.463 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:48.861Z,1598470548.861 [DAT](INFO): Runnable
2020-08-26T19:35:48.862Z,1598470548.862 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:49.280Z,1598470549.280 [DAT](INFO): Runnable
2020-08-26T19:35:49.281Z,1598470549.281 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:49.696Z,1598470549.696 [DAT](INFO): Runnable
2020-08-26T19:35:49.697Z,1598470549.697 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:50.132Z,1598470550.132 [DAT](INFO): Runnable
2020-08-26T19:35:50.133Z,1598470550.133 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:50.485Z,1598470550.485 [DAT](INFO): Runnable
2020-08-26T19:35:50.485Z,1598470550.485 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:50.877Z,1598470550.877 [DAT](INFO): Runnable
2020-08-26T19:35:50.878Z,1598470550.878 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:51.281Z,1598470551.281 [DAT](INFO): Runnable
2020-08-26T19:35:51.282Z,1598470551.282 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:51.686Z,1598470551.686 [DAT](INFO): Runnable
2020-08-26T19:35:51.686Z,1598470551.686 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:52.090Z,1598470552.090 [DAT](INFO): Runnable
2020-08-26T19:35:52.090Z,1598470552.090 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:52.493Z,1598470552.493 [DAT](INFO): Runnable
2020-08-26T19:35:52.494Z,1598470552.494 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:52.931Z,1598470552.931 [DAT](INFO): Runnable
2020-08-26T19:35:52.938Z,1598470552.938 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:53.301Z,1598470553.301 [DAT](INFO): Runnable
2020-08-26T19:35:53.302Z,1598470553.302 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:53.738Z,1598470553.738 [DAT](INFO): Runnable
2020-08-26T19:35:53.738Z,1598470553.738 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:54.122Z,1598470554.122 [DAT](INFO): Runnable
2020-08-26T19:35:54.122Z,1598470554.122 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:54.456Z,1598470554.456 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20200826T193220/Courier0000.lzma
2020-08-26T19:35:54.532Z,1598470554.532 [DAT](INFO): Runnable
2020-08-26T19:35:54.533Z,1598470554.533 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:54.993Z,1598470554.993 [DAT](INFO): Runnable
2020-08-26T19:35:54.993Z,1598470554.993 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:55.364Z,1598470555.364 [DAT](INFO): Runnable
2020-08-26T19:35:55.365Z,1598470555.365 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:55.457Z,1598470555.457 [DataOverHttps](INFO): Moved sent file to Logs/20200826T193220/Courier0000.lzma.bak
2020-08-26T19:35:55.458Z,1598470555.458 [DataOverHttps](INFO): SBD MOMSN=12622307
2020-08-26T19:35:55.736Z,1598470555.736 [DAT](INFO): Runnable
2020-08-26T19:35:55.737Z,1598470555.737 [DAT](INFO): ************** SENDING_ACK_WAITING **************

2020-08-26T19:35:55.737Z,1598470555.737 [DAT](FAULT): Ack receipt timeout failure.
2020-08-26T19:35:55.737Z,1598470555.737 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2020-08-26T19:35:56.130Z,1598470556.130 [DAT](INFO): Runnable
2020-08-26T19:35:56.130Z,1598470556.130 [DAT](INFO): ************** SENDING_FILL_BUFFER **************

2020-08-26T19:35:57.486Z,1598470557.486 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2020-08-26T19:35:57.537Z,1598470557.537 [Startup:StartupSatComms:B] Stopped
2020-08-26T19:35:57.537Z,1598470557.537 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-08-26T19:35:57.537Z,1598470557.537 [Startup:StartupSatComms] Stopped
2020-08-26T19:35:57.537Z,1598470557.537 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-08-26T19:35:57.538Z,1598470557.538 [Startup](INFO): Completed Startup
2020-08-26T19:35:57.538Z,1598470557.538 [MissionManager](INFO): Startup is completed.
2020-08-26T19:35:57.538Z,1598470557.538 [MissionManager](INFO): Uninitializing Mission Startup
2020-08-26T19:35:57.538Z,1598470557.538 [Startup] Stopped
2020-08-26T19:35:57.538Z,1598470557.538 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-08-26T19:35:57.539Z,1598470557.539 [Startup:A.GoToSurface] Stopped
2020-08-26T19:35:57.539Z,1598470557.539 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-08-26T19:35:57.641Z,1598470557.641 [DAT](INFO): Runnable
2020-08-26T19:35:57.673Z,1598470557.673 [MissionManager](IMPORTANT): Started mission Default
2020-08-26T19:35:57.673Z,1598470557.673 [Default] Running Loop=1
2020-08-26T19:35:57.673Z,1598470557.673 [Default](DEBUG): Aggregate::initialize Default
2020-08-26T19:35:57.673Z,1598470557.673 [Default:B.GoToSurface] Running Loop=1
2020-08-26T19:35:57.673Z,1598470557.673 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-08-26T19:35:57.674Z,1598470557.674 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-08-26T19:35:57.674Z,1598470557.674 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-08-26T19:35:57.674Z,1598470557.674 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-08-26T19:35:57.675Z,1598470557.675 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-08-26T19:35:57.675Z,1598470557.675 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-08-26T19:35:57.675Z,1598470557.675 [Default:A.Wait] Running Loop=1
2020-08-26T19:35:57.675Z,1598470557.675 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-08-26T19:35:58.001Z,1598470558.001 [DAT](INFO): Pause
2020-08-26T19:35:58.184Z,1598470558.184 [AMEcho](INFO): Powering down
2020-08-26T19:35:58.405Z,1598470558.405 [DAT](INFO): Paused
2020-08-26T19:35:58.810Z,1598470558.810 [DAT](INFO): Paused
2020-08-26T19:35:59.242Z,1598470559.242 [DAT](INFO): Paused
2020-08-26T19:35:59.243Z,1598470559.243 [AMEcho](INFO): Powering up
2020-08-26T19:35:59.656Z,1598470559.656 [DAT](INFO): Paused
2020-08-26T19:36:00.052Z,1598470560.052 [DAT](INFO): Paused
2020-08-26T19:36:00.511Z,1598470560.511 [DAT](INFO): Paused
2020-08-26T19:36:00.972Z,1598470560.972 [DAT](INFO): Paused
2020-08-26T19:36:01.282Z,1598470561.282 [DAT](INFO): Paused
2020-08-26T19:36:01.706Z,1598470561.706 [DAT](INFO): Paused
2020-08-26T19:36:02.156Z,1598470562.156 [DAT](INFO): Paused
2020-08-26T19:36:02.523Z,1598470562.523 [DAT](INFO): Paused
2020-08-26T19:36:02.917Z,1598470562.917 [DAT](INFO): Paused
2020-08-26T19:36:03.321Z,1598470563.321 [DAT](INFO): Paused
2020-08-26T19:36:03.726Z,1598470563.726 [DAT](INFO): Paused
2020-08-26T19:36:04.130Z,1598470564.130 [DAT](INFO): Paused
2020-08-26T19:36:04.544Z,1598470564.544 [DAT](INFO): Paused
2020-08-26T19:36:04.968Z,1598470564.968 [DAT](INFO): Paused
2020-08-26T19:36:05.352Z,1598470565.352 [DAT](INFO): Paused
2020-08-26T19:36:05.756Z,1598470565.756 [DAT](INFO): Paused
2020-08-26T19:36:06.153Z,1598470566.153 [DAT](INFO): Paused
2020-08-26T19:36:06.586Z,1598470566.586 [DAT](INFO): Paused
2020-08-26T19:36:06.985Z,1598470566.985 [DAT](INFO): Paused
2020-08-26T19:36:07.361Z,1598470567.361 [DAT](INFO): Paused
2020-08-26T19:36:07.765Z,1598470567.765 [DAT](INFO): Paused
2020-08-26T19:36:08.169Z,1598470568.169 [DAT](INFO): Paused
2020-08-26T19:36:08.574Z,1598470568.574 [DAT](INFO): Paused
2020-08-26T19:36:08.981Z,1598470568.981 [DAT](INFO): Paused
2020-08-26T19:36:09.381Z,1598470569.381 [DAT](INFO): Paused
2020-08-26T19:36:09.800Z,1598470569.800 [DAT](INFO): Paused
2020-08-26T19:36:10.200Z,1598470570.200 [DAT](INFO): Paused
2020-08-26T19:36:10.660Z,1598470570.660 [DAT](INFO): Paused
2020-08-26T19:36:11.008Z,1598470571.008 [DAT](INFO): Paused
2020-08-26T19:36:11.049Z,1598470571.049 [Default:A.Wait](INFO): Done Waiting.
2020-08-26T19:36:11.049Z,1598470571.049 [Default:A.Wait] Stopped
2020-08-26T19:36:11.049Z,1598470571.049 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-08-26T19:36:11.410Z,1598470571.410 [DAT](INFO): Paused
2020-08-26T19:36:11.466Z,1598470571.466 [Default:CheckIn] Running Loop=1
2020-08-26T19:36:11.466Z,1598470571.466 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-08-26T19:36:11.466Z,1598470571.466 [Default:CheckIn:Read_GPS] Running Loop=1
2020-08-26T19:36:11.813Z,1598470571.813 [DAT](INFO): Paused
2020-08-26T19:36:11.825Z,1598470571.825 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-08-26T19:36:12.210Z,1598470572.210 [DAT](INFO): Paused
2020-08-26T19:36:12.618Z,1598470572.618 [DAT](INFO): Paused
2020-08-26T19:36:13.029Z,1598470573.029 [DAT](INFO): Paused
2020-08-26T19:36:13.442Z,1598470573.442 [DAT](INFO): Paused
2020-08-26T19:36:13.829Z,1598470573.829 [DAT](INFO): Paused
2020-08-26T19:36:14.233Z,1598470574.233 [DAT](INFO): Paused
2020-08-26T19:36:14.647Z,1598470574.647 [DAT](INFO): Paused
2020-08-26T19:36:15.060Z,1598470575.060 [DAT](INFO): Paused
2020-08-26T19:36:15.456Z,1598470575.456 [DAT](INFO): Paused
2020-08-26T19:36:15.893Z,1598470575.893 [DAT](INFO): Paused
2020-08-26T19:36:16.300Z,1598470576.300 [DAT](INFO): Paused
2020-08-26T19:36:16.665Z,1598470576.665 [DAT](INFO): Paused
2020-08-26T19:36:17.070Z,1598470577.070 [DAT](INFO): Paused
2020-08-26T19:36:17.474Z,1598470577.474 [DAT](INFO): Paused
2020-08-26T19:36:17.886Z,1598470577.886 [DAT](INFO): Paused
2020-08-26T19:36:18.281Z,1598470578.281 [DAT](INFO): Paused
2020-08-26T19:36:18.685Z,1598470578.685 [DAT](INFO): Paused
2020-08-26T19:36:19.121Z,1598470579.121 [DAT](INFO): Paused
2020-08-26T19:36:19.532Z,1598470579.532 [DAT](INFO): Paused
2020-08-26T19:36:19.897Z,1598470579.897 [DAT](INFO): Paused
2020-08-26T19:36:20.324Z,1598470580.324 [DAT](INFO): Paused
2020-08-26T19:36:20.716Z,1598470580.716 [DAT](INFO): Paused
2020-08-26T19:36:21.136Z,1598470581.136 [DAT](INFO): Paused
2020-08-26T19:36:21.532Z,1598470581.532 [DAT](INFO): Paused
2020-08-26T19:36:21.934Z,1598470581.934 [DAT](INFO): Paused
2020-08-26T19:36:22.332Z,1598470582.332 [DAT](INFO): Paused
2020-08-26T19:36:22.725Z,1598470582.725 [DAT](INFO): Paused
2020-08-26T19:36:23.130Z,1598470583.130 [DAT](INFO): Paused
2020-08-26T19:36:23.533Z,1598470583.533 [DAT](INFO): Paused
2020-08-26T19:36:23.937Z,1598470583.937 [DAT](INFO): Paused
2020-08-26T19:36:24.370Z,1598470584.370 [DAT](INFO): Paused
2020-08-26T19:36:24.762Z,1598470584.762 [DAT](INFO): Paused
2020-08-26T19:36:25.163Z,1598470585.163 [DAT](INFO): Paused
2020-08-26T19:36:25.568Z,1598470585.568 [DAT](INFO): Paused
2020-08-26T19:36:25.976Z,1598470585.976 [DAT](INFO): Paused
2020-08-26T19:36:26.372Z,1598470586.372 [DAT](INFO): Paused
2020-08-26T19:36:26.785Z,1598470586.785 [DAT](INFO): Paused
2020-08-26T19:36:26.804Z,1598470586.804 [CommandLine](IMPORTANT): got command quit
2020-08-26T19:36:27.178Z,1598470587.178 [DAT](INFO): Paused
2020-08-26T19:36:27.573Z,1598470587.573 [DAT](INFO): Paused
2020-08-26T19:36:27.808Z,1598470587.808 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:27.808Z,1598470587.808 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:27.864Z,1598470587.864 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-08-26T19:36:27.864Z,1598470587.864 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:27.865Z,1598470587.865 [CommandLine](INFO): Join timeout helper Thread ID is 6424
2020-08-26T19:36:27.865Z,1598470587.865 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-08-26T19:36:27.865Z,1598470587.865 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:27.866Z,1598470587.866 [NavChartDb](INFO): Join timeout helper Thread ID is 6425
2020-08-26T19:36:27.978Z,1598470587.978 [DAT](INFO): Paused
2020-08-26T19:36:28.144Z,1598470588.144 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:28.145Z,1598470588.145 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:28.160Z,1598470588.160 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-08-26T19:36:28.160Z,1598470588.160 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:28.160Z,1598470588.160 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6428
2020-08-26T19:36:28.292Z,1598470588.292 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:28.293Z,1598470588.293 [WetLabsBB2FL](INFO): Powering down
2020-08-26T19:36:28.293Z,1598470588.293 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:28.312Z,1598470588.312 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-08-26T19:36:28.312Z,1598470588.312 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:28.312Z,1598470588.312 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6431
2020-08-26T19:36:28.381Z,1598470588.381 [DAT](INFO): Paused
2020-08-26T19:36:28.798Z,1598470588.798 [DAT](INFO): Paused
2020-08-26T19:36:28.836Z,1598470588.836 [CTD_Seabird](INFO): Powering down
2020-08-26T19:36:28.848Z,1598470588.848 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:28.848Z,1598470588.848 [CTD_Seabird](INFO): Powering down
2020-08-26T19:36:28.860Z,1598470588.860 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:28.880Z,1598470588.880 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-08-26T19:36:28.880Z,1598470588.880 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:28.880Z,1598470588.880 [BackSeatDriver](INFO): Join timeout helper Thread ID is 6434
2020-08-26T19:36:28.908Z,1598470588.908 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:28.909Z,1598470588.909 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:28.925Z,1598470588.925 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-08-26T19:36:28.925Z,1598470588.925 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:28.925Z,1598470588.925 [Radio_Surface](INFO): Join timeout helper Thread ID is 6437
2020-08-26T19:36:29.278Z,1598470589.278 [DAT](INFO): Paused
2020-08-26T19:36:29.288Z,1598470589.288 [Radio_Surface](INFO): Powering down
2020-08-26T19:36:29.289Z,1598470589.289 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:29.289Z,1598470589.289 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:29.297Z,1598470589.297 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-08-26T19:36:29.297Z,1598470589.297 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:29.297Z,1598470589.297 [Onboard](INFO): Join timeout helper Thread ID is 6440
2020-08-26T19:36:29.348Z,1598470589.348 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-08-26T19:36:29.594Z,1598470589.594 [DAT](INFO): Paused
2020-08-26T19:36:30.006Z,1598470590.006 [DAT](INFO): Paused
2020-08-26T19:36:30.422Z,1598470590.422 [DAT](INFO): Paused
2020-08-26T19:36:30.829Z,1598470590.829 [DAT](INFO): Paused
2020-08-26T19:36:31.224Z,1598470591.224 [DAT](INFO): Paused
2020-08-26T19:36:31.628Z,1598470591.628 [DAT](INFO): Paused
2020-08-26T19:36:32.028Z,1598470592.028 [DAT](INFO): Paused
2020-08-26T19:36:32.434Z,1598470592.434 [DAT](INFO): Paused
2020-08-26T19:36:32.830Z,1598470592.830 [DAT](INFO): Paused
2020-08-26T19:36:32.948Z,1598470592.948 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:32.948Z,1598470592.948 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:32.961Z,1598470592.961 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-08-26T19:36:32.961Z,1598470592.961 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:32.961Z,1598470592.961 [DataOverHttps](INFO): Join timeout helper Thread ID is 6461
2020-08-26T19:36:33.229Z,1598470593.229 [DAT](INFO): Paused
2020-08-26T19:36:33.245Z,1598470593.245 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2285304 %. Average:280389.531250
2020-08-26T19:36:33.364Z,1598470593.364 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:33.365Z,1598470593.365 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:33.377Z,1598470593.377 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-08-26T19:36:33.377Z,1598470593.377 [logger ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:33.377Z,1598470593.377 [logger](INFO): Join timeout helper Thread ID is 6464
2020-08-26T19:36:33.416Z,1598470593.416 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:33.416Z,1598470593.416 [logger ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:33.436Z,1598470593.436 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-08-26T19:36:33.437Z,1598470593.437 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:33.437Z,1598470593.437 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-08-26T19:36:33.437Z,1598470593.437 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:33.437Z,1598470593.437 [controlThread](INFO): Join timeout helper Thread ID is 6465
2020-08-26T19:36:33.640Z,1598470593.640 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-26T19:36:33.640Z,1598470593.640 [controlThread](DEBUG): Uninitializing ControlThread
2020-08-26T19:36:33.640Z,1598470593.640 [AHRS_M2](INFO): Powering down
2020-08-26T19:36:33.712Z,1598470593.712 [NAL9602](INFO): Powering down
2020-08-26T19:36:33.713Z,1598470593.713 [DAT](INFO): uninitialize
2020-08-26T19:36:33.714Z,1598470593.714 [DAT](INFO): Powering down
2020-08-26T19:36:34.004Z,1598470594.004 [AMEcho](INFO): Powering down
2020-08-26T19:36:34.005Z,1598470594.005 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-08-26T19:36:34.006Z,1598470594.006 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-08-26T19:36:34.007Z,1598470594.007 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-08-26T19:36:34.007Z,1598470594.007 [MissionManager](INFO): Uninitializing Mission Default
2020-08-26T19:36:34.007Z,1598470594.007 [Default] Stopped
2020-08-26T19:36:34.007Z,1598470594.007 [Default](DEBUG): Aggregate::uninitialize Default
2020-08-26T19:36:34.007Z,1598470594.007 [Default:B.GoToSurface] Stopped
2020-08-26T19:36:34.007Z,1598470594.007 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-08-26T19:36:34.007Z,1598470594.007 [Default:CheckIn] Stopped
2020-08-26T19:36:34.008Z,1598470594.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-08-26T19:36:34.008Z,1598470594.008 [Default:CheckIn:Read_GPS] Stopped
2020-08-26T19:36:34.010Z,1598470594.010 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-08-26T19:36:34.010Z,1598470594.010 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-08-26T19:36:34.011Z,1598470594.011 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-08-26T19:36:34.011Z,1598470594.011 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-08-26T19:36:34.011Z,1598470594.011 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-08-26T19:36:34.011Z,1598470594.011 [BuoyancyServo](INFO): Powering down
2020-08-26T19:36:34.024Z,1598470594.024 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-08-26T19:36:34.024Z,1598470594.024 [ElevatorServo](INFO): Powering down
2020-08-26T19:36:34.025Z,1598470594.025 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-26T19:36:34.025Z,1598470594.025 [MassServo](INFO): Powering down
2020-08-26T19:36:34.026Z,1598470594.026 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-08-26T19:36:34.026Z,1598470594.026 [RudderServo](INFO): Powering down
2020-08-26T19:36:34.027Z,1598470594.027 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-08-26T19:36:34.027Z,1598470594.027 [ThrusterServo](INFO): Powering down
2020-08-26T19:36:34.028Z,1598470594.028 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-08-26T19:36:34.028Z,1598470594.028 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-08-26T19:36:34.028Z,1598470594.028 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-08-26T19:36:34.028Z,1598470594.028 [CBIT](DEBUG): Powering off loads.
2020-08-26T19:36:34.040Z,1598470594.040 [CBIT](DEBUG): Disabling WDT.
2020-08-26T19:36:34.052Z,1598470594.052 [CBIT](DEBUG): Opening all GF detection circuits.
2020-08-26T19:36:34.053Z,1598470594.053 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:34.325Z,1598470594.325 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:34.337Z,1598470594.337 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:34.343Z,1598470594.343 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:34.404Z,1598470594.404 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:34.407Z,1598470594.407 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:34.410Z,1598470594.410 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:34.420Z,1598470594.420 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-26T19:36:34.470Z,1598470594.470 [logger ThreadHandler](INFO): Thread cancelled.