2019-10-01T05:31:04.479Z,1569907864.479 [Supervisor](DEBUG): Initializing supervisor.
2019-10-01T05:31:04.482Z,1569907864.482 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-01T05:31:04.483Z,1569907864.483 [SyncHandler](INFO): Protected caller Thread ID is 2566
2019-10-01T05:31:04.484Z,1569907864.484 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-01T05:31:04.485Z,1569907864.485 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-01T05:31:04.485Z,1569907864.485 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2567
2019-10-01T05:31:04.488Z,1569907864.488 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-01T05:31:04.499Z,1569907864.499 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-01T05:31:04.500Z,1569907864.500 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-01T05:31:04.501Z,1569907864.501 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2568
2019-10-01T05:31:04.502Z,1569907864.502 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-01T05:31:04.502Z,1569907864.502 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-01T05:31:04.503Z,1569907864.503 [logger ThreadHandler](INFO): Protected caller Thread ID is 2569
2019-10-01T05:31:04.505Z,1569907864.505 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-01T05:31:04.505Z,1569907864.505 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-01T05:31:04.507Z,1569907864.507 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-01T05:31:04.599Z,1569907864.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-01T05:31:04.600Z,1569907864.600 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-01T05:31:05.156Z,1569907865.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-01T05:31:05.156Z,1569907865.156 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-01T05:31:05.254Z,1569907865.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-01T05:31:05.254Z,1569907865.254 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-01T05:31:05.353Z,1569907865.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-01T05:31:05.354Z,1569907865.354 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-01T05:31:05.432Z,1569907865.432 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-01T05:31:05.567Z,1569907865.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-01T05:31:05.567Z,1569907865.567 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-01T05:31:05.858Z,1569907865.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-01T05:31:05.859Z,1569907865.859 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-01T05:31:06.304Z,1569907866.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-01T05:31:06.305Z,1569907866.305 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-01T05:31:06.446Z,1569907866.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-01T05:31:06.447Z,1569907866.447 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-01T05:31:06.632Z,1569907866.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-01T05:31:06.633Z,1569907866.633 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-01T05:31:07.079Z,1569907867.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-01T05:31:07.080Z,1569907867.080 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-01T05:31:07.295Z,1569907867.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-01T05:31:07.296Z,1569907867.296 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-01T05:31:07.499Z,1569907867.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-01T05:31:07.499Z,1569907867.499 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-01T05:31:07.892Z,1569907867.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-01T05:31:07.893Z,1569907867.893 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-01T05:31:08.222Z,1569907868.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-01T05:31:08.224Z,1569907868.224 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-10-01T05:31:08.225Z,1569907868.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-10-01T05:31:08.306Z,1569907868.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-10-01T05:31:08.456Z,1569907868.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-10-01T05:31:08.561Z,1569907868.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-10-01T05:31:08.656Z,1569907868.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-10-01T05:31:08.871Z,1569907868.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-10-01T05:31:09.214Z,1569907869.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-10-01T05:31:09.490Z,1569907869.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-01T05:31:09.491Z,1569907869.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-10-01T05:31:09.583Z,1569907869.583 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-10-01T05:31:09.679Z,1569907869.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-10-01T05:31:09.814Z,1569907869.814 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-10-01T05:31:09.915Z,1569907869.915 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-10-01T05:31:09.915Z,1569907869.915 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-01T05:31:09.926Z,1569907869.926 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-01T05:31:09.974Z,1569907869.974 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-01T05:31:10.084Z,1569907870.084 [VerticalControl] Loaded
2019-10-01T05:31:10.085Z,1569907870.085 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-01T05:31:10.086Z,1569907870.086 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-01T05:31:10.154Z,1569907870.154 [HorizontalControl] Loaded
2019-10-01T05:31:10.154Z,1569907870.154 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-01T05:31:10.155Z,1569907870.155 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-01T05:31:10.160Z,1569907870.160 [SpeedControl] Loaded
2019-10-01T05:31:10.160Z,1569907870.160 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-01T05:31:10.161Z,1569907870.161 [LoopControl](DEBUG): Construct LoopControl.
2019-10-01T05:31:10.162Z,1569907870.162 [LoopControl] Loaded
2019-10-01T05:31:10.162Z,1569907870.162 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-01T05:31:10.162Z,1569907870.162 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-01T05:31:10.163Z,1569907870.163 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-01T05:31:10.203Z,1569907870.203 [DepthRateCalculator] Loaded
2019-10-01T05:31:10.203Z,1569907870.203 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-01T05:31:10.208Z,1569907870.208 [PitchRateCalculator] Loaded
2019-10-01T05:31:10.208Z,1569907870.208 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-01T05:31:10.224Z,1569907870.224 [SpeedCalculator] Loaded
2019-10-01T05:31:10.224Z,1569907870.224 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-01T05:31:10.244Z,1569907870.244 [TempGradientCalculator] Loaded
2019-10-01T05:31:10.245Z,1569907870.245 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-01T05:31:10.250Z,1569907870.250 [YawRateCalculator] Loaded
2019-10-01T05:31:10.250Z,1569907870.250 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-01T05:31:10.280Z,1569907870.280 [ElevatorOffsetCalculator] Loaded
2019-10-01T05:31:10.280Z,1569907870.280 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-01T05:31:10.281Z,1569907870.281 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-01T05:31:10.281Z,1569907870.281 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-01T05:31:10.314Z,1569907870.314 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-01T05:31:10.314Z,1569907870.314 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-01T05:31:10.384Z,1569907870.384 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-01T05:31:10.384Z,1569907870.384 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-01T05:31:10.671Z,1569907870.671 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-01T05:31:10.672Z,1569907870.672 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-01T05:31:10.769Z,1569907870.769 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-01T05:31:10.770Z,1569907870.770 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-01T05:31:11.078Z,1569907871.078 [DataOverHttps] Loaded
2019-10-01T05:31:11.078Z,1569907871.078 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-01T05:31:11.079Z,1569907871.079 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2019-10-01T05:31:11.079Z,1569907871.079 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2648
2019-10-01T05:31:11.116Z,1569907871.116 [Depth_Keller] Loaded
2019-10-01T05:31:11.116Z,1569907871.116 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-01T05:31:11.121Z,1569907871.121 [DropWeight] Loaded
2019-10-01T05:31:11.121Z,1569907871.121 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-01T05:31:11.219Z,1569907871.219 [NAL9602] Loaded
2019-10-01T05:31:11.219Z,1569907871.219 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-01T05:31:11.234Z,1569907871.234 [Onboard] Loaded
2019-10-01T05:31:11.235Z,1569907871.235 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-01T05:31:11.238Z,1569907871.238 [Radio_Surface] Loaded
2019-10-01T05:31:11.238Z,1569907871.238 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-01T05:31:11.239Z,1569907871.239 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2019-10-01T05:31:11.240Z,1569907871.240 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2649
2019-10-01T05:31:11.283Z,1569907871.283 [RDI_Pathfinder] Loaded
2019-10-01T05:31:11.283Z,1569907871.283 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-10-01T05:31:11.403Z,1569907871.403 [DAT] Loaded
2019-10-01T05:31:11.404Z,1569907871.404 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-01T05:31:12.908Z,1569907872.908 [BPC1] Loaded
2019-10-01T05:31:12.909Z,1569907872.909 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-01T05:31:12.909Z,1569907872.909 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-01T05:31:12.910Z,1569907872.910 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-01T05:31:13.012Z,1569907873.012 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-01T05:31:13.012Z,1569907873.012 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-01T05:31:13.033Z,1569907873.033 [NavChart] Loaded
2019-10-01T05:31:13.033Z,1569907873.033 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-01T05:31:13.037Z,1569907873.037 [UniversalFixResidualReporter] Loaded
2019-10-01T05:31:13.037Z,1569907873.037 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-01T05:31:13.037Z,1569907873.037 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-01T05:31:13.038Z,1569907873.038 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-01T05:31:13.170Z,1569907873.170 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-01T05:31:13.181Z,1569907873.181 [SBIT] Loaded
2019-10-01T05:31:13.181Z,1569907873.181 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-01T05:31:13.182Z,1569907873.182 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-01T05:31:13.193Z,1569907873.193 [IBIT] Loaded
2019-10-01T05:31:13.193Z,1569907873.193 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-01T05:31:13.197Z,1569907873.197 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-01T05:31:13.335Z,1569907873.335 [CBIT] Loaded
2019-10-01T05:31:13.335Z,1569907873.335 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-01T05:31:13.335Z,1569907873.335 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-01T05:31:13.336Z,1569907873.336 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-01T05:31:13.439Z,1569907873.439 [BuoyancyServo] Loaded
2019-10-01T05:31:13.439Z,1569907873.439 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-01T05:31:13.450Z,1569907873.450 [ElevatorServo] Loaded
2019-10-01T05:31:13.451Z,1569907873.451 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-01T05:31:13.461Z,1569907873.461 [MassServo] Loaded
2019-10-01T05:31:13.462Z,1569907873.462 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-01T05:31:13.472Z,1569907873.472 [RudderServo] Loaded
2019-10-01T05:31:13.473Z,1569907873.473 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-01T05:31:13.483Z,1569907873.483 [ThrusterServo] Loaded
2019-10-01T05:31:13.484Z,1569907873.484 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-01T05:31:13.484Z,1569907873.484 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-01T05:31:13.485Z,1569907873.485 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-01T05:31:13.498Z,1569907873.498 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-01T05:31:13.498Z,1569907873.498 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-01T05:31:13.679Z,1569907873.679 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-01T05:31:13.684Z,1569907873.684 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-01T05:31:13.686Z,1569907873.686 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-01T05:31:13.691Z,1569907873.691 [CTD_Seabird](INFO): created writer for : depth
2019-10-01T05:31:13.691Z,1569907873.691 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-01T05:31:13.696Z,1569907873.696 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-01T05:31:13.697Z,1569907873.697 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-01T05:31:13.702Z,1569907873.702 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-01T05:31:13.703Z,1569907873.703 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-01T05:31:13.708Z,1569907873.708 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-01T05:31:13.709Z,1569907873.709 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-01T05:31:13.714Z,1569907873.714 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-01T05:31:13.715Z,1569907873.715 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-01T05:31:13.720Z,1569907873.720 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-01T05:31:13.747Z,1569907873.747 [CTD_Seabird] Loaded
2019-10-01T05:31:13.748Z,1569907873.748 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-01T05:31:13.749Z,1569907873.749 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6A4E0
2019-10-01T05:31:13.749Z,1569907873.749 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2650
2019-10-01T05:31:13.763Z,1569907873.763 [PAR_Licor] Loaded
2019-10-01T05:31:13.764Z,1569907873.764 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-01T05:31:13.811Z,1569907873.811 [WetLabsBB2FL] Loaded
2019-10-01T05:31:13.812Z,1569907873.812 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-01T05:31:13.812Z,1569907873.812 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0
2019-10-01T05:31:13.813Z,1569907873.813 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2651
2019-10-01T05:31:13.827Z,1569907873.827 [WetLabsUBAT..SerialDriver](INFO): Created logger
2019-10-01T05:31:13.828Z,1569907873.828 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2019-10-01T05:31:13.828Z,1569907873.828 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2019-10-01T05:31:13.834Z,1569907873.834 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest
2019-10-01T05:31:13.834Z,1569907873.834 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2019-10-01T05:31:13.834Z,1569907873.834 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2019-10-01T05:31:13.838Z,1569907873.838 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2019-10-01T05:31:13.838Z,1569907873.838 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2019-10-01T05:31:13.842Z,1569907873.842 [WetLabsUBAT] Loaded
2019-10-01T05:31:13.843Z,1569907873.843 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2019-10-01T05:31:13.844Z,1569907873.844 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0
2019-10-01T05:31:13.844Z,1569907873.844 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 2652
2019-10-01T05:31:13.844Z,1569907873.844 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-01T05:31:13.856Z,1569907873.856 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-01T05:31:13.857Z,1569907873.857 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-01T05:31:13.887Z,1569907873.887 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-01T05:31:13.888Z,1569907873.888 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0
2019-10-01T05:31:13.889Z,1569907873.889 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2653
2019-10-01T05:31:13.894Z,1569907873.894 [Supervisor](INFO): Main Thread ID is 1021
2019-10-01T05:31:13.894Z,1569907873.894 [Supervisor](DEBUG): Running supervisor.
2019-10-01T05:31:13.894Z,1569907873.894 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2654
2019-10-01T05:31:13.897Z,1569907873.897 [controlThread ThreadHandler](INFO): Handler Thread ID is 2655
2019-10-01T05:31:13.898Z,1569907873.898 [controlThread](DEBUG): Initializing ControlThread
2019-10-01T05:31:13.899Z,1569907873.899 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-01T05:31:13.900Z,1569907873.900 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-01T05:31:13.901Z,1569907873.901 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-01T05:31:13.902Z,1569907873.902 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-01T05:31:13.902Z,1569907873.902 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-01T05:31:13.902Z,1569907873.902 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-01T05:31:13.903Z,1569907873.903 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-01T05:31:13.903Z,1569907873.903 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-01T05:31:13.904Z,1569907873.904 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-01T05:31:13.904Z,1569907873.904 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-01T05:31:13.908Z,1569907873.908 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-01T05:31:13.908Z,1569907873.908 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-01T05:31:13.909Z,1569907873.909 [SBIT](INFO): Initialize SBIT Component.
2019-10-01T05:31:13.909Z,1569907873.909 [SBIT](IMPORTANT): git: 2019-09-23_TRN-1-gc869e16
2019-10-01T05:31:13.909Z,1569907873.909 [SBIT](INFO): git hash: c869e1686da227878f18dc8214a531995b772cf5
2019-10-01T05:31:13.910Z,1569907873.910 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-01T05:31:13.910Z,1569907873.910 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-10-01T05:31:13.911Z,1569907873.911 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-10-01T05:31:13.911Z,1569907873.911 [IBIT](INFO): Initialize IBIT Component.
2019-10-01T05:31:13.912Z,1569907873.912 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-01T05:31:13.913Z,1569907873.913 [logger ThreadHandler](INFO): Handler Thread ID is 2656
2019-10-01T05:31:13.925Z,1569907873.925 [CBIT](DEBUG): Initialized mux pins.
2019-10-01T05:31:13.925Z,1569907873.925 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-01T05:31:13.929Z,1569907873.929 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2657
2019-10-01T05:31:13.931Z,1569907873.931 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-01T05:31:13.941Z,1569907873.941 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2658
2019-10-01T05:31:13.945Z,1569907873.945 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2659
2019-10-01T05:31:13.946Z,1569907873.946 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T05:31:13.948Z,1569907873.948 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2661
2019-10-01T05:31:13.949Z,1569907873.949 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-01T05:31:13.949Z,1569907873.949 [CBIT](DEBUG): Initializing heartbeat.
2019-10-01T05:31:13.950Z,1569907873.950 [WetLabsBB2FL](INFO): Powering down
2019-10-01T05:31:13.970Z,1569907873.970 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T05:31:13.977Z,1569907873.977 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 2662
2019-10-01T05:31:13.995Z,1569907873.995 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2663
2019-10-01T05:31:14.021Z,1569907874.021 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-01T05:31:14.021Z,1569907874.021 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-01T05:31:14.057Z,1569907874.057 [CBIT](DEBUG): Backplane powered.
2019-10-01T05:31:14.062Z,1569907874.062 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-01T05:31:14.089Z,1569907874.089 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-01T05:31:14.116Z,1569907874.116 [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>


2019-10-01T05:31:14.116Z,1569907874.116 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-01T05:31:14.208Z,1569907874.208 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-01T05:31:14.208Z,1569907874.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-01T05:31:14.208Z,1569907874.208 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-01T05:31:14.208Z,1569907874.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-01T05:31:14.208Z,1569907874.208 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-01T05:31:14.208Z,1569907874.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-01T05:31:14.209Z,1569907874.209 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-01T05:31:14.209Z,1569907874.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-01T05:31:14.209Z,1569907874.209 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-01T05:31:14.209Z,1569907874.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-01T05:31:14.210Z,1569907874.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-01T05:31:14.210Z,1569907874.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-01T05:31:14.210Z,1569907874.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-01T05:31:14.210Z,1569907874.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-01T05:31:14.210Z,1569907874.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-01T05:31:14.210Z,1569907874.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-01T05:31:14.250Z,1569907874.250 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-01T05:31:14.251Z,1569907874.251 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-01T05:31:14.253Z,1569907874.253 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-01T05:31:14.283Z,1569907874.283 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-01T05:31:14.318Z,1569907874.318 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-01T05:31:14.323Z,1569907874.323 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-01T05:31:14.345Z,1569907874.345 [Radio_Surface](INFO): Powering up
2019-10-01T05:31:14.348Z,1569907874.348 [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>


2019-10-01T05:31:14.357Z,1569907874.357 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-01T05:31:14.398Z,1569907874.398 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2019-10-01T05:31:14.424Z,1569907874.424 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-01T05:31:14.424Z,1569907874.424 [DAT](INFO): Powering up
2019-10-01T05:31:14.424Z,1569907874.424 [DAT](DEBUG): Initializing DAT.
2019-10-01T05:31:14.497Z,1569907874.497 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-01T05:31:14.594Z,1569907874.594 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-01T05:31:14.627Z,1569907874.627 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-01T05:31:14.633Z,1569907874.633 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-01T05:31:14.634Z,1569907874.634 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-01T05:31:14.641Z,1569907874.641 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-01T05:31:14.643Z,1569907874.643 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-01T05:31:14.649Z,1569907874.649 [MassServo](DEBUG): Initializing MassServo.
2019-10-01T05:31:14.650Z,1569907874.650 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-01T05:31:14.657Z,1569907874.657 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-01T05:31:14.658Z,1569907874.658 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-01T05:31:14.665Z,1569907874.665 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-01T05:31:14.787Z,1569907874.787 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-10-01T05:31:14.787Z,1569907874.787 [DropWeight] Hardware Fault, FailCount= 1
2019-10-01T05:31:14.787Z,1569907874.787 [DropWeight](ERROR): Hardware Fault
2019-10-01T05:31:14.817Z,1569907874.817 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-01T05:31:14.832Z,1569907874.832 [CommandLine](FAULT): Scheduling is paused
2019-10-01T05:31:14.832Z,1569907874.832 [CBIT](INFO): Critical error at 20191001T053114
2019-10-01T05:31:14.833Z,1569907874.833 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-01T05:31:14.835Z,1569907874.835 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-01T05:31:14.836Z,1569907874.836 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-01T05:31:15.529Z,1569907875.529 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-01T05:31:15.529Z,1569907875.529 [RudderServo](FAULT): Rudder failed to initialize
2019-10-01T05:31:15.529Z,1569907875.529 [RudderServo] Communications Fault, FailCount= 1
2019-10-01T05:31:15.529Z,1569907875.529 [RudderServo](ERROR): Communications Fault
2019-10-01T05:31:15.638Z,1569907875.638 [CBIT](INFO): Critical error at 20191001T053114
2019-10-01T05:31:15.641Z,1569907875.641 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-01T05:31:15.874Z,1569907875.874 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-01T05:31:15.874Z,1569907875.874 [RudderServo](INFO): Powering down
2019-10-01T05:31:16.511Z,1569907876.511 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-01T05:31:16.630Z,1569907876.630 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-01T05:31:16.634Z,1569907876.634 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-01T05:31:16.634Z,1569907876.634 [RudderServo] No Fault, FailCount= 1
2019-10-01T05:31:21.981Z,1569907881.981 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-10-01T05:31:24.242Z,1569907884.242 [CBIT](CRITICAL): Environmental Failure. Press:14.465972 PSI. Humidity:39%. Temp:23 C. ABORTING MISSION
2019-10-01T05:31:24.637Z,1569907884.637 [CBIT](INFO): Critical error at 20191001T053124
2019-10-01T05:31:27.794Z,1569907887.794 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-10-01T05:31:28.593Z,1569907888.593 [DAT](INFO): setting local address to 4
2019-10-01T05:31:29.038Z,1569907889.038 [DAT](INFO): set local address to 4
2019-10-01T05:31:41.502Z,1569907901.502 [NAL9602](INFO): Powering up NAL9602
2019-10-01T05:31:52.410Z,1569907912.410 [NAL9602](INFO): NAL9602 initialized
2019-10-01T05:31:53.229Z,1569907913.229 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:32:13.459Z,1569907933.459 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-01T05:32:13.463Z,1569907933.463 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-01T05:32:24.536Z,1569907944.536 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000143
CHAN A1 (24V): -0.000041
CHAN A2 (12V): -0.007142
CHAN A3 (5V): -0.002233
CHAN B0 (3.3V): -0.000454
CHAN B1 (3.15aV): -0.000169
CHAN B2 (3.15bV): -0.000382
CHAN B3 (GND): 0.002092
OPEN: 0.005402
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-01T05:32:45.692Z,1569907965.692 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T05:32:54.592Z,1569907974.592 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-10-01T05:33:06.779Z,1569907986.779 [SBIT](IMPORTANT): SBIT PASSED
2019-10-01T05:33:06.830Z,1569907986.830 [CommandLine](IMPORTANT): got command configSet list
2019-10-01T05:33:06.830Z,1569907986.830 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-01T05:33:06.831Z,1569907986.831 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-10-01T05:33:06.831Z,1569907986.831 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere;

2019-10-01T05:33:06.831Z,1569907986.831 [CommandLine](IMPORTANT): Express none RDI_Pathfinder.height_above_sea_floor;

2019-10-01T05:33:06.831Z,1569907986.831 [CommandLine](IMPORTANT): Express linearApproximation Tracking.range_to_contact 10.000000 meter;

2019-10-01T05:33:06.831Z,1569907986.831 [CommandLine](IMPORTANT): Express none WetLabsUBAT.biolum_potential;

2019-10-01T05:33:06.832Z,1569907986.832 [CommandLine](IMPORTANT): Express none WetLabsUBAT.flow_rate;

2019-10-01T05:33:06.832Z,1569907986.832 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;

2019-10-01T05:33:06.832Z,1569907986.832 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsUBAT.biolum_potential 1000000.000000 none;

2019-10-01T05:33:06.832Z,1569907986.832 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-10-01T05:33:06.832Z,1569907986.832 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=80 cubic_centimeter;

2019-10-01T05:33:06.832Z,1569907986.832 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;

2019-10-01T05:33:07.184Z,1569907987.184 [MissionManager](IMPORTANT): Started mission Startup
2019-10-01T05:33:07.185Z,1569907987.185 [Startup] Running Loop=1
2019-10-01T05:33:07.185Z,1569907987.185 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-01T05:33:07.185Z,1569907987.185 [Startup:A.GoToSurface] Running Loop=1
2019-10-01T05:33:07.185Z,1569907987.185 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-01T05:33:07.186Z,1569907987.186 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-01T05:33:07.187Z,1569907987.187 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-01T05:33:07.187Z,1569907987.187 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-01T05:33:07.187Z,1569907987.187 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-01T05:33:07.188Z,1569907987.188 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-01T05:33:07.189Z,1569907987.189 [Startup:StartupSatComms] Running Loop=1
2019-10-01T05:33:07.189Z,1569907987.189 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-01T05:33:07.189Z,1569907987.189 [Startup:StartupSatComms:A] Running Loop=1
2019-10-01T05:33:07.640Z,1569907987.640 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-01T05:33:14.069Z,1569907994.069 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:33:14.069Z,1569907994.069 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-01T05:33:14.069Z,1569907994.069 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:33:14.080Z,1569907994.080 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:33:14.469Z,1569907994.469 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:33:14.469Z,1569907994.469 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-01T05:33:18.550Z,1569907998.550 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T05:33:31.900Z,1569908011.900 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T05:33:43.015Z,1569908023.015 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005036
2019-10-01T05:34:07.413Z,1569908047.413 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-01T05:33:07.2Z
2019-10-01T05:34:07.413Z,1569908047.413 [Startup:StartupSatComms:A] Stopped
2019-10-01T05:34:07.413Z,1569908047.413 [Startup:StartupSatComms:B] Running Loop=1
2019-10-01T05:34:07.787Z,1569908047.787 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-01T05:34:13.738Z,1569908053.738 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20190930T222209/Courier0124.lzma
2019-10-01T05:34:15.735Z,1569908055.735 [DataOverHttps](INFO): Moved sent file to Logs/20190930T222209/Courier0124.lzma.bak
2019-10-01T05:34:15.735Z,1569908055.735 [DataOverHttps](INFO): SBD MOMSN=11834844
2019-10-01T05:34:26.436Z,1569908066.436 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T05:34:32.745Z,1569908072.745 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20191001T052957/Courier0000.lzma
2019-10-01T05:34:34.750Z,1569908074.750 [DataOverHttps](INFO): Moved sent file to Logs/20191001T052957/Courier0000.lzma.bak
2019-10-01T05:34:34.750Z,1569908074.750 [DataOverHttps](INFO): SBD MOMSN=11834847
2019-10-01T05:34:50.946Z,1569908090.946 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20191001T053104/Courier0000.lzma
2019-10-01T05:34:52.914Z,1569908092.914 [DataOverHttps](INFO): Moved sent file to Logs/20191001T053104/Courier0000.lzma.bak
2019-10-01T05:34:52.915Z,1569908092.915 [DataOverHttps](INFO): SBD MOMSN=11834853
2019-10-01T05:34:57.896Z,1569908097.896 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-10-01T05:35:07.738Z,1569908107.738 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-01T05:34:07.4Z
2019-10-01T05:35:07.738Z,1569908107.738 [Startup:StartupSatComms:B] Stopped
2019-10-01T05:35:07.738Z,1569908107.738 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-01T05:35:07.738Z,1569908107.738 [Startup:StartupSatComms] Stopped
2019-10-01T05:35:07.738Z,1569908107.738 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-01T05:35:07.739Z,1569908107.739 [Startup](INFO): Completed Startup
2019-10-01T05:35:07.739Z,1569908107.739 [MissionManager](INFO): Startup is completed.
2019-10-01T05:35:07.739Z,1569908107.739 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-01T05:35:07.739Z,1569908107.739 [Startup] Stopped
2019-10-01T05:35:07.739Z,1569908107.739 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-01T05:35:07.740Z,1569908107.740 [Startup:A.GoToSurface] Stopped
2019-10-01T05:35:07.740Z,1569908107.740 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-01T05:35:08.003Z,1569908108.003 [MissionManager](IMPORTANT): Started mission Default
2019-10-01T05:35:08.003Z,1569908108.003 [Default] Running Loop=1
2019-10-01T05:35:08.003Z,1569908108.003 [Default](DEBUG): Aggregate::initialize Default
2019-10-01T05:35:08.004Z,1569908108.004 [Default:B.GoToSurface] Running Loop=1
2019-10-01T05:35:08.004Z,1569908108.004 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-01T05:35:08.004Z,1569908108.004 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-01T05:35:08.004Z,1569908108.004 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-01T05:35:08.004Z,1569908108.004 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-01T05:35:08.025Z,1569908108.025 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-01T05:35:08.025Z,1569908108.025 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-01T05:35:08.026Z,1569908108.026 [Default:A.Wait] Running Loop=1
2019-10-01T05:35:08.026Z,1569908108.026 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-01T05:35:10.093Z,1569908110.093 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20190930T222209/Express0125.lzma
2019-10-01T05:35:12.099Z,1569908112.099 [DataOverHttps](INFO): Moved sent file to Logs/20190930T222209/Express0125.lzma.bak
2019-10-01T05:35:12.099Z,1569908112.099 [DataOverHttps](INFO): SBD MOMSN=11834859
2019-10-01T05:35:14.911Z,1569908114.911 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:35:14.911Z,1569908114.911 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-01T05:35:14.911Z,1569908114.911 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:35:14.941Z,1569908114.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:35:15.297Z,1569908115.297 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:35:15.298Z,1569908115.298 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-01T05:35:21.368Z,1569908121.368 [Default:A.Wait](INFO): Done Waiting.
2019-10-01T05:35:21.368Z,1569908121.368 [Default:A.Wait] Stopped
2019-10-01T05:35:21.368Z,1569908121.368 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-01T05:35:21.762Z,1569908121.762 [Default:CheckIn] Running Loop=1
2019-10-01T05:35:21.762Z,1569908121.762 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-01T05:35:21.762Z,1569908121.762 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-01T05:35:22.127Z,1569908122.127 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-01T05:35:35.125Z,1569908135.125 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T05:36:01.370Z,1569908161.370 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T05:36:04.584Z,1569908164.584 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-10-01T05:36:41.820Z,1569908201.820 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T05:36:55.814Z,1569908215.814 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-01T05:36:56.633Z,1569908216.633 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:01.085Z,1569908221.085 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:03.917Z,1569908223.917 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:05.929Z,1569908225.929 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:07.945Z,1569908227.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:09.965Z,1569908229.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:13.197Z,1569908233.197 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:16.029Z,1569908236.029 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:16.085Z,1569908236.085 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:37:16.085Z,1569908236.085 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-10-01T05:37:16.085Z,1569908236.085 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:37:16.112Z,1569908236.112 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:37:16.480Z,1569908236.480 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:37:16.480Z,1569908236.480 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-10-01T05:37:19.257Z,1569908239.257 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:22.093Z,1569908242.093 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:24.921Z,1569908244.921 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:26.937Z,1569908246.937 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:28.961Z,1569908248.961 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:32.193Z,1569908252.193 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:35.013Z,1569908255.013 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:38.249Z,1569908258.249 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:41.073Z,1569908261.073 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:44.305Z,1569908264.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:47.137Z,1569908267.137 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:49.961Z,1569908269.961 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:53.193Z,1569908273.193 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:56.033Z,1569908276.033 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:37:59.253Z,1569908279.253 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:02.085Z,1569908282.085 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:04.913Z,1569908284.913 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:06.929Z,1569908286.929 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:08.949Z,1569908288.949 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:10.977Z,1569908290.977 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:14.213Z,1569908294.213 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:15.532Z,1569908295.532 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T05:38:17.042Z,1569908297.042 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:20.261Z,1569908300.261 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:23.089Z,1569908303.089 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:25.921Z,1569908305.921 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:27.945Z,1569908307.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:29.957Z,1569908309.957 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:33.189Z,1569908313.189 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:36.019Z,1569908316.019 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:39.249Z,1569908319.249 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:42.081Z,1569908322.081 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:42.954Z,1569908322.954 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-10-01T05:38:42.962Z,1569908322.962 [BPC1](INFO): Received data from all battery sticks.
2019-10-01T05:38:44.913Z,1569908324.913 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:46.929Z,1569908326.929 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:48.945Z,1569908328.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:52.181Z,1569908332.181 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:55.005Z,1569908335.005 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:38:58.241Z,1569908338.241 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:01.069Z,1569908341.069 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:04.297Z,1569908344.297 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:07.125Z,1569908347.125 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:09.961Z,1569908349.961 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:13.185Z,1569908353.185 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:16.013Z,1569908356.013 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:17.249Z,1569908357.249 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:39:17.250Z,1569908357.250 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-10-01T05:39:17.250Z,1569908357.250 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:39:17.260Z,1569908357.260 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:39:17.670Z,1569908357.670 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:39:17.671Z,1569908357.671 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-10-01T05:39:19.245Z,1569908359.245 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:22.077Z,1569908362.077 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:25.305Z,1569908365.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:28.137Z,1569908368.137 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:30.969Z,1569908370.969 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:34.197Z,1569908374.197 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:37.021Z,1569908377.021 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:39.856Z,1569908379.856 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-10-01T05:39:40.253Z,1569908380.253 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:43.085Z,1569908383.085 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:45.913Z,1569908385.913 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:47.929Z,1569908387.929 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:49.949Z,1569908389.949 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:51.969Z,1569908391.969 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:55.205Z,1569908395.205 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:39:58.033Z,1569908398.033 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:01.265Z,1569908401.265 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:04.089Z,1569908404.089 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:06.921Z,1569908406.921 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:08.941Z,1569908408.941 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:10.957Z,1569908410.957 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:14.189Z,1569908414.189 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:17.021Z,1569908417.021 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:17.446Z,1569908417.446 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-10-01T05:40:17.446Z,1569908417.446 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19100105410, -0.1,   0.0,1448.9,  0
2019-10-01T05:40:20.249Z,1569908420.249 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:21.890Z,1569908421.890 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-01T05:35:21.8Z
2019-10-01T05:40:21.891Z,1569908421.891 [Default:CheckIn:Read_GPS] Stopped
2019-10-01T05:40:21.891Z,1569908421.891 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-01T05:40:22.294Z,1569908422.294 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-01T05:40:23.077Z,1569908423.077 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:25.905Z,1569908425.905 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:27.929Z,1569908427.929 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:29.137Z,1569908429.137 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20191001T053104/Courier0004.lzma
2019-10-01T05:40:29.949Z,1569908429.949 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:31.142Z,1569908431.142 [DataOverHttps](INFO): Moved sent file to Logs/20191001T053104/Courier0004.lzma.bak
2019-10-01T05:40:31.142Z,1569908431.142 [DataOverHttps](INFO): SBD MOMSN=11834877
2019-10-01T05:40:31.973Z,1569908431.973 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:35.205Z,1569908435.205 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:38.029Z,1569908438.029 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:41.261Z,1569908441.261 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:44.093Z,1569908444.093 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:46.921Z,1569908446.921 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:48.941Z,1569908448.941 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:50.598Z,1569908450.598 [DataOverHttps](INFO): Sending 575 bytes from file Logs/20191001T052957/Express0001.lzma
2019-10-01T05:40:50.965Z,1569908450.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:52.614Z,1569908452.614 [DataOverHttps](INFO): Moved sent file to Logs/20191001T052957/Express0001.lzma.bak
2019-10-01T05:40:52.615Z,1569908452.615 [DataOverHttps](INFO): SBD MOMSN=11834879
2019-10-01T05:40:54.193Z,1569908454.193 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:40:57.021Z,1569908457.021 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:00.249Z,1569908460.249 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:03.081Z,1569908463.081 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:05.909Z,1569908465.909 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:07.929Z,1569908467.929 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:09.540Z,1569908469.540 [DataOverHttps](INFO): Sending 1089 bytes from file Logs/20191001T053104/Express0001.lzma
2019-10-01T05:41:09.953Z,1569908469.953 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:11.542Z,1569908471.542 [DataOverHttps](INFO): Moved sent file to Logs/20191001T053104/Express0001.lzma.bak
2019-10-01T05:41:11.543Z,1569908471.543 [DataOverHttps](INFO): SBD MOMSN=11834894
2019-10-01T05:41:11.965Z,1569908471.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:15.201Z,1569908475.201 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:18.029Z,1569908478.029 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:18.059Z,1569908478.059 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:41:18.059Z,1569908478.059 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-10-01T05:41:18.059Z,1569908478.059 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:41:18.093Z,1569908478.093 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:41:18.467Z,1569908478.467 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:41:18.467Z,1569908478.467 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-10-01T05:41:21.261Z,1569908481.261 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:24.085Z,1569908484.085 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:26.917Z,1569908486.917 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:28.433Z,1569908488.433 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20191001T053104/Express0005.lzma
2019-10-01T05:41:28.937Z,1569908488.937 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:30.438Z,1569908490.438 [DataOverHttps](INFO): Moved sent file to Logs/20191001T053104/Express0005.lzma.bak
2019-10-01T05:41:30.439Z,1569908490.439 [DataOverHttps](INFO): SBD MOMSN=11834922
2019-10-01T05:41:30.953Z,1569908490.953 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:32.186Z,1569908492.186 [Default:CheckIn:Read_Iridium] Stopped
2019-10-01T05:41:32.186Z,1569908492.186 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-01T05:41:32.186Z,1569908492.186 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-01T05:41:34.197Z,1569908494.197 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:37.017Z,1569908497.017 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:40.245Z,1569908500.245 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:43.073Z,1569908503.073 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:46.305Z,1569908506.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:49.133Z,1569908509.133 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:51.961Z,1569908511.961 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:41:55.182Z,1569908515.182 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-01T05:41:55.182Z,1569908515.182 [NAL9602] Data Fault, FailCount= 1
2019-10-01T05:41:55.182Z,1569908515.182 [NAL9602](ERROR): Data Fault
2019-10-01T05:41:55.245Z,1569908515.245 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-01T05:41:55.586Z,1569908515.586 [NAL9602](INFO): Powering down
2019-10-01T05:41:56.436Z,1569908516.436 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-01T05:41:56.436Z,1569908516.436 [NAL9602] No Fault, FailCount= 1
2019-10-01T05:42:25.887Z,1569908545.887 [NAL9602](INFO): Powering up NAL9602
2019-10-01T05:42:36.794Z,1569908556.794 [NAL9602](INFO): NAL9602 initialized
2019-10-01T05:42:37.613Z,1569908557.613 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:42:40.441Z,1569908560.441 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:42:43.673Z,1569908563.673 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:42:46.501Z,1569908566.501 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:42:49.329Z,1569908569.329 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:42:51.353Z,1569908571.353 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:42:54.581Z,1569908574.581 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:42:57.409Z,1569908577.409 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:00.645Z,1569908580.645 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:03.469Z,1569908583.469 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:06.709Z,1569908586.709 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:09.533Z,1569908589.533 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:12.357Z,1569908592.357 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:15.593Z,1569908595.593 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:18.417Z,1569908598.417 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:19.257Z,1569908599.257 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:43:19.257Z,1569908599.257 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-10-01T05:43:19.257Z,1569908599.257 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:43:19.268Z,1569908599.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:43:19.662Z,1569908599.662 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:43:19.662Z,1569908599.662 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-10-01T05:43:21.649Z,1569908601.649 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:24.477Z,1569908604.477 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:27.309Z,1569908607.309 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:29.325Z,1569908609.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:31.345Z,1569908611.345 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:34.577Z,1569908614.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:37.405Z,1569908617.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:40.641Z,1569908620.641 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:43.465Z,1569908623.465 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:46.705Z,1569908626.705 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:49.529Z,1569908629.529 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:52.353Z,1569908632.353 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:55.585Z,1569908635.585 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:43:58.416Z,1569908638.416 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:01.649Z,1569908641.649 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:04.473Z,1569908644.473 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:07.305Z,1569908647.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:09.321Z,1569908649.321 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:11.341Z,1569908651.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:14.577Z,1569908654.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:17.401Z,1569908657.401 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:20.637Z,1569908660.637 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:23.469Z,1569908663.469 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:26.701Z,1569908666.701 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:29.525Z,1569908669.525 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:32.349Z,1569908672.349 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:35.581Z,1569908675.581 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:38.413Z,1569908678.413 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:41.645Z,1569908681.645 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:44.469Z,1569908684.469 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:47.305Z,1569908687.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:49.317Z,1569908689.317 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:51.341Z,1569908691.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:53.357Z,1569908693.357 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:56.589Z,1569908696.589 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:44:59.419Z,1569908699.419 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:02.653Z,1569908702.653 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:05.477Z,1569908705.477 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:08.305Z,1569908708.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:10.325Z,1569908710.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:12.349Z,1569908712.349 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:15.577Z,1569908715.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:18.409Z,1569908718.409 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:20.445Z,1569908720.445 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:45:20.445Z,1569908720.445 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-10-01T05:45:20.445Z,1569908720.445 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:45:20.476Z,1569908720.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:45:20.859Z,1569908720.859 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:45:20.859Z,1569908720.859 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-10-01T05:45:21.641Z,1569908721.641 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:24.465Z,1569908724.465 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:27.697Z,1569908727.697 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:30.529Z,1569908730.529 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:33.353Z,1569908733.353 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:36.585Z,1569908736.585 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:39.417Z,1569908739.417 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:42.645Z,1569908742.645 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:45.473Z,1569908745.473 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:48.301Z,1569908748.301 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:50.325Z,1569908750.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:52.345Z,1569908752.345 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:55.573Z,1569908755.573 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:45:58.405Z,1569908758.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:01.637Z,1569908761.637 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:04.461Z,1569908764.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:07.693Z,1569908767.693 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:10.525Z,1569908770.525 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:13.357Z,1569908773.357 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:14.999Z,1569908774.999 [CBIT](INFO): Clearing failed state for component DropWeight
2019-10-01T05:46:14.999Z,1569908774.999 [DropWeight] No Fault, FailCount= 1
2019-10-01T05:46:16.585Z,1569908776.585 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:19.413Z,1569908779.413 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:22.645Z,1569908782.645 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:25.475Z,1569908785.475 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:28.717Z,1569908788.717 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:31.533Z,1569908791.533 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:32.778Z,1569908792.778 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-01T05:46:32.778Z,1569908792.778 [Default:CheckIn:C.Wait] Stopped
2019-10-01T05:46:32.778Z,1569908792.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-01T05:46:32.778Z,1569908792.778 [Default:CheckIn:D] Running Loop=1
2019-10-01T05:46:33.162Z,1569908793.162 [Default:CheckIn:D] Stopped
2019-10-01T05:46:33.162Z,1569908793.162 [Default:CheckIn:E] Running Loop=1
2019-10-01T05:46:33.566Z,1569908793.566 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.419317 min 
2019-10-01T05:46:33.567Z,1569908793.567 [Default:CheckIn:E] Stopped
2019-10-01T05:46:33.567Z,1569908793.567 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-01T05:46:33.567Z,1569908793.567 [Default:CheckIn] Stopped
2019-10-01T05:46:33.567Z,1569908793.567 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-01T05:46:33.567Z,1569908793.567 [Default:CheckIn](INFO): Running loop #2
2019-10-01T05:46:33.567Z,1569908793.567 [Default:CheckIn] Running Loop=2
2019-10-01T05:46:33.567Z,1569908793.567 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-01T05:46:33.567Z,1569908793.567 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-01T05:46:34.357Z,1569908794.357 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:37.589Z,1569908797.589 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:40.421Z,1569908800.421 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:43.657Z,1569908803.657 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:46.477Z,1569908806.477 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:49.309Z,1569908809.309 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:51.325Z,1569908811.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:53.349Z,1569908813.349 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:56.581Z,1569908816.581 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:46:59.411Z,1569908819.411 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:02.641Z,1569908822.641 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:05.473Z,1569908825.473 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:08.709Z,1569908828.709 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:11.525Z,1569908831.525 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:14.353Z,1569908834.353 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:17.585Z,1569908837.585 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:20.417Z,1569908840.417 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:21.253Z,1569908841.253 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:47:21.253Z,1569908841.253 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-10-01T05:47:21.253Z,1569908841.253 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:47:21.264Z,1569908841.264 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:47:21.666Z,1569908841.666 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:47:21.666Z,1569908841.666 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-10-01T05:47:23.649Z,1569908843.649 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:26.473Z,1569908846.473 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:29.301Z,1569908849.301 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:31.325Z,1569908851.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:33.349Z,1569908853.349 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:36.573Z,1569908856.573 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:39.401Z,1569908859.401 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:42.637Z,1569908862.637 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:45.461Z,1569908865.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:48.709Z,1569908868.709 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:51.525Z,1569908871.525 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:54.355Z,1569908874.355 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:47:57.585Z,1569908877.585 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:00.409Z,1569908880.409 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:03.645Z,1569908883.645 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:06.469Z,1569908886.469 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:09.305Z,1569908889.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:11.317Z,1569908891.317 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:13.337Z,1569908893.337 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:16.569Z,1569908896.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:19.397Z,1569908899.397 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:22.629Z,1569908902.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:25.461Z,1569908905.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:28.705Z,1569908908.705 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:31.521Z,1569908911.521 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:34.345Z,1569908914.345 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:37.577Z,1569908917.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:40.409Z,1569908920.409 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:43.641Z,1569908923.641 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:46.469Z,1569908926.469 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:49.293Z,1569908929.293 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:51.313Z,1569908931.313 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:53.337Z,1569908933.337 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:55.353Z,1569908935.353 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:48:58.589Z,1569908938.589 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:01.413Z,1569908941.413 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:04.649Z,1569908944.649 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:07.477Z,1569908947.477 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:10.301Z,1569908950.301 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:12.321Z,1569908952.321 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:14.349Z,1569908954.349 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:17.577Z,1569908957.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:20.405Z,1569908960.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:22.055Z,1569908962.055 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:49:22.055Z,1569908962.055 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-10-01T05:49:22.055Z,1569908962.055 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:49:22.065Z,1569908962.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:49:22.446Z,1569908962.446 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:49:22.446Z,1569908962.446 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-10-01T05:49:23.637Z,1569908963.637 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:26.461Z,1569908966.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:29.705Z,1569908969.705 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:32.529Z,1569908972.529 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:35.353Z,1569908975.353 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:38.585Z,1569908978.585 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:41.409Z,1569908981.409 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:44.645Z,1569908984.645 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:47.477Z,1569908987.477 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:50.301Z,1569908990.301 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:52.317Z,1569908992.317 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:54.337Z,1569908994.337 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:49:57.575Z,1569908997.575 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:00.409Z,1569909000.409 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:03.637Z,1569909003.637 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:06.457Z,1569909006.457 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:09.701Z,1569909009.701 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:12.521Z,1569909012.521 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:15.345Z,1569909015.345 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:18.585Z,1569909018.585 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:21.413Z,1569909021.413 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:24.641Z,1569909024.641 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:27.469Z,1569909027.469 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:30.301Z,1569909030.301 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:32.321Z,1569909032.321 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:34.333Z,1569909034.333 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:37.569Z,1569909037.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:40.393Z,1569909040.393 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:43.629Z,1569909043.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:46.453Z,1569909046.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:49.706Z,1569909049.706 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:52.513Z,1569909052.513 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:55.341Z,1569909055.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:50:58.573Z,1569909058.573 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:01.405Z,1569909061.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:04.641Z,1569909064.641 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:07.465Z,1569909067.465 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:10.309Z,1569909070.309 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:12.309Z,1569909072.309 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:14.329Z,1569909074.329 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:17.561Z,1569909077.561 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:20.389Z,1569909080.389 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:23.233Z,1569909083.233 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:51:23.233Z,1569909083.233 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-10-01T05:51:23.233Z,1569909083.233 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:51:23.243Z,1569909083.243 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:51:23.629Z,1569909083.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:23.684Z,1569909083.684 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:51:23.684Z,1569909083.684 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-10-01T05:51:26.449Z,1569909086.449 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:29.693Z,1569909089.693 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:32.509Z,1569909092.509 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:33.756Z,1569909093.756 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-01T05:46:33.6Z
2019-10-01T05:51:33.756Z,1569909093.756 [Default:CheckIn:Read_GPS] Stopped
2019-10-01T05:51:33.756Z,1569909093.756 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-01T05:51:35.341Z,1569909095.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:38.569Z,1569909098.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:40.940Z,1569909100.940 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191001T053104/Courier0007.lzma
2019-10-01T05:51:41.397Z,1569909101.397 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:42.947Z,1569909102.947 [DataOverHttps](INFO): Moved sent file to Logs/20191001T053104/Courier0007.lzma.bak
2019-10-01T05:51:42.947Z,1569909102.947 [DataOverHttps](INFO): SBD MOMSN=11834945
2019-10-01T05:51:44.629Z,1569909104.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:47.461Z,1569909107.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:50.701Z,1569909110.701 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:53.525Z,1569909113.525 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:56.349Z,1569909116.349 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:59.585Z,1569909119.585 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:51:59.829Z,1569909119.829 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20191001T053104/Express0008.lzma
2019-10-01T05:52:01.834Z,1569909121.834 [DataOverHttps](INFO): Moved sent file to Logs/20191001T053104/Express0008.lzma.bak
2019-10-01T05:52:01.835Z,1569909121.835 [DataOverHttps](INFO): SBD MOMSN=11834947
2019-10-01T05:52:02.409Z,1569909122.409 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:03.632Z,1569909123.632 [Default:CheckIn:Read_Iridium] Stopped
2019-10-01T05:52:03.632Z,1569909123.632 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-01T05:52:03.646Z,1569909123.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-01T05:52:05.649Z,1569909125.649 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:08.469Z,1569909128.469 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:11.293Z,1569909131.293 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:13.313Z,1569909133.313 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:15.337Z,1569909135.337 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:18.569Z,1569909138.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:21.393Z,1569909141.393 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:24.633Z,1569909144.633 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:27.453Z,1569909147.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:30.709Z,1569909150.709 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:33.517Z,1569909153.517 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:36.345Z,1569909156.345 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:52:39.569Z,1569909159.569 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-01T05:52:39.569Z,1569909159.569 [NAL9602] Data Fault, FailCount= 2
2019-10-01T05:52:39.569Z,1569909159.569 [NAL9602](ERROR): Data Fault
2019-10-01T05:52:39.598Z,1569909159.598 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-01T05:52:39.966Z,1569909159.966 [NAL9602](INFO): Powering down
2019-10-01T05:52:40.878Z,1569909160.878 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-01T05:52:40.878Z,1569909160.878 [NAL9602] No Fault, FailCount= 2
2019-10-01T05:53:10.272Z,1569909190.272 [NAL9602](INFO): Powering up NAL9602
2019-10-01T05:53:21.174Z,1569909201.174 [NAL9602](INFO): NAL9602 initialized
2019-10-01T05:53:21.993Z,1569909201.993 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:24.438Z,1569909204.438 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:53:24.438Z,1569909204.438 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-10-01T05:53:24.438Z,1569909204.438 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:53:24.448Z,1569909204.448 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:53:24.821Z,1569909204.821 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:24.892Z,1569909204.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:53:24.892Z,1569909204.892 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-10-01T05:53:28.053Z,1569909208.053 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:30.889Z,1569909210.889 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:33.709Z,1569909213.709 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:35.733Z,1569909215.733 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:38.973Z,1569909218.973 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:41.793Z,1569909221.793 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:45.025Z,1569909225.025 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:47.849Z,1569909227.849 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:51.081Z,1569909231.081 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:53.949Z,1569909233.949 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:55.632Z,1569909235.632 [CommandLine](IMPORTANT): got command configSet BackSeatDriver.verbosity 1.000000 count
2019-10-01T05:53:56.765Z,1569909236.765 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:53:59.997Z,1569909239.997 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:02.825Z,1569909242.825 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:06.069Z,1569909246.069 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:08.885Z,1569909248.885 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:09.242Z,1569909249.242 [CommandLine](IMPORTANT): got command get BackSeatDriver.loadAtStartup
2019-10-01T05:54:09.243Z,1569909249.243 [CommandLine](IMPORTANT): BackSeatDriver.loadAtStartup 0 bool
2019-10-01T05:54:11.725Z,1569909251.725 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:13.741Z,1569909253.741 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:16.965Z,1569909256.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:19.793Z,1569909259.793 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:23.029Z,1569909263.029 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:25.857Z,1569909265.857 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:28.704Z,1569909268.704 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:30.717Z,1569909270.717 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:32.737Z,1569909272.737 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:34.749Z,1569909274.749 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:37.981Z,1569909277.981 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:40.801Z,1569909280.801 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:44.037Z,1569909284.037 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:46.865Z,1569909286.865 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:49.705Z,1569909289.705 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:51.721Z,1569909291.721 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:53.737Z,1569909293.737 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:56.961Z,1569909296.961 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:54:59.789Z,1569909299.789 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:03.033Z,1569909303.033 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:05.849Z,1569909305.849 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:08.677Z,1569909308.677 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:10.713Z,1569909310.713 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:12.733Z,1569909312.733 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:14.741Z,1569909314.741 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:17.973Z,1569909317.973 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:20.798Z,1569909320.798 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:24.037Z,1569909324.037 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:25.665Z,1569909325.665 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:55:25.665Z,1569909325.665 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-10-01T05:55:25.665Z,1569909325.665 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:55:25.676Z,1569909325.676 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:55:26.126Z,1569909326.126 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:55:26.126Z,1569909326.126 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-10-01T05:55:26.861Z,1569909326.861 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:29.705Z,1569909329.705 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:31.717Z,1569909331.717 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:33.729Z,1569909333.729 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:35.745Z,1569909335.745 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:38.981Z,1569909338.981 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:41.805Z,1569909341.805 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:45.043Z,1569909345.043 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:47.869Z,1569909347.869 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:50.709Z,1569909350.709 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:52.737Z,1569909352.737 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:54.733Z,1569909354.733 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:55:57.969Z,1569909357.969 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:00.797Z,1569909360.797 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:04.029Z,1569909364.029 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:06.857Z,1569909366.857 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:09.699Z,1569909369.699 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:11.717Z,1569909371.717 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:13.729Z,1569909373.729 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:16.957Z,1569909376.957 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:19.781Z,1569909379.781 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:23.017Z,1569909383.017 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:25.845Z,1569909385.845 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:29.081Z,1569909389.081 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:31.901Z,1569909391.901 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:34.729Z,1569909394.729 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:37.965Z,1569909397.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:40.793Z,1569909400.793 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:44.025Z,1569909404.025 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:46.853Z,1569909406.853 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:49.697Z,1569909409.697 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:51.713Z,1569909411.713 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:53.721Z,1569909413.721 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:56.949Z,1569909416.949 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:56:59.781Z,1569909419.781 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:03.013Z,1569909423.013 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:04.238Z,1569909424.238 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-01T05:57:04.238Z,1569909424.238 [Default:CheckIn:C.Wait] Stopped
2019-10-01T05:57:04.238Z,1569909424.238 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-01T05:57:04.239Z,1569909424.239 [Default:CheckIn:D] Running Loop=1
2019-10-01T05:57:04.644Z,1569909424.644 [Default:CheckIn:D] Stopped
2019-10-01T05:57:04.644Z,1569909424.644 [Default:CheckIn:E] Running Loop=1
2019-10-01T05:57:05.056Z,1569909425.056 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.944004 min 
2019-10-01T05:57:05.056Z,1569909425.056 [Default:CheckIn:E] Stopped
2019-10-01T05:57:05.056Z,1569909425.056 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-01T05:57:05.056Z,1569909425.056 [Default:CheckIn] Stopped
2019-10-01T05:57:05.056Z,1569909425.056 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-01T05:57:05.056Z,1569909425.056 [Default:CheckIn](INFO): Running loop #3
2019-10-01T05:57:05.057Z,1569909425.057 [Default:CheckIn] Running Loop=3
2019-10-01T05:57:05.057Z,1569909425.057 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-01T05:57:05.057Z,1569909425.057 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-01T05:57:05.837Z,1569909425.837 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:09.073Z,1569909429.073 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:11.901Z,1569909431.901 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:14.733Z,1569909434.733 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:17.965Z,1569909437.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:20.789Z,1569909440.789 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:24.021Z,1569909444.021 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:26.849Z,1569909446.849 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:26.880Z,1569909446.880 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:57:26.880Z,1569909446.880 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-10-01T05:57:26.880Z,1569909446.880 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:57:26.903Z,1569909446.903 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:57:27.312Z,1569909447.312 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:57:27.312Z,1569909447.312 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-10-01T05:57:30.077Z,1569909450.077 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:32.905Z,1569909452.905 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:35.741Z,1569909455.741 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:38.969Z,1569909458.969 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:41.797Z,1569909461.797 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:45.029Z,1569909465.029 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:47.861Z,1569909467.861 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:50.697Z,1569909470.697 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:52.717Z,1569909472.717 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:54.721Z,1569909474.721 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:56.741Z,1569909476.741 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:57:59.973Z,1569909479.973 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:02.805Z,1569909482.805 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:06.053Z,1569909486.053 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:08.865Z,1569909488.865 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:11.709Z,1569909491.709 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:13.713Z,1569909493.713 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:15.733Z,1569909495.733 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:18.965Z,1569909498.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:21.793Z,1569909501.793 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:25.029Z,1569909505.029 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:27.853Z,1569909507.853 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:31.081Z,1569909511.081 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:33.913Z,1569909513.913 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:36.741Z,1569909516.741 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:39.973Z,1569909519.973 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:42.801Z,1569909522.801 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:46.033Z,1569909526.033 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:48.861Z,1569909528.861 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:51.705Z,1569909531.705 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:53.705Z,1569909533.705 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:55.729Z,1569909535.729 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:58:58.957Z,1569909538.957 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:01.793Z,1569909541.793 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:05.021Z,1569909545.021 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:07.849Z,1569909547.849 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:10.687Z,1569909550.687 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:12.701Z,1569909552.701 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:14.713Z,1569909554.713 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:16.733Z,1569909556.733 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:19.973Z,1569909559.973 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:22.797Z,1569909562.797 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:26.033Z,1569909566.033 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:28.068Z,1569909568.068 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T05:59:28.068Z,1569909568.068 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-10-01T05:59:28.068Z,1569909568.068 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T05:59:28.078Z,1569909568.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T05:59:28.473Z,1569909568.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T05:59:28.474Z,1569909568.474 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-10-01T05:59:28.853Z,1569909568.853 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:31.697Z,1569909571.697 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:33.705Z,1569909573.705 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:35.725Z,1569909575.725 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:38.957Z,1569909578.957 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:41.785Z,1569909581.785 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:45.021Z,1569909585.021 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:47.845Z,1569909587.845 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:51.073Z,1569909591.073 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:53.905Z,1569909593.905 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:56.733Z,1569909596.733 [NAL9602](DEBUG): Fix Requested
2019-10-01T05:59:59.965Z,1569909599.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:02.793Z,1569909602.793 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:06.025Z,1569909606.025 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:08.849Z,1569909608.849 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:11.689Z,1569909611.689 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:13.701Z,1569909613.701 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:15.717Z,1569909615.717 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:17.745Z,1569909617.745 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:18.395Z,1569909618.395 [CommandLine](IMPORTANT): got command get BackSeatDriver.loadAtStartup
2019-10-01T06:00:18.395Z,1569909618.395 [CommandLine](IMPORTANT): BackSeatDriver.loadAtStartup 0 bool
2019-10-01T06:00:20.977Z,1569909620.977 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:23.797Z,1569909623.797 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:27.051Z,1569909627.051 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:29.865Z,1569909629.865 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:32.697Z,1569909632.697 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:34.709Z,1569909634.709 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:36.725Z,1569909636.725 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:39.965Z,1569909639.965 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:40.611Z,1569909640.611 [CommandLine](IMPORTANT): got command restart application
2019-10-01T06:00:41.630Z,1569909641.630 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:41.630Z,1569909641.630 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:41.777Z,1569909641.777 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-01T06:00:41.777Z,1569909641.777 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:41.778Z,1569909641.778 [CommandLine](INFO): Join timeout helper Thread ID is 2732
2019-10-01T06:00:41.778Z,1569909641.778 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-01T06:00:41.778Z,1569909641.778 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:41.779Z,1569909641.779 [NavChartDb](INFO): Join timeout helper Thread ID is 2733
2019-10-01T06:00:42.085Z,1569909642.085 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:42.085Z,1569909642.085 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:42.097Z,1569909642.097 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2019-10-01T06:00:42.097Z,1569909642.097 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:42.097Z,1569909642.097 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 2734
2019-10-01T06:00:42.333Z,1569909642.333 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:42.333Z,1569909642.333 [WetLabsUBAT](INFO): Powering down
2019-10-01T06:00:42.361Z,1569909642.361 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:42.373Z,1569909642.373 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-01T06:00:42.373Z,1569909642.373 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:42.374Z,1569909642.374 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2735
2019-10-01T06:00:42.718Z,1569909642.718 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:42.719Z,1569909642.719 [WetLabsBB2FL](INFO): Powering down
2019-10-01T06:00:42.721Z,1569909642.721 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:42.733Z,1569909642.733 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-01T06:00:42.733Z,1569909642.733 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:42.733Z,1569909642.733 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2736
2019-10-01T06:00:42.797Z,1569909642.797 [NAL9602](DEBUG): Fix Requested
2019-10-01T06:00:43.229Z,1569909643.229 [CTD_Seabird](INFO): Powering down
2019-10-01T06:00:43.245Z,1569909643.245 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:43.245Z,1569909643.245 [CTD_Seabird](INFO): Powering down
2019-10-01T06:00:43.257Z,1569909643.257 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.266Z,1569909643.266 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-01T06:00:43.266Z,1569909643.266 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.266Z,1569909643.266 [Radio_Surface](INFO): Join timeout helper Thread ID is 2737
2019-10-01T06:00:43.505Z,1569909643.505 [Radio_Surface](INFO): Powering down
2019-10-01T06:00:43.506Z,1569909643.506 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:43.506Z,1569909643.506 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.510Z,1569909643.510 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-01T06:00:43.510Z,1569909643.510 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.510Z,1569909643.510 [DataOverHttps](INFO): Join timeout helper Thread ID is 2738
2019-10-01T06:00:43.637Z,1569909643.637 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:43.637Z,1569909643.637 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.658Z,1569909643.658 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-01T06:00:43.658Z,1569909643.658 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.658Z,1569909643.658 [logger](INFO): Join timeout helper Thread ID is 2739
2019-10-01T06:00:43.670Z,1569909643.670 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:43.670Z,1569909643.670 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.686Z,1569909643.686 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-01T06:00:43.686Z,1569909643.686 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.686Z,1569909643.686 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-01T06:00:43.686Z,1569909643.686 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:43.686Z,1569909643.686 [controlThread](INFO): Join timeout helper Thread ID is 2740
2019-10-01T06:00:43.981Z,1569909643.981 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T06:00:43.981Z,1569909643.981 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-01T06:00:43.982Z,1569909643.982 [NAL9602](INFO): Powering down
2019-10-01T06:00:44.053Z,1569909644.053 [RDI_Pathfinder](INFO): Powering down
2019-10-01T06:00:44.054Z,1569909644.054 [DAT](INFO): Powering down
2019-10-01T06:00:44.174Z,1569909644.174 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-01T06:00:44.175Z,1569909644.175 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-01T06:00:44.175Z,1569909644.175 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-01T06:00:44.176Z,1569909644.176 [MissionManager](INFO): Uninitializing Mission Default
2019-10-01T06:00:44.176Z,1569909644.176 [Default] Stopped
2019-10-01T06:00:44.176Z,1569909644.176 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-01T06:00:44.176Z,1569909644.176 [Default:B.GoToSurface] Stopped
2019-10-01T06:00:44.176Z,1569909644.176 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-01T06:00:44.176Z,1569909644.176 [Default:CheckIn] Stopped
2019-10-01T06:00:44.176Z,1569909644.176 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-01T06:00:44.176Z,1569909644.176 [Default:CheckIn:Read_GPS] Stopped
2019-10-01T06:00:44.179Z,1569909644.179 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-01T06:00:44.179Z,1569909644.179 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-01T06:00:44.179Z,1569909644.179 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-01T06:00:44.179Z,1569909644.179 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-01T06:00:44.180Z,1569909644.180 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-01T06:00:44.180Z,1569909644.180 [BuoyancyServo](INFO): Powering down
2019-10-01T06:00:44.193Z,1569909644.193 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-01T06:00:44.193Z,1569909644.193 [ElevatorServo](INFO): Powering down
2019-10-01T06:00:44.194Z,1569909644.194 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-01T06:00:44.194Z,1569909644.194 [MassServo](INFO): Powering down
2019-10-01T06:00:44.195Z,1569909644.195 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-01T06:00:44.195Z,1569909644.195 [RudderServo](INFO): Powering down
2019-10-01T06:00:44.195Z,1569909644.195 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-01T06:00:44.196Z,1569909644.196 [ThrusterServo](INFO): Powering down
2019-10-01T06:00:44.196Z,1569909644.196 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-01T06:00:44.197Z,1569909644.197 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-01T06:00:44.197Z,1569909644.197 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-01T06:00:44.197Z,1569909644.197 [CBIT](DEBUG): Powering off loads.
2019-10-01T06:00:44.209Z,1569909644.209 [CBIT](DEBUG): Disabling WDT.
2019-10-01T06:00:44.221Z,1569909644.221 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-01T06:00:44.222Z,1569909644.222 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:44.304Z,1569909644.304 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:44.310Z,1569909644.310 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:44.361Z,1569909644.361 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:44.362Z,1569909644.362 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2019-10-01T06:00:44.362Z,1569909644.362 [WetLabsUBAT](INFO): WetLabs destructor: done
2019-10-01T06:00:44.365Z,1569909644.365 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:44.368Z,1569909644.368 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:44.378Z,1569909644.378 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T06:00:44.437Z,1569909644.437 [logger ThreadHandler](INFO): Thread cancelled.