2019-09-26T21:44:40.879Z,1569534280.879 [Supervisor](DEBUG): Initializing supervisor.
2019-09-26T21:44:40.882Z,1569534280.882 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-09-26T21:44:40.882Z,1569534280.882 [SyncHandler](INFO): Protected caller Thread ID is 2135
2019-09-26T21:44:40.882Z,1569534280.882 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-09-26T21:44:40.883Z,1569534280.883 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-09-26T21:44:40.884Z,1569534280.884 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2136
2019-09-26T21:44:40.887Z,1569534280.887 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-09-26T21:44:40.899Z,1569534280.899 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-09-26T21:44:40.900Z,1569534280.900 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-09-26T21:44:40.901Z,1569534280.901 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2137
2019-09-26T21:44:40.902Z,1569534280.902 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-09-26T21:44:40.902Z,1569534280.902 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-09-26T21:44:40.903Z,1569534280.903 [logger ThreadHandler](INFO): Protected caller Thread ID is 2138
2019-09-26T21:44:40.905Z,1569534280.905 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-09-26T21:44:40.905Z,1569534280.905 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-09-26T21:44:40.907Z,1569534280.907 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-09-26T21:44:41.002Z,1569534281.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-09-26T21:44:41.003Z,1569534281.003 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-09-26T21:44:41.215Z,1569534281.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-09-26T21:44:41.216Z,1569534281.216 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-09-26T21:44:41.358Z,1569534281.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-09-26T21:44:41.359Z,1569534281.359 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-09-26T21:44:41.966Z,1569534281.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-09-26T21:44:41.967Z,1569534281.967 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-09-26T21:44:42.442Z,1569534282.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-09-26T21:44:42.443Z,1569534282.443 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-09-26T21:44:42.951Z,1569534282.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-09-26T21:44:42.952Z,1569534282.952 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-09-26T21:44:43.280Z,1569534283.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-09-26T21:44:43.280Z,1569534283.280 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-09-26T21:44:43.639Z,1569534283.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-09-26T21:44:43.640Z,1569534283.640 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-09-26T21:44:44.059Z,1569534284.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-09-26T21:44:44.060Z,1569534284.060 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-09-26T21:44:44.214Z,1569534284.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-09-26T21:44:44.214Z,1569534284.214 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-09-26T21:44:44.321Z,1569534284.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-09-26T21:44:44.321Z,1569534284.321 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-09-26T21:44:44.402Z,1569534284.402 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-09-26T21:44:44.504Z,1569534284.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-09-26T21:44:44.505Z,1569534284.505 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-09-26T21:44:44.716Z,1569534284.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-09-26T21:44:44.717Z,1569534284.717 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-09-26T21:44:44.934Z,1569534284.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-09-26T21:44:44.936Z,1569534284.936 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-09-26T21:44:44.936Z,1569534284.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-09-26T21:44:45.019Z,1569534285.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-09-26T21:44:45.254Z,1569534285.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-09-26T21:44:45.255Z,1569534285.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-09-26T21:44:45.353Z,1569534285.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-09-26T21:44:45.534Z,1569534285.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-09-26T21:44:46.400Z,1569534286.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-09-26T21:44:46.584Z,1569534286.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-09-26T21:44:46.781Z,1569534286.781 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-09-26T21:44:46.878Z,1569534286.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-09-26T21:44:47.045Z,1569534287.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-09-26T21:44:47.155Z,1569534287.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-09-26T21:44:47.255Z,1569534287.255 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-09-26T21:44:47.259Z,1569534287.259 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-09-26T21:44:47.361Z,1569534287.361 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-09-26T21:44:47.362Z,1569534287.362 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-09-26T21:44:47.471Z,1569534287.471 [BuoyancyServo] Loaded
2019-09-26T21:44:47.472Z,1569534287.472 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-09-26T21:44:47.487Z,1569534287.487 [ElevatorServo] Loaded
2019-09-26T21:44:47.488Z,1569534287.488 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-09-26T21:44:47.503Z,1569534287.503 [MassServo] Loaded
2019-09-26T21:44:47.503Z,1569534287.503 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-09-26T21:44:47.519Z,1569534287.519 [RudderServo] Loaded
2019-09-26T21:44:47.519Z,1569534287.519 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-09-26T21:44:47.535Z,1569534287.535 [ThrusterServo] Loaded
2019-09-26T21:44:47.535Z,1569534287.535 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-09-26T21:44:47.535Z,1569534287.535 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-09-26T21:44:47.536Z,1569534287.536 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-09-26T21:44:47.676Z,1569534287.676 [SBIT](DEBUG): Construct Startup Built In Test.
2019-09-26T21:44:47.707Z,1569534287.707 [SBIT] Loaded
2019-09-26T21:44:47.707Z,1569534287.707 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-09-26T21:44:47.708Z,1569534287.708 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-09-26T21:44:47.737Z,1569534287.737 [IBIT] Loaded
2019-09-26T21:44:47.737Z,1569534287.737 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-09-26T21:44:47.740Z,1569534287.740 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-09-26T21:44:48.147Z,1569534288.147 [CBIT] Loaded
2019-09-26T21:44:48.148Z,1569534288.148 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-09-26T21:44:48.148Z,1569534288.148 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-09-26T21:44:48.149Z,1569534288.149 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-09-26T21:44:48.337Z,1569534288.337 [Aanderaa_O2] Loaded
2019-09-26T21:44:48.337Z,1569534288.337 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-09-26T21:44:48.347Z,1569534288.347 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-09-26T21:44:48.352Z,1569534288.352 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-09-26T21:44:48.354Z,1569534288.354 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-09-26T21:44:48.359Z,1569534288.359 [CTD_Seabird](INFO): created writer for : depth
2019-09-26T21:44:48.359Z,1569534288.359 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-09-26T21:44:48.365Z,1569534288.365 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-09-26T21:44:48.366Z,1569534288.366 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-09-26T21:44:48.371Z,1569534288.371 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-09-26T21:44:48.371Z,1569534288.371 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-09-26T21:44:48.377Z,1569534288.377 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-09-26T21:44:48.378Z,1569534288.378 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-09-26T21:44:48.383Z,1569534288.383 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-09-26T21:44:48.384Z,1569534288.384 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-09-26T21:44:48.389Z,1569534288.389 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-09-26T21:44:48.417Z,1569534288.417 [CTD_Seabird] Loaded
2019-09-26T21:44:48.417Z,1569534288.417 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-09-26T21:44:48.418Z,1569534288.418 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066B4E0
2019-09-26T21:44:48.419Z,1569534288.419 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2218
2019-09-26T21:44:48.450Z,1569534288.450 [ESPComponent] Loaded
2019-09-26T21:44:48.451Z,1569534288.451 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-09-26T21:44:48.465Z,1569534288.465 [PAR_Licor] Loaded
2019-09-26T21:44:48.466Z,1569534288.466 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-09-26T21:44:48.516Z,1569534288.516 [WetLabsBB2FL] Loaded
2019-09-26T21:44:48.516Z,1569534288.516 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-09-26T21:44:48.517Z,1569534288.517 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069B4E0
2019-09-26T21:44:48.518Z,1569534288.518 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2219
2019-09-26T21:44:48.519Z,1569534288.519 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-09-26T21:44:48.519Z,1569534288.519 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-09-26T21:44:48.563Z,1569534288.563 [DepthRateCalculator] Loaded
2019-09-26T21:44:48.563Z,1569534288.563 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-09-26T21:44:48.569Z,1569534288.569 [PitchRateCalculator] Loaded
2019-09-26T21:44:48.569Z,1569534288.569 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-09-26T21:44:48.586Z,1569534288.586 [SpeedCalculator] Loaded
2019-09-26T21:44:48.586Z,1569534288.586 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-09-26T21:44:48.608Z,1569534288.608 [TempGradientCalculator] Loaded
2019-09-26T21:44:48.609Z,1569534288.609 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-09-26T21:44:48.615Z,1569534288.615 [YawRateCalculator] Loaded
2019-09-26T21:44:48.615Z,1569534288.615 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-09-26T21:44:48.650Z,1569534288.650 [ElevatorOffsetCalculator] Loaded
2019-09-26T21:44:48.650Z,1569534288.650 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-09-26T21:44:48.650Z,1569534288.650 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-09-26T21:44:48.651Z,1569534288.651 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-09-26T21:44:48.684Z,1569534288.684 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-09-26T21:44:48.685Z,1569534288.685 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-09-26T21:44:48.792Z,1569534288.792 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-09-26T21:44:48.792Z,1569534288.792 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-09-26T21:44:48.815Z,1569534288.815 [NavChart] Loaded
2019-09-26T21:44:48.815Z,1569534288.815 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-09-26T21:44:48.819Z,1569534288.819 [UniversalFixResidualReporter] Loaded
2019-09-26T21:44:48.819Z,1569534288.819 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-09-26T21:44:48.820Z,1569534288.820 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-09-26T21:44:48.821Z,1569534288.821 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-09-26T21:44:49.183Z,1569534289.183 [AHRS_M2] Loaded
2019-09-26T21:44:49.183Z,1569534289.183 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-09-26T21:44:49.260Z,1569534289.260 [DataOverHttps] Loaded
2019-09-26T21:44:49.260Z,1569534289.260 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-09-26T21:44:49.261Z,1569534289.261 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409744E0
2019-09-26T21:44:49.262Z,1569534289.262 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2220
2019-09-26T21:44:49.275Z,1569534289.275 [Depth_Keller] Loaded
2019-09-26T21:44:49.275Z,1569534289.275 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-09-26T21:44:49.371Z,1569534289.371 [NAL9602] Loaded
2019-09-26T21:44:49.371Z,1569534289.371 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-09-26T21:44:49.376Z,1569534289.376 [Onboard] Loaded
2019-09-26T21:44:49.377Z,1569534289.377 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-09-26T21:44:49.380Z,1569534289.380 [Radio_Surface] Loaded
2019-09-26T21:44:49.380Z,1569534289.380 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-09-26T21:44:49.381Z,1569534289.381 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0
2019-09-26T21:44:49.382Z,1569534289.382 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2221
2019-09-26T21:44:49.516Z,1569534289.516 [DAT] Loaded
2019-09-26T21:44:49.516Z,1569534289.516 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-09-26T21:44:50.846Z,1569534290.846 [BPC1] Loaded
2019-09-26T21:44:50.846Z,1569534290.846 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-09-26T21:44:50.847Z,1569534290.847 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-09-26T21:44:50.847Z,1569534290.847 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-09-26T21:44:50.918Z,1569534290.918 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-09-26T21:44:50.919Z,1569534290.919 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-09-26T21:44:50.986Z,1569534290.986 [VerticalControl](DEBUG): Construct VerticalControl.
2019-09-26T21:44:51.074Z,1569534291.074 [VerticalControl] Loaded
2019-09-26T21:44:51.074Z,1569534291.074 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-09-26T21:44:51.075Z,1569534291.075 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-09-26T21:44:51.136Z,1569534291.136 [HorizontalControl] Loaded
2019-09-26T21:44:51.136Z,1569534291.136 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-09-26T21:44:51.137Z,1569534291.137 [SpeedControl](DEBUG): Construct SpeedControl.
2019-09-26T21:44:51.139Z,1569534291.139 [SpeedControl] Loaded
2019-09-26T21:44:51.139Z,1569534291.139 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-09-26T21:44:51.140Z,1569534291.140 [LoopControl](DEBUG): Construct LoopControl.
2019-09-26T21:44:51.140Z,1569534291.140 [LoopControl] Loaded
2019-09-26T21:44:51.141Z,1569534291.141 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-09-26T21:44:51.141Z,1569534291.141 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-09-26T21:44:51.142Z,1569534291.142 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-09-26T21:44:51.228Z,1569534291.228 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-09-26T21:44:51.228Z,1569534291.228 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-09-26T21:44:51.737Z,1569534291.737 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-09-26T21:44:51.741Z,1569534291.741 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-09-26T21:44:51.741Z,1569534291.741 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-09-26T21:44:51.748Z,1569534291.748 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-09-26T21:44:51.750Z,1569534291.750 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2019-09-26T21:44:51.750Z,1569534291.750 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2222
2019-09-26T21:44:51.755Z,1569534291.755 [Supervisor](INFO): Main Thread ID is 2134
2019-09-26T21:44:51.755Z,1569534291.755 [Supervisor](DEBUG): Running supervisor.
2019-09-26T21:44:51.756Z,1569534291.756 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2223
2019-09-26T21:44:51.758Z,1569534291.758 [controlThread ThreadHandler](INFO): Handler Thread ID is 2224
2019-09-26T21:44:51.759Z,1569534291.759 [controlThread](DEBUG): Initializing ControlThread
2019-09-26T21:44:51.760Z,1569534291.760 [SBIT](INFO): Initialize SBIT Component.
2019-09-26T21:44:51.761Z,1569534291.761 [SBIT](IMPORTANT): git: 2019-08-13
2019-09-26T21:44:51.761Z,1569534291.761 [SBIT](INFO): git hash: 6b080875362fb71081cecd990260e30fa66d1cc0
2019-09-26T21:44:51.761Z,1569534291.761 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-09-26T21:44:51.763Z,1569534291.763 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-09-26T21:44:51.764Z,1569534291.764 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-09-26T21:44:51.764Z,1569534291.764 [IBIT](INFO): Initialize IBIT Component.
2019-09-26T21:44:51.765Z,1569534291.765 [CBIT](DEBUG): Initialize CBIT Component.
2019-09-26T21:44:51.766Z,1569534291.766 [logger ThreadHandler](INFO): Handler Thread ID is 2225
2019-09-26T21:44:51.777Z,1569534291.777 [CBIT](DEBUG): Initialized mux pins.
2019-09-26T21:44:51.777Z,1569534291.777 [CBIT](DEBUG): Initializing the watchdog timer.
2019-09-26T21:44:51.785Z,1569534291.785 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2226
2019-09-26T21:44:51.786Z,1569534291.786 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-26T21:44:51.789Z,1569534291.789 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-26T21:44:51.791Z,1569534291.791 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2228
2019-09-26T21:44:51.792Z,1569534291.792 [WetLabsBB2FL](INFO): Powering down
2019-09-26T21:44:51.801Z,1569534291.801 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-09-26T21:44:51.801Z,1569534291.801 [CBIT](DEBUG): Initializing heartbeat.
2019-09-26T21:44:51.825Z,1569534291.825 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2229
2019-09-26T21:44:51.826Z,1569534291.826 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-09-26T21:44:51.837Z,1569534291.837 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2230
2019-09-26T21:44:51.849Z,1569534291.849 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2231
2019-09-26T21:44:51.852Z,1569534291.852 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-09-26T21:44:51.852Z,1569534291.852 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-09-26T21:44:51.853Z,1569534291.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-09-26T21:44:51.853Z,1569534291.853 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-09-26T21:44:51.853Z,1569534291.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-09-26T21:44:51.853Z,1569534291.853 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-09-26T21:44:51.853Z,1569534291.853 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-09-26T21:44:51.854Z,1569534291.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-09-26T21:44:51.854Z,1569534291.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-09-26T21:44:51.854Z,1569534291.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-09-26T21:44:51.854Z,1569534291.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-09-26T21:44:51.854Z,1569534291.854 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-09-26T21:44:51.854Z,1569534291.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-09-26T21:44:51.855Z,1569534291.855 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-09-26T21:44:51.855Z,1569534291.855 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-09-26T21:44:51.855Z,1569534291.855 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-09-26T21:44:51.873Z,1569534291.873 [CBIT](DEBUG): Deactivating GF circuits.
2019-09-26T21:44:51.873Z,1569534291.873 [CBIT](DEBUG): Deactivating emergency mode.
2019-09-26T21:44:51.909Z,1569534291.909 [CBIT](DEBUG): Backplane powered.
2019-09-26T21:44:51.913Z,1569534291.913 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-09-26T21:44:51.913Z,1569534291.913 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-09-26T21:44:51.914Z,1569534291.914 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-09-26T21:44:51.914Z,1569534291.914 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-09-26T21:44:51.915Z,1569534291.915 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-09-26T21:44:51.915Z,1569534291.915 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-09-26T21:44:51.916Z,1569534291.916 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-09-26T21:44:51.916Z,1569534291.916 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-09-26T21:44:51.926Z,1569534291.926 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-09-26T21:44:51.928Z,1569534291.928 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-09-26T21:44:51.928Z,1569534291.928 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-09-26T21:44:51.937Z,1569534291.937 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-09-26T21:44:51.938Z,1569534291.938 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-09-26T21:44:51.964Z,1569534291.964 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-26T21:44:51.000Z,1569534292.000 [MissionManager](DEBUG):
2019-09-26T21:44:52.012Z,1569534292.012 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-09-26T21:44:52.088Z,1569534292.088 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-09-26T21:44:52.105Z,1569534292.105 [Default:A.Wait](DEBUG): Construct Wait.
2019-09-26T21:44:52.107Z,1569534292.107 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-26T21:44:52.125Z,1569534292.125 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-09-26T21:44:52.128Z,1569534292.128 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-09-26T21:44:52.164Z,1569534292.164 [Default:E.Execute](DEBUG): Construct Execute.
2019-09-26T21:44:52.175Z,1569534292.175 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-09-26T21:44:52.180Z,1569534292.180 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-09-26T21:44:52.228Z,1569534292.228 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-26T21:44:52.241Z,1569534292.241 [Radio_Surface](INFO): Powering up
2019-09-26T21:44:52.395Z,1569534292.395 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-09-26T21:44:52.395Z,1569534292.395 [DAT](INFO): Powering up
2019-09-26T21:44:52.396Z,1569534292.396 [DAT](DEBUG): Initializing DAT.
2019-09-26T21:44:52.482Z,1569534292.482 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-09-26T21:44:52.485Z,1569534292.485 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-09-26T21:44:52.517Z,1569534292.517 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-09-26T21:44:52.518Z,1569534292.518 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-09-26T21:44:52.525Z,1569534292.525 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-09-26T21:44:52.526Z,1569534292.526 [MassServo](DEBUG): Initializing EZServoServo.
2019-09-26T21:44:52.537Z,1569534292.537 [MassServo](DEBUG): Initializing MassServo.
2019-09-26T21:44:52.538Z,1569534292.538 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-26T21:44:52.549Z,1569534292.549 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-26T21:44:52.550Z,1569534292.550 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-09-26T21:44:52.561Z,1569534292.561 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-09-26T21:44:55.541Z,1569534295.541 [Aanderaa_O2](INFO): Powering down
2019-09-26T21:45:01.865Z,1569534301.865 [CommandLine](IMPORTANT): got command quit
2019-09-26T21:45:02.869Z,1569534302.869 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-09-26T21:45:02.869Z,1569534302.869 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-26T21:45:02.869Z,1569534302.869 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:02.909Z,1569534302.909 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-09-26T21:45:02.909Z,1569534302.909 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:02.910Z,1569534302.910 [CommandLine](INFO): Join timeout helper Thread ID is 2241
2019-09-26T21:45:02.945Z,1569534302.945 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-26T21:45:02.945Z,1569534302.945 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:02.945Z,1569534302.945 [NavChartDb](INFO): Join timeout helper Thread ID is 2242
2019-09-26T21:45:06.482Z,1569534306.482 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004747
2019-09-26T21:45:07.572Z,1569534307.572 [DAT](INFO): setting local address to 3
2019-09-26T21:45:07.974Z,1569534307.974 [DAT](INFO): set local address to 3
2019-09-26T21:45:09.393Z,1569534309.393 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-26T21:45:09.393Z,1569534309.393 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:09.397Z,1569534309.397 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-26T21:45:09.397Z,1569534309.397 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:09.397Z,1569534309.397 [Radio_Surface](INFO): Join timeout helper Thread ID is 2243
2019-09-26T21:45:09.613Z,1569534309.613 [Radio_Surface](INFO): Powering down
2019-09-26T21:45:09.614Z,1569534309.614 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-26T21:45:09.614Z,1569534309.614 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:09.629Z,1569534309.629 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-26T21:45:09.629Z,1569534309.629 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:09.629Z,1569534309.629 [DataOverHttps](INFO): Join timeout helper Thread ID is 2244
2019-09-26T21:45:09.709Z,1569534309.709 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-26T21:45:09.709Z,1569534309.709 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:09.725Z,1569534309.725 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-26T21:45:09.725Z,1569534309.725 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:09.725Z,1569534309.725 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2245
2019-09-26T21:45:09.941Z,1569534309.941 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-26T21:45:09.941Z,1569534309.941 [WetLabsBB2FL](INFO): Powering down
2019-09-26T21:45:09.942Z,1569534309.942 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:09.954Z,1569534309.954 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-26T21:45:09.954Z,1569534309.954 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:09.954Z,1569534309.954 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2246
2019-09-26T21:45:10.861Z,1569534310.861 [CTD_Seabird](INFO): Powering down
2019-09-26T21:45:10.873Z,1569534310.873 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-26T21:45:10.873Z,1569534310.873 [CTD_Seabird](INFO): Powering down
2019-09-26T21:45:10.885Z,1569534310.885 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:10.902Z,1569534310.902 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-26T21:45:10.902Z,1569534310.902 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:10.902Z,1569534310.902 [logger](INFO): Join timeout helper Thread ID is 2247
2019-09-26T21:45:10.914Z,1569534310.914 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-26T21:45:10.915Z,1569534310.915 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:10.929Z,1569534310.929 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-26T21:45:10.930Z,1569534310.930 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:10.930Z,1569534310.930 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-26T21:45:10.930Z,1569534310.930 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:10.930Z,1569534310.930 [controlThread](INFO): Join timeout helper Thread ID is 2248
2019-09-26T21:45:11.173Z,1569534311.173 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-26T21:45:11.173Z,1569534311.173 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-26T21:45:11.173Z,1569534311.173 [Aanderaa_O2](INFO): Powering down
2019-09-26T21:45:11.195Z,1569534311.195 [AHRS_M2](INFO): Powering down
2019-09-26T21:45:11.265Z,1569534311.265 [NAL9602](INFO): Powering down
2019-09-26T21:45:11.285Z,1569534311.285 [DAT](INFO): Powering down
2019-09-26T21:45:11.406Z,1569534311.406 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-26T21:45:11.407Z,1569534311.407 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-26T21:45:11.407Z,1569534311.407 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-26T21:45:11.408Z,1569534311.408 [MissionManager](INFO): Uninitializing Mission Default
2019-09-26T21:45:11.410Z,1569534311.410 [MissionManager](INFO): Uninitializing Mission Startup
2019-09-26T21:45:11.411Z,1569534311.411 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-26T21:45:11.411Z,1569534311.411 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-26T21:45:11.412Z,1569534311.412 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-26T21:45:11.412Z,1569534311.412 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-26T21:45:11.412Z,1569534311.412 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-26T21:45:11.412Z,1569534311.412 [BuoyancyServo](INFO): Powering down
2019-09-26T21:45:11.545Z,1569534311.545 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-26T21:45:11.545Z,1569534311.545 [ElevatorServo](INFO): Powering down
2019-09-26T21:45:11.565Z,1569534311.565 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-26T21:45:11.565Z,1569534311.565 [MassServo](INFO): Powering down
2019-09-26T21:45:11.566Z,1569534311.566 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-26T21:45:11.566Z,1569534311.566 [RudderServo](INFO): Powering down
2019-09-26T21:45:11.567Z,1569534311.567 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-26T21:45:11.567Z,1569534311.567 [ThrusterServo](INFO): Powering down
2019-09-26T21:45:11.568Z,1569534311.568 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-26T21:45:11.568Z,1569534311.568 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-26T21:45:11.568Z,1569534311.568 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-26T21:45:11.569Z,1569534311.569 [CBIT](DEBUG): Powering off loads.
2019-09-26T21:45:11.581Z,1569534311.581 [CBIT](DEBUG): Disabling WDT.
2019-09-26T21:45:11.593Z,1569534311.593 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-26T21:45:11.593Z,1569534311.593 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:11.761Z,1569534311.761 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:11.766Z,1569534311.766 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:11.853Z,1569534311.853 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:11.857Z,1569534311.857 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:11.908Z,1569534311.908 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-26T21:45:11.954Z,1569534311.954 [logger ThreadHandler](INFO): Thread cancelled.