2018-07-31T22:47:56.205Z,1533077276.205 [Supervisor](DEBUG): Initializing supervisor.
2018-07-31T22:47:56.207Z,1533077276.207 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-31T22:47:56.208Z,1533077276.208 [SyncHandler](INFO): Protected caller Thread ID is 8372
2018-07-31T22:47:56.208Z,1533077276.208 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-31T22:47:56.209Z,1533077276.209 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-31T22:47:56.210Z,1533077276.210 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8373
2018-07-31T22:47:56.212Z,1533077276.212 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-31T22:47:56.224Z,1533077276.224 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-31T22:47:56.225Z,1533077276.225 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-31T22:47:56.225Z,1533077276.225 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8374
2018-07-31T22:47:56.226Z,1533077276.226 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-31T22:47:56.227Z,1533077276.227 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-31T22:47:56.228Z,1533077276.228 [logger ThreadHandler](INFO): Protected caller Thread ID is 8375
2018-07-31T22:47:56.229Z,1533077276.229 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-31T22:47:56.230Z,1533077276.230 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-31T22:47:56.231Z,1533077276.231 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-31T22:47:56.329Z,1533077276.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-31T22:47:56.329Z,1533077276.329 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-31T22:47:56.431Z,1533077276.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-31T22:47:56.431Z,1533077276.431 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-31T22:47:56.636Z,1533077276.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-31T22:47:56.637Z,1533077276.637 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-31T22:47:56.972Z,1533077276.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-31T22:47:56.973Z,1533077276.973 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-31T22:47:57.321Z,1533077277.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-31T22:47:57.322Z,1533077277.322 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-31T22:47:57.798Z,1533077277.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-31T22:47:57.799Z,1533077277.799 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-31T22:47:58.230Z,1533077278.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-31T22:47:58.230Z,1533077278.230 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-31T22:47:58.715Z,1533077278.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-31T22:47:58.715Z,1533077278.715 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-31T22:47:58.795Z,1533077278.795 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-31T22:47:59.104Z,1533077279.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-31T22:47:59.105Z,1533077279.105 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-31T22:47:59.252Z,1533077279.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-31T22:47:59.253Z,1533077279.253 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-31T22:47:59.466Z,1533077279.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-31T22:47:59.467Z,1533077279.467 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-31T22:47:59.684Z,1533077279.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-31T22:47:59.684Z,1533077279.684 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-31T22:47:59.783Z,1533077279.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-31T22:47:59.784Z,1533077279.784 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-31T22:47:59.929Z,1533077279.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-31T22:47:59.931Z,1533077279.931 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-07-31T22:47:59.932Z,1533077279.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-07-31T22:48:00.168Z,1533077280.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-31T22:48:00.169Z,1533077280.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-07-31T22:48:00.274Z,1533077280.274 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-07-31T22:48:00.419Z,1533077280.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-07-31T22:48:00.513Z,1533077280.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-07-31T22:48:00.598Z,1533077280.598 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-07-31T22:48:00.742Z,1533077280.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-07-31T22:48:00.925Z,1533077280.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-07-31T22:48:01.271Z,1533077281.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-07-31T22:48:01.522Z,1533077281.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-07-31T22:48:01.683Z,1533077281.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-07-31T22:48:01.865Z,1533077281.865 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-07-31T22:48:01.873Z,1533077281.873 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-31T22:48:01.977Z,1533077281.977 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-31T22:48:01.978Z,1533077281.978 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-31T22:48:01.991Z,1533077281.991 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-31T22:48:01.992Z,1533077281.992 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-31T22:48:02.034Z,1533077282.034 [DepthRateCalculator] Loaded
2018-07-31T22:48:02.035Z,1533077282.035 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-31T22:48:02.041Z,1533077282.041 [PitchRateCalculator] Loaded
2018-07-31T22:48:02.041Z,1533077282.041 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-31T22:48:02.058Z,1533077282.058 [SpeedCalculator] Loaded
2018-07-31T22:48:02.058Z,1533077282.058 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-31T22:48:02.080Z,1533077282.080 [TempGradientCalculator] Loaded
2018-07-31T22:48:02.080Z,1533077282.080 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-31T22:48:02.096Z,1533077282.096 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-31T22:48:02.096Z,1533077282.096 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-31T22:48:02.102Z,1533077282.102 [YawRateCalculator] Loaded
2018-07-31T22:48:02.102Z,1533077282.102 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-31T22:48:02.143Z,1533077282.143 [ElevatorOffsetCalculator] Loaded
2018-07-31T22:48:02.143Z,1533077282.143 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-31T22:48:02.143Z,1533077282.143 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-31T22:48:02.144Z,1533077282.144 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-31T22:48:02.169Z,1533077282.169 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-31T22:48:02.170Z,1533077282.170 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-31T22:48:02.461Z,1533077282.461 [DataOverHttps] Loaded
2018-07-31T22:48:02.461Z,1533077282.461 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-31T22:48:02.486Z,1533077282.486 [Depth_Keller] Loaded
2018-07-31T22:48:02.486Z,1533077282.486 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-31T22:48:02.588Z,1533077282.588 [NAL9602] Loaded
2018-07-31T22:48:02.588Z,1533077282.588 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-31T22:48:02.604Z,1533077282.604 [Onboard] Loaded
2018-07-31T22:48:02.605Z,1533077282.605 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-31T22:48:02.615Z,1533077282.615 [Radio_Surface] Loaded
2018-07-31T22:48:02.615Z,1533077282.615 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-31T22:48:02.616Z,1533077282.616 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B74E0
2018-07-31T22:48:02.617Z,1533077282.617 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8454
2018-07-31T22:48:02.666Z,1533077282.666 [PNI_TCM] Loaded
2018-07-31T22:48:02.667Z,1533077282.667 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-07-31T22:48:04.495Z,1533077284.495 [BPC1] Loaded
2018-07-31T22:48:04.496Z,1533077284.496 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-31T22:48:04.496Z,1533077284.496 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-31T22:48:04.497Z,1533077284.497 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-31T22:48:04.632Z,1533077284.632 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-31T22:48:04.655Z,1533077284.655 [SBIT] Loaded
2018-07-31T22:48:04.655Z,1533077284.655 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-31T22:48:04.656Z,1533077284.656 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-31T22:48:04.668Z,1533077284.668 [IBIT] Loaded
2018-07-31T22:48:04.668Z,1533077284.668 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-31T22:48:04.671Z,1533077284.671 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-31T22:48:04.826Z,1533077284.826 [CBIT] Loaded
2018-07-31T22:48:04.826Z,1533077284.826 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-31T22:48:04.826Z,1533077284.826 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-31T22:48:04.827Z,1533077284.827 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-31T22:48:04.974Z,1533077284.974 [Aanderaa_O2] Loaded
2018-07-31T22:48:04.974Z,1533077284.974 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-07-31T22:48:05.023Z,1533077285.023 [CTD_Seabird] Loaded
2018-07-31T22:48:05.023Z,1533077285.023 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-07-31T22:48:05.024Z,1533077285.024 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4087A4E0
2018-07-31T22:48:05.024Z,1533077285.024 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8455
2018-07-31T22:48:05.042Z,1533077285.042 [ESPComponent] Loaded
2018-07-31T22:48:05.042Z,1533077285.042 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-07-31T22:48:05.056Z,1533077285.056 [PAR_Licor] Loaded
2018-07-31T22:48:05.057Z,1533077285.057 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-07-31T22:48:05.107Z,1533077285.107 [WetLabsBB2FL] Loaded
2018-07-31T22:48:05.107Z,1533077285.107 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-31T22:48:05.108Z,1533077285.108 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408AA4E0
2018-07-31T22:48:05.109Z,1533077285.109 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8456
2018-07-31T22:48:05.109Z,1533077285.109 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-31T22:48:05.110Z,1533077285.110 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-31T22:48:05.360Z,1533077285.360 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-31T22:48:05.360Z,1533077285.360 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-31T22:48:05.431Z,1533077285.431 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-31T22:48:05.527Z,1533077285.527 [VerticalControl] Loaded
2018-07-31T22:48:05.527Z,1533077285.527 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-31T22:48:05.528Z,1533077285.528 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-31T22:48:05.591Z,1533077285.591 [HorizontalControl] Loaded
2018-07-31T22:48:05.592Z,1533077285.592 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-31T22:48:05.592Z,1533077285.592 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-31T22:48:05.598Z,1533077285.598 [SpeedControl] Loaded
2018-07-31T22:48:05.598Z,1533077285.598 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-31T22:48:05.599Z,1533077285.599 [LoopControl](DEBUG): Construct LoopControl.
2018-07-31T22:48:05.599Z,1533077285.599 [LoopControl] Loaded
2018-07-31T22:48:05.600Z,1533077285.600 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-31T22:48:05.600Z,1533077285.600 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-31T22:48:05.600Z,1533077285.600 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-31T22:48:05.685Z,1533077285.685 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-31T22:48:05.685Z,1533077285.685 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-31T22:48:05.685Z,1533077285.685 [StratificationFrontDetector] Loaded
2018-07-31T22:48:05.686Z,1533077285.686 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-31T22:48:05.686Z,1533077285.686 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-31T22:48:05.687Z,1533077285.687 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-31T22:48:05.788Z,1533077285.788 [BuoyancyServo] Loaded
2018-07-31T22:48:05.789Z,1533077285.789 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-31T22:48:05.800Z,1533077285.800 [ElevatorServo] Loaded
2018-07-31T22:48:05.801Z,1533077285.801 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-31T22:48:05.812Z,1533077285.812 [MassServo] Loaded
2018-07-31T22:48:05.812Z,1533077285.812 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-31T22:48:05.823Z,1533077285.823 [RudderServo] Loaded
2018-07-31T22:48:05.824Z,1533077285.824 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-31T22:48:05.835Z,1533077285.835 [ThrusterServo] Loaded
2018-07-31T22:48:05.835Z,1533077285.835 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-31T22:48:05.835Z,1533077285.835 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-31T22:48:05.836Z,1533077285.836 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-31T22:48:05.929Z,1533077285.929 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-31T22:48:05.929Z,1533077285.929 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-31T22:48:05.946Z,1533077285.946 [NavChart] Loaded
2018-07-31T22:48:05.947Z,1533077285.947 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-31T22:48:05.951Z,1533077285.951 [UniversalFixResidualReporter] Loaded
2018-07-31T22:48:05.951Z,1533077285.951 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-31T22:48:05.951Z,1533077285.951 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-31T22:48:05.955Z,1533077285.955 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-31T22:48:05.956Z,1533077285.956 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-31T22:48:05.963Z,1533077285.963 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-31T22:48:05.964Z,1533077285.964 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A034E0
2018-07-31T22:48:05.964Z,1533077285.964 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8457
2018-07-31T22:48:05.969Z,1533077285.969 [Supervisor](INFO): Main Thread ID is 8371
2018-07-31T22:48:05.969Z,1533077285.969 [Supervisor](DEBUG): Running supervisor.
2018-07-31T22:48:05.969Z,1533077285.969 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8458
2018-07-31T22:48:05.972Z,1533077285.972 [controlThread ThreadHandler](INFO): Handler Thread ID is 8459
2018-07-31T22:48:05.972Z,1533077285.972 [controlThread](DEBUG): Initializing ControlThread
2018-07-31T22:48:05.973Z,1533077285.973 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-31T22:48:05.974Z,1533077285.974 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-31T22:48:05.974Z,1533077285.974 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-31T22:48:05.974Z,1533077285.974 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-31T22:48:05.975Z,1533077285.975 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-31T22:48:05.976Z,1533077285.976 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-31T22:48:05.976Z,1533077285.976 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-31T22:48:05.980Z,1533077285.980 [SBIT](INFO): Initialize SBIT Component.
2018-07-31T22:48:05.980Z,1533077285.980 [SBIT](IMPORTANT): git: 2018-07-25
2018-07-31T22:48:05.980Z,1533077285.980 [SBIT](INFO): git hash: 2912d14d75d7acdc47d6eebe250915c9e75a6268
2018-07-31T22:48:05.981Z,1533077285.981 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-31T22:48:05.982Z,1533077285.982 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-31T22:48:05.983Z,1533077285.983 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2018-07-31T22:48:05.984Z,1533077285.984 [IBIT](INFO): Initialize IBIT Component.
2018-07-31T22:48:05.984Z,1533077285.984 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-31T22:48:05.985Z,1533077285.985 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-07-31T22:48:05.986Z,1533077285.986 [logger ThreadHandler](INFO): Handler Thread ID is 8460
2018-07-31T22:48:06.003Z,1533077286.003 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8461
2018-07-31T22:48:06.009Z,1533077286.009 [Radio_Surface](INFO): Powering up
2018-07-31T22:48:06.015Z,1533077286.015 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8462
2018-07-31T22:48:06.016Z,1533077286.016 [CTD_Seabird](INFO): Initializing
2018-07-31T22:48:06.016Z,1533077286.016 [CTD_Seabird](INFO): Checking LCM
2018-07-31T22:48:06.025Z,1533077286.025 [CTD_Seabird](INFO): LCM OK
2018-07-31T22:48:06.025Z,1533077286.025 [CTD_Seabird](INFO): Powering up
2018-07-31T22:48:06.031Z,1533077286.031 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8463
2018-07-31T22:48:06.032Z,1533077286.032 [WetLabsBB2FL](INFO): Powering down
2018-07-31T22:48:06.063Z,1533077286.063 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8464
2018-07-31T22:48:06.066Z,1533077286.066 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-31T22:48:06.066Z,1533077286.066 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-31T22:48:06.067Z,1533077286.067 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-31T22:48:06.067Z,1533077286.067 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-31T22:48:06.067Z,1533077286.067 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-31T22:48:06.067Z,1533077286.067 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-31T22:48:06.068Z,1533077286.068 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-31T22:48:06.068Z,1533077286.068 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-31T22:48:06.068Z,1533077286.068 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-31T22:48:06.068Z,1533077286.068 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-31T22:48:06.068Z,1533077286.068 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-31T22:48:06.069Z,1533077286.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-31T22:48:06.069Z,1533077286.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-31T22:48:06.069Z,1533077286.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-31T22:48:06.069Z,1533077286.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-31T22:48:06.069Z,1533077286.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-31T22:48:06.095Z,1533077286.095 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-31T22:48:06.097Z,1533077286.097 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-31T22:48:06.097Z,1533077286.097 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-31T22:48:06.098Z,1533077286.098 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-31T22:48:06.100Z,1533077286.100 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-31T22:48:06.101Z,1533077286.101 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-31T22:48:06.101Z,1533077286.101 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-31T22:48:06.102Z,1533077286.102 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-31T22:48:06.102Z,1533077286.102 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-31T22:48:06.103Z,1533077286.103 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-31T22:48:06.167Z,1533077286.167 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-31T22:48:06.211Z,1533077286.211 [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-07-31T22:48:06.212Z,1533077286.212 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-31T22:48:06.291Z,1533077286.291 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-31T22:48:06.293Z,1533077286.293 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-31T22:48:06.310Z,1533077286.310 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-31T22:48:06.333Z,1533077286.333 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-31T22:48:06.336Z,1533077286.336 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-31T22:48:06.346Z,1533077286.346 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-31T22:48:06.350Z,1533077286.350 [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 300</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/></Syslo