2018-10-16T04:58:54.403Z,1539665934.403 [Supervisor](DEBUG): Initializing supervisor.
2018-10-16T04:58:54.407Z,1539665934.407 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-16T04:58:54.408Z,1539665934.408 [SyncHandler](INFO): Protected caller Thread ID is 5549
2018-10-16T04:58:54.408Z,1539665934.408 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-16T04:58:54.409Z,1539665934.409 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-16T04:58:54.409Z,1539665934.409 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5550
2018-10-16T04:58:54.413Z,1539665934.413 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-16T04:58:54.425Z,1539665934.425 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-16T04:58:54.426Z,1539665934.426 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-16T04:58:54.427Z,1539665934.427 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5551
2018-10-16T04:58:54.427Z,1539665934.427 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-16T04:58:54.428Z,1539665934.428 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-16T04:58:54.429Z,1539665934.429 [logger ThreadHandler](INFO): Protected caller Thread ID is 5552
2018-10-16T04:58:54.431Z,1539665934.431 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-16T04:58:54.431Z,1539665934.431 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-16T04:58:54.433Z,1539665934.433 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-16T04:58:54.759Z,1539665934.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-16T04:58:54.760Z,1539665934.760 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-16T04:58:54.958Z,1539665934.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-16T04:58:54.959Z,1539665934.959 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-16T04:58:55.255Z,1539665935.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-16T04:58:55.256Z,1539665935.256 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-16T04:58:55.400Z,1539665935.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-16T04:58:55.401Z,1539665935.401 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-16T04:58:55.498Z,1539665935.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-16T04:58:55.499Z,1539665935.499 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-16T04:58:55.972Z,1539665935.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-16T04:58:55.972Z,1539665935.972 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-16T04:58:56.641Z,1539665936.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-16T04:58:56.642Z,1539665936.642 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-16T04:58:57.106Z,1539665937.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-16T04:58:57.107Z,1539665937.107 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-16T04:58:57.209Z,1539665937.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-16T04:58:57.210Z,1539665937.210 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-16T04:58:57.305Z,1539665937.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-16T04:58:57.305Z,1539665937.305 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-16T04:58:57.523Z,1539665937.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-16T04:58:57.523Z,1539665937.523 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-16T04:58:57.726Z,1539665937.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-16T04:58:57.727Z,1539665937.727 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-16T04:58:58.077Z,1539665938.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-16T04:58:58.078Z,1539665938.078 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-16T04:58:58.222Z,1539665938.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-16T04:58:58.223Z,1539665938.223 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-16T04:58:58.304Z,1539665938.304 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-10-16T04:58:58.305Z,1539665938.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-10-16T04:58:58.407Z,1539665938.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-10-16T04:58:58.524Z,1539665938.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-10-16T04:58:58.617Z,1539665938.617 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-10-16T04:58:58.701Z,1539665938.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-10-16T04:58:58.864Z,1539665938.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-10-16T04:58:59.043Z,1539665939.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-10-16T04:58:59.118Z,1539665939.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-10-16T04:58:59.348Z,1539665939.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-16T04:58:59.348Z,1539665939.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-10-16T04:58:59.430Z,1539665939.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-10-16T04:58:59.520Z,1539665939.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-10-16T04:58:59.621Z,1539665939.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-10-16T04:58:59.747Z,1539665939.747 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-10-16T04:58:59.747Z,1539665939.747 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-16T04:58:59.759Z,1539665939.759 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-16T04:59:00.000Z,1539665940.000 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-16T04:59:00.001Z,1539665940.001 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-16T04:59:00.044Z,1539665940.044 [DepthRateCalculator] Loaded
2018-10-16T04:59:00.044Z,1539665940.044 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-16T04:59:00.050Z,1539665940.050 [PitchRateCalculator] Loaded
2018-10-16T04:59:00.051Z,1539665940.051 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-16T04:59:00.067Z,1539665940.067 [SpeedCalculator] Loaded
2018-10-16T04:59:00.067Z,1539665940.067 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-16T04:59:00.088Z,1539665940.088 [TempGradientCalculator] Loaded
2018-10-16T04:59:00.088Z,1539665940.088 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-16T04:59:00.094Z,1539665940.094 [YawRateCalculator] Loaded
2018-10-16T04:59:00.094Z,1539665940.094 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-16T04:59:00.134Z,1539665940.134 [ElevatorOffsetCalculator] Loaded
2018-10-16T04:59:00.134Z,1539665940.134 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-16T04:59:00.135Z,1539665940.135 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-16T04:59:00.135Z,1539665940.135 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-16T04:59:00.200Z,1539665940.200 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-16T04:59:00.200Z,1539665940.200 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-16T04:59:00.313Z,1539665940.313 [BuoyancyServo] Loaded
2018-10-16T04:59:00.313Z,1539665940.313 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-16T04:59:00.327Z,1539665940.327 [ElevatorServo] Loaded
2018-10-16T04:59:00.328Z,1539665940.328 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-16T04:59:00.342Z,1539665940.342 [MassServo] Loaded
2018-10-16T04:59:00.343Z,1539665940.343 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-16T04:59:00.358Z,1539665940.358 [RudderServo] Loaded
2018-10-16T04:59:00.358Z,1539665940.358 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-16T04:59:00.372Z,1539665940.372 [ThrusterServo] Loaded
2018-10-16T04:59:00.372Z,1539665940.372 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-16T04:59:00.373Z,1539665940.373 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-16T04:59:00.373Z,1539665940.373 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-16T04:59:00.387Z,1539665940.387 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-16T04:59:00.388Z,1539665940.388 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-16T04:59:00.489Z,1539665940.489 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-16T04:59:00.489Z,1539665940.489 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-16T04:59:00.790Z,1539665940.790 [DataOverHttps] Loaded
2018-10-16T04:59:00.790Z,1539665940.790 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-16T04:59:00.804Z,1539665940.804 [Depth_Keller] Loaded
2018-10-16T04:59:00.804Z,1539665940.804 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-16T04:59:00.809Z,1539665940.809 [DropWeight] Loaded
2018-10-16T04:59:00.809Z,1539665940.809 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-16T04:59:00.907Z,1539665940.907 [NAL9602] Loaded
2018-10-16T04:59:00.907Z,1539665940.907 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-16T04:59:00.923Z,1539665940.923 [Onboard] Loaded
2018-10-16T04:59:00.923Z,1539665940.923 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-16T04:59:00.934Z,1539665940.934 [Radio_Surface] Loaded
2018-10-16T04:59:00.934Z,1539665940.934 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-16T04:59:00.935Z,1539665940.935 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0
2018-10-16T04:59:00.936Z,1539665940.936 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5634
2018-10-16T04:59:00.982Z,1539665940.982 [PNI_TCM] Loaded
2018-10-16T04:59:00.982Z,1539665940.982 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-10-16T04:59:01.042Z,1539665941.042 [Rowe_600LCM] Loaded
2018-10-16T04:59:01.043Z,1539665941.043 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-10-16T04:59:01.044Z,1539665941.044 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0
2018-10-16T04:59:01.044Z,1539665941.044 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 5635
2018-10-16T04:59:01.045Z,1539665941.045 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-16T04:59:01.045Z,1539665941.045 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-16T04:59:01.399Z,1539665941.399 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-16T04:59:01.471Z,1539665941.471 [SBIT] Loaded
2018-10-16T04:59:01.472Z,1539665941.472 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-16T04:59:01.473Z,1539665941.473 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-16T04:59:01.528Z,1539665941.528 [IBIT] Loaded
2018-10-16T04:59:01.528Z,1539665941.528 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-16T04:59:01.531Z,1539665941.531 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-16T04:59:02.002Z,1539665942.002 [CBIT] Loaded
2018-10-16T04:59:02.003Z,1539665942.003 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-16T04:59:02.003Z,1539665942.003 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-16T04:59:02.004Z,1539665942.004 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-16T04:59:02.224Z,1539665942.224 [Aanderaa_O2] Loaded
2018-10-16T04:59:02.224Z,1539665942.224 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-10-16T04:59:02.239Z,1539665942.239 [PAR_Licor] Loaded
2018-10-16T04:59:02.239Z,1539665942.239 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-10-16T04:59:02.288Z,1539665942.288 [WetLabsBB2FL] Loaded
2018-10-16T04:59:02.289Z,1539665942.289 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-16T04:59:02.290Z,1539665942.290 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2018-10-16T04:59:02.290Z,1539665942.290 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5636
2018-10-16T04:59:02.291Z,1539665942.291 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-16T04:59:02.291Z,1539665942.291 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-16T04:59:02.361Z,1539665942.361 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-16T04:59:02.445Z,1539665942.445 [VerticalControl] Loaded
2018-10-16T04:59:02.446Z,1539665942.446 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-16T04:59:02.446Z,1539665942.446 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-16T04:59:02.504Z,1539665942.504 [HorizontalControl] Loaded
2018-10-16T04:59:02.504Z,1539665942.504 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-16T04:59:02.504Z,1539665942.504 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-16T04:59:02.506Z,1539665942.506 [SpeedControl] Loaded
2018-10-16T04:59:02.506Z,1539665942.506 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-16T04:59:02.507Z,1539665942.507 [LoopControl](DEBUG): Construct LoopControl.
2018-10-16T04:59:02.508Z,1539665942.508 [LoopControl] Loaded
2018-10-16T04:59:02.508Z,1539665942.508 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-16T04:59:02.508Z,1539665942.508 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-16T04:59:02.509Z,1539665942.509 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-16T04:59:02.533Z,1539665942.533 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-16T04:59:02.534Z,1539665942.534 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-16T04:59:02.623Z,1539665942.623 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-16T04:59:02.623Z,1539665942.623 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-16T04:59:02.639Z,1539665942.639 [NavChart] Loaded
2018-10-16T04:59:02.640Z,1539665942.640 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-16T04:59:02.644Z,1539665942.644 [UniversalFixResidualReporter] Loaded
2018-10-16T04:59:02.644Z,1539665942.644 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-16T04:59:02.644Z,1539665942.644 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-16T04:59:02.648Z,1539665942.648 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-16T04:59:02.649Z,1539665942.649 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-16T04:59:02.656Z,1539665942.656 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-16T04:59:02.657Z,1539665942.657 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2018-10-16T04:59:02.657Z,1539665942.657 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5637
2018-10-16T04:59:02.663Z,1539665942.663 [Supervisor](INFO): Main Thread ID is 5405
2018-10-16T04:59:02.663Z,1539665942.663 [Supervisor](DEBUG): Running supervisor.
2018-10-16T04:59:02.663Z,1539665942.663 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5638
2018-10-16T04:59:02.666Z,1539665942.666 [controlThread ThreadHandler](INFO): Handler Thread ID is 5639
2018-10-16T04:59:02.666Z,1539665942.666 [controlThread](DEBUG): Initializing ControlThread
2018-10-16T04:59:02.667Z,1539665942.667 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-16T04:59:02.668Z,1539665942.668 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-16T04:59:02.668Z,1539665942.668 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-16T04:59:02.668Z,1539665942.668 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-16T04:59:02.669Z,1539665942.669 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-16T04:59:02.669Z,1539665942.669 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-16T04:59:02.676Z,1539665942.676 [SBIT](INFO): Initialize SBIT Component.
2018-10-16T04:59:02.677Z,1539665942.677 [SBIT](IMPORTANT): git: 2018-10-09-22-g812aa5f
2018-10-16T04:59:02.677Z,1539665942.677 [SBIT](INFO): git hash: 812aa5f738ae24d7d3f6c5c137b2640d1960a87f
2018-10-16T04:59:02.677Z,1539665942.677 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-16T04:59:02.678Z,1539665942.678 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-16T04:59:02.679Z,1539665942.679 [SBIT](INFO): Beginning SBIT in 36.000000 seconds.
2018-10-16T04:59:02.679Z,1539665942.679 [IBIT](INFO): Initialize IBIT Component.
2018-10-16T04:59:02.680Z,1539665942.680 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-16T04:59:02.681Z,1539665942.681 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-16T04:59:02.681Z,1539665942.681 [logger ThreadHandler](INFO): Handler Thread ID is 5640
2018-10-16T04:59:02.699Z,1539665942.699 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5641
2018-10-16T04:59:02.704Z,1539665942.704 [Radio_Surface](INFO): Powering up
2018-10-16T04:59:02.711Z,1539665942.711 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 5642
2018-10-16T04:59:02.711Z,1539665942.711 [Rowe_600LCM](INFO): Initializing
2018-10-16T04:59:02.712Z,1539665942.712 [Rowe_600LCM](INFO): Checking LCM
2018-10-16T04:59:02.721Z,1539665942.721 [Rowe_600LCM](INFO): LCM OK
2018-10-16T04:59:02.721Z,1539665942.721 [Rowe_600LCM](INFO): Powering up
2018-10-16T04:59:02.727Z,1539665942.727 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5643
2018-10-16T04:59:02.728Z,1539665942.728 [WetLabsBB2FL](INFO): Powering down
2018-10-16T04:59:02.755Z,1539665942.755 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5644
2018-10-16T04:59:02.758Z,1539665942.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-16T04:59:02.759Z,1539665942.759 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-16T04:59:02.759Z,1539665942.759 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-16T04:59:02.759Z,1539665942.759 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-16T04:59:02.759Z,1539665942.759 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-16T04:59:02.759Z,1539665942.759 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-16T04:59:02.760Z,1539665942.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-16T04:59:02.760Z,1539665942.760 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-16T04:59:02.760Z,1539665942.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-16T04:59:02.760Z,1539665942.760 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-16T04:59:02.760Z,1539665942.760 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-16T04:59:02.761Z,1539665942.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-16T04:59:02.761Z,1539665942.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-16T04:59:02.761Z,1539665942.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-16T04:59:02.761Z,1539665942.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-16T04:59:02.761Z,1539665942.761 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-16T04:59:02.791Z,1539665942.791 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-16T04:59:02.793Z,1539665942.793 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-16T04:59:02.793Z,1539665942.793 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-16T04:59:02.794Z,1539665942.794 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-16T04:59:02.794Z,1539665942.794 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T04:59:02.795Z,1539665942.795 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T04:59:02.795Z,1539665942.795 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T04:59:02.796Z,1539665942.796 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-16T04:59:02.796Z,1539665942.796 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-16T04:59:02.797Z,1539665942.797 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-16T04:59:02.814Z,1539665942.814 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-16T04:59:02.857Z,1539665942.857 [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-10-16T04:59:02.858Z,1539665942.858 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-16T04:59:02.936Z,1539665942.936 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-16T04:59:02.938Z,1539665942.938 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-16T04:59:02.956Z,1539665942.956 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-16T04:59:02.987Z,1539665942.987 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-16T04:59:02.989Z,1539665942.989 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-16T04:59:03.011Z,1539665943.011 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-16T04:59:03.020Z,1539665943.020 [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-10-16T04:59:03.031Z,1539665943.031 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,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-10-16T04:59:03.043Z,1539665943.043 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-16T04:59:03.056Z,1539665943.056 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-10-16T04:59:03.152Z,1539665943.152 [Aanderaa_O2](INFO): Start
2018-10-16T04:59:03.343Z,1539665943.343 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-16T04:59:03.412Z,1539665943.412 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T04:59:03.523Z,1539665943.523 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-16T04:59:03.531Z,1539665943.531 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-16T04:59:03.553Z,1539665943.553 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-16T04:59:03.559Z,1539665943.559 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-16T04:59:03.581Z,1539665943.581 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-16T04:59:03.587Z,1539665943.587 [MassServo](DEBUG): Initializing MassServo.
2018-10-16T04:59:03.593Z,1539665943.593 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-16T04:59:03.599Z,1539665943.599 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-16T04:59:03.604Z,1539665943.604 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-16T04:59:03.615Z,1539665943.615 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-16T04:59:03.883Z,1539665943.883 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:03.894Z,1539665943.894 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T04:59:03.925Z,1539665943.925 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T04:59:04.112Z,1539665944.112 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:04.139Z,1539665944.139 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T04:59:04.610Z,1539665944.610 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:04.637Z,1539665944.637 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T04:59:05.023Z,1539665945.023 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:05.032Z,1539665945.032 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T04:59:05.409Z,1539665945.409 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:05.409Z,1539665945.409 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:05.413Z,1539665945.413 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:05.423Z,1539665945.423 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T04:59:05.809Z,1539665945.809 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:05.853Z,1539665945.853 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T04:59:06.223Z,1539665946.223 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:06.229Z,1539665946.229 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-16T04:59:06.620Z,1539665946.620 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:07.055Z,1539665947.055 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:07.067Z,1539665947.067 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-10-16T04:59:07.197Z,1539665947.197 [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-10-16T04:59:07.255Z,1539665947.255 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-10-16T04:59:07.256Z,1539665947.256 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-10-16T04:59:07.257Z,1539665947.257 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-10-16T04:59:07.582Z,1539665947.582 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:07.594Z,1539665947.594 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:07.611Z,1539665947.611 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T04:59:07.611Z,1539665947.611 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-10-16T04:59:07.611Z,1539665947.611 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T04:59:07.763Z,1539665947.763 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T04:59:07.955Z,1539665947.955 [Aanderaa_O2](INFO): Stop
2018-10-16T04:59:07.956Z,1539665947.956 [Aanderaa_O2](INFO): Powering down
2018-10-16T04:59:08.373Z,1539665948.373 [Aanderaa_O2](INFO): Stopping
2018-10-16T04:59:08.783Z,1539665948.783 [Aanderaa_O2](INFO): Stopped
2018-10-16T04:59:08.942Z,1539665948.942 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T04:59:08.942Z,1539665948.942 [Aanderaa_O2] No Fault, FailCount= 1
2018-10-16T04:59:09.141Z,1539665949.141 [Aanderaa_O2](INFO): Start
2018-10-16T04:59:09.532Z,1539665949.532 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:09.546Z,1539665949.546 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T04:59:09.933Z,1539665949.933 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:10.333Z,1539665950.333 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:10.732Z,1539665950.732 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:11.135Z,1539665951.135 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:11.533Z,1539665951.533 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:11.533Z,1539665951.533 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:11.536Z,1539665951.536 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:11.944Z,1539665951.944 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:12.355Z,1539665952.355 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:12.744Z,1539665952.744 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:13.133Z,1539665953.133 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:13.532Z,1539665953.532 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:13.533Z,1539665953.533 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:13.537Z,1539665953.537 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:13.946Z,1539665953.946 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:14.339Z,1539665954.339 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:14.340Z,1539665954.340 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:14.349Z,1539665954.349 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:14.733Z,1539665954.733 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:15.132Z,1539665955.132 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:15.533Z,1539665955.533 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:15.533Z,1539665955.533 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:15.537Z,1539665955.537 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:15.958Z,1539665955.958 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:16.335Z,1539665956.335 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:16.335Z,1539665956.335 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:16.344Z,1539665956.344 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:16.732Z,1539665956.732 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:17.133Z,1539665957.133 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:17.533Z,1539665957.533 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:17.533Z,1539665957.533 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:17.537Z,1539665957.537 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:17.942Z,1539665957.942 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:18.340Z,1539665958.340 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:18.733Z,1539665958.733 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:18.733Z,1539665958.733 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:18.737Z,1539665958.737 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:19.132Z,1539665959.132 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:19.533Z,1539665959.533 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:19.533Z,1539665959.533 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:19.536Z,1539665959.536 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T04:59:19.537Z,1539665959.537 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-10-16T04:59:19.537Z,1539665959.537 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-10-16T04:59:19.537Z,1539665959.537 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T04:59:19.614Z,1539665959.614 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T04:59:19.961Z,1539665959.961 [Aanderaa_O2](INFO): Stop
2018-10-16T04:59:19.971Z,1539665959.971 [Aanderaa_O2](INFO): Powering down
2018-10-16T04:59:20.333Z,1539665960.333 [Aanderaa_O2](INFO): Stopping
2018-10-16T04:59:20.733Z,1539665960.733 [Aanderaa_O2](INFO): Stopped
2018-10-16T04:59:20.822Z,1539665960.822 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T04:59:20.823Z,1539665960.823 [Aanderaa_O2] No Fault, FailCount= 2
2018-10-16T04:59:21.133Z,1539665961.133 [Aanderaa_O2](INFO): Start
2018-10-16T04:59:21.534Z,1539665961.534 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:21.547Z,1539665961.547 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T04:59:21.934Z,1539665961.934 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:22.379Z,1539665962.379 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:22.732Z,1539665962.732 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:23.133Z,1539665963.133 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:23.533Z,1539665963.533 [Aanderaa_O2](INFO): Starting
2018-10-16T04:59:23.533Z,1539665963.533 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T04:59:23.543Z,1539665963.543 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T04:59:23.543Z,1539665963.543 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-10-16T04:59:23.543Z,1539665963.543 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T04:59:23.608Z,1539665963.608 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T04:59:23.609Z,1539665963.609 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-10-16T04:59:23.932Z,1539665963.932 [Aanderaa_O2](INFO): Stop
2018-10-16T04:59:23.933Z,1539665963.933 [Aanderaa_O2](INFO): Powering down
2018-10-16T04:59:24.333Z,1539665964.333 [Aanderaa_O2](INFO): Stopping
2018-10-16T04:59:24.732Z,1539665964.732 [Aanderaa_O2](INFO): Stopped
2018-10-16T04:59:31.113Z,1539665971.113 [NAL9602](INFO): Powering up NAL9602
2018-10-16T04:59:39.166Z,1539665979.166 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-16T04:59:39.199Z,1539665979.199 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-16T04:59:42.444Z,1539665982.444 [NAL9602](INFO): NAL9602 initialized
2018-10-16T04:59:50.327Z,1539665990.327 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): -0.007602
CHAN A2 (12V): -0.002247
CHAN A3 (5V): -0.001731
CHAN B0 (3.3V): 0.000381
CHAN B1 (3.15aV): -0.000157
CHAN B2 (3.15bV): -0.000385
CHAN B3 (GND): 0.000111
OPEN: -0.000279
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-16T05:00:33.396Z,1539666033.396 [SBIT](IMPORTANT): SBIT PASSED
2018-10-16T05:00:33.495Z,1539666033.495 [CommandLine](IMPORTANT): got command configSet list
2018-10-16T05:00:33.496Z,1539666033.496 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-16T05:00:33.496Z,1539666033.496 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=25 count;

2018-10-16T05:00:33.497Z,1539666033.497 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-10-16T05:00:33.497Z,1539666033.497 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 minute;

2018-10-16T05:00:33.497Z,1539666033.497 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2018-10-16T05:00:33.497Z,1539666033.497 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2018-10-16T05:00:33.497Z,1539666033.497 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-10-16T05:00:33.497Z,1539666033.497 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-10-16T05:00:33.497Z,1539666033.497 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-10-16T05:00:33.497Z,1539666033.497 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2018-10-16T05:00:33.498Z,1539666033.498 [CommandLine](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2018-10-16T05:00:33.498Z,1539666033.498 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-10-16T05:00:33.498Z,1539666033.498 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-10-16T05:00:33.796Z,1539666033.796 [MissionManager](IMPORTANT): Started mission Startup
2018-10-16T05:00:33.796Z,1539666033.796 [Startup] Running Loop=1
2018-10-16T05:00:33.796Z,1539666033.796 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-16T05:00:33.796Z,1539666033.796 [Startup:A.GoToSurface] Running Loop=1
2018-10-16T05:00:33.796Z,1539666033.796 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-16T05:00:33.797Z,1539666033.797 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-16T05:00:33.798Z,1539666033.798 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-16T05:00:33.798Z,1539666033.798 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-16T05:00:33.799Z,1539666033.799 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-16T05:00:33.799Z,1539666033.799 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-16T05:00:33.806Z,1539666033.806 [Startup:StartupSatComms] Running Loop=1
2018-10-16T05:00:33.806Z,1539666033.806 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-16T05:00:33.806Z,1539666033.806 [Startup:StartupSatComms:A] Running Loop=1
2018-10-16T05:00:34.206Z,1539666034.206 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-16T05:01:34.204Z,1539666094.204 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-16T05:00:33.8Z
2018-10-16T05:01:34.204Z,1539666094.204 [Startup:StartupSatComms:A] Stopped
2018-10-16T05:01:34.204Z,1539666094.204 [Startup:StartupSatComms:B] Running Loop=1
2018-10-16T05:01:34.607Z,1539666094.607 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-16T05:01:43.503Z,1539666103.503 [CommandLine](IMPORTANT): got command failComponent
2018-10-16T05:01:43.503Z,1539666103.503 [CommandLine](IMPORTANT): Failed components:
2018-10-16T05:01:43.504Z,1539666103.504 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2018-10-16T05:01:53.807Z,1539666113.807 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T05:01:53.807Z,1539666113.807 [Aanderaa_O2] No Fault, FailCount= 3
2018-10-16T05:01:54.200Z,1539666114.200 [Aanderaa_O2](INFO): Start
2018-10-16T05:01:54.578Z,1539666114.578 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:54.590Z,1539666114.590 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:01:54.967Z,1539666114.967 [CommandLine](IMPORTANT): got command failComponent none Aanderaa_O2
2018-10-16T05:01:54.968Z,1539666114.968 [CommandLine](IMPORTANT): Aanderaa_O2 failureMode is No Fault
2018-10-16T05:01:55.016Z,1539666115.016 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:55.397Z,1539666115.397 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:55.799Z,1539666115.799 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:56.236Z,1539666116.236 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:56.585Z,1539666116.585 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:56.585Z,1539666116.585 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:01:56.588Z,1539666116.588 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:01:56.994Z,1539666116.994 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:57.391Z,1539666117.391 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:57.774Z,1539666117.774 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:58.173Z,1539666118.173 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:58.598Z,1539666118.598 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:58.599Z,1539666118.599 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:01:58.604Z,1539666118.604 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:01:58.981Z,1539666118.981 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:59.393Z,1539666119.393 [Aanderaa_O2](INFO): Starting
2018-10-16T05:01:59.394Z,1539666119.394 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:01:59.399Z,1539666119.399 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:01:59.795Z,1539666119.795 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:00.198Z,1539666120.198 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:00.630Z,1539666120.630 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:00.631Z,1539666120.631 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:00.650Z,1539666120.650 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T05:02:00.651Z,1539666120.651 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-10-16T05:02:00.651Z,1539666120.651 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:02:00.712Z,1539666120.712 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:02:00.978Z,1539666120.978 [Aanderaa_O2](INFO): Stop
2018-10-16T05:02:00.978Z,1539666120.978 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:02:01.382Z,1539666121.382 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:02:01.778Z,1539666121.778 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:02:01.845Z,1539666121.845 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T05:02:01.845Z,1539666121.845 [Aanderaa_O2] No Fault, FailCount= 1
2018-10-16T05:02:02.191Z,1539666122.191 [Aanderaa_O2](INFO): Start
2018-10-16T05:02:02.596Z,1539666122.596 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:02.610Z,1539666122.610 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:02:02.992Z,1539666122.992 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:03.013Z,1539666123.013 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-16T05:02:03.013Z,1539666123.013 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-16T05:02:03.013Z,1539666123.013 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-16T05:02:03.047Z,1539666123.047 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-16T05:02:03.394Z,1539666123.394 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:03.417Z,1539666123.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-16T05:02:03.417Z,1539666123.417 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-16T05:02:03.781Z,1539666123.781 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:03.815Z,1539666123.815 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-16T05:02:03.815Z,1539666123.815 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-16T05:02:03.816Z,1539666123.816 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-16T05:02:04.210Z,1539666124.210 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:07.590Z,1539666127.590 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:07.591Z,1539666127.591 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:07.610Z,1539666127.610 [Aanderaa_O2](ERROR): Error parsing data: 6
2018-10-16T05:02:07.747Z,1539666127.747 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:07.747Z,1539666127.747 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:08.166Z,1539666128.166 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:08.167Z,1539666128.167 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:08.172Z,1539666128.172 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:08.476Z,1539666128.476 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004007
2018-10-16T05:02:08.530Z,1539666128.530 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:08.530Z,1539666128.530 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:08.539Z,1539666128.539 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:08.953Z,1539666128.953 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:08.953Z,1539666128.953 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:08.957Z,1539666128.957 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:09.306Z,1539666129.306 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:12.878Z,1539666132.878 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:12.878Z,1539666132.878 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:12.884Z,1539666132.884 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:12.884Z,1539666132.884 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-10-16T05:02:12.884Z,1539666132.884 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-10-16T05:02:12.884Z,1539666132.884 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:02:12.904Z,1539666132.904 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:02:12.958Z,1539666132.958 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181016T044353/Courier0007.lzma
2018-10-16T05:02:13.019Z,1539666133.019 [Aanderaa_O2](INFO): Stop
2018-10-16T05:02:13.019Z,1539666133.019 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:02:13.364Z,1539666133.364 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:02:13.712Z,1539666133.712 [DataOverHttps](INFO): Moved sent file to Logs/20181016T044353/Courier0007.lzma.bak
2018-10-16T05:02:13.712Z,1539666133.712 [DataOverHttps](INFO): SBD MOMSN=8715601
2018-10-16T05:02:13.764Z,1539666133.764 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:02:13.820Z,1539666133.820 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-10-16T05:02:13.820Z,1539666133.820 [Aanderaa_O2] No Fault, FailCount= 2
2018-10-16T05:02:14.251Z,1539666134.251 [Aanderaa_O2](INFO): Start
2018-10-16T05:02:14.614Z,1539666134.614 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:14.638Z,1539666134.638 [Aanderaa_O2](INFO): Starting: do start.
2018-10-16T05:02:18.063Z,1539666138.063 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:18.063Z,1539666138.063 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:18.071Z,1539666138.071 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:18.223Z,1539666138.223 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:18.624Z,1539666138.624 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:18.624Z,1539666138.624 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:18.628Z,1539666138.628 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:19.032Z,1539666139.032 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:19.406Z,1539666139.406 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:19.805Z,1539666139.805 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:19.805Z,1539666139.805 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:19.809Z,1539666139.809 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:23.196Z,1539666143.196 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:23.196Z,1539666143.196 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:23.201Z,1539666143.201 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:23.280Z,1539666143.280 [DataOverHttps](INFO): Sending 289 bytes from file Logs/20181016T044353/Express0008.lzma
2018-10-16T05:02:23.343Z,1539666143.343 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:23.343Z,1539666143.343 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:23.347Z,1539666143.347 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:23.711Z,1539666143.711 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:23.712Z,1539666143.712 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:23.716Z,1539666143.716 [Aanderaa_O2](ERROR): Error parsing data: 

2018-10-16T05:02:24.072Z,1539666144.072 [DataOverHttps](INFO): Moved sent file to Logs/20181016T044353/Express0008.lzma.bak
2018-10-16T05:02:24.072Z,1539666144.072 [DataOverHttps](INFO): SBD MOMSN=8715603
2018-10-16T05:02:24.182Z,1539666144.182 [Aanderaa_O2](INFO): Starting
2018-10-16T05:02:24.182Z,1539666144.182 [Aanderaa_O2](INFO): Starting: can read until.
2018-10-16T05:02:24.198Z,1539666144.198 [Aanderaa_O2](ERROR): Uart error: serial timeout
2018-10-16T05:02:24.198Z,1539666144.198 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-10-16T05:02:24.203Z,1539666144.203 [Aanderaa_O2](ERROR): Communications Fault
2018-10-16T05:02:24.307Z,1539666144.307 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-10-16T05:02:24.307Z,1539666144.307 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-10-16T05:02:24.637Z,1539666144.637 [Aanderaa_O2](INFO): Stop
2018-10-16T05:02:24.637Z,1539666144.637 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:02:24.908Z,1539666144.908 [Aanderaa_O2](INFO): Stopping
2018-10-16T05:02:28.246Z,1539666148.246 [Aanderaa_O2](INFO): Stopped
2018-10-16T05:02:33.276Z,1539666153.276 [DataOverHttps](INFO): Sending 1003 bytes from file Logs/20181016T045854/Express0001.lzma
2018-10-16T05:02:34.075Z,1539666154.075 [DataOverHttps](INFO): Moved sent file to Logs/20181016T045854/Express0001.lzma.bak
2018-10-16T05:02:34.075Z,1539666154.075 [DataOverHttps](INFO): SBD MOMSN=8715614
2018-10-16T05:02:34.622Z,1539666154.622 [Startup:StartupSatComms:B](INFO): Timed out from 2018-10-16T05:01:34.2Z
2018-10-16T05:02:34.622Z,1539666154.622 [Startup:StartupSatComms:B] Stopped
2018-10-16T05:02:34.622Z,1539666154.622 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-16T05:02:34.622Z,1539666154.622 [Startup:StartupSatComms] Stopped
2018-10-16T05:02:34.623Z,1539666154.623 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-16T05:02:34.623Z,1539666154.623 [Startup](INFO): Completed Startup
2018-10-16T05:02:34.624Z,1539666154.624 [MissionManager](INFO): Startup is completed.
2018-10-16T05:02:34.624Z,1539666154.624 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-16T05:02:34.624Z,1539666154.624 [Startup] Stopped
2018-10-16T05:02:34.624Z,1539666154.624 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-16T05:02:34.624Z,1539666154.624 [Startup:A.GoToSurface] Stopped
2018-10-16T05:02:34.624Z,1539666154.624 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-16T05:02:34.924Z,1539666154.924 [MissionManager](IMPORTANT): Started mission Default
2018-10-16T05:02:34.924Z,1539666154.924 [Default] Running Loop=1
2018-10-16T05:02:34.924Z,1539666154.924 [Default](DEBUG): Aggregate::initialize Default
2018-10-16T05:02:34.925Z,1539666154.925 [Default:B.GoToSurface] Running Loop=1
2018-10-16T05:02:34.925Z,1539666154.925 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-16T05:02:34.925Z,1539666154.925 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-16T05:02:34.925Z,1539666154.925 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-16T05:02:34.926Z,1539666154.926 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-16T05:02:34.926Z,1539666154.926 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-16T05:02:34.931Z,1539666154.931 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-16T05:02:34.931Z,1539666154.931 [Default:A.Wait] Running Loop=1
2018-10-16T05:02:34.931Z,1539666154.931 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-16T05:02:44.853Z,1539666164.853 [NAL9602](INFO): SBD MO Status=2, MOMSN=10405, MT Status=2, MTMSN=0
2018-10-16T05:02:44.853Z,1539666164.853 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-10-16T05:02:48.142Z,1539666168.142 [Default:A.Wait](INFO): Done Waiting.
2018-10-16T05:02:48.146Z,1539666168.146 [Default:A.Wait] Stopped
2018-10-16T05:02:48.146Z,1539666168.146 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-10-16T05:02:48.501Z,1539666168.501 [Default:CheckIn] Running Loop=1
2018-10-16T05:02:48.501Z,1539666168.501 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-16T05:02:48.501Z,1539666168.501 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-16T05:02:48.898Z,1539666168.898 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-10-16T05:03:02.072Z,1539666182.072 [NAL9602](INFO): SBD MO Status=2, MOMSN=10405, MT Status=2, MTMSN=0
2018-10-16T05:03:02.076Z,1539666182.076 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-10-16T05:03:48.829Z,1539666228.829 [NAL9602](INFO): SBD MO Status=2, MOMSN=10405, MT Status=2, MTMSN=0
2018-10-16T05:03:48.829Z,1539666228.829 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-10-16T05:04:11.156Z,1539666251.156 [CommandLine](IMPORTANT): got command restart application
2018-10-16T05:04:12.162Z,1539666252.162 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-10-16T05:04:12.165Z,1539666252.165 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:04:12.166Z,1539666252.166 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:12.355Z,1539666252.355 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-16T05:04:12.355Z,1539666252.355 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:12.356Z,1539666252.356 [CommandLine](INFO): Join timeout helper Thread ID is 5663
2018-10-16T05:04:12.356Z,1539666252.356 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-16T05:04:12.356Z,1539666252.356 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:12.357Z,1539666252.357 [NavChartDb](INFO): Join timeout helper Thread ID is 5664
2018-10-16T05:04:12.524Z,1539666252.524 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:04:12.524Z,1539666252.524 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:12.538Z,1539666252.538 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-16T05:04:12.538Z,1539666252.538 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:12.539Z,1539666252.539 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5665
2018-10-16T05:04:12.859Z,1539666252.859 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:04:12.859Z,1539666252.859 [WetLabsBB2FL](INFO): Powering down
2018-10-16T05:04:12.860Z,1539666252.860 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:12.866Z,1539666252.866 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-10-16T05:04:12.867Z,1539666252.867 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:12.867Z,1539666252.867 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 5666
2018-10-16T05:04:13.138Z,1539666253.138 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:04:13.139Z,1539666253.139 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-10-16T05:04:13.314Z,1539666253.314 [Rowe_600LCM](INFO): Powering down
2018-10-16T05:04:13.315Z,1539666253.315 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.334Z,1539666253.334 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-16T05:04:13.335Z,1539666253.335 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.335Z,1539666253.335 [Radio_Surface](INFO): Join timeout helper Thread ID is 5668
2018-10-16T05:04:13.515Z,1539666253.515 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:04:13.515Z,1539666253.515 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.523Z,1539666253.523 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-16T05:04:13.524Z,1539666253.524 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.524Z,1539666253.524 [logger](INFO): Join timeout helper Thread ID is 5669
2018-10-16T05:04:13.527Z,1539666253.527 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:04:13.527Z,1539666253.527 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.543Z,1539666253.543 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-16T05:04:13.543Z,1539666253.543 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.543Z,1539666253.543 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-16T05:04:13.543Z,1539666253.543 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.544Z,1539666253.544 [controlThread](INFO): Join timeout helper Thread ID is 5670
2018-10-16T05:04:13.610Z,1539666253.610 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-16T05:04:13.611Z,1539666253.611 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-16T05:04:13.612Z,1539666253.612 [NAL9602](INFO): Powering down
2018-10-16T05:04:13.613Z,1539666253.613 [PNI_TCM](INFO): Powering down
2018-10-16T05:04:13.699Z,1539666253.699 [Aanderaa_O2](INFO): Powering down
2018-10-16T05:04:13.700Z,1539666253.700 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-16T05:04:13.701Z,1539666253.701 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-16T05:04:13.702Z,1539666253.702 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-16T05:04:13.702Z,1539666253.702 [MissionManager](INFO): Uninitializing Mission Default
2018-10-16T05:04:13.702Z,1539666253.702 [Default] Stopped
2018-10-16T05:04:13.702Z,1539666253.702 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-16T05:04:13.703Z,1539666253.703 [Default:B.GoToSurface] Stopped
2018-10-16T05:04:13.703Z,1539666253.703 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-16T05:04:13.703Z,1539666253.703 [Default:CheckIn] Stopped
2018-10-16T05:04:13.703Z,1539666253.703 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-16T05:04:13.703Z,1539666253.703 [Default:CheckIn:Read_GPS] Stopped
2018-10-16T05:04:13.706Z,1539666253.706 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-16T05:04:13.707Z,1539666253.707 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-16T05:04:13.707Z,1539666253.707 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-16T05:04:13.707Z,1539666253.707 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-16T05:04:13.707Z,1539666253.707 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-16T05:04:13.708Z,1539666253.708 [BuoyancyServo](INFO): Powering down
2018-10-16T05:04:13.723Z,1539666253.723 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-16T05:04:13.723Z,1539666253.723 [ElevatorServo](INFO): Powering down
2018-10-16T05:04:13.724Z,1539666253.724 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-16T05:04:13.724Z,1539666253.724 [MassServo](INFO): Powering down
2018-10-16T05:04:13.725Z,1539666253.725 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-16T05:04:13.725Z,1539666253.725 [RudderServo](INFO): Powering down
2018-10-16T05:04:13.726Z,1539666253.726 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-16T05:04:13.726Z,1539666253.726 [ThrusterServo](INFO): Powering down
2018-10-16T05:04:13.727Z,1539666253.727 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-16T05:04:13.727Z,1539666253.727 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-16T05:04:13.727Z,1539666253.727 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-16T05:04:13.728Z,1539666253.728 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.793Z,1539666253.793 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.800Z,1539666253.800 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.842Z,1539666253.842 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.872Z,1539666253.872 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-16T05:04:13.930Z,1539666253.930 [logger ThreadHandler](INFO): Thread cancelled.