2018-12-13T00:06:52.876Z,1544659612.876 [Supervisor](DEBUG): Initializing supervisor.
2018-12-13T00:06:52.879Z,1544659612.879 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-12-13T00:06:52.879Z,1544659612.879 [SyncHandler](INFO): Protected caller Thread ID is 981
2018-12-13T00:06:52.880Z,1544659612.880 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-12-13T00:06:52.881Z,1544659612.881 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-12-13T00:06:52.881Z,1544659612.881 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 982
2018-12-13T00:06:52.885Z,1544659612.885 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-12-13T00:06:52.897Z,1544659612.897 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-12-13T00:06:52.898Z,1544659612.898 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-12-13T00:06:52.898Z,1544659612.898 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 983
2018-12-13T00:06:52.899Z,1544659612.899 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-12-13T00:06:52.900Z,1544659612.900 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-12-13T00:06:52.900Z,1544659612.900 [logger ThreadHandler](INFO): Protected caller Thread ID is 984
2018-12-13T00:06:52.902Z,1544659612.902 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-12-13T00:06:52.902Z,1544659612.902 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-12-13T00:06:52.904Z,1544659612.904 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-12-13T00:06:53.023Z,1544659613.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-12-13T00:06:53.023Z,1544659613.023 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-12-13T00:06:53.124Z,1544659613.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-12-13T00:06:53.125Z,1544659613.125 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-12-13T00:06:53.321Z,1544659613.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-12-13T00:06:53.321Z,1544659613.321 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-12-13T00:06:53.682Z,1544659613.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-12-13T00:06:53.683Z,1544659613.683 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-12-13T00:06:54.009Z,1544659614.009 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-12-13T00:06:54.009Z,1544659614.009 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-12-13T00:06:54.458Z,1544659614.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-12-13T00:06:54.459Z,1544659614.459 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-12-13T00:06:54.935Z,1544659614.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-12-13T00:06:54.936Z,1544659614.936 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-12-13T00:06:55.387Z,1544659615.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-12-13T00:06:55.387Z,1544659615.387 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-12-13T00:06:55.469Z,1544659615.469 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-12-13T00:06:55.779Z,1544659615.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-12-13T00:06:55.779Z,1544659615.779 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-12-13T00:06:55.927Z,1544659615.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-12-13T00:06:55.928Z,1544659615.928 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-12-13T00:06:56.146Z,1544659616.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-12-13T00:06:56.147Z,1544659616.147 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-12-13T00:06:56.402Z,1544659616.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-12-13T00:06:56.402Z,1544659616.402 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-12-13T00:06:56.908Z,1544659616.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-12-13T00:06:56.909Z,1544659616.909 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-12-13T00:06:57.054Z,1544659617.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-12-13T00:06:57.056Z,1544659617.056 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-12-13T00:06:57.057Z,1544659617.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-12-13T00:06:57.287Z,1544659617.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-12-13T00:06:57.288Z,1544659617.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-12-13T00:06:57.391Z,1544659617.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-12-13T00:06:57.542Z,1544659617.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-12-13T00:06:57.635Z,1544659617.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-12-13T00:06:57.720Z,1544659617.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-12-13T00:06:57.873Z,1544659617.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-12-13T00:06:58.066Z,1544659618.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-12-13T00:06:58.164Z,1544659618.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-12-13T00:06:58.259Z,1544659618.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-12-13T00:06:58.357Z,1544659618.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-12-13T00:06:58.440Z,1544659618.440 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-12-13T00:06:58.441Z,1544659618.441 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-12-13T00:06:58.445Z,1544659618.445 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-12-13T00:06:58.570Z,1544659618.570 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-12-13T00:06:58.571Z,1544659618.571 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-12-13T00:06:58.595Z,1544659618.595 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-12-13T00:06:58.595Z,1544659618.595 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-12-13T00:06:58.650Z,1544659618.650 [DepthRateCalculator] Loaded
2018-12-13T00:06:58.650Z,1544659618.650 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-12-13T00:06:58.656Z,1544659618.656 [PitchRateCalculator] Loaded
2018-12-13T00:06:58.656Z,1544659618.656 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-12-13T00:06:58.672Z,1544659618.672 [SpeedCalculator] Loaded
2018-12-13T00:06:58.672Z,1544659618.672 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-12-13T00:06:58.693Z,1544659618.693 [TempGradientCalculator] Loaded
2018-12-13T00:06:58.693Z,1544659618.693 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-12-13T00:06:58.699Z,1544659618.699 [YawRateCalculator] Loaded
2018-12-13T00:06:58.699Z,1544659618.699 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-12-13T00:06:58.738Z,1544659618.738 [ElevatorOffsetCalculator] Loaded
2018-12-13T00:06:58.738Z,1544659618.738 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-12-13T00:06:58.738Z,1544659618.738 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-12-13T00:06:58.739Z,1544659618.739 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-12-13T00:06:58.779Z,1544659618.779 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-12-13T00:06:58.779Z,1544659618.779 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-12-13T00:06:59.136Z,1544659619.136 [AcousticModem_Benthos_ATM900] Loaded
2018-12-13T00:06:59.136Z,1544659619.136 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-12-13T00:06:59.204Z,1544659619.204 [DataOverHttps] Loaded
2018-12-13T00:06:59.204Z,1544659619.204 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-12-13T00:06:59.218Z,1544659619.218 [Depth_Keller] Loaded
2018-12-13T00:06:59.219Z,1544659619.219 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-12-13T00:06:59.223Z,1544659619.223 [DropWeight] Loaded
2018-12-13T00:06:59.223Z,1544659619.223 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-12-13T00:06:59.319Z,1544659619.319 [NAL9602] Loaded
2018-12-13T00:06:59.319Z,1544659619.319 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-12-13T00:06:59.334Z,1544659619.334 [Onboard] Loaded
2018-12-13T00:06:59.334Z,1544659619.334 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-12-13T00:06:59.344Z,1544659619.344 [Radio_Surface] Loaded
2018-12-13T00:06:59.344Z,1544659619.344 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-12-13T00:06:59.346Z,1544659619.346 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407CE4E0
2018-12-13T00:06:59.346Z,1544659619.346 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1063
2018-12-13T00:06:59.392Z,1544659619.392 [PNI_TCM] Loaded
2018-12-13T00:06:59.392Z,1544659619.392 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-12-13T00:06:59.451Z,1544659619.451 [Rowe_600LCM] Loaded
2018-12-13T00:06:59.451Z,1544659619.451 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-12-13T00:06:59.452Z,1544659619.452 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407FE4E0
2018-12-13T00:06:59.452Z,1544659619.452 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1064
2018-12-13T00:07:01.167Z,1544659621.167 [BPC1] Loaded
2018-12-13T00:07:01.167Z,1544659621.167 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-12-13T00:07:01.168Z,1544659621.168 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-12-13T00:07:01.168Z,1544659621.168 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-12-13T00:07:01.324Z,1544659621.324 [SBIT](DEBUG): Construct Startup Built In Test.
2018-12-13T00:07:01.346Z,1544659621.346 [SBIT] Loaded
2018-12-13T00:07:01.346Z,1544659621.346 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-12-13T00:07:01.347Z,1544659621.347 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-12-13T00:07:01.358Z,1544659621.358 [IBIT] Loaded
2018-12-13T00:07:01.359Z,1544659621.359 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-12-13T00:07:01.362Z,1544659621.362 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-12-13T00:07:01.497Z,1544659621.497 [CBIT] Loaded
2018-12-13T00:07:01.497Z,1544659621.497 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-12-13T00:07:01.497Z,1544659621.497 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-12-13T00:07:01.498Z,1544659621.498 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-12-13T00:07:01.824Z,1544659621.824 [Aanderaa_O2] Loaded
2018-12-13T00:07:01.824Z,1544659621.824 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-12-13T00:07:01.905Z,1544659621.905 [CTD_Seabird] Loaded
2018-12-13T00:07:01.905Z,1544659621.905 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-12-13T00:07:01.906Z,1544659621.906 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408DD4E0
2018-12-13T00:07:01.907Z,1544659621.907 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1065
2018-12-13T00:07:01.924Z,1544659621.924 [ESPComponent] Loaded
2018-12-13T00:07:01.924Z,1544659621.924 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-12-13T00:07:01.938Z,1544659621.938 [PAR_Licor] Loaded
2018-12-13T00:07:01.938Z,1544659621.938 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-12-13T00:07:01.985Z,1544659621.985 [WetLabsBB2FL] Loaded
2018-12-13T00:07:01.986Z,1544659621.986 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-12-13T00:07:01.987Z,1544659621.987 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2018-12-13T00:07:01.987Z,1544659621.987 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1066
2018-12-13T00:07:01.988Z,1544659621.988 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-12-13T00:07:01.988Z,1544659621.988 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-12-13T00:07:02.261Z,1544659622.261 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-12-13T00:07:02.261Z,1544659622.261 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-12-13T00:07:02.348Z,1544659622.348 [VerticalControl](DEBUG): Construct VerticalControl.
2018-12-13T00:07:02.440Z,1544659622.440 [VerticalControl] Loaded
2018-12-13T00:07:02.440Z,1544659622.440 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-12-13T00:07:02.441Z,1544659622.441 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-12-13T00:07:02.500Z,1544659622.500 [HorizontalControl] Loaded
2018-12-13T00:07:02.500Z,1544659622.500 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-12-13T00:07:02.501Z,1544659622.501 [SpeedControl](DEBUG): Construct SpeedControl.
2018-12-13T00:07:02.506Z,1544659622.506 [SpeedControl] Loaded
2018-12-13T00:07:02.506Z,1544659622.506 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-12-13T00:07:02.507Z,1544659622.507 [LoopControl](DEBUG): Construct LoopControl.
2018-12-13T00:07:02.507Z,1544659622.507 [LoopControl] Loaded
2018-12-13T00:07:02.508Z,1544659622.508 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-12-13T00:07:02.508Z,1544659622.508 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-12-13T00:07:02.509Z,1544659622.509 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-12-13T00:07:02.608Z,1544659622.608 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-12-13T00:07:02.609Z,1544659622.609 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-12-13T00:07:02.725Z,1544659622.725 [BuoyancyServo] Loaded
2018-12-13T00:07:02.725Z,1544659622.725 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-12-13T00:07:02.736Z,1544659622.736 [ElevatorServo] Loaded
2018-12-13T00:07:02.737Z,1544659622.737 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-12-13T00:07:02.747Z,1544659622.747 [MassServo] Loaded
2018-12-13T00:07:02.748Z,1544659622.748 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-12-13T00:07:02.758Z,1544659622.758 [RudderServo] Loaded
2018-12-13T00:07:02.759Z,1544659622.759 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-12-13T00:07:02.769Z,1544659622.769 [ThrusterServo] Loaded
2018-12-13T00:07:02.769Z,1544659622.769 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-12-13T00:07:02.770Z,1544659622.770 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-12-13T00:07:02.771Z,1544659622.771 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-12-13T00:07:02.881Z,1544659622.881 [DeadReckonUsingSpeedCalculator] Loaded
2018-12-13T00:07:02.882Z,1544659622.882 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-12-13T00:07:02.897Z,1544659622.897 [NavChart] Loaded
2018-12-13T00:07:02.897Z,1544659622.897 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-12-13T00:07:02.901Z,1544659622.901 [UniversalFixResidualReporter] Loaded
2018-12-13T00:07:02.902Z,1544659622.902 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-12-13T00:07:02.902Z,1544659622.902 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-12-13T00:07:02.905Z,1544659622.905 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-12-13T00:07:02.907Z,1544659622.907 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-12-13T00:07:02.913Z,1544659622.913 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-12-13T00:07:02.914Z,1544659622.914 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A674E0
2018-12-13T00:07:02.915Z,1544659622.915 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1067
2018-12-13T00:07:02.920Z,1544659622.920 [Supervisor](INFO): Main Thread ID is 794
2018-12-13T00:07:02.920Z,1544659622.920 [Supervisor](DEBUG): Running supervisor.
2018-12-13T00:07:02.920Z,1544659622.920 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1068
2018-12-13T00:07:02.923Z,1544659622.923 [controlThread ThreadHandler](INFO): Handler Thread ID is 1069
2018-12-13T00:07:02.923Z,1544659622.923 [controlThread](DEBUG): Initializing ControlThread
2018-12-13T00:07:02.924Z,1544659622.924 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-13T00:07:02.925Z,1544659622.925 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-12-13T00:07:02.925Z,1544659622.925 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-12-13T00:07:02.925Z,1544659622.925 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-12-13T00:07:02.926Z,1544659622.926 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-12-13T00:07:02.926Z,1544659622.926 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-12-13T00:07:02.933Z,1544659622.933 [SBIT](INFO): Initialize SBIT Component.
2018-12-13T00:07:02.933Z,1544659622.933 [SBIT](IMPORTANT): git: 2018-12-11
2018-12-13T00:07:02.934Z,1544659622.934 [SBIT](INFO): git hash: 6a3a4f0b8da85acb38648d00e0c49af0c8c4f4ae
2018-12-13T00:07:02.934Z,1544659622.934 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-12-13T00:07:02.934Z,1544659622.934 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-12-13T00:07:02.935Z,1544659622.935 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-12-13T00:07:02.936Z,1544659622.936 [IBIT](INFO): Initialize IBIT Component.
2018-12-13T00:07:02.937Z,1544659622.937 [CBIT](DEBUG): Initialize CBIT Component.
2018-12-13T00:07:02.938Z,1544659622.938 [logger ThreadHandler](INFO): Handler Thread ID is 1070
2018-12-13T00:07:02.951Z,1544659622.951 [CBIT](DEBUG): Initialized mux pins.
2018-12-13T00:07:02.951Z,1544659622.951 [CBIT](DEBUG): Initializing the watchdog timer.
2018-12-13T00:07:02.955Z,1544659622.955 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1071
2018-12-13T00:07:02.961Z,1544659622.961 [Radio_Surface](INFO): Powering up
2018-12-13T00:07:02.971Z,1544659622.971 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1072
2018-12-13T00:07:02.972Z,1544659622.972 [Rowe_600LCM](INFO): Initializing
2018-12-13T00:07:02.972Z,1544659622.972 [Rowe_600LCM](INFO): Checking LCM
2018-12-13T00:07:02.979Z,1544659622.979 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-12-13T00:07:02.979Z,1544659622.979 [CBIT](DEBUG): Initializing heartbeat.
2018-12-13T00:07:02.982Z,1544659622.982 [Rowe_600LCM](INFO): LCM OK
2018-12-13T00:07:02.982Z,1544659622.982 [Rowe_600LCM](INFO): Powering up
2018-12-13T00:07:02.987Z,1544659622.987 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1073
2018-12-13T00:07:02.988Z,1544659622.988 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-13T00:07:02.991Z,1544659622.991 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-13T00:07:02.992Z,1544659622.992 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1075
2018-12-13T00:07:02.994Z,1544659622.994 [WetLabsBB2FL](INFO): Powering down
2018-12-13T00:07:03.051Z,1544659623.051 [CBIT](DEBUG): Deactivating GF circuits.
2018-12-13T00:07:03.051Z,1544659623.051 [CBIT](DEBUG): Deactivating emergency mode.
2018-12-13T00:07:03.051Z,1544659623.051 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1076
2018-12-13T00:07:03.056Z,1544659623.056 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-12-13T00:07:03.057Z,1544659623.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-12-13T00:07:03.057Z,1544659623.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-12-13T00:07:03.057Z,1544659623.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-12-13T00:07:03.057Z,1544659623.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-12-13T00:07:03.057Z,1544659623.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-12-13T00:07:03.058Z,1544659623.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-12-13T00:07:03.058Z,1544659623.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-12-13T00:07:03.058Z,1544659623.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-12-13T00:07:03.058Z,1544659623.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-12-13T00:07:03.058Z,1544659623.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-12-13T00:07:03.059Z,1544659623.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-12-13T00:07:03.059Z,1544659623.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-12-13T00:07:03.059Z,1544659623.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-12-13T00:07:03.059Z,1544659623.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-12-13T00:07:03.059Z,1544659623.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-12-13T00:07:03.091Z,1544659623.091 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-12-13T00:07:03.093Z,1544659623.093 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-12-13T00:07:03.093Z,1544659623.093 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-12-13T00:07:03.094Z,1544659623.094 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-12-13T00:07:03.096Z,1544659623.096 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-13T00:07:03.097Z,1544659623.097 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-13T00:07:03.097Z,1544659623.097 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-13T00:07:03.097Z,1544659623.097 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-12-13T00:07:03.098Z,1544659623.098 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-12-13T00:07:03.099Z,1544659623.099 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-12-13T00:07:03.113Z,1544659623.113 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-13T00:07:03.157Z,1544659623.157 [MissionManager](DEBUG):
2018-12-13T00:07:03.158Z,1544659623.158 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-12-13T00:07:03.280Z,1544659623.280 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-12-13T00:07:03.281Z,1544659623.281 [Default:A.Wait](DEBUG): Construct Wait.
2018-12-13T00:07:03.283Z,1544659623.283 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-13T00:07:03.329Z,1544659623.329 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-12-13T00:07:03.356Z,1544659623.356 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-12-13T00:07:03.362Z,1544659623.362 [Default:E.Execute](DEBUG): Construct Execute.
2018-12-13T00:07:03.397Z,1544659623.397 [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
2018-12-13T00:07:03.402Z,1544659623.402 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-12-13T00:07:03.451Z,1544659623.451 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-12-13T00:07:03.452Z,1544659623.452 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-12-13T00:07:03.481Z,1544659623.481 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-12-13T00:07:03.781Z,1544659623.781 [ESPComponent](INFO): powering down ESP
2018-12-13T00:07:04.475Z,1544659624.475 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-13T00:07:04.491Z,1544659624.491 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-13T00:07:04.517Z,1544659624.517 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-12-13T00:07:04.531Z,1544659624.531 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-12-13T00:07:04.549Z,1544659624.549 [MassServo](DEBUG): Initializing EZServoServo.
2018-12-13T00:07:04.559Z,1544659624.559 [MassServo](DEBUG): Initializing MassServo.
2018-12-13T00:07:04.585Z,1544659624.585 [RudderServo](DEBUG): Initializing EZServoServo.
2018-12-13T00:07:04.591Z,1544659624.591 [RudderServo](DEBUG): Initializing RudderServo.
2018-12-13T00:07:04.615Z,1544659624.615 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-12-13T00:07:04.627Z,1544659624.627 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-12-13T00:07:04.679Z,1544659624.679 [CommandLine](FAULT): Scheduling is paused
2018-12-13T00:07:04.679Z,1544659624.679 [CBIT](INFO): Critical error at 20181213T000702
2018-12-13T00:07:04.679Z,1544659624.679 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-12-13T00:07:04.850Z,1544659624.850 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-12-13T00:07:04.851Z,1544659624.851 [DropWeight] Hardware Fault, FailCount= 1
2018-12-13T00:07:04.851Z,1544659624.851 [DropWeight](ERROR): Hardware Fault
2018-12-13T00:07:04.992Z,1544659624.992 [CBIT](INFO): Critical error at 20181213T000704
2018-12-13T00:07:04.995Z,1544659624.995 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-12-13T00:07:04.995Z,1544659624.995 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-12-13T00:07:05.576Z,1544659625.576 [CBIT](INFO): Critical error at 20181213T000704
2018-12-13T00:07:07.405Z,1544659627.405 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-13T00:07:07.489Z,1544659627.489 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-12-13T00:07:08.700Z,1544659628.700 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-13T00:07:08.700Z,1544659628.700 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-13T00:07:08.701Z,1544659628.701 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-13T00:07:10.792Z,1544659630.792 [AcousticModem_Benthos_ATM900](DEBUG):
2018-12-13T00:07:11.207Z,1544659631.207 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-13T00:07:11.207Z,1544659631.207 [PNI_TCM] Data Fault, FailCount= 1
2018-12-13T00:07:11.207Z,1544659631.207 [PNI_TCM](ERROR): Data Fault
2018-12-13T00:07:11.288Z,1544659631.288 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-13T00:07:11.606Z,1544659631.606 [PNI_TCM](INFO): Powering down
2018-12-13T00:07:12.389Z,1544659632.389 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-12-13T00:07:12.390Z,1544659632.390 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-12-13T00:07:12.390Z,1544659632.390 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-12-13T00:07:12.391Z,1544659632.391 [AcousticModem_Benthos_ATM900](DEBUG): Nov 11 2018 09:34:21
2018-12-13T00:07:12.788Z,1544659632.788 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-12-13T00:07:12.896Z,1544659632.896 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-13T00:07:12.896Z,1544659632.896 [PNI_TCM] No Fault, FailCount= 1
2018-12-13T00:07:13.317Z,1544659633.317 [CBIT](CRITICAL): Environmental Failure. Press:14.881536 PSI. Humidity:40%. Temp:25 C. ABORTING MISSION
2018-12-13T00:07:13.588Z,1544659633.588 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-12-13T00:07:13.690Z,1544659633.690 [CBIT](INFO): Critical error at 20181213T000713
2018-12-13T00:07:13.988Z,1544659633.988 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-12-13T00:07:13.989Z,1544659633.989 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-12-13T00:07:13.989Z,1544659633.989 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-12-13T00:07:14.019Z,1544659634.019 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-13T00:07:14.020Z,1544659634.020 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-12-13T00:07:14.020Z,1544659634.020 [Aanderaa_O2](ERROR): Communications Fault
2018-12-13T00:07:14.068Z,1544659634.068 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-13T00:07:14.388Z,1544659634.388 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-12-13T00:07:14.417Z,1544659634.417 [Aanderaa_O2](INFO): Powering down
2018-12-13T00:07:14.788Z,1544659634.788 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-12-13T00:07:14.789Z,1544659634.789 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-12-13T00:07:15.189Z,1544659635.189 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-12-13T00:07:15.294Z,1544659635.294 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-13T00:07:15.294Z,1544659635.294 [Aanderaa_O2] No Fault, FailCount= 1
2018-12-13T00:07:17.043Z,1544659637.043 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-13T00:07:17.043Z,1544659637.043 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-13T00:07:17.043Z,1544659637.043 [CTD_Seabird](ERROR): Communications Fault
2018-12-13T00:07:17.284Z,1544659637.284 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-13T00:07:17.447Z,1544659637.447 [CTD_Seabird](INFO): Powering down
2018-12-13T00:07:19.675Z,1544659639.675 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-13T00:07:19.676Z,1544659639.676 [CTD_Seabird] No Fault, FailCount= 1
2018-12-13T00:07:20.295Z,1544659640.295 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-13T00:07:20.296Z,1544659640.296 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-13T00:07:20.412Z,1544659640.412 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-13T00:07:20.412Z,1544659640.412 [PNI_TCM] Data Fault, FailCount= 2
2018-12-13T00:07:20.412Z,1544659640.412 [PNI_TCM](ERROR): Data Fault
2018-12-13T00:07:20.507Z,1544659640.507 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-13T00:07:20.807Z,1544659640.807 [PNI_TCM](INFO): Powering down
2018-12-13T00:07:22.099Z,1544659642.099 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-13T00:07:22.099Z,1544659642.099 [PNI_TCM] No Fault, FailCount= 2
2018-12-13T00:07:26.008Z,1544659646.008 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-13T00:07:26.008Z,1544659646.008 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-12-13T00:07:26.008Z,1544659646.008 [Aanderaa_O2](ERROR): Communications Fault
2018-12-13T00:07:26.099Z,1544659646.099 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-13T00:07:26.408Z,1544659646.408 [Aanderaa_O2](INFO): Powering down
2018-12-13T00:07:27.324Z,1544659647.324 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-12-13T00:07:27.324Z,1544659647.324 [Aanderaa_O2] No Fault, FailCount= 2
2018-12-13T00:07:27.659Z,1544659647.659 [SBIT](IMPORTANT): Beginning Startup BIT
2018-12-13T00:07:27.679Z,1544659647.679 [CBIT](IMPORTANT): Beginning ground fault scan
2018-12-13T00:07:29.592Z,1544659649.592 [NAL9602](INFO): Powering up NAL9602
2018-12-13T00:07:29.609Z,1544659649.609 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-13T00:07:29.609Z,1544659649.609 [PNI_TCM] Data Fault, FailCount= 3
2018-12-13T00:07:29.609Z,1544659649.609 [PNI_TCM](ERROR): Data Fault
2018-12-13T00:07:30.093Z,1544659650.093 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-13T00:07:30.133Z,1544659650.133 [PNI_TCM](INFO): Powering down
2018-12-13T00:07:31.501Z,1544659651.501 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-13T00:07:31.501Z,1544659651.501 [PNI_TCM] No Fault, FailCount= 3
2018-12-13T00:07:32.023Z,1544659652.023 [CommandLine](IMPORTANT): got command get depth
2018-12-13T00:07:32.024Z,1544659652.024 [CommandLine](IMPORTANT): depth 0.102918 m
2018-12-13T00:07:34.339Z,1544659654.339 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-13T00:07:34.339Z,1544659654.339 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-13T00:07:34.339Z,1544659654.339 [CTD_Seabird](ERROR): Communications Fault
2018-12-13T00:07:34.609Z,1544659654.609 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-13T00:07:34.743Z,1544659654.743 [CTD_Seabird](INFO): Powering down
2018-12-13T00:07:37.008Z,1544659657.008 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-13T00:07:37.008Z,1544659657.008 [CTD_Seabird] No Fault, FailCount= 2
2018-12-13T00:07:37.691Z,1544659657.691 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-13T00:07:37.710Z,1544659657.710 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-13T00:07:37.726Z,1544659657.726 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-12-13T00:07:37.727Z,1544659657.727 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-12-13T00:07:37.727Z,1544659657.727 [Aanderaa_O2](ERROR): Communications Fault
2018-12-13T00:07:37.837Z,1544659657.837 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-12-13T00:07:37.837Z,1544659657.837 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-12-13T00:07:38.239Z,1544659658.239 [Aanderaa_O2](INFO): Powering down
2018-12-13T00:07:38.924Z,1544659658.924 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-13T00:07:38.924Z,1544659658.924 [PNI_TCM] Data Fault, FailCount= 4
2018-12-13T00:07:38.924Z,1544659658.924 [PNI_TCM](ERROR): Data Fault
2018-12-13T00:07:38.990Z,1544659658.990 [CommandLine](IMPORTANT): got command get depth
2018-12-13T00:07:38.990Z,1544659658.990 [CommandLine](IMPORTANT): depth 0.106059 m
2018-12-13T00:07:39.001Z,1544659659.001 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-13T00:07:39.028Z,1544659659.028 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006981
CHAN A1 (24V): -0.011396
CHAN A2 (12V): -0.005116
CHAN A3 (5V): -0.002343
CHAN B0 (3.3V): -0.001419
CHAN B1 (3.15aV): -0.001190
CHAN B2 (3.15bV): -0.000899
CHAN B3 (GND): -0.000092
OPEN: 0.004835
Full Scale Calc: 4.765 mA, -1.589 mA
2018-12-13T00:07:39.319Z,1544659659.319 [PNI_TCM](INFO): Powering down
2018-12-13T00:07:40.512Z,1544659660.512 [NAL9602](INFO): NAL9602 initialized
2018-12-13T00:07:40.649Z,1544659660.649 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-12-13T00:07:40.649Z,1544659660.649 [PNI_TCM] No Fault, FailCount= 4
2018-12-13T00:07:45.836Z,1544659665.836 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.000000 meter
2018-12-13T00:07:45.837Z,1544659665.837 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2018-12-13T00:07:46.089Z,1544659666.089 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-12-13T00:07:47.960Z,1544659667.960 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-12-13T00:07:47.960Z,1544659667.960 [PNI_TCM] Data Fault, FailCount= 5
2018-12-13T00:07:47.961Z,1544659667.961 [PNI_TCM](ERROR): Data Fault
2018-12-13T00:07:48.101Z,1544659668.101 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-12-13T00:07:48.101Z,1544659668.101 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-12-13T00:07:48.359Z,1544659668.359 [PNI_TCM](INFO): Powering down
2018-12-13T00:07:48.730Z,1544659668.730 [CBIT](INFO): Critical error at 20181213T000748
2018-12-13T00:07:51.759Z,1544659671.759 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-13T00:07:51.759Z,1544659671.759 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-13T00:07:51.759Z,1544659671.759 [CTD_Seabird](ERROR): Communications Fault
2018-12-13T00:07:52.061Z,1544659672.061 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-13T00:07:52.061Z,1544659672.061 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-13T00:07:52.163Z,1544659672.163 [CTD_Seabird](INFO): Powering down
2018-12-13T00:08:00.923Z,1544659680.923 [CommandLine](IMPORTANT): got command get depth
2018-12-13T00:08:00.924Z,1544659680.924 [CommandLine](IMPORTANT): depth 0.000000 m
2018-12-13T00:08:08.471Z,1544659688.471 [CommandLine](IMPORTANT): got command maintain clear
2018-12-13T00:08:08.864Z,1544659688.864 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-12-13T00:08:11.147Z,1544659691.147 [CommandLine](IMPORTANT): got command get depth
2018-12-13T00:08:11.148Z,1544659691.148 [CommandLine](IMPORTANT): depth 0.092148 m
2018-12-13T00:08:14.651Z,1544659694.651 [CommandLine](IMPORTANT): got command show best depth
2018-12-13T00:08:14.652Z,1544659694.652 [CommandLine](IMPORTANT): depth best is Depth_Keller.depth
2018-12-13T00:08:21.728Z,1544659701.728 [SBIT](IMPORTANT): SBIT PASSED
2018-12-13T00:08:21.764Z,1544659701.764 [CommandLine](IMPORTANT): got command configSet list
2018-12-13T00:08:21.765Z,1544659701.765 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-12-13T00:08:21.766Z,1544659701.766 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;
2018-12-13T00:08:22.119Z,1544659702.119 [MissionManager](IMPORTANT): Started mission Startup
2018-12-13T00:08:22.119Z,1544659702.119 [Startup] Running Loop=1
2018-12-13T00:08:22.119Z,1544659702.119 [Startup](DEBUG): Aggregate::initialize Startup
2018-12-13T00:08:22.119Z,1544659702.119 [Startup:A.GoToSurface] Running Loop=1
2018-12-13T00:08:22.119Z,1544659702.119 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-12-13T00:08:22.120Z,1544659702.120 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-12-13T00:08:22.120Z,1544659702.120 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-12-13T00:08:22.121Z,1544659702.121 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-12-13T00:08:22.121Z,1544659702.121 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-12-13T00:08:22.122Z,1544659702.122 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-12-13T00:08:22.127Z,1544659702.127 [Startup:StartupSatComms] Running Loop=1
2018-12-13T00:08:22.127Z,1544659702.127 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-12-13T00:08:22.127Z,1544659702.127 [Startup:StartupSatComms:A] Running Loop=1
2018-12-13T00:08:22.526Z,1544659702.526 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-12-13T00:08:35.339Z,1544659715.339 [CommandLine](IMPORTANT): got command failComponent
2018-12-13T00:08:35.340Z,1544659715.340 [CommandLine](IMPORTANT): Failed components:
2018-12-13T00:08:35.340Z,1544659715.340 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-12-13T00:08:35.340Z,1544659715.340 [CommandLine](IMPORTANT): PNI_TCM: Data Fault
2018-12-13T00:08:35.340Z,1544659715.340 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2018-12-13T00:08:35.340Z,1544659715.340 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2018-12-13T00:08:47.921Z,1544659727.921 [CommandLine](IMPORTANT): got command configSet DropWeight.loadAtStartup 0.000000 bool persist
2018-12-13T00:08:47.921Z,1544659727.921 [CommandLine](IMPORTANT): configSet DropWeight.loadAtStartup requires a restart to take effect.
2018-12-13T00:08:59.185Z,1544659739.185 [CommandLine](IMPORTANT): got command configSet Aanderaa_O2.loadAtStartup 0.000000 bool persist
2018-12-13T00:08:59.185Z,1544659739.185 [CommandLine](IMPORTANT): configSet Aanderaa_O2.loadAtStartup requires a restart to take effect.
2018-12-13T00:09:02.841Z,1544659742.841 [CommandLine](IMPORTANT): got command show variable lCM
2018-12-13T00:09:02.848Z,1544659742.848 [CommandLine](IMPORTANT): LcmPublisher.loadAtStartup (bool)
2018-12-13T00:09:02.849Z,1544659742.849 [CommandLine](IMPORTANT): LcmPublisher.loopHz (hertz)
2018-12-13T00:09:02.849Z,1544659742.849 [CommandLine](IMPORTANT): LcmPublisher.nChan (count)
2018-12-13T00:09:02.849Z,1544659742.849 [CommandLine](IMPORTANT): LcmPublisher.nDoubleItems (count)
2018-12-13T00:09:02.850Z,1544659742.850 [CommandLine](IMPORTANT): LcmPublisher.publishPrefix (none)
2018-12-13T00:09:02.850Z,1544659742.850 [CommandLine](IMPORTANT): LcmListener.loadAtStartup (bool)
2018-12-13T00:09:02.850Z,1544659742.850 [CommandLine](IMPORTANT): LcmListener.listenPrefix (none)
2018-12-13T00:09:02.851Z,1544659742.851 [CommandLine](IMPORTANT): LcmListener.logMsg (bool)
2018-12-13T00:09:02.853Z,1544659742.853 [CommandLine](IMPORTANT): CTD_SeabirdLCM.loadAtStartup (bool)
2018-12-13T00:09:02.853Z,1544659742.853 [CommandLine](IMPORTANT): CTD_SeabirdLCM.simulateHardware (bool)
2018-12-13T00:09:02.854Z,1544659742.854 [CommandLine](IMPORTANT): CTD_SeabirdLCM.maxPressBound (decibar)
2018-12-13T00:09:02.854Z,1544659742.854 [CommandLine](IMPORTANT): CTD_SeabirdLCM.minPressBound (decibar)
2018-12-13T00:09:02.854Z,1544659742.854 [CommandLine](IMPORTANT): CTD_SeabirdLCM.maxSalinityBound (practical_salinity_unit)
2018-12-13T00:09:02.870Z,1544659742.870 [CommandLine](IMPORTANT): CTD_SeabirdLCM.minSalinityBound (practical_salinity_unit)
2018-12-13T00:09:03.043Z,1544659743.043 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup (bool)
2018-12-13T00:09:03.044Z,1544659743.044 [CommandLine](IMPORTANT): Rowe_600LCM.simulateHardware (bool)
2018-12-13T00:09:03.044Z,1544659743.044 [CommandLine](IMPORTANT): Rowe_600LCM.altitudeAccuracy (meter)
2018-12-13T00:09:03.044Z,1544659743.044 [CommandLine](IMPORTANT): Rowe_600LCM.bottomTrackVelocityAccuracy (meter_per_second)
2018-12-13T00:09:03.045Z,1544659743.045 [CommandLine](IMPORTANT): Rowe_600LCM.waterTrackVelocityAccuracy (meter_per_second)
2018-12-13T00:09:03.055Z,1544659743.055 [CommandLine](IMPORTANT): CTD_SeabirdLCM.loadControl (none)
2018-12-13T00:09:03.056Z,1544659743.056 [CommandLine](IMPORTANT): CTD_SeabirdLCM.uart (none)
2018-12-13T00:09:03.056Z,1544659743.056 [CommandLine](IMPORTANT): CTD_SeabirdLCM.baud (bit_per_second)
2018-12-13T00:09:03.056Z,1544659743.056 [CommandLine](IMPORTANT): CTD_SeabirdLCM.lcmApplication (none)
2018-12-13T00:09:03.071Z,1544659743.071 [CommandLine](IMPORTANT): Rowe_600LCM.loadControl (none)
2018-12-13T00:09:03.071Z,1544659743.071 [CommandLine](IMPORTANT): Rowe_600LCM.uart (none)
2018-12-13T00:09:03.072Z,1544659743.072 [CommandLine](IMPORTANT): Rowe_600LCM.baud (bit_per_second)
2018-12-13T00:09:03.072Z,1544659743.072 [CommandLine](IMPORTANT): Rowe_600LCM.lcmApplication (none)
2018-12-13T00:09:03.072Z,1544659743.072 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelBottom (none)
2018-12-13T00:09:03.073Z,1544659743.073 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelDVL (none)
2018-12-13T00:09:03.073Z,1544659743.073 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelWater (none)
2018-12-13T00:09:03.073Z,1544659743.073 [CommandLine](IMPORTANT): Rowe_600LCM.maxSpeed (meter_per_second)
2018-12-13T00:09:03.105Z,1544659743.105 [CommandLine](IMPORTANT): Rowe_600LCM.height_above_sea_floor (meter)
2018-12-13T00:09:03.105Z,1544659743.105 [CommandLine](IMPORTANT): Rowe_600LCM.platform_x_velocity_wrt_ground (meter_per_second)
2018-12-13T00:09:03.106Z,1544659743.106 [CommandLine](IMPORTANT): Rowe_600LCM.platform_y_velocity_wrt_ground (meter_per_second)
2018-12-13T00:09:03.106Z,1544659743.106 [CommandLine](IMPORTANT): Rowe_600LCM.platform_z_velocity_wrt_ground (meter_per_second)
2018-12-13T00:09:03.106Z,1544659743.106 [CommandLine](IMPORTANT): Rowe_600LCM.platform_x_velocity_wrt_sea_water (meter_per_second)
2018-12-13T00:09:03.107Z,1544659743.107 [CommandLine](IMPORTANT): Rowe_600LCM.platform_y_velocity_wrt_sea_water (meter_per_second)
2018-12-13T00:09:03.107Z,1544659743.107 [CommandLine](IMPORTANT): Rowe_600LCM.platform_z_velocity_wrt_sea_water (meter_per_second)
2018-12-13T00:09:03.107Z,1544659743.107 [CommandLine](IMPORTANT): Rowe_600LCM.platform_velocity_wrt_ground (meter_per_second)
2018-12-13T00:09:03.108Z,1544659743.108 [CommandLine](IMPORTANT): Rowe_600LCM.platform_velocity_wrt_sea_water (meter_per_second)
2018-12-13T00:09:03.108Z,1544659743.108 [CommandLine](IMPORTANT): Rowe_600LCM.Altitude1 (meter)
2018-12-13T00:09:03.108Z,1544659743.108 [CommandLine](IMPORTANT): Rowe_600LCM.Altitude2 (meter)
2018-12-13T00:09:03.109Z,1544659743.109 [CommandLine](IMPORTANT): Rowe_600LCM.Altitude3 (meter)
2018-12-13T00:09:03.109Z,1544659743.109 [CommandLine](IMPORTANT): Rowe_600LCM.Altitude4 (meter)
2018-12-13T00:09:03.150Z,1544659743.150 [CommandLine](IMPORTANT): Rowe_600LCM.durationOfLastRun (second)
2018-12-13T00:09:03.173Z,1544659743.173 [CommandLine](IMPORTANT): Rowe_600LCM.component_voltage (volt)
2018-12-13T00:09:03.174Z,1544659743.174 [CommandLine](IMPORTANT): Rowe_600LCM.component_avgVoltage (volt)
2018-12-13T00:09:03.174Z,1544659743.174 [CommandLine](IMPORTANT): Rowe_600LCM.component_current (milliampere)
2018-12-13T00:09:03.175Z,1544659743.175 [CommandLine](IMPORTANT): Rowe_600LCM.component_avgCurrent (milliampere)
2018-12-13T00:09:03.340Z,1544659743.340 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-12-13T00:09:03.340Z,1544659743.340 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-12-13T00:09:03.340Z,1544659743.340 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-12-13T00:09:03.375Z,1544659743.375 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-12-13T00:09:03.375Z,1544659743.375 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-12-13T00:09:03.375Z,1544659743.375 [Rowe_600LCM](ERROR): Communications Fault
2018-12-13T00:09:03.381Z,1544659743.381 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-12-13T00:09:03.382Z,1544659743.382 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-12-13T00:09:03.728Z,1544659743.728 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-12-13T00:09:03.728Z,1544659743.728 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-12-13T00:09:03.779Z,1544659743.779 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-13T00:09:03.981Z,1544659743.981 [Rowe_600LCM](INFO): Powering down
2018-12-13T00:09:04.116Z,1544659744.116 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-13T00:09:04.117Z,1544659744.117 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-13T00:09:04.117Z,1544659744.117 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-13T00:09:05.322Z,1544659745.322 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-12-13T00:09:05.346Z,1544659745.346 [Rowe_600LCM] No Fault, FailCount= 1
2018-12-13T00:09:05.595Z,1544659745.595 [Rowe_600LCM](INFO): Initializing
2018-12-13T00:09:05.595Z,1544659745.595 [Rowe_600LCM](INFO): Checking LCM
2018-12-13T00:09:05.595Z,1544659745.595 [Rowe_600LCM](INFO): LCM OK
2018-12-13T00:09:05.595Z,1544659745.595 [Rowe_600LCM](INFO): Powering up
2018-12-13T00:09:09.808Z,1544659749.808 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-12-13T00:09:09.873Z,1544659749.873 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-12-13T00:09:09.874Z,1544659749.874 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-12-13T00:09:09.884Z,1544659749.884 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-12-13T00:09:09.884Z,1544659749.884 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-12-13T00:09:10.459Z,1544659750.459 [CommandLine](IMPORTANT): got command failComponent
2018-12-13T00:09:10.459Z,1544659750.459 [CommandLine](IMPORTANT): Failed components:
2018-12-13T00:09:10.460Z,1544659750.460 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-12-13T00:09:10.460Z,1544659750.460 [CommandLine](IMPORTANT): PNI_TCM: Data Fault
2018-12-13T00:09:10.460Z,1544659750.460 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2018-12-13T00:09:10.461Z,1544659750.461 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2018-12-13T00:09:22.150Z,1544659762.150 [Startup:StartupSatComms:A](INFO): Timed out from 2018-12-13T00:08:22.1Z
2018-12-13T00:09:22.150Z,1544659762.150 [Startup:StartupSatComms:A] Stopped
2018-12-13T00:09:22.150Z,1544659762.150 [Startup:StartupSatComms:B] Running Loop=1
2018-12-13T00:09:22.548Z,1544659762.548 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-12-13T00:09:25.348Z,1544659765.348 [CommandLine](IMPORTANT): got command restart application
2018-12-13T00:09:26.358Z,1544659766.358 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-13T00:09:26.358Z,1544659766.358 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.459Z,1544659766.459 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2018-12-13T00:09:26.459Z,1544659766.459 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.459Z,1544659766.459 [CommandLine](INFO): Join timeout helper Thread ID is 1107
2018-12-13T00:09:26.471Z,1544659766.471 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-12-13T00:09:26.471Z,1544659766.471 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.471Z,1544659766.471 [NavChartDb](INFO): Join timeout helper Thread ID is 1108
2018-12-13T00:09:26.679Z,1544659766.679 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-13T00:09:26.679Z,1544659766.679 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.691Z,1544659766.691 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-12-13T00:09:26.691Z,1544659766.691 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.691Z,1544659766.691 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1109
2018-12-13T00:09:26.819Z,1544659766.819 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-13T00:09:26.819Z,1544659766.819 [WetLabsBB2FL](INFO): Powering down
2018-12-13T00:09:26.820Z,1544659766.820 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.831Z,1544659766.831 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-12-13T00:09:26.831Z,1544659766.831 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.831Z,1544659766.831 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1110
2018-12-13T00:09:26.875Z,1544659766.875 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-13T00:09:26.875Z,1544659766.875 [CTD_Seabird](INFO): Powering down
2018-12-13T00:09:26.887Z,1544659766.887 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.906Z,1544659766.906 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-12-13T00:09:26.906Z,1544659766.906 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:26.906Z,1544659766.906 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1111
2018-12-13T00:09:27.003Z,1544659767.003 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-13T00:09:27.003Z,1544659767.003 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-12-13T00:09:27.166Z,1544659767.166 [Rowe_600LCM](INFO): Powering down
2018-12-13T00:09:27.168Z,1544659767.168 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:27.188Z,1544659767.188 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-12-13T00:09:27.188Z,1544659767.188 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:27.188Z,1544659767.188 [Radio_Surface](INFO): Join timeout helper Thread ID is 1113
2018-12-13T00:09:27.287Z,1544659767.287 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-13T00:09:27.287Z,1544659767.287 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:27.307Z,1544659767.307 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-12-13T00:09:27.308Z,1544659767.308 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:27.308Z,1544659767.308 [logger](INFO): Join timeout helper Thread ID is 1114
2018-12-13T00:09:27.347Z,1544659767.347 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-13T00:09:27.347Z,1544659767.347 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:27.359Z,1544659767.359 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-12-13T00:09:27.360Z,1544659767.360 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:27.360Z,1544659767.360 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-12-13T00:09:27.360Z,1544659767.360 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:27.360Z,1544659767.360 [controlThread](INFO): Join timeout helper Thread ID is 1115
2018-12-13T00:09:28.589Z,1544659768.589 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-13T00:09:28.589Z,1544659768.589 [controlThread](DEBUG): Uninitializing ControlThread
2018-12-13T00:09:28.590Z,1544659768.590 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-12-13T00:09:28.715Z,1544659768.715 [NAL9602](INFO): Powering down
2018-12-13T00:09:28.717Z,1544659768.717 [PNI_TCM](INFO): Powering down
2018-12-13T00:09:28.718Z,1544659768.718 [Aanderaa_O2](INFO): Powering down
2018-12-13T00:09:28.721Z,1544659768.721 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2018-12-13T00:09:28.722Z,1544659768.722 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-12-13T00:09:28.722Z,1544659768.722 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-12-13T00:09:28.723Z,1544659768.723 [MissionManager](INFO): Uninitializing Mission Default
2018-12-13T00:09:28.725Z,1544659768.725 [MissionManager](INFO): Uninitializing Mission Startup
2018-12-13T00:09:28.726Z,1544659768.726 [Startup] Stopped
2018-12-13T00:09:28.726Z,1544659768.726 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-12-13T00:09:28.726Z,1544659768.726 [Startup:A.GoToSurface] Stopped
2018-12-13T00:09:28.726Z,1544659768.726 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-12-13T00:09:28.726Z,1544659768.726 [Startup:StartupSatComms] Stopped
2018-12-13T00:09:28.726Z,1544659768.726 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-12-13T00:09:28.726Z,1544659768.726 [Startup:StartupSatComms:B] Stopped
2018-12-13T00:09:28.728Z,1544659768.728 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-12-13T00:09:28.728Z,1544659768.728 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-12-13T00:09:28.728Z,1544659768.728 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-12-13T00:09:28.729Z,1544659768.729 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-12-13T00:09:28.729Z,1544659768.729 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-12-13T00:09:28.729Z,1544659768.729 [BuoyancyServo](INFO): Powering down
2018-12-13T00:09:28.743Z,1544659768.743 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-12-13T00:09:28.743Z,1544659768.743 [ElevatorServo](INFO): Powering down
2018-12-13T00:09:28.744Z,1544659768.744 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-12-13T00:09:28.744Z,1544659768.744 [MassServo](INFO): Powering down
2018-12-13T00:09:28.745Z,1544659768.745 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-12-13T00:09:28.745Z,1544659768.745 [RudderServo](INFO): Powering down
2018-12-13T00:09:28.746Z,1544659768.746 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-12-13T00:09:28.746Z,1544659768.746 [ThrusterServo](INFO): Powering down
2018-12-13T00:09:28.747Z,1544659768.747 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-12-13T00:09:28.747Z,1544659768.747 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-12-13T00:09:28.747Z,1544659768.747 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-12-13T00:09:28.748Z,1544659768.748 [CBIT](DEBUG): Powering off loads.
2018-12-13T00:09:28.758Z,1544659768.758 [CBIT](DEBUG): Disabling WDT.
2018-12-13T00:09:28.770Z,1544659768.770 [CBIT](DEBUG): Opening all GF detection circuits.
2018-12-13T00:09:28.772Z,1544659768.772 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:28.876Z,1544659768.876 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:28.882Z,1544659768.882 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:28.924Z,1544659768.924 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:28.929Z,1544659768.929 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:28.977Z,1544659768.977 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-13T00:09:29.039Z,1544659769.039 [logger ThreadHandler](INFO): Thread cancelled.