2018-11-07T22:23:47.477Z,1541629427.477 [Supervisor](DEBUG): Initializing supervisor.
2018-11-07T22:23:47.480Z,1541629427.480 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-07T22:23:47.481Z,1541629427.481 [SyncHandler](INFO): Protected caller Thread ID is 1325
2018-11-07T22:23:47.482Z,1541629427.482 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-07T22:23:47.483Z,1541629427.483 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-07T22:23:47.483Z,1541629427.483 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1326
2018-11-07T22:23:47.487Z,1541629427.487 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-07T22:23:47.499Z,1541629427.499 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-07T22:23:47.500Z,1541629427.500 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-07T22:23:47.500Z,1541629427.500 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1327
2018-11-07T22:23:47.501Z,1541629427.501 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-07T22:23:47.502Z,1541629427.502 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-07T22:23:47.502Z,1541629427.502 [logger ThreadHandler](INFO): Protected caller Thread ID is 1328
2018-11-07T22:23:47.504Z,1541629427.504 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-07T22:23:47.504Z,1541629427.504 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-07T22:23:47.506Z,1541629427.506 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-07T22:23:47.829Z,1541629427.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-07T22:23:47.829Z,1541629427.829 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-07T22:23:48.275Z,1541629428.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-07T22:23:48.276Z,1541629428.276 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-07T22:23:48.621Z,1541629428.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-07T22:23:48.621Z,1541629428.621 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-07T22:23:48.817Z,1541629428.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-07T22:23:48.818Z,1541629428.818 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-07T22:23:49.474Z,1541629429.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-07T22:23:49.475Z,1541629429.475 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-07T22:23:49.955Z,1541629429.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-07T22:23:49.956Z,1541629429.956 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-07T22:23:50.060Z,1541629430.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-07T22:23:50.061Z,1541629430.061 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-07T22:23:50.260Z,1541629430.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-07T22:23:50.261Z,1541629430.261 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-07T22:23:50.405Z,1541629430.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-07T22:23:50.406Z,1541629430.406 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-07T22:23:50.488Z,1541629430.488 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-07T22:23:50.706Z,1541629430.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-07T22:23:50.707Z,1541629430.707 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-07T22:23:50.808Z,1541629430.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-07T22:23:50.808Z,1541629430.808 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-07T22:23:50.905Z,1541629430.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-07T22:23:50.905Z,1541629430.905 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-07T22:23:51.056Z,1541629431.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-07T22:23:51.057Z,1541629431.057 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-07T22:23:51.374Z,1541629431.374 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-07T22:23:51.376Z,1541629431.376 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-11-07T22:23:51.377Z,1541629431.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-11-07T22:23:51.509Z,1541629431.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-11-07T22:23:51.734Z,1541629431.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-07T22:23:51.734Z,1541629431.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-11-07T22:23:51.908Z,1541629431.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-11-07T22:23:52.009Z,1541629432.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-11-07T22:23:52.108Z,1541629432.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-11-07T22:23:52.303Z,1541629432.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-11-07T22:23:52.388Z,1541629432.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-11-07T22:23:52.487Z,1541629432.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-11-07T22:23:52.581Z,1541629432.581 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-11-07T22:23:52.664Z,1541629432.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-11-07T22:23:52.774Z,1541629432.774 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-11-07T22:23:52.774Z,1541629432.774 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-07T22:23:52.776Z,1541629432.776 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-07T22:23:52.909Z,1541629432.909 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-07T22:23:52.940Z,1541629432.940 [SBIT] Loaded
2018-11-07T22:23:52.940Z,1541629432.940 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-07T22:23:52.941Z,1541629432.941 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-07T22:23:52.968Z,1541629432.968 [IBIT] Loaded
2018-11-07T22:23:52.968Z,1541629432.968 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-07T22:23:52.971Z,1541629432.971 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-07T22:23:53.124Z,1541629433.124 [CBIT] Loaded
2018-11-07T22:23:53.125Z,1541629433.125 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-07T22:23:53.125Z,1541629433.125 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-07T22:23:53.126Z,1541629433.126 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-07T22:23:53.241Z,1541629433.241 [BuoyancyServo] Loaded
2018-11-07T22:23:53.242Z,1541629433.242 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-07T22:23:53.256Z,1541629433.256 [ElevatorServo] Loaded
2018-11-07T22:23:53.257Z,1541629433.257 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-07T22:23:53.271Z,1541629433.271 [MassServo] Loaded
2018-11-07T22:23:53.271Z,1541629433.271 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-07T22:23:53.286Z,1541629433.286 [RudderServo] Loaded
2018-11-07T22:23:53.286Z,1541629433.286 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-07T22:23:53.301Z,1541629433.301 [ThrusterServo] Loaded
2018-11-07T22:23:53.301Z,1541629433.301 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-07T22:23:53.301Z,1541629433.301 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-07T22:23:53.302Z,1541629433.302 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-07T22:23:53.401Z,1541629433.401 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-11-07T22:23:53.401Z,1541629433.401 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-11-07T22:23:53.454Z,1541629433.454 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-07T22:23:53.455Z,1541629433.455 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-07T22:23:53.471Z,1541629433.471 [NavChart] Loaded
2018-11-07T22:23:53.471Z,1541629433.471 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-07T22:23:53.475Z,1541629433.475 [UniversalFixResidualReporter] Loaded
2018-11-07T22:23:53.475Z,1541629433.475 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-07T22:23:53.476Z,1541629433.476 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-07T22:23:53.476Z,1541629433.476 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-07T22:23:53.490Z,1541629433.490 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-07T22:23:53.491Z,1541629433.491 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-07T22:23:53.740Z,1541629433.740 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-07T22:23:53.740Z,1541629433.740 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-07T22:23:54.050Z,1541629434.050 [AHRS_M2] Loaded
2018-11-07T22:23:54.050Z,1541629434.050 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-11-07T22:23:54.121Z,1541629434.121 [DataOverHttps] Loaded
2018-11-07T22:23:54.121Z,1541629434.121 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-07T22:23:54.134Z,1541629434.134 [Depth_Keller] Loaded
2018-11-07T22:23:54.134Z,1541629434.134 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-07T22:23:54.139Z,1541629434.139 [DropWeight] Loaded
2018-11-07T22:23:54.139Z,1541629434.139 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-07T22:23:54.228Z,1541629434.228 [NAL9602] Loaded
2018-11-07T22:23:54.229Z,1541629434.229 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-07T22:23:54.234Z,1541629434.234 [Onboard] Loaded
2018-11-07T22:23:54.234Z,1541629434.234 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-07T22:23:54.241Z,1541629434.241 [Radio_Surface] Loaded
2018-11-07T22:23:54.241Z,1541629434.241 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-07T22:23:54.242Z,1541629434.242 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408364E0
2018-11-07T22:23:54.243Z,1541629434.243 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1407
2018-11-07T22:23:54.270Z,1541629434.270 [RDI_Pathfinder] Loaded
2018-11-07T22:23:54.271Z,1541629434.271 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-11-07T22:23:54.292Z,1541629434.292 [RDI_PathfinderUp] Loaded
2018-11-07T22:23:54.293Z,1541629434.293 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2018-11-07T22:23:54.299Z,1541629434.299 [SCPI] Loaded
2018-11-07T22:23:54.299Z,1541629434.299 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-11-07T22:23:56.694Z,1541629436.694 [BPC1] Loaded
2018-11-07T22:23:56.694Z,1541629436.694 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-07T22:23:56.695Z,1541629436.695 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-07T22:23:56.695Z,1541629436.695 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-07T22:23:56.765Z,1541629436.765 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-07T22:23:56.853Z,1541629436.853 [VerticalControl] Loaded
2018-11-07T22:23:56.853Z,1541629436.853 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-07T22:23:56.854Z,1541629436.854 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-07T22:23:56.911Z,1541629436.911 [HorizontalControl] Loaded
2018-11-07T22:23:56.911Z,1541629436.911 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-07T22:23:56.912Z,1541629436.912 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-07T22:23:56.914Z,1541629436.914 [SpeedControl] Loaded
2018-11-07T22:23:56.914Z,1541629436.914 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-07T22:23:56.915Z,1541629436.915 [LoopControl](DEBUG): Construct LoopControl.
2018-11-07T22:23:56.916Z,1541629436.916 [LoopControl] Loaded
2018-11-07T22:23:56.916Z,1541629436.916 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-07T22:23:56.916Z,1541629436.917 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-07T22:23:56.917Z,1541629436.917 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-07T22:23:57.020Z,1541629437.020 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-07T22:23:57.020Z,1541629437.020 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-07T22:23:57.184Z,1541629437.184 [Aanderaa_O2] Loaded
2018-11-07T22:23:57.184Z,1541629437.184 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-07T22:23:57.268Z,1541629437.268 [CTD_NeilBrown] Loaded
2018-11-07T22:23:57.269Z,1541629437.269 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-07T22:23:57.270Z,1541629437.270 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409364E0
2018-11-07T22:23:57.270Z,1541629437.270 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1408
2018-11-07T22:23:57.315Z,1541629437.315 [WetLabsSeaOWL_UV_A] Loaded
2018-11-07T22:23:57.315Z,1541629437.315 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-11-07T22:23:57.316Z,1541629437.316 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409664E0
2018-11-07T22:23:57.316Z,1541629437.316 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1409
2018-11-07T22:23:57.317Z,1541629437.317 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-07T22:23:57.317Z,1541629437.317 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-07T22:23:57.385Z,1541629437.385 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-07T22:23:57.386Z,1541629437.386 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-07T22:23:57.429Z,1541629437.429 [DepthRateCalculator] Loaded
2018-11-07T22:23:57.429Z,1541629437.429 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-07T22:23:57.434Z,1541629437.434 [PitchRateCalculator] Loaded
2018-11-07T22:23:57.434Z,1541629437.434 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-07T22:23:57.446Z,1541629437.446 [SpeedCalculator] Loaded
2018-11-07T22:23:57.447Z,1541629437.447 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-07T22:23:57.467Z,1541629437.467 [TempGradientCalculator] Loaded
2018-11-07T22:23:57.468Z,1541629437.468 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-07T22:23:57.473Z,1541629437.473 [YawRateCalculator] Loaded
2018-11-07T22:23:57.473Z,1541629437.473 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-07T22:23:57.503Z,1541629437.503 [ElevatorOffsetCalculator] Loaded
2018-11-07T22:23:57.503Z,1541629437.503 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-07T22:23:57.503Z,1541629437.503 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-07T22:23:57.504Z,1541629437.504 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-07T22:23:57.530Z,1541629437.530 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-07T22:23:57.533Z,1541629437.533 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-07T22:23:57.534Z,1541629437.534 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-07T22:23:57.541Z,1541629437.541 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-07T22:23:57.542Z,1541629437.542 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2018-11-07T22:23:57.543Z,1541629437.543 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1410
2018-11-07T22:23:57.548Z,1541629437.548 [Supervisor](INFO): Main Thread ID is 795
2018-11-07T22:23:57.548Z,1541629437.548 [Supervisor](DEBUG): Running supervisor.
2018-11-07T22:23:57.548Z,1541629437.548 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1411
2018-11-07T22:23:57.551Z,1541629437.551 [controlThread ThreadHandler](INFO): Handler Thread ID is 1412
2018-11-07T22:23:57.551Z,1541629437.551 [controlThread](DEBUG): Initializing ControlThread
2018-11-07T22:23:57.552Z,1541629437.552 [SBIT](INFO): Initialize SBIT Component.
2018-11-07T22:23:57.553Z,1541629437.553 [SBIT](IMPORTANT): git: 2018-10-09-36-g6180f29
2018-11-07T22:23:57.553Z,1541629437.553 [SBIT](INFO): git hash: 6180f296723eb7a23c89bee0a0e9d0952585955a
2018-11-07T22:23:57.553Z,1541629437.553 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-07T22:23:57.553Z,1541629437.553 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-07T22:23:57.555Z,1541629437.555 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-11-07T22:23:57.555Z,1541629437.555 [IBIT](INFO): Initialize IBIT Component.
2018-11-07T22:23:57.556Z,1541629437.556 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-07T22:23:57.556Z,1541629437.556 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-07T22:23:57.557Z,1541629437.557 [logger ThreadHandler](INFO): Handler Thread ID is 1413
2018-11-07T22:23:57.583Z,1541629437.583 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1414
2018-11-07T22:23:57.589Z,1541629437.589 [Radio_Surface](INFO): Powering up
2018-11-07T22:23:57.603Z,1541629437.603 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1415
2018-11-07T22:23:57.604Z,1541629437.604 [CTD_NeilBrown](INFO): Powering down
2018-11-07T22:23:57.632Z,1541629437.632 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1416
2018-11-07T22:23:57.632Z,1541629437.632 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-11-07T22:23:57.659Z,1541629437.659 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1417
2018-11-07T22:23:57.664Z,1541629437.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-07T22:23:57.664Z,1541629437.664 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-07T22:23:57.665Z,1541629437.665 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-07T22:23:57.665Z,1541629437.665 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-07T22:23:57.666Z,1541629437.666 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-07T22:23:57.666Z,1541629437.666 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-07T22:23:57.666Z,1541629437.666 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-07T22:23:57.667Z,1541629437.667 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-07T22:23:57.674Z,1541629437.674 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-07T22:23:57.676Z,1541629437.676 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-07T22:23:57.677Z,1541629437.677 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-07T22:23:57.677Z,1541629437.677 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-07T22:23:57.678Z,1541629437.678 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-07T22:23:57.678Z,1541629437.678 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-07T22:23:57.679Z,1541629437.679 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-07T22:23:57.679Z,1541629437.679 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-07T22:23:57.680Z,1541629437.680 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-07T22:23:57.680Z,1541629437.680 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-07T22:23:57.681Z,1541629437.681 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-07T22:23:57.690Z,1541629437.690 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-07T22:23:57.690Z,1541629437.690 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-07T22:23:57.690Z,1541629437.690 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-07T22:23:57.690Z,1541629437.690 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-07T22:23:57.691Z,1541629437.691 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-07T22:23:57.691Z,1541629437.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-07T22:23:57.691Z,1541629437.691 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-07T22:23:57.691Z,1541629437.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-07T22:23:57.692Z,1541629437.692 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-07T22:23:57.692Z,1541629437.692 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-07T22:23:57.692Z,1541629437.692 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-07T22:23:57.692Z,1541629437.692 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-07T22:23:57.692Z,1541629437.692 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-07T22:23:57.693Z,1541629437.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-07T22:23:57.693Z,1541629437.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-07T22:23:57.693Z,1541629437.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-07T22:23:57.730Z,1541629437.730 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-07T22:23:57.765Z,1541629437.765 [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-07T22:23:57.766Z,1541629437.766 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-07T22:23:57.844Z,1541629437.844 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-07T22:23:57.845Z,1541629437.845 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-07T22:23:57.863Z,1541629437.863 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-07T22:23:57.890Z,1541629437.890 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-07T22:23:57.893Z,1541629437.893 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-07T22:23:57.920Z,1541629437.920 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-07T22:23:57.929Z,1541629437.929 [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-07T22:23:57.943Z,1541629437.943 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-07T22:23:57.971Z,1541629437.971 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-11-07T22:23:58.025Z,1541629438.025 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-07T22:23:58.066Z,1541629438.066 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-11-07T22:23:58.278Z,1541629438.278 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-07T22:23:58.507Z,1541629438.507 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-07T22:23:58.515Z,1541629438.515 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-07T22:23:58.529Z,1541629438.529 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-07T22:23:58.535Z,1541629438.535 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-07T22:23:58.557Z,1541629438.557 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-07T22:23:58.563Z,1541629438.563 [MassServo](DEBUG): Initializing MassServo.
2018-11-07T22:23:58.577Z,1541629438.577 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-07T22:23:58.583Z,1541629438.583 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-07T22:23:58.597Z,1541629438.597 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-07T22:23:58.603Z,1541629438.603 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-07T22:23:58.775Z,1541629438.775 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-07T22:23:58.776Z,1541629438.776 [DropWeight] Hardware Fault, FailCount= 1
2018-11-07T22:23:58.776Z,1541629438.776 [DropWeight](ERROR): Hardware Fault
2018-11-07T22:23:58.867Z,1541629438.867 [RDI_PathfinderUp](INFO): Powering down
2018-11-07T22:23:58.931Z,1541629438.931 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-07T22:23:58.960Z,1541629438.960 [CommandLine](FAULT): Scheduling is paused
2018-11-07T22:23:58.960Z,1541629438.960 [CBIT](INFO): Critical error at 20181107T222358
2018-11-07T22:23:58.961Z,1541629438.961 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-07T22:23:58.963Z,1541629438.963 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-07T22:23:58.964Z,1541629438.964 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-07T22:23:59.123Z,1541629439.123 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-07T22:23:59.455Z,1541629439.455 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-07T22:23:59.455Z,1541629439.455 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-07T22:23:59.455Z,1541629439.455 [BuoyancyServo] Communications Fault, FailCount= 1
2018-11-07T22:23:59.455Z,1541629439.455 [BuoyancyServo](ERROR): Communications Fault
2018-11-07T22:23:59.661Z,1541629439.661 [CBIT](INFO): Critical error at 20181107T222358
2018-11-07T22:23:59.663Z,1541629439.663 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-07T22:23:59.720Z,1541629439.720 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-07T22:23:59.722Z,1541629439.722 [BuoyancyServo](INFO): Powering down
2018-11-07T22:24:01.375Z,1541629441.375 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:01.767Z,1541629441.767 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:01.924Z,1541629441.924 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-07T22:24:01.924Z,1541629441.924 [BuoyancyServo] No Fault, FailCount= 1
2018-11-07T22:24:02.167Z,1541629442.167 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:02.201Z,1541629442.201 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-07T22:24:02.320Z,1541629442.320 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-07T22:24:02.567Z,1541629442.567 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:03.083Z,1541629443.083 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:03.483Z,1541629443.483 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:03.883Z,1541629443.883 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:04.283Z,1541629444.283 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:04.683Z,1541629444.683 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:05.083Z,1541629445.083 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:05.483Z,1541629445.483 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:05.883Z,1541629445.883 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:06.283Z,1541629446.283 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:06.683Z,1541629446.683 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:07.072Z,1541629447.072 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:07.483Z,1541629447.483 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:07.887Z,1541629447.887 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:07.943Z,1541629447.943 [CBIT](CRITICAL): Environmental Failure. Press:14.853555 PSI. Humidity:39%. Temp:25 C. ABORTING MISSION
2018-11-07T22:24:08.283Z,1541629448.283 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-07T22:24:08.283Z,1541629448.283 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-11-07T22:24:08.283Z,1541629448.283 [AHRS_M2] Communications Fault, FailCount= 1
2018-11-07T22:24:08.283Z,1541629448.283 [AHRS_M2](ERROR): Communications Fault
2018-11-07T22:24:08.306Z,1541629448.306 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-07T22:24:08.306Z,1541629448.306 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-07T22:24:08.306Z,1541629448.306 [Aanderaa_O2](ERROR): Communications Fault
2018-11-07T22:24:08.341Z,1541629448.341 [CBIT](INFO): Critical error at 20181107T222407
2018-11-07T22:24:08.343Z,1541629448.343 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-11-07T22:24:08.344Z,1541629448.344 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-07T22:24:08.587Z,1541629448.587 [AHRS_M2](INFO): Powering down
2018-11-07T22:24:08.823Z,1541629448.823 [Aanderaa_O2](INFO): Powering down
2018-11-07T22:24:09.057Z,1541629449.057 [CommandLine](IMPORTANT): got command restart application
2018-11-07T22:24:09.447Z,1541629449.447 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-07T22:24:09.447Z,1541629449.447 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-07T22:24:10.066Z,1541629450.066 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-07T22:24:10.066Z,1541629450.066 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:10.163Z,1541629450.163 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-07T22:24:10.163Z,1541629450.163 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:10.164Z,1541629450.164 [CommandLine](INFO): Join timeout helper Thread ID is 1429
2018-11-07T22:24:10.179Z,1541629450.179 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-07T22:24:10.179Z,1541629450.179 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:10.180Z,1541629450.180 [NavChartDb](INFO): Join timeout helper Thread ID is 1430
2018-11-07T22:24:12.236Z,1541629452.236 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-11-07T22:24:12.236Z,1541629452.236 [AHRS_M2] No Fault, FailCount= 1
2018-11-07T22:24:12.587Z,1541629452.587 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-11-07T22:24:13.247Z,1541629453.247 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-07T22:24:13.247Z,1541629453.247 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.259Z,1541629453.259 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-11-07T22:24:13.259Z,1541629453.259 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.259Z,1541629453.259 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1432
2018-11-07T22:24:13.423Z,1541629453.423 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-07T22:24:13.423Z,1541629453.423 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-11-07T22:24:13.424Z,1541629453.424 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.427Z,1541629453.427 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-11-07T22:24:13.427Z,1541629453.427 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.427Z,1541629453.427 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1433
2018-11-07T22:24:13.430Z,1541629453.430 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-07T22:24:13.430Z,1541629453.430 [CTD_NeilBrown](INFO): Powering down
2018-11-07T22:24:13.431Z,1541629453.431 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.447Z,1541629453.447 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-07T22:24:13.447Z,1541629453.447 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.447Z,1541629453.447 [Radio_Surface](INFO): Join timeout helper Thread ID is 1434
2018-11-07T22:24:13.771Z,1541629453.771 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-07T22:24:13.771Z,1541629453.771 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.780Z,1541629453.780 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-07T22:24:13.780Z,1541629453.780 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.780Z,1541629453.780 [logger](INFO): Join timeout helper Thread ID is 1435
2018-11-07T22:24:13.794Z,1541629453.794 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-07T22:24:13.795Z,1541629453.795 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.799Z,1541629453.799 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-07T22:24:13.800Z,1541629453.800 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.801Z,1541629453.801 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-07T22:24:13.802Z,1541629453.802 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:13.805Z,1541629453.805 [controlThread](INFO): Join timeout helper Thread ID is 1436
2018-11-07T22:24:13.830Z,1541629453.830 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-07T22:24:13.830Z,1541629453.830 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-07T22:24:13.830Z,1541629453.830 [AHRS_M2](INFO): Powering down
2018-11-07T22:24:13.904Z,1541629453.904 [NAL9602](INFO): Powering down
2018-11-07T22:24:13.995Z,1541629453.995 [RDI_Pathfinder](INFO): Powering down
2018-11-07T22:24:13.996Z,1541629453.996 [RDI_PathfinderUp](INFO): Powering down
2018-11-07T22:24:14.115Z,1541629454.115 [Aanderaa_O2](INFO): Powering down
2018-11-07T22:24:14.116Z,1541629454.116 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-07T22:24:14.117Z,1541629454.117 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-07T22:24:14.117Z,1541629454.117 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-07T22:24:14.118Z,1541629454.118 [MissionManager](INFO): Uninitializing Mission Default
2018-11-07T22:24:14.120Z,1541629454.120 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-07T22:24:14.122Z,1541629454.122 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-07T22:24:14.122Z,1541629454.122 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-07T22:24:14.123Z,1541629454.123 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-07T22:24:14.123Z,1541629454.123 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-07T22:24:14.123Z,1541629454.123 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-07T22:24:14.123Z,1541629454.123 [BuoyancyServo](INFO): Powering down
2018-11-07T22:24:14.135Z,1541629454.135 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-07T22:24:14.135Z,1541629454.135 [ElevatorServo](INFO): Powering down
2018-11-07T22:24:14.155Z,1541629454.155 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-07T22:24:14.155Z,1541629454.155 [MassServo](INFO): Powering down
2018-11-07T22:24:14.156Z,1541629454.156 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-07T22:24:14.156Z,1541629454.156 [RudderServo](INFO): Powering down
2018-11-07T22:24:14.157Z,1541629454.157 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-07T22:24:14.157Z,1541629454.157 [ThrusterServo](INFO): Powering down
2018-11-07T22:24:14.158Z,1541629454.158 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-07T22:24:14.158Z,1541629454.158 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-07T22:24:14.158Z,1541629454.158 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-07T22:24:14.159Z,1541629454.159 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:14.370Z,1541629454.370 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:14.410Z,1541629454.410 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:14.413Z,1541629454.413 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:14.434Z,1541629454.434 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-07T22:24:14.488Z,1541629454.488 [logger ThreadHandler](INFO): Thread cancelled.