2018-11-06T06:03:42.156Z,1541484222.156 [Supervisor](DEBUG): Initializing supervisor.
2018-11-06T06:03:42.159Z,1541484222.159 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-06T06:03:42.160Z,1541484222.160 [SyncHandler](INFO): Protected caller Thread ID is 3108
2018-11-06T06:03:42.161Z,1541484222.161 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-06T06:03:42.162Z,1541484222.162 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-06T06:03:42.162Z,1541484222.162 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3109
2018-11-06T06:03:42.165Z,1541484222.165 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-06T06:03:42.177Z,1541484222.177 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-06T06:03:42.178Z,1541484222.178 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-06T06:03:42.178Z,1541484222.178 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3110
2018-11-06T06:03:42.179Z,1541484222.179 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-06T06:03:42.180Z,1541484222.180 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-06T06:03:42.180Z,1541484222.180 [logger ThreadHandler](INFO): Protected caller Thread ID is 3111
2018-11-06T06:03:42.182Z,1541484222.182 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-06T06:03:42.183Z,1541484222.183 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-06T06:03:42.184Z,1541484222.184 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-06T06:03:42.281Z,1541484222.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-06T06:03:42.282Z,1541484222.282 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-06T06:03:42.381Z,1541484222.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-06T06:03:42.381Z,1541484222.381 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-06T06:03:42.579Z,1541484222.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-06T06:03:42.580Z,1541484222.580 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-06T06:03:42.911Z,1541484222.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-06T06:03:42.912Z,1541484222.912 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-06T06:03:43.251Z,1541484223.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-06T06:03:43.252Z,1541484223.252 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-06T06:03:43.706Z,1541484223.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-06T06:03:43.706Z,1541484223.706 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-06T06:03:44.165Z,1541484224.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-06T06:03:44.165Z,1541484224.165 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-06T06:03:44.611Z,1541484224.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-06T06:03:44.611Z,1541484224.611 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-06T06:03:44.692Z,1541484224.692 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-06T06:03:45.013Z,1541484225.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-06T06:03:45.014Z,1541484225.014 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-06T06:03:45.162Z,1541484225.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-06T06:03:45.162Z,1541484225.162 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-06T06:03:45.375Z,1541484225.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-06T06:03:45.375Z,1541484225.375 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-06T06:03:45.580Z,1541484225.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-06T06:03:45.580Z,1541484225.580 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-06T06:03:45.678Z,1541484225.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-06T06:03:45.678Z,1541484225.678 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-06T06:03:45.823Z,1541484225.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-06T06:03:45.825Z,1541484225.825 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-11-06T06:03:45.826Z,1541484225.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-11-06T06:03:46.052Z,1541484226.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-06T06:03:46.053Z,1541484226.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-11-06T06:03:46.157Z,1541484226.157 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-11-06T06:03:46.305Z,1541484226.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-11-06T06:03:46.398Z,1541484226.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-11-06T06:03:46.499Z,1541484226.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-11-06T06:03:46.786Z,1541484226.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-11-06T06:03:47.059Z,1541484227.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-11-06T06:03:47.225Z,1541484227.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-11-06T06:03:47.326Z,1541484227.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-11-06T06:03:47.423Z,1541484227.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-11-06T06:03:47.506Z,1541484227.506 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-11-06T06:03:47.506Z,1541484227.506 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-06T06:03:47.509Z,1541484227.509 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-06T06:03:47.611Z,1541484227.611 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-06T06:03:47.611Z,1541484227.611 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-06T06:03:47.624Z,1541484227.624 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-06T06:03:47.625Z,1541484227.625 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-06T06:03:47.667Z,1541484227.667 [DepthRateCalculator] Loaded
2018-11-06T06:03:47.667Z,1541484227.667 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-06T06:03:47.673Z,1541484227.673 [PitchRateCalculator] Loaded
2018-11-06T06:03:47.673Z,1541484227.673 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-06T06:03:47.689Z,1541484227.689 [SpeedCalculator] Loaded
2018-11-06T06:03:47.689Z,1541484227.689 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-06T06:03:47.710Z,1541484227.710 [TempGradientCalculator] Loaded
2018-11-06T06:03:47.710Z,1541484227.710 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-06T06:03:47.716Z,1541484227.716 [YawRateCalculator] Loaded
2018-11-06T06:03:47.716Z,1541484227.716 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-06T06:03:47.755Z,1541484227.755 [ElevatorOffsetCalculator] Loaded
2018-11-06T06:03:47.756Z,1541484227.756 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-06T06:03:47.756Z,1541484227.756 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-06T06:03:47.757Z,1541484227.757 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-06T06:03:47.782Z,1541484227.782 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-06T06:03:47.782Z,1541484227.782 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-06T06:03:48.059Z,1541484228.059 [AcousticModem_Benthos_ATM900] Loaded
2018-11-06T06:03:48.059Z,1541484228.059 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-11-06T06:03:48.132Z,1541484228.132 [DataOverHttps] Loaded
2018-11-06T06:03:48.132Z,1541484228.132 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-06T06:03:48.146Z,1541484228.146 [Depth_Keller] Loaded
2018-11-06T06:03:48.146Z,1541484228.146 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-06T06:03:48.151Z,1541484228.151 [DropWeight] Loaded
2018-11-06T06:03:48.151Z,1541484228.151 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-06T06:03:48.249Z,1541484228.249 [NAL9602] Loaded
2018-11-06T06:03:48.249Z,1541484228.249 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-06T06:03:48.265Z,1541484228.265 [Onboard] Loaded
2018-11-06T06:03:48.265Z,1541484228.265 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-06T06:03:48.275Z,1541484228.275 [Radio_Surface] Loaded
2018-11-06T06:03:48.275Z,1541484228.275 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-06T06:03:48.276Z,1541484228.276 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0
2018-11-06T06:03:48.277Z,1541484228.277 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3190
2018-11-06T06:03:48.323Z,1541484228.323 [PNI_TCM] Loaded
2018-11-06T06:03:48.324Z,1541484228.324 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-11-06T06:03:48.384Z,1541484228.384 [Rowe_600LCM] Loaded
2018-11-06T06:03:48.385Z,1541484228.385 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-11-06T06:03:48.386Z,1541484228.386 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0
2018-11-06T06:03:48.386Z,1541484228.386 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 3191
2018-11-06T06:03:50.133Z,1541484230.133 [BPC1] Loaded
2018-11-06T06:03:50.133Z,1541484230.133 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-06T06:03:50.133Z,1541484230.133 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-06T06:03:50.134Z,1541484230.134 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-06T06:03:50.265Z,1541484230.265 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-06T06:03:50.288Z,1541484230.288 [SBIT] Loaded
2018-11-06T06:03:50.289Z,1541484230.289 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-06T06:03:50.290Z,1541484230.290 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-06T06:03:50.301Z,1541484230.301 [IBIT] Loaded
2018-11-06T06:03:50.301Z,1541484230.301 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-06T06:03:50.305Z,1541484230.305 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-06T06:03:50.451Z,1541484230.451 [CBIT] Loaded
2018-11-06T06:03:50.451Z,1541484230.451 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-06T06:03:50.451Z,1541484230.451 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-06T06:03:50.452Z,1541484230.452 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-06T06:03:50.612Z,1541484230.612 [Aanderaa_O2] Loaded
2018-11-06T06:03:50.612Z,1541484230.612 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-06T06:03:50.669Z,1541484230.669 [CTD_Seabird] Loaded
2018-11-06T06:03:50.669Z,1541484230.669 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-11-06T06:03:50.670Z,1541484230.670 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BE4E0
2018-11-06T06:03:50.670Z,1541484230.670 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3192
2018-11-06T06:03:50.688Z,1541484230.688 [ESPComponent] Loaded
2018-11-06T06:03:50.688Z,1541484230.688 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-11-06T06:03:50.702Z,1541484230.702 [PAR_Licor] Loaded
2018-11-06T06:03:50.702Z,1541484230.702 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-11-06T06:03:50.750Z,1541484230.750 [WetLabsBB2FL] Loaded
2018-11-06T06:03:50.751Z,1541484230.751 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-06T06:03:50.752Z,1541484230.752 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408EE4E0
2018-11-06T06:03:50.752Z,1541484230.752 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3193
2018-11-06T06:03:50.753Z,1541484230.753 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-06T06:03:50.753Z,1541484230.753 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-06T06:03:50.994Z,1541484230.994 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-06T06:03:50.995Z,1541484230.995 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-06T06:03:51.062Z,1541484231.062 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-06T06:03:51.156Z,1541484231.156 [VerticalControl] Loaded
2018-11-06T06:03:51.156Z,1541484231.156 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-06T06:03:51.157Z,1541484231.157 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-06T06:03:51.217Z,1541484231.217 [HorizontalControl] Loaded
2018-11-06T06:03:51.218Z,1541484231.218 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-06T06:03:51.218Z,1541484231.218 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-06T06:03:51.224Z,1541484231.224 [SpeedControl] Loaded
2018-11-06T06:03:51.224Z,1541484231.224 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-06T06:03:51.225Z,1541484231.225 [LoopControl](DEBUG): Construct LoopControl.
2018-11-06T06:03:51.225Z,1541484231.225 [LoopControl] Loaded
2018-11-06T06:03:51.225Z,1541484231.225 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-06T06:03:51.226Z,1541484231.226 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-06T06:03:51.226Z,1541484231.226 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-06T06:03:51.291Z,1541484231.291 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-06T06:03:51.292Z,1541484231.292 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-06T06:03:51.390Z,1541484231.390 [BuoyancyServo] Loaded
2018-11-06T06:03:51.390Z,1541484231.390 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-06T06:03:51.401Z,1541484231.401 [ElevatorServo] Loaded
2018-11-06T06:03:51.402Z,1541484231.402 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-06T06:03:51.412Z,1541484231.412 [MassServo] Loaded
2018-11-06T06:03:51.413Z,1541484231.413 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-06T06:03:51.424Z,1541484231.424 [RudderServo] Loaded
2018-11-06T06:03:51.425Z,1541484231.425 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-06T06:03:51.435Z,1541484231.435 [ThrusterServo] Loaded
2018-11-06T06:03:51.435Z,1541484231.435 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-06T06:03:51.436Z,1541484231.436 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-06T06:03:51.436Z,1541484231.436 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-06T06:03:51.527Z,1541484231.527 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-06T06:03:51.527Z,1541484231.527 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-06T06:03:51.543Z,1541484231.543 [NavChart] Loaded
2018-11-06T06:03:51.543Z,1541484231.543 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-06T06:03:51.547Z,1541484231.547 [UniversalFixResidualReporter] Loaded
2018-11-06T06:03:51.547Z,1541484231.547 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-06T06:03:51.548Z,1541484231.548 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-06T06:03:51.571Z,1541484231.571 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-06T06:03:51.572Z,1541484231.572 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-06T06:03:51.599Z,1541484231.599 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-06T06:03:51.600Z,1541484231.600 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2018-11-06T06:03:51.600Z,1541484231.600 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3194
2018-11-06T06:03:51.636Z,1541484231.636 [Supervisor](INFO): Main Thread ID is 2717
2018-11-06T06:03:51.637Z,1541484231.637 [Supervisor](DEBUG): Running supervisor.
2018-11-06T06:03:51.637Z,1541484231.637 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3195
2018-11-06T06:03:51.645Z,1541484231.645 [controlThread ThreadHandler](INFO): Handler Thread ID is 3196
2018-11-06T06:03:51.646Z,1541484231.646 [controlThread](DEBUG): Initializing ControlThread
2018-11-06T06:03:51.647Z,1541484231.647 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T06:03:51.647Z,1541484231.647 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-06T06:03:51.647Z,1541484231.647 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-06T06:03:51.647Z,1541484231.647 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-06T06:03:51.648Z,1541484231.648 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-06T06:03:51.648Z,1541484231.648 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-06T06:03:51.656Z,1541484231.656 [SBIT](INFO): Initialize SBIT Component.
2018-11-06T06:03:51.656Z,1541484231.656 [SBIT](IMPORTANT): git: 2018-11-05
2018-11-06T06:03:51.656Z,1541484231.656 [SBIT](INFO): git hash: 17f8e4440ddc7f0910aa911cb821ed1a114d9cee
2018-11-06T06:03:51.657Z,1541484231.657 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-06T06:03:51.657Z,1541484231.657 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-06T06:03:51.658Z,1541484231.658 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-11-06T06:03:51.659Z,1541484231.659 [IBIT](INFO): Initialize IBIT Component.
2018-11-06T06:03:51.660Z,1541484231.660 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-06T06:03:51.660Z,1541484231.660 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2018-11-06T06:03:51.660Z,1541484231.660 [CBIT] Hardware Fault, FailCount= 1
2018-11-06T06:03:51.660Z,1541484231.660 [CBIT](ERROR): Hardware Fault
2018-11-06T06:03:51.676Z,1541484231.676 [logger ThreadHandler](INFO): Handler Thread ID is 3197
2018-11-06T06:03:51.694Z,1541484231.694 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3198
2018-11-06T06:03:51.706Z,1541484231.706 [Radio_Surface](INFO): Powering up
2018-11-06T06:03:51.713Z,1541484231.713 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 3199
2018-11-06T06:03:51.714Z,1541484231.714 [Rowe_600LCM](INFO): Initializing
2018-11-06T06:03:51.715Z,1541484231.715 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T06:03:51.724Z,1541484231.724 [Rowe_600LCM](INFO): LCM OK
2018-11-06T06:03:51.724Z,1541484231.724 [Rowe_600LCM](INFO): Powering up
2018-11-06T06:03:51.725Z,1541484231.725 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3200
2018-11-06T06:03:51.726Z,1541484231.726 [CTD_Seabird](INFO): Initializing
2018-11-06T06:03:51.726Z,1541484231.726 [CTD_Seabird](INFO): Checking LCM
2018-11-06T06:03:51.726Z,1541484231.726 [CTD_Seabird](INFO): LCM OK
2018-11-06T06:03:51.727Z,1541484231.727 [CTD_Seabird](INFO): Powering up
2018-11-06T06:03:51.738Z,1541484231.738 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3201
2018-11-06T06:03:51.739Z,1541484231.739 [WetLabsBB2FL](INFO): Powering down
2018-11-06T06:03:51.773Z,1541484231.773 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-06T06:03:51.775Z,1541484231.775 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-06T06:03:51.776Z,1541484231.776 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-06T06:03:51.776Z,1541484231.776 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-06T06:03:51.778Z,1541484231.778 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T06:03:51.779Z,1541484231.779 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T06:03:51.779Z,1541484231.779 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T06:03:51.780Z,1541484231.780 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-06T06:03:51.780Z,1541484231.780 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-06T06:03:51.781Z,1541484231.781 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-06T06:03:51.786Z,1541484231.786 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3202
2018-11-06T06:03:51.793Z,1541484231.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-06T06:03:51.793Z,1541484231.793 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-06T06:03:51.793Z,1541484231.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-06T06:03:51.794Z,1541484231.794 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-06T06:03:51.794Z,1541484231.794 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-06T06:03:51.794Z,1541484231.794 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-06T06:03:51.794Z,1541484231.794 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-06T06:03:51.794Z,1541484231.794 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-06T06:03:51.795Z,1541484231.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-06T06:03:51.795Z,1541484231.795 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-06T06:03:51.795Z,1541484231.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-06T06:03:51.795Z,1541484231.795 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-06T06:03:51.795Z,1541484231.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-06T06:03:51.796Z,1541484231.796 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-06T06:03:51.796Z,1541484231.796 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-06T06:03:51.796Z,1541484231.796 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-06T06:03:51.857Z,1541484231.857 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T06:03:51.888Z,1541484231.888 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-11-06T06:03:51.897Z,1541484231.897 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-06T06:03:51.992Z,1541484231.992 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-06T06:03:51.993Z,1541484231.993 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-06T06:03:51.995Z,1541484231.995 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T06:03:52.039Z,1541484232.039 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-06T06:03:52.042Z,1541484232.042 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-06T06:03:52.064Z,1541484232.064 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-06T06:03:52.067Z,1541484232.067 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-11-06T06:03:52.072Z,1541484232.072 [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-11-06T06:03:52.097Z,1541484232.097 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-11-06T06:03:52.097Z,1541484232.097 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-11-06T06:03:52.124Z,1541484232.124 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-06T06:03:52.412Z,1541484232.412 [ESPComponent](INFO): powering down ESP
2018-11-06T06:03:52.974Z,1541484232.974 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:03:52.981Z,1541484232.981 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T06:03:52.996Z,1541484232.996 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:03:53.001Z,1541484233.001 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-06T06:03:53.023Z,1541484233.023 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:03:53.029Z,1541484233.029 [MassServo](DEBUG): Initializing MassServo.
2018-11-06T06:03:53.035Z,1541484233.035 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:03:53.041Z,1541484233.041 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-06T06:03:53.047Z,1541484233.047 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:03:53.053Z,1541484233.053 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-06T06:03:53.097Z,1541484233.097 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-06T06:03:53.185Z,1541484233.185 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-06T06:03:53.185Z,1541484233.185 [DropWeight] Hardware Fault, FailCount= 1
2018-11-06T06:03:53.185Z,1541484233.185 [DropWeight](ERROR): Hardware Fault
2018-11-06T06:03:53.893Z,1541484233.893 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-06T06:03:53.893Z,1541484233.893 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-06T06:03:53.893Z,1541484233.893 [BuoyancyServo] Communications Fault, FailCount= 1
2018-11-06T06:03:53.893Z,1541484233.893 [BuoyancyServo](ERROR): Communications Fault
2018-11-06T06:03:54.205Z,1541484234.205 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2018-11-06T06:03:54.205Z,1541484234.205 [ElevatorServo](FAULT): Elevator failed to initialize
2018-11-06T06:03:54.205Z,1541484234.205 [ElevatorServo] Communications Fault, FailCount= 1
2018-11-06T06:03:54.205Z,1541484234.205 [ElevatorServo](ERROR): Communications Fault
2018-11-06T06:03:54.509Z,1541484234.509 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2018-11-06T06:03:54.509Z,1541484234.509 [RudderServo](FAULT): Rudder failed to initialize
2018-11-06T06:03:54.509Z,1541484234.509 [RudderServo] Communications Fault, FailCount= 1
2018-11-06T06:03:54.509Z,1541484234.509 [RudderServo](ERROR): Communications Fault
2018-11-06T06:03:54.833Z,1541484234.833 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-06T06:03:55.157Z,1541484235.157 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2018-11-06T06:03:55.157Z,1541484235.157 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-06T06:03:55.157Z,1541484235.157 [ThrusterServo] Communications Fault, FailCount= 1
2018-11-06T06:03:55.157Z,1541484235.157 [ThrusterServo](ERROR): Communications Fault
2018-11-06T06:03:55.245Z,1541484235.245 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-06T06:03:55.245Z,1541484235.245 [ElevatorServo](INFO): Powering down
2018-11-06T06:03:55.605Z,1541484235.605 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2018-11-06T06:03:55.605Z,1541484235.605 [MassServo] Communications Fault, FailCount= 1
2018-11-06T06:03:55.605Z,1541484235.605 [MassServo](ERROR): Communications Fault
2018-11-06T06:03:55.606Z,1541484235.606 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-06T06:03:55.606Z,1541484235.606 [RudderServo](INFO): Powering down
2018-11-06T06:03:55.632Z,1541484235.632 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-06T06:03:55.632Z,1541484235.632 [ThrusterServo](INFO): Powering down
2018-11-06T06:03:55.830Z,1541484235.830 [ElevatorServo](FAULT): LCB fault:  Current Limiter Activated.
2018-11-06T06:03:55.831Z,1541484235.831 [ElevatorServo] Hardware Fault, FailCount= 1
2018-11-06T06:03:55.831Z,1541484235.831 [ElevatorServo](ERROR): Hardware Fault
2018-11-06T06:03:55.831Z,1541484235.831 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-06T06:03:55.831Z,1541484235.831 [MassServo](INFO): Powering down
2018-11-06T06:03:55.903Z,1541484235.903 [RudderServo](FAULT): LCB fault:  Current Limiter Activated.
2018-11-06T06:03:55.903Z,1541484235.903 [RudderServo] Hardware Fault, FailCount= 1
2018-11-06T06:03:55.903Z,1541484235.903 [RudderServo](ERROR): Hardware Fault
2018-11-06T06:03:56.020Z,1541484236.020 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T06:03:56.084Z,1541484236.084 [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-11-06T06:03:56.173Z,1541484236.173 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:03:56.189Z,1541484236.189 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-06T06:03:56.191Z,1541484236.191 [MassServo](FAULT): LCB fault:  Current Limiter Activated.
2018-11-06T06:03:56.191Z,1541484236.191 [MassServo] Hardware Fault, FailCount= 1
2018-11-06T06:03:56.191Z,1541484236.191 [MassServo](ERROR): Hardware Fault
2018-11-06T06:03:56.192Z,1541484236.192 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:03:56.192Z,1541484236.192 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-06T06:03:56.210Z,1541484236.210 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T06:03:56.211Z,1541484236.211 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T06:03:56.211Z,1541484236.211 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T06:03:56.575Z,1541484236.575 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-06T06:03:56.576Z,1541484236.576 [MassServo](DEBUG): Initializing MassServo.
2018-11-06T06:03:56.951Z,1541484236.951 [BuoyancyServo](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2018-11-06T06:03:56.951Z,1541484236.951 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-11-06T06:03:56.951Z,1541484236.951 [BuoyancyServo](ERROR): Hardware Fault
2018-11-06T06:03:57.202Z,1541484237.202 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T06:03:57.288Z,1541484237.288 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-11-06T06:03:57.290Z,1541484237.290 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-11-06T06:03:57.638Z,1541484237.638 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T06:03:57.638Z,1541484237.638 [CTD_Seabird](INFO): Powering down
2018-11-06T06:04:00.917Z,1541484240.917 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T06:04:00.917Z,1541484240.917 [PNI_TCM] Data Fault, FailCount= 1
2018-11-06T06:04:00.917Z,1541484240.917 [PNI_TCM](ERROR): Data Fault
2018-11-06T06:04:01.317Z,1541484241.317 [PNI_TCM](INFO): Powering down
2018-11-06T06:04:02.519Z,1541484242.519 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T06:04:02.519Z,1541484242.519 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T06:04:02.519Z,1541484242.519 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T06:04:02.918Z,1541484242.918 [Aanderaa_O2](INFO): Powering down
2018-11-06T06:04:12.498Z,1541484252.498 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2018-11-06T06:04:12.498Z,1541484252.498 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2018-11-06T06:04:12.498Z,1541484252.498 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2018-11-06T06:04:12.898Z,1541484252.898 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-11-06T06:04:13.298Z,1541484253.298 [AcousticModem_Benthos_ATM900](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2018-11-06T06:04:13.299Z,1541484253.299 [AcousticModem_Benthos_ATM900] Hardware Fault, FailCount= 1
2018-11-06T06:04:13.299Z,1541484253.299 [AcousticModem_Benthos_ATM900](ERROR): Hardware Fault
2018-11-06T06:04:15.954Z,1541484255.954 [CommandLine](IMPORTANT): got command failComponent
2018-11-06T06:04:15.954Z,1541484255.954 [CommandLine](IMPORTANT): Failed components:
2018-11-06T06:04:15.954Z,1541484255.954 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900: Hardware Fault
2018-11-06T06:04:15.955Z,1541484255.955 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-11-06T06:04:15.955Z,1541484255.955 [CommandLine](IMPORTANT): PNI_TCM: Data Fault
2018-11-06T06:04:15.955Z,1541484255.955 [CommandLine](IMPORTANT): CBIT: Hardware Fault
2018-11-06T06:04:15.955Z,1541484255.955 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2018-11-06T06:04:15.955Z,1541484255.955 [CommandLine](IMPORTANT): BuoyancyServo: Hardware Fault
2018-11-06T06:04:15.956Z,1541484255.956 [CommandLine](IMPORTANT): ElevatorServo: Hardware Fault
2018-11-06T06:04:15.956Z,1541484255.956 [CommandLine](IMPORTANT): MassServo: Hardware Fault
2018-11-06T06:04:15.956Z,1541484255.956 [CommandLine](IMPORTANT): RudderServo: Hardware Fault
2018-11-06T06:04:15.956Z,1541484255.956 [CommandLine](IMPORTANT): ThrusterServo: Communications Fault
2018-11-06T06:04:16.157Z,1541484256.157 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-06T06:04:18.503Z,1541484258.503 [NAL9602](INFO): Powering up NAL9602
2018-11-06T06:04:22.570Z,1541484262.570 [CommandLine](IMPORTANT): got command restart application
2018-11-06T06:04:23.573Z,1541484263.573 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-11-06T06:04:23.576Z,1541484263.576 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:04:23.576Z,1541484263.576 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:23.673Z,1541484263.673 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-06T06:04:23.673Z,1541484263.673 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:23.674Z,1541484263.674 [CommandLine](INFO): Join timeout helper Thread ID is 3221
2018-11-06T06:04:23.685Z,1541484263.685 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-06T06:04:23.685Z,1541484263.685 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:23.686Z,1541484263.686 [NavChartDb](INFO): Join timeout helper Thread ID is 3222
2018-11-06T06:04:23.987Z,1541484263.987 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:04:23.987Z,1541484263.987 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.005Z,1541484264.005 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-06T06:04:24.005Z,1541484264.005 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.005Z,1541484264.006 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3223
2018-11-06T06:04:24.049Z,1541484264.049 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:04:24.049Z,1541484264.049 [WetLabsBB2FL](INFO): Powering down
2018-11-06T06:04:24.050Z,1541484264.050 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.065Z,1541484264.065 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-11-06T06:04:24.065Z,1541484264.065 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.066Z,1541484264.066 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3224
2018-11-06T06:04:24.333Z,1541484264.333 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:04:24.417Z,1541484264.417 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T06:04:24.417Z,1541484264.417 [CTD_Seabird](INFO): Powering down
2018-11-06T06:04:24.418Z,1541484264.418 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.433Z,1541484264.433 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-11-06T06:04:24.433Z,1541484264.433 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.434Z,1541484264.434 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 3226
2018-11-06T06:04:24.497Z,1541484264.497 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:04:24.498Z,1541484264.498 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T06:04:24.654Z,1541484264.654 [Rowe_600LCM](INFO): Powering down
2018-11-06T06:04:24.655Z,1541484264.655 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.670Z,1541484264.670 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-06T06:04:24.670Z,1541484264.670 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.671Z,1541484264.671 [Radio_Surface](INFO): Join timeout helper Thread ID is 3228
2018-11-06T06:04:24.869Z,1541484264.869 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:04:24.870Z,1541484264.870 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.890Z,1541484264.890 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-06T06:04:24.890Z,1541484264.890 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.890Z,1541484264.890 [logger](INFO): Join timeout helper Thread ID is 3229
2018-11-06T06:04:24.893Z,1541484264.893 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:04:24.893Z,1541484264.893 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.912Z,1541484264.912 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-06T06:04:24.912Z,1541484264.912 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.912Z,1541484264.912 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-06T06:04:24.912Z,1541484264.912 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:24.913Z,1541484264.913 [controlThread](INFO): Join timeout helper Thread ID is 3230
2018-11-06T06:04:24.933Z,1541484264.933 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T06:04:24.933Z,1541484264.933 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-06T06:04:24.933Z,1541484264.933 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-11-06T06:04:24.935Z,1541484264.935 [NAL9602](INFO): Powering down
2018-11-06T06:04:24.957Z,1541484264.957 [PNI_TCM](INFO): Powering down
2018-11-06T06:04:24.959Z,1541484264.959 [Aanderaa_O2](INFO): Powering down
2018-11-06T06:04:24.961Z,1541484264.961 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T06:04:24.962Z,1541484264.962 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-06T06:04:24.963Z,1541484264.963 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-06T06:04:24.963Z,1541484264.963 [MissionManager](INFO): Uninitializing Mission Default
2018-11-06T06:04:24.966Z,1541484264.966 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-06T06:04:24.968Z,1541484264.968 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-06T06:04:24.968Z,1541484264.968 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-06T06:04:24.968Z,1541484264.968 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-06T06:04:24.968Z,1541484264.968 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-06T06:04:24.969Z,1541484264.969 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-06T06:04:24.969Z,1541484264.969 [BuoyancyServo](INFO): Powering down
2018-11-06T06:04:25.121Z,1541484265.121 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-06T06:04:25.121Z,1541484265.121 [ElevatorServo](INFO): Powering down
2018-11-06T06:04:25.122Z,1541484265.122 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-06T06:04:25.122Z,1541484265.122 [MassServo](INFO): Powering down
2018-11-06T06:04:25.123Z,1541484265.123 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-06T06:04:25.123Z,1541484265.123 [RudderServo](INFO): Powering down
2018-11-06T06:04:25.124Z,1541484265.124 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-06T06:04:25.124Z,1541484265.124 [ThrusterServo](INFO): Powering down
2018-11-06T06:04:25.125Z,1541484265.125 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-06T06:04:25.126Z,1541484265.126 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-06T06:04:25.126Z,1541484265.126 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-06T06:04:25.140Z,1541484265.140 [CBIT](CRITICAL): run-time exception #7
2018-11-06T06:04:25.140Z,1541484265.140 [CBIT](CRITICAL): Bad address: 0x4041E004
2018-11-06T06:04:25.140Z,1541484265.140 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x169f00]

2018-11-06T06:04:25.141Z,1541484265.141 [CBIT] Software Fault, FailCount= 1
2018-11-06T06:04:25.141Z,1541484265.141 [CBIT](ERROR): Software Fault
2018-11-06T06:04:25.143Z,1541484265.143 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-06T06:04:25.143Z,1541484265.143 [SyncHandler](INFO): Protected caller Thread ID is 3231
2018-11-06T06:04:25.144Z,1541484265.144 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:25.237Z,1541484265.237 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:25.244Z,1541484265.244 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:25.282Z,1541484265.282 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:25.287Z,1541484265.287 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:25.692Z,1541484265.692 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T06:04:25.750Z,1541484265.750 [logger ThreadHandler](INFO): Thread cancelled.