2019-05-19T06:44:46.531Z,1558248286.531 [Supervisor](DEBUG): Initializing supervisor.
2019-05-19T06:44:46.534Z,1558248286.534 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-19T06:44:46.535Z,1558248286.535 [SyncHandler](INFO): Protected caller Thread ID is 1105
2019-05-19T06:44:46.535Z,1558248286.535 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-19T06:44:46.536Z,1558248286.536 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-19T06:44:46.537Z,1558248286.537 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1106
2019-05-19T06:44:46.540Z,1558248286.540 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-19T06:44:46.551Z,1558248286.551 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-19T06:44:46.552Z,1558248286.552 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-19T06:44:46.553Z,1558248286.553 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1107
2019-05-19T06:44:46.554Z,1558248286.554 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-19T06:44:46.554Z,1558248286.554 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-19T06:44:46.555Z,1558248286.555 [logger ThreadHandler](INFO): Protected caller Thread ID is 1108
2019-05-19T06:44:46.557Z,1558248286.557 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-19T06:44:46.557Z,1558248286.557 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-19T06:44:46.559Z,1558248286.559 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-19T06:44:46.732Z,1558248286.732 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Navigation.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:46.756Z,1558248286.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-19T06:44:46.757Z,1558248286.757 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-19T06:44:46.774Z,1558248286.774 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Guidance.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:46.838Z,1558248286.838 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-19T06:44:47.236Z,1558248287.236 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Simulator.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:47.260Z,1558248287.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-19T06:44:47.261Z,1558248287.261 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-19T06:44:47.556Z,1558248287.556 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Control.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:47.581Z,1558248287.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-19T06:44:47.581Z,1558248287.581 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-19T06:44:47.618Z,1558248287.618 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/secure.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:47.677Z,1558248287.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-19T06:44:47.678Z,1558248287.678 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-19T06:44:47.944Z,1558248287.944 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Servo.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:47.968Z,1558248287.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-19T06:44:47.969Z,1558248287.969 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-19T06:44:48.126Z,1558248288.126 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/BIT.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:48.160Z,1558248288.160 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-19T06:44:48.161Z,1558248288.161 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-19T06:44:48.592Z,1558248288.592 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/vehicle.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:48.617Z,1558248288.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-19T06:44:48.617Z,1558248288.617 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-19T06:44:48.662Z,1558248288.662 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/workSite.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:48.721Z,1558248288.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-19T06:44:48.721Z,1558248288.721 [Supervisor](INFO): Opening Config file at: Config/._vehicle.cfg
2019-05-19T06:44:48.790Z,1558248288.790 [Config/._vehicle](ERROR): Could not parse value: resource
2019-05-19T06:44:48.814Z,1558248288.814 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/._vehicle.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:48.862Z,1558248288.862 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-19T06:44:48.906Z,1558248288.906 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Sample.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:48.962Z,1558248288.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-19T06:44:48.963Z,1558248288.963 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-19T06:44:49.486Z,1558248289.486 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Sensor.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:49.522Z,1558248289.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-19T06:44:49.523Z,1558248289.523 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-19T06:44:49.877Z,1558248289.877 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Science.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:49.901Z,1558248289.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-19T06:44:49.902Z,1558248289.902 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-19T06:44:50.066Z,1558248290.066 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/logger.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.091Z,1558248290.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-19T06:44:50.092Z,1558248290.092 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-19T06:44:50.190Z,1558248290.190 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Estimation.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.235Z,1558248290.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-19T06:44:50.236Z,1558248290.236 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-19T06:44:50.338Z,1558248290.338 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/;cp -r Config/Derivation.cfg Logs/20190519T064446/Config/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.383Z,1558248290.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-19T06:44:50.385Z,1558248290.385 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-05-19T06:44:50.386Z,1558248290.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-05-19T06:44:50.418Z,1558248290.418 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/Navigation.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.478Z,1558248290.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-05-19T06:44:50.502Z,1558248290.502 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/Simulator.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.563Z,1558248290.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-05-19T06:44:50.606Z,1558248290.606 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/Control.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.666Z,1558248290.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-05-19T06:44:50.698Z,1558248290.698 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/secure.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.750Z,1558248290.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-05-19T06:44:50.786Z,1558248290.786 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/Servo.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.845Z,1558248290.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-05-19T06:44:50.886Z,1558248290.886 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/BIT.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:50.946Z,1558248290.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-05-19T06:44:51.190Z,1558248291.190 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/vehicle.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:51.289Z,1558248291.289 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/vehicle.cfg Logs/20190519T064446/Config/lrauv-daphne/' elapsed 0.098227
2019-05-19T06:44:51.389Z,1558248291.389 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/vehicle.cfg Logs/20190519T064446/Config/lrauv-daphne/' elapsed 0.198351
2019-05-19T06:44:51.399Z,1558248291.399 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-05-19T06:44:51.426Z,1558248291.426 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/workSite.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:51.525Z,1558248291.525 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/workSite.cfg Logs/20190519T064446/Config/lrauv-daphne/' elapsed 0.098896
2019-05-19T06:44:51.566Z,1558248291.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-05-19T06:44:51.898Z,1558248291.898 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/Sensor.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:51.997Z,1558248291.997 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/Sensor.cfg Logs/20190519T064446/Config/lrauv-daphne/' elapsed 0.098948
2019-05-19T06:44:52.030Z,1558248292.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-05-19T06:44:52.154Z,1558248292.154 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/Science.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:52.212Z,1558248292.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-05-19T06:44:52.270Z,1558248292.270 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/logger.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:52.330Z,1558248292.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-05-19T06:44:52.530Z,1558248292.530 [Supervisor](FAULT): Executing 'mkdir -p Logs/20190519T064446/Config/lrauv-daphne/;cp -r Config/lrauv-daphne/Battery.cfg Logs/20190519T064446/Config/lrauv-daphne/' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:52.557Z,1558248292.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-19T06:44:52.559Z,1558248292.559 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2019-05-19T06:44:52.559Z,1558248292.559 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-19T06:44:52.573Z,1558248292.573 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-19T06:44:52.643Z,1558248292.643 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-19T06:44:52.752Z,1558248292.752 [VerticalControl] Loaded
2019-05-19T06:44:52.752Z,1558248292.752 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-19T06:44:52.753Z,1558248292.753 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-19T06:44:52.821Z,1558248292.821 [HorizontalControl] Loaded
2019-05-19T06:44:52.821Z,1558248292.821 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-19T06:44:52.822Z,1558248292.822 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-19T06:44:52.827Z,1558248292.827 [SpeedControl] Loaded
2019-05-19T06:44:52.827Z,1558248292.827 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-19T06:44:52.828Z,1558248292.828 [LoopControl](DEBUG): Construct LoopControl.
2019-05-19T06:44:52.829Z,1558248292.829 [LoopControl] Loaded
2019-05-19T06:44:52.829Z,1558248292.829 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-19T06:44:52.829Z,1558248292.829 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-19T06:44:52.830Z,1558248292.830 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-19T06:44:52.843Z,1558248292.843 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-19T06:44:52.843Z,1558248292.843 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-19T06:44:52.942Z,1558248292.942 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-19T06:44:52.943Z,1558248292.943 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-19T06:44:53.066Z,1558248293.066 [BuoyancyServo] Loaded
2019-05-19T06:44:53.067Z,1558248293.067 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-19T06:44:53.078Z,1558248293.078 [ElevatorServo] Loaded
2019-05-19T06:44:53.078Z,1558248293.078 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-19T06:44:53.089Z,1558248293.089 [MassServo] Loaded
2019-05-19T06:44:53.089Z,1558248293.089 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-19T06:44:53.100Z,1558248293.100 [RudderServo] Loaded
2019-05-19T06:44:53.100Z,1558248293.100 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-19T06:44:53.111Z,1558248293.111 [ThrusterServo] Loaded
2019-05-19T06:44:53.111Z,1558248293.111 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-19T06:44:53.112Z,1558248293.112 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-19T06:44:53.112Z,1558248293.112 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-19T06:44:53.218Z,1558248293.218 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-19T06:44:53.218Z,1558248293.218 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-19T06:44:53.238Z,1558248293.238 [NavChart] Loaded
2019-05-19T06:44:53.239Z,1558248293.239 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-19T06:44:53.242Z,1558248293.242 [UniversalFixResidualReporter] Loaded
2019-05-19T06:44:53.243Z,1558248293.243 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-19T06:44:53.243Z,1558248293.243 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-19T06:44:53.244Z,1558248293.244 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-19T06:44:53.313Z,1558248293.313 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-19T06:44:53.313Z,1558248293.313 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-19T06:44:53.649Z,1558248293.649 [DataOverHttps] Loaded
2019-05-19T06:44:53.649Z,1558248293.649 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-19T06:44:53.650Z,1558248293.650 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087B4E0
2019-05-19T06:44:53.650Z,1558248293.650 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1193
2019-05-19T06:44:53.664Z,1558248293.664 [Depth_Keller] Loaded
2019-05-19T06:44:53.664Z,1558248293.664 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-19T06:44:53.722Z,1558248293.722 [DUSBL_Hydroid] Loaded
2019-05-19T06:44:53.722Z,1558248293.722 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-05-19T06:44:53.818Z,1558248293.818 [NAL9602] Loaded
2019-05-19T06:44:53.818Z,1558248293.818 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-19T06:44:53.833Z,1558248293.833 [Onboard] Loaded
2019-05-19T06:44:53.833Z,1558248293.833 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-19T06:44:53.839Z,1558248293.839 [PowerOnly] Loaded
2019-05-19T06:44:53.839Z,1558248293.839 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-05-19T06:44:53.842Z,1558248293.842 [Radio_Surface] Loaded
2019-05-19T06:44:53.843Z,1558248293.843 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-19T06:44:53.844Z,1558248293.844 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AB4E0
2019-05-19T06:44:53.844Z,1558248293.844 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1194
2019-05-19T06:44:53.887Z,1558248293.887 [RDI_Pathfinder] Loaded
2019-05-19T06:44:53.888Z,1558248293.888 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-05-19T06:44:54.015Z,1558248294.015 [DAT] Loaded
2019-05-19T06:44:54.016Z,1558248294.016 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-05-19T06:44:54.017Z,1558248294.017 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-19T06:44:54.017Z,1558248294.017 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-19T06:44:54.057Z,1558248294.057 [DepthRateCalculator] Loaded
2019-05-19T06:44:54.057Z,1558248294.057 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-19T06:44:54.062Z,1558248294.062 [PitchRateCalculator] Loaded
2019-05-19T06:44:54.063Z,1558248294.063 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-19T06:44:54.074Z,1558248294.074 [SpeedCalculator] Loaded
2019-05-19T06:44:54.075Z,1558248294.075 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-19T06:44:54.095Z,1558248294.095 [TempGradientCalculator] Loaded
2019-05-19T06:44:54.095Z,1558248294.095 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-19T06:44:54.101Z,1558248294.101 [YawRateCalculator] Loaded
2019-05-19T06:44:54.101Z,1558248294.101 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-19T06:44:54.130Z,1558248294.130 [ElevatorOffsetCalculator] Loaded
2019-05-19T06:44:54.130Z,1558248294.130 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-19T06:44:54.131Z,1558248294.131 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-19T06:44:54.131Z,1558248294.131 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-19T06:44:54.388Z,1558248294.388 [CTD_NeilBrown] Loaded
2019-05-19T06:44:54.388Z,1558248294.388 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-19T06:44:54.389Z,1558248294.389 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409AD4E0
2019-05-19T06:44:54.389Z,1558248294.389 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1195
2019-05-19T06:44:54.419Z,1558248294.419 [ESPComponent] Loaded
2019-05-19T06:44:54.419Z,1558248294.419 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-05-19T06:44:54.433Z,1558248294.433 [PAR_Licor] Loaded
2019-05-19T06:44:54.433Z,1558248294.433 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-19T06:44:54.480Z,1558248294.480 [WetLabsBB2FL] Loaded
2019-05-19T06:44:54.480Z,1558248294.480 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-19T06:44:54.481Z,1558248294.481 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2019-05-19T06:44:54.482Z,1558248294.482 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1196
2019-05-19T06:44:54.482Z,1558248294.482 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-19T06:44:54.483Z,1558248294.483 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-19T06:44:54.509Z,1558248294.509 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-19T06:44:54.510Z,1558248294.510 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-19T06:44:54.803Z,1558248294.803 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-19T06:44:54.803Z,1558248294.803 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-19T06:44:54.937Z,1558248294.937 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-19T06:44:54.948Z,1558248294.948 [SBIT] Loaded
2019-05-19T06:44:54.948Z,1558248294.948 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-19T06:44:54.949Z,1558248294.949 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-19T06:44:54.961Z,1558248294.961 [IBIT] Loaded
2019-05-19T06:44:54.961Z,1558248294.961 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-19T06:44:54.965Z,1558248294.965 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-19T06:44:55.347Z,1558248295.347 [CBIT] Loaded
2019-05-19T06:44:55.347Z,1558248295.347 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-19T06:44:55.347Z,1558248295.347 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-19T06:44:55.351Z,1558248295.351 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-19T06:44:55.352Z,1558248295.352 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-19T06:44:55.359Z,1558248295.359 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-19T06:44:55.360Z,1558248295.360 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0
2019-05-19T06:44:55.361Z,1558248295.361 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1197
2019-05-19T06:44:55.366Z,1558248295.366 [Supervisor](INFO): Main Thread ID is 803
2019-05-19T06:44:55.366Z,1558248295.366 [Supervisor](DEBUG): Running supervisor.
2019-05-19T06:44:55.366Z,1558248295.366 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1198
2019-05-19T06:44:55.369Z,1558248295.369 [controlThread ThreadHandler](INFO): Handler Thread ID is 1199
2019-05-19T06:44:55.369Z,1558248295.369 [controlThread](DEBUG): Initializing ControlThread
2019-05-19T06:44:55.370Z,1558248295.370 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-19T06:44:55.371Z,1558248295.371 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-19T06:44:55.372Z,1558248295.372 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-19T06:44:55.373Z,1558248295.373 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-19T06:44:55.374Z,1558248295.374 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-19T06:44:55.374Z,1558248295.374 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-19T06:44:55.378Z,1558248295.378 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-19T06:44:55.378Z,1558248295.378 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-19T06:44:55.379Z,1558248295.379 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-19T06:44:55.379Z,1558248295.379 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-19T06:44:55.380Z,1558248295.380 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-19T06:44:55.380Z,1558248295.380 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-19T06:44:55.385Z,1558248295.385 [SBIT](INFO): Initialize SBIT Component.
2019-05-19T06:44:55.386Z,1558248295.386 [SBIT](IMPORTANT): git: 2019-05-01-10-gcab12ce
2019-05-19T06:44:55.386Z,1558248295.386 [SBIT](INFO): git hash: cab12ce958be6da2bb158d8a74bde728d70637cd
2019-05-19T06:44:55.386Z,1558248295.386 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-19T06:44:55.387Z,1558248295.387 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-05-19T06:44:55.388Z,1558248295.388 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-05-19T06:44:55.389Z,1558248295.389 [IBIT](INFO): Initialize IBIT Component.
2019-05-19T06:44:55.390Z,1558248295.390 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-19T06:44:55.390Z,1558248295.390 [logger ThreadHandler](INFO): Handler Thread ID is 1200
2019-05-19T06:44:55.401Z,1558248295.401 [CBIT](DEBUG): Initialized mux pins.
2019-05-19T06:44:55.401Z,1558248295.401 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-19T06:44:55.409Z,1558248295.409 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1201
2019-05-19T06:44:55.410Z,1558248295.410 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-19T06:44:55.421Z,1558248295.421 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1202
2019-05-19T06:44:55.425Z,1558248295.425 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-19T06:44:55.425Z,1558248295.425 [CBIT](DEBUG): Initializing heartbeat.
2019-05-19T06:44:55.433Z,1558248295.433 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1203
2019-05-19T06:44:55.434Z,1558248295.434 [CTD_NeilBrown](INFO): Powering down
2019-05-19T06:44:55.461Z,1558248295.461 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1204
2019-05-19T06:44:55.462Z,1558248295.462 [WetLabsBB2FL](INFO): Powering down
2019-05-19T06:44:55.489Z,1558248295.489 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1205
2019-05-19T06:44:55.492Z,1558248295.492 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-19T06:44:55.493Z,1558248295.493 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-19T06:44:55.493Z,1558248295.493 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-19T06:44:55.493Z,1558248295.493 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-19T06:44:55.493Z,1558248295.493 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-19T06:44:55.493Z,1558248295.493 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-19T06:44:55.494Z,1558248295.494 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-19T06:44:55.494Z,1558248295.494 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-19T06:44:55.494Z,1558248295.494 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-19T06:44:55.494Z,1558248295.494 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-19T06:44:55.494Z,1558248295.494 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-19T06:44:55.495Z,1558248295.495 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-19T06:44:55.495Z,1558248295.495 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-19T06:44:55.495Z,1558248295.495 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-19T06:44:55.495Z,1558248295.495 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-19T06:44:55.495Z,1558248295.495 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-19T06:44:55.497Z,1558248295.497 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-19T06:44:55.497Z,1558248295.497 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-19T06:44:55.533Z,1558248295.533 [CBIT](DEBUG): Backplane powered.
2019-05-19T06:44:55.534Z,1558248295.534 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-19T06:44:55.544Z,1558248295.544 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-19T06:44:55.555Z,1558248295.555 [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-05-19T06:44:55.556Z,1558248295.556 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-19T06:44:55.627Z,1558248295.627 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-19T06:44:55.628Z,1558248295.628 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-19T06:44:55.645Z,1558248295.645 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-19T06:44:55.668Z,1558248295.668 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-19T06:44:55.670Z,1558248295.670 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-19T06:44:55.684Z,1558248295.684 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-19T06:44:55.688Z,1558248295.688 [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-05-19T06:44:55.712Z,1558248295.712 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DUSBL_Hydroid,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,DAT,ESPComponent,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-05-19T06:44:55.728Z,1558248295.728 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-05-19T06:44:55.771Z,1558248295.771 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-05-19T06:44:55.771Z,1558248295.771 [DAT](INFO): Powering up
2019-05-19T06:44:55.771Z,1558248295.771 [DAT](DEBUG): Initializing DAT.
2019-05-19T06:44:55.789Z,1558248295.789 [ESPComponent](INFO): powering down ESP
2019-05-19T06:44:55.850Z,1558248295.850 [Radio_Surface](INFO): Powering up
2019-05-19T06:44:55.868Z,1558248295.868 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-05-19T06:44:55.917Z,1558248295.917 [CommandLine](FAULT): Executing 'echo 1 > /dev/loadB2' with timout: 5.0000 should timeout at :1558248320.000000
2019-05-19T06:44:56.054Z,1558248296.054 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-19T06:44:56.081Z,1558248296.081 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-19T06:44:56.089Z,1558248296.089 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-19T06:44:56.090Z,1558248296.090 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-19T06:44:56.097Z,1558248296.097 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-19T06:44:56.098Z,1558248296.098 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-19T06:44:56.105Z,1558248296.105 [MassServo](DEBUG): Initializing MassServo.
2019-05-19T06:44:56.106Z,1558248296.106 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-19T06:44:56.113Z,1558248296.113 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-19T06:44:56.114Z,1558248296.114 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-19T06:44:56.121Z,1558248296.121 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-19T06:45:09.081Z,1558248309.081 [DAT](INFO): setting local address to 2
2019-05-19T06:45:09.485Z,1558248309.485 [DAT](INFO): set local address to 2
2019-05-19T06:45:24.014Z,1558248324.014 [NAL9602](INFO): Powering up NAL9602
2019-05-19T06:45:34.924Z,1558248334.924 [NAL9602](INFO): NAL9602 initialized
2019-05-19T06:45:35.741Z,1558248335.741 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:45:55.149Z,1558248355.149 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-19T06:45:55.153Z,1558248355.153 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-19T06:46:06.153Z,1558248366.153 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.014501
CHAN A1 (24V): -0.016769
CHAN A2 (12V): -0.002007
CHAN A3 (5V): -0.001424
CHAN B0 (3.3V): -0.000096
CHAN B1 (3.15aV): -0.000028
CHAN B2 (3.15bV): -0.000504
CHAN B3 (GND): -0.000709
OPEN: 0.004893
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-19T06:46:20.990Z,1558248380.990 [CommandLine](IMPORTANT): got command failComponent
2019-05-19T06:46:20.991Z,1558248380.991 [CommandLine](IMPORTANT): Failed components:
2019-05-19T06:46:20.991Z,1558248380.991 [CommandLine](IMPORTANT): No failed Components.
2019-05-19T06:46:34.914Z,1558248394.914 [CommandLine](INFO): End of History
2019-05-19T06:46:35.861Z,1558248395.861 [CommandLine](INFO): End of History
2019-05-19T06:46:48.826Z,1558248408.826 [SBIT](IMPORTANT): SBIT PASSED
2019-05-19T06:46:48.874Z,1558248408.874 [CommandLine](IMPORTANT): got command configSet list
2019-05-19T06:46:48.874Z,1558248408.874 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-19T06:46:48.875Z,1558248408.875 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-05-19T06:46:48.875Z,1558248408.875 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-05-19T06:46:48.875Z,1558248408.875 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count;

2019-05-19T06:46:48.875Z,1558248408.875 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=3 hour;

2019-05-19T06:46:48.875Z,1558248408.875 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2019-05-19T06:46:48.875Z,1558248408.875 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere;

2019-05-19T06:46:48.875Z,1558248408.875 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): Express none DVL_micro.height_above_sea_floor;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=90 cubic_centimeter;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 centimeter;

2019-05-19T06:46:48.876Z,1558248408.876 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-33 millimeter;

2019-05-19T06:46:48.877Z,1558248408.877 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=27 millimeter;

2019-05-19T06:46:49.215Z,1558248409.215 [MissionManager](IMPORTANT): Started mission Startup
2019-05-19T06:46:49.215Z,1558248409.215 [Startup] Running Loop=1
2019-05-19T06:46:49.216Z,1558248409.216 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-19T06:46:49.216Z,1558248409.216 [Startup:A.GoToSurface] Running Loop=1
2019-05-19T06:46:49.216Z,1558248409.216 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-19T06:46:49.216Z,1558248409.216 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-19T06:46:49.217Z,1558248409.217 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-19T06:46:49.217Z,1558248409.217 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-19T06:46:49.218Z,1558248409.218 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-19T06:46:49.218Z,1558248409.218 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-19T06:46:49.219Z,1558248409.219 [Startup:StartupSatComms] Running Loop=1
2019-05-19T06:46:49.220Z,1558248409.220 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-19T06:46:49.220Z,1558248409.220 [Startup:StartupSatComms:A] Running Loop=1
2019-05-19T06:46:49.633Z,1558248409.633 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-19T06:46:55.693Z,1558248415.693 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T06:46:55.693Z,1558248415.693 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-19T06:46:55.693Z,1558248415.693 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T06:46:55.703Z,1558248415.703 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T06:46:56.110Z,1558248416.110 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T06:46:56.110Z,1558248416.110 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-19T06:47:34.007Z,1558248454.007 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005067
2019-05-19T06:47:49.427Z,1558248469.427 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-19T06:46:49.2Z
2019-05-19T06:47:49.427Z,1558248469.427 [Startup:StartupSatComms:A] Stopped
2019-05-19T06:47:49.427Z,1558248469.427 [Startup:StartupSatComms:B] Running Loop=1
2019-05-19T06:47:49.818Z,1558248469.818 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-19T06:47:55.664Z,1558248475.664 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20190519T063717/Courier0004.lzma
2019-05-19T06:47:56.470Z,1558248476.470 [DataOverHttps](INFO): Moved sent file to Logs/20190519T063717/Courier0004.lzma.bak
2019-05-19T06:47:56.470Z,1558248476.470 [DataOverHttps](INFO): SBD MOMSN=11109628
2019-05-19T06:47:56.673Z,1558248476.673 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2019-05-19T06:47:56.673Z,1558248476.673 [RDI_Pathfinder] Data Fault, FailCount= 1
2019-05-19T06:47:56.673Z,1558248476.673 [RDI_Pathfinder](ERROR): Data Fault
2019-05-19T06:47:56.689Z,1558248476.689 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2019-05-19T06:47:56.689Z,1558248476.689 [CBIT](CRITICAL): Data Fault in component: RDI_Pathfinder
2019-05-19T06:47:57.077Z,1558248477.077 [RDI_Pathfinder](INFO): Powering down
2019-05-19T06:47:57.127Z,1558248477.127 [CommandLine](FAULT): Scheduling is paused
2019-05-19T06:47:57.128Z,1558248477.128 [CBIT](INFO): Critical error at 20190519T064756
2019-05-19T06:47:57.128Z,1558248477.128 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-19T06:48:10.344Z,1558248490.344 [DataOverHttps](INFO): Sending 1494 bytes from file Logs/20190519T063717/Express0001.lzma
2019-05-19T06:48:11.146Z,1558248491.146 [DataOverHttps](INFO): Moved sent file to Logs/20190519T063717/Express0001.lzma.bak
2019-05-19T06:48:11.146Z,1558248491.146 [DataOverHttps](INFO): SBD MOMSN=11109636
2019-05-19T06:48:23.677Z,1558248503.677 [DataOverHttps](INFO): Sending 354 bytes from file Logs/20190519T063717/Express0005.lzma
2019-05-19T06:48:24.482Z,1558248504.482 [DataOverHttps](INFO): Moved sent file to Logs/20190519T063717/Express0005.lzma.bak
2019-05-19T06:48:24.482Z,1558248504.482 [DataOverHttps](INFO): SBD MOMSN=11109700
2019-05-19T06:48:37.022Z,1558248517.022 [DataOverHttps](INFO): Sending 1546 bytes from file Logs/20190519T064446/Express0001.lzma
2019-05-19T06:48:37.826Z,1558248517.826 [DataOverHttps](INFO): Moved sent file to Logs/20190519T064446/Express0001.lzma.bak
2019-05-19T06:48:37.826Z,1558248517.826 [DataOverHttps](INFO): SBD MOMSN=11109709
2019-05-19T06:48:40.745Z,1558248520.745 [Startup:StartupSatComms:B] Stopped
2019-05-19T06:48:40.745Z,1558248520.745 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-19T06:48:40.745Z,1558248520.745 [Startup:StartupSatComms] Stopped
2019-05-19T06:48:40.745Z,1558248520.745 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-19T06:48:40.746Z,1558248520.746 [Startup](INFO): Completed Startup
2019-05-19T06:48:40.746Z,1558248520.746 [MissionManager](INFO): Startup is completed.
2019-05-19T06:48:40.746Z,1558248520.746 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-19T06:48:40.746Z,1558248520.746 [Startup] Stopped
2019-05-19T06:48:40.746Z,1558248520.746 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-19T06:48:40.746Z,1558248520.746 [Startup:A.GoToSurface] Stopped
2019-05-19T06:48:40.746Z,1558248520.746 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-19T06:48:41.154Z,1558248521.154 [MissionManager](IMPORTANT): Started mission Default
2019-05-19T06:48:41.154Z,1558248521.154 [Default] Running Loop=1
2019-05-19T06:48:41.154Z,1558248521.154 [Default](DEBUG): Aggregate::initialize Default
2019-05-19T06:48:41.154Z,1558248521.154 [Default:B.GoToSurface] Running Loop=1
2019-05-19T06:48:41.154Z,1558248521.154 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-19T06:48:41.154Z,1558248521.154 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-19T06:48:41.155Z,1558248521.155 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-19T06:48:41.155Z,1558248521.155 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-19T06:48:41.155Z,1558248521.155 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-19T06:48:41.156Z,1558248521.156 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-19T06:48:41.156Z,1558248521.156 [Default:A.Wait] Running Loop=1
2019-05-19T06:48:41.156Z,1558248521.156 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-19T06:48:54.456Z,1558248534.456 [Default:A.Wait](INFO): Done Waiting.
2019-05-19T06:48:54.456Z,1558248534.456 [Default:A.Wait] Stopped
2019-05-19T06:48:54.456Z,1558248534.456 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-19T06:48:54.866Z,1558248534.866 [Default:CheckIn] Running Loop=1
2019-05-19T06:48:54.866Z,1558248534.866 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-19T06:48:54.866Z,1558248534.866 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-19T06:48:55.266Z,1558248535.266 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-19T06:48:56.893Z,1558248536.893 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T06:48:56.893Z,1558248536.893 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-19T06:48:56.893Z,1558248536.893 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T06:48:56.903Z,1558248536.903 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T06:48:57.306Z,1558248537.306 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T06:48:57.306Z,1558248537.306 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-19T06:50:38.258Z,1558248638.258 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-05-19T06:50:39.081Z,1558248639.081 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:50:43.929Z,1558248643.929 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:50:46.757Z,1558248646.757 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:50:48.777Z,1558248648.777 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:50:50.797Z,1558248650.797 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:50:54.025Z,1558248654.025 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:50:56.857Z,1558248656.857 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:50:58.082Z,1558248658.082 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T06:50:58.082Z,1558248658.082 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-19T06:50:58.082Z,1558248658.082 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T06:50:58.092Z,1558248658.092 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T06:50:58.498Z,1558248658.498 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T06:50:58.498Z,1558248658.498 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-19T06:51:00.089Z,1558248660.089 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:02.917Z,1558248662.917 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:06.149Z,1558248666.149 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:08.977Z,1558248668.977 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:11.805Z,1558248671.805 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:13.825Z,1558248673.825 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:17.057Z,1558248677.057 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:19.885Z,1558248679.885 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:23.113Z,1558248683.113 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:25.949Z,1558248685.949 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:29.181Z,1558248689.181 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:32.005Z,1558248692.005 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:34.833Z,1558248694.833 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:36.857Z,1558248696.857 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:38.873Z,1558248698.873 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:42.101Z,1558248702.101 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:44.929Z,1558248704.929 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:48.161Z,1558248708.161 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:50.989Z,1558248710.989 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:54.221Z,1558248714.221 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:57.049Z,1558248717.049 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:51:59.885Z,1558248719.885 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:01.901Z,1558248721.901 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:05.129Z,1558248725.129 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:07.961Z,1558248727.961 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:11.193Z,1558248731.193 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:14.025Z,1558248734.025 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:17.253Z,1558248737.253 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:20.081Z,1558248740.081 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:22.909Z,1558248742.909 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:24.929Z,1558248744.929 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:26.945Z,1558248746.945 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:30.185Z,1558248750.185 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:33.009Z,1558248753.009 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:36.241Z,1558248756.241 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:39.069Z,1558248759.069 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:42.305Z,1558248762.305 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:45.133Z,1558248765.133 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:47.953Z,1558248767.953 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:49.977Z,1558248769.977 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:53.209Z,1558248773.209 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:56.033Z,1558248776.033 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:59.277Z,1558248779.277 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:52:59.297Z,1558248779.297 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T06:52:59.297Z,1558248779.297 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-05-19T06:52:59.297Z,1558248779.297 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T06:52:59.307Z,1558248779.307 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T06:52:59.714Z,1558248779.714 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T06:52:59.714Z,1558248779.714 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-05-19T06:53:02.097Z,1558248782.097 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:05.329Z,1558248785.329 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:08.153Z,1558248788.153 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:10.985Z,1558248790.985 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:13.005Z,1558248793.005 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:15.025Z,1558248795.025 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:18.257Z,1558248798.257 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:21.085Z,1558248801.085 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:24.313Z,1558248804.313 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:27.145Z,1558248807.145 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:30.373Z,1558248810.373 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:33.209Z,1558248813.209 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:36.033Z,1558248816.033 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:38.053Z,1558248818.053 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:41.281Z,1558248821.281 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:44.117Z,1558248824.117 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:47.345Z,1558248827.345 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:50.173Z,1558248830.173 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:53.401Z,1558248833.401 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:55.031Z,1558248835.031 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-19T06:48:54.9Z
2019-05-19T06:53:55.031Z,1558248835.031 [Default:CheckIn:Read_GPS] Stopped
2019-05-19T06:53:55.031Z,1558248835.031 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-19T06:53:55.439Z,1558248835.439 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-19T06:53:56.233Z,1558248836.233 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:53:59.057Z,1558248839.057 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:01.081Z,1558248841.081 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:02.735Z,1558248842.735 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20190519T064446/Courier0004.lzma
2019-05-19T06:54:03.097Z,1558248843.097 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:03.542Z,1558248843.542 [DataOverHttps](INFO): Moved sent file to Logs/20190519T064446/Courier0004.lzma.bak
2019-05-19T06:54:03.542Z,1558248843.542 [DataOverHttps](INFO): SBD MOMSN=11109794
2019-05-19T06:54:06.329Z,1558248846.329 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:09.157Z,1558248849.157 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:12.393Z,1558248852.393 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:15.225Z,1558248855.225 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:15.619Z,1558248855.619 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20190519T064446/Express0005.lzma
2019-05-19T06:54:16.410Z,1558248856.410 [DataOverHttps](INFO): Moved sent file to Logs/20190519T064446/Express0005.lzma.bak
2019-05-19T06:54:16.410Z,1558248856.410 [DataOverHttps](INFO): SBD MOMSN=11109797
2019-05-19T06:54:18.453Z,1558248858.453 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:19.294Z,1558248859.294 [Default:CheckIn:Read_Iridium] Stopped
2019-05-19T06:54:19.294Z,1558248859.294 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-19T06:54:19.294Z,1558248859.294 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-19T06:54:21.277Z,1558248861.277 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:24.109Z,1558248864.109 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:26.129Z,1558248866.129 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:29.361Z,1558248869.361 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:32.189Z,1558248872.189 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:35.421Z,1558248875.421 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:38.253Z,1558248878.253 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:41.477Z,1558248881.477 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:44.305Z,1558248884.305 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:47.137Z,1558248887.137 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:49.157Z,1558248889.157 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:52.389Z,1558248892.389 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:55.221Z,1558248895.221 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:54:58.445Z,1558248898.445 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:00.104Z,1558248900.104 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T06:55:00.104Z,1558248900.104 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-05-19T06:55:00.104Z,1558248900.104 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T06:55:00.114Z,1558248900.114 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T06:55:00.517Z,1558248900.517 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T06:55:00.517Z,1558248900.517 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-05-19T06:55:01.273Z,1558248901.273 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:04.505Z,1558248904.505 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:07.337Z,1558248907.337 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:10.169Z,1558248910.169 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:12.185Z,1558248912.185 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:14.205Z,1558248914.205 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:17.437Z,1558248917.437 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:20.265Z,1558248920.265 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:23.497Z,1558248923.497 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:26.321Z,1558248926.321 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:29.557Z,1558248929.557 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:32.385Z,1558248932.385 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:35.213Z,1558248935.213 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:55:37.222Z,1558248937.222 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-05-19T06:55:37.222Z,1558248937.222 [NAL9602] Data Fault, FailCount= 1
2019-05-19T06:55:37.222Z,1558248937.222 [NAL9602](ERROR): Data Fault
2019-05-19T06:55:37.273Z,1558248937.273 [CBIT](ERROR): Data Fault in component: NAL9602
2019-05-19T06:55:37.622Z,1558248937.622 [NAL9602](INFO): Powering down
2019-05-19T06:55:38.466Z,1558248938.466 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-19T06:55:38.466Z,1558248938.466 [NAL9602] No Fault, FailCount= 1
2019-05-19T06:56:07.926Z,1558248967.926 [NAL9602](INFO): Powering up NAL9602
2019-05-19T06:56:18.830Z,1558248978.830 [NAL9602](INFO): NAL9602 initialized
2019-05-19T06:56:19.653Z,1558248979.653 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:22.477Z,1558248982.477 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:25.309Z,1558248985.309 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:28.541Z,1558248988.541 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:31.369Z,1558248991.369 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:34.605Z,1558248994.605 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:37.433Z,1558248997.433 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:40.261Z,1558249000.261 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:43.485Z,1558249003.485 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:46.313Z,1558249006.313 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:49.553Z,1558249009.553 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:52.381Z,1558249012.381 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:55.613Z,1558249015.613 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:56:58.465Z,1558249018.465 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:00.888Z,1558249020.888 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T06:57:00.888Z,1558249020.888 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-05-19T06:57:00.889Z,1558249020.889 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T06:57:00.934Z,1558249020.934 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T06:57:01.261Z,1558249021.261 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:01.317Z,1558249021.317 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T06:57:01.317Z,1558249021.317 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-05-19T06:57:03.285Z,1558249023.285 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:06.513Z,1558249026.513 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:09.349Z,1558249029.349 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:12.577Z,1558249032.577 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:15.401Z,1558249035.401 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:18.633Z,1558249038.633 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:21.469Z,1558249041.469 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:24.289Z,1558249044.289 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:26.313Z,1558249046.313 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:28.329Z,1558249048.329 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:31.561Z,1558249051.561 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:34.393Z,1558249054.393 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:37.621Z,1558249057.621 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:40.457Z,1558249060.457 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:43.689Z,1558249063.689 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:46.513Z,1558249066.513 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:49.341Z,1558249069.341 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:51.357Z,1558249071.357 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:54.593Z,1558249074.593 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:57:57.425Z,1558249077.425 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:00.653Z,1558249080.653 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:03.481Z,1558249083.481 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:06.713Z,1558249086.713 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:09.537Z,1558249089.537 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:12.369Z,1558249092.369 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:14.389Z,1558249094.389 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:16.405Z,1558249096.405 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:19.641Z,1558249099.641 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:22.465Z,1558249102.465 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:25.705Z,1558249105.705 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:28.525Z,1558249108.525 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:31.761Z,1558249111.761 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:34.589Z,1558249114.589 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:37.413Z,1558249117.413 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:39.433Z,1558249119.433 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:42.669Z,1558249122.669 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:45.497Z,1558249125.497 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:48.729Z,1558249128.729 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:51.557Z,1558249131.557 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:54.789Z,1558249134.789 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:58:57.617Z,1558249137.617 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:00.445Z,1558249140.445 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:02.100Z,1558249142.100 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T06:59:02.100Z,1558249142.100 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-05-19T06:59:02.100Z,1558249142.100 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T06:59:02.110Z,1558249142.110 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T06:59:02.461Z,1558249142.461 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:02.494Z,1558249142.494 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T06:59:02.494Z,1558249142.494 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-05-19T06:59:05.697Z,1558249145.697 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:08.521Z,1558249148.521 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:11.757Z,1558249151.757 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:14.585Z,1558249154.585 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:17.817Z,1558249157.817 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:19.850Z,1558249159.850 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-05-19T06:59:19.850Z,1558249159.850 [Default:CheckIn:C.Wait] Stopped
2019-05-19T06:59:19.850Z,1558249159.850 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-19T06:59:19.850Z,1558249159.850 [Default:CheckIn:D] Running Loop=1
2019-05-19T06:59:20.253Z,1558249160.253 [Default:CheckIn:D] Stopped
2019-05-19T06:59:20.253Z,1558249160.253 [Default:CheckIn:E] Running Loop=1
2019-05-19T06:59:20.641Z,1558249160.641 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:20.670Z,1558249160.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.651653 min 
2019-05-19T06:59:20.671Z,1558249160.671 [Default:CheckIn:E] Stopped
2019-05-19T06:59:20.671Z,1558249160.671 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-05-19T06:59:20.671Z,1558249160.671 [Default:CheckIn] Stopped
2019-05-19T06:59:20.671Z,1558249160.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-19T06:59:20.671Z,1558249160.671 [Default:CheckIn](INFO): Running loop #2
2019-05-19T06:59:20.671Z,1558249160.671 [Default:CheckIn] Running Loop=2
2019-05-19T06:59:20.671Z,1558249160.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-19T06:59:20.671Z,1558249160.671 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-19T06:59:23.473Z,1558249163.473 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:25.493Z,1558249165.493 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:27.517Z,1558249167.517 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:30.745Z,1558249170.745 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:33.577Z,1558249173.577 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:36.801Z,1558249176.801 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:39.637Z,1558249179.637 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:42.865Z,1558249182.865 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:43.590Z,1558249183.590 [CommandLine](IMPORTANT): got command ! echo foo;sleep 10;echo bar
2019-05-19T06:59:43.610Z,1558249183.610 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' with timout: 5.0000 should timeout at :1558249216.000000
2019-05-19T06:59:43.631Z,1558249183.631 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.020171
2019-05-19T06:59:43.729Z,1558249183.729 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.118704
2019-05-19T06:59:43.829Z,1558249183.829 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.218563
2019-05-19T06:59:43.929Z,1558249183.929 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.318516
2019-05-19T06:59:44.029Z,1558249184.029 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.418827
2019-05-19T06:59:44.129Z,1558249184.129 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.518568
2019-05-19T06:59:44.229Z,1558249184.229 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.618570
2019-05-19T06:59:44.329Z,1558249184.329 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.718508
2019-05-19T06:59:44.429Z,1558249184.429 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.818561
2019-05-19T06:59:44.529Z,1558249184.529 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 0.918506
2019-05-19T06:59:44.629Z,1558249184.629 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.018573
2019-05-19T06:59:44.729Z,1558249184.729 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.118505
2019-05-19T06:59:44.829Z,1558249184.829 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.218579
2019-05-19T06:59:44.929Z,1558249184.929 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.318548
2019-05-19T06:59:45.029Z,1558249185.029 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.418526
2019-05-19T06:59:45.129Z,1558249185.129 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.518657
2019-05-19T06:59:45.229Z,1558249185.229 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.618538
2019-05-19T06:59:45.329Z,1558249185.329 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.718667
2019-05-19T06:59:45.429Z,1558249185.429 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.818502
2019-05-19T06:59:45.529Z,1558249185.529 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 1.918523
2019-05-19T06:59:45.629Z,1558249185.629 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.018606
2019-05-19T06:59:45.693Z,1558249185.693 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:45.733Z,1558249185.733 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.122548
2019-05-19T06:59:45.833Z,1558249185.833 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.222514
2019-05-19T06:59:45.937Z,1558249185.937 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.326918
2019-05-19T06:59:46.040Z,1558249186.040 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.429792
2019-05-19T06:59:46.137Z,1558249186.137 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.526525
2019-05-19T06:59:46.237Z,1558249186.237 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.626508
2019-05-19T06:59:46.337Z,1558249186.337 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.726503
2019-05-19T06:59:46.437Z,1558249186.437 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.826555
2019-05-19T06:59:46.537Z,1558249186.537 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 2.926670
2019-05-19T06:59:46.637Z,1558249186.637 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.026506
2019-05-19T06:59:46.737Z,1558249186.737 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.126515
2019-05-19T06:59:46.837Z,1558249186.837 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.226508
2019-05-19T06:59:46.937Z,1558249186.937 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.326506
2019-05-19T06:59:47.037Z,1558249187.037 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.426501
2019-05-19T06:59:47.137Z,1558249187.137 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.526499
2019-05-19T06:59:47.237Z,1558249187.237 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.626573
2019-05-19T06:59:47.337Z,1558249187.337 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.726521
2019-05-19T06:59:47.437Z,1558249187.437 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.826503
2019-05-19T06:59:47.537Z,1558249187.537 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 3.926511
2019-05-19T06:59:47.637Z,1558249187.637 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.026506
2019-05-19T06:59:47.737Z,1558249187.737 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.126690
2019-05-19T06:59:47.837Z,1558249187.837 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.226510
2019-05-19T06:59:47.937Z,1558249187.937 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.326501
2019-05-19T06:59:48.037Z,1558249188.037 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.426555
2019-05-19T06:59:48.137Z,1558249188.137 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.526605
2019-05-19T06:59:48.237Z,1558249188.237 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.626556
2019-05-19T06:59:48.337Z,1558249188.337 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.726556
2019-05-19T06:59:48.437Z,1558249188.437 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.826573
2019-05-19T06:59:48.521Z,1558249188.521 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:48.537Z,1558249188.537 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 4.926691
2019-05-19T06:59:48.637Z,1558249188.637 [CommandLine](FAULT): Executing 'echo foo;sleep 10;echo bar' elapsed 5.026517
2019-05-19T06:59:50.537Z,1558249190.537 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:53.689Z,1558249193.689 [CommandLine](FAULT): Command echo foo;sleep 10;echo bar timed out
2019-05-19T06:59:53.769Z,1558249193.769 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:56.605Z,1558249196.605 [NAL9602](DEBUG): Fix Requested
2019-05-19T06:59:59.833Z,1558249199.833 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:02.657Z,1558249202.657 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:05.893Z,1558249205.893 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:08.721Z,1558249208.721 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:11.553Z,1558249211.553 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:13.565Z,1558249213.565 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:15.589Z,1558249215.589 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:18.821Z,1558249218.821 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:19.870Z,1558249219.870 [CommandLine](IMPORTANT): got command ! sleep 10;echo foo
2019-05-19T07:00:19.886Z,1558249219.886 [CommandLine](FAULT): Executing 'sleep 10;echo foo' with timout: 5.0000 should timeout at :1558249216.000000
2019-05-19T07:00:19.985Z,1558249219.985 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.098057
2019-05-19T07:00:20.085Z,1558249220.085 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.198219
2019-05-19T07:00:20.185Z,1558249220.185 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.298103
2019-05-19T07:00:20.285Z,1558249220.285 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.398068
2019-05-19T07:00:20.385Z,1558249220.385 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.498081
2019-05-19T07:00:20.485Z,1558249220.485 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.598122
2019-05-19T07:00:20.585Z,1558249220.585 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.698109
2019-05-19T07:00:20.688Z,1558249220.688 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.801343
2019-05-19T07:00:20.785Z,1558249220.785 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.898066
2019-05-19T07:00:20.885Z,1558249220.885 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 0.998054
2019-05-19T07:00:20.985Z,1558249220.985 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.098091
2019-05-19T07:00:21.085Z,1558249221.085 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.198050
2019-05-19T07:00:21.185Z,1558249221.185 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.298064
2019-05-19T07:00:21.285Z,1558249221.285 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.398061
2019-05-19T07:00:21.385Z,1558249221.385 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.498077
2019-05-19T07:00:21.485Z,1558249221.485 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.598058
2019-05-19T07:00:21.585Z,1558249221.585 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.698114
2019-05-19T07:00:21.645Z,1558249221.645 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:21.685Z,1558249221.685 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.798255
2019-05-19T07:00:21.785Z,1558249221.785 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.898119
2019-05-19T07:00:21.885Z,1558249221.885 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 1.998052
2019-05-19T07:00:21.985Z,1558249221.985 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.098092
2019-05-19T07:00:22.085Z,1558249222.085 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.198127
2019-05-19T07:00:22.185Z,1558249222.185 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.298092
2019-05-19T07:00:22.285Z,1558249222.285 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.398046
2019-05-19T07:00:22.385Z,1558249222.385 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.498097
2019-05-19T07:00:22.485Z,1558249222.485 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.598070
2019-05-19T07:00:22.585Z,1558249222.585 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.698281
2019-05-19T07:00:22.685Z,1558249222.685 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.798062
2019-05-19T07:00:22.785Z,1558249222.785 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.898107
2019-05-19T07:00:22.885Z,1558249222.885 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 2.998215
2019-05-19T07:00:22.985Z,1558249222.985 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.098260
2019-05-19T07:00:23.085Z,1558249223.085 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.198053
2019-05-19T07:00:23.185Z,1558249223.185 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.298190
2019-05-19T07:00:23.285Z,1558249223.285 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.398128
2019-05-19T07:00:23.385Z,1558249223.385 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.498243
2019-05-19T07:00:23.485Z,1558249223.485 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.598060
2019-05-19T07:00:23.585Z,1558249223.585 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.698136
2019-05-19T07:00:23.685Z,1558249223.685 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.798222
2019-05-19T07:00:23.785Z,1558249223.785 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.898086
2019-05-19T07:00:23.885Z,1558249223.885 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 3.998123
2019-05-19T07:00:23.985Z,1558249223.985 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.098058
2019-05-19T07:00:24.085Z,1558249224.085 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.198241
2019-05-19T07:00:24.185Z,1558249224.185 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.298058
2019-05-19T07:00:24.285Z,1558249224.285 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.398173
2019-05-19T07:00:24.385Z,1558249224.385 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.498052
2019-05-19T07:00:24.485Z,1558249224.485 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.598149
2019-05-19T07:00:24.585Z,1558249224.585 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.698052
2019-05-19T07:00:24.685Z,1558249224.685 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.798109
2019-05-19T07:00:24.785Z,1558249224.785 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.898061
2019-05-19T07:00:24.881Z,1558249224.881 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:24.886Z,1558249224.886 [CommandLine](FAULT): Executing 'sleep 10;echo foo' elapsed 4.999118
2019-05-19T07:00:27.709Z,1558249227.709 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:29.929Z,1558249229.929 [CommandLine](FAULT): Command sleep 10;echo foo timed out
2019-05-19T07:00:30.945Z,1558249230.945 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:33.765Z,1558249233.765 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:36.601Z,1558249236.601 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:38.613Z,1558249238.613 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:41.849Z,1558249241.849 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:44.673Z,1558249244.673 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:47.905Z,1558249247.905 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:50.737Z,1558249250.737 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:53.174Z,1558249253.174 [CommandLine](IMPORTANT): got command ! sleep 10
2019-05-19T07:00:53.206Z,1558249253.206 [CommandLine](FAULT): Executing 'sleep 10' with timout: 5.0000 should timeout at :1558249216.000000
2019-05-19T07:00:53.305Z,1558249253.305 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.098414
2019-05-19T07:00:53.405Z,1558249253.405 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.198453
2019-05-19T07:00:53.505Z,1558249253.505 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.298419
2019-05-19T07:00:53.605Z,1558249253.605 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.398415
2019-05-19T07:00:53.705Z,1558249253.705 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.498410
2019-05-19T07:00:53.805Z,1558249253.805 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.598456
2019-05-19T07:00:53.905Z,1558249253.905 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.698422
2019-05-19T07:00:53.965Z,1558249253.965 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:54.005Z,1558249254.005 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.798572
2019-05-19T07:00:54.105Z,1558249254.105 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.898537
2019-05-19T07:00:54.205Z,1558249254.205 [CommandLine](FAULT): Executing 'sleep 10' elapsed 0.998615
2019-05-19T07:00:54.305Z,1558249254.305 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.098443
2019-05-19T07:00:54.405Z,1558249254.405 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.198679
2019-05-19T07:00:54.505Z,1558249254.505 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.298583
2019-05-19T07:00:54.605Z,1558249254.605 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.398478
2019-05-19T07:00:54.705Z,1558249254.705 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.498450
2019-05-19T07:00:54.805Z,1558249254.805 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.598478
2019-05-19T07:00:54.905Z,1558249254.905 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.698408
2019-05-19T07:00:55.005Z,1558249255.005 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.798562
2019-05-19T07:00:55.105Z,1558249255.105 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.898406
2019-05-19T07:00:55.205Z,1558249255.205 [CommandLine](FAULT): Executing 'sleep 10' elapsed 1.998440
2019-05-19T07:00:55.305Z,1558249255.305 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.098406
2019-05-19T07:00:55.405Z,1558249255.405 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.198457
2019-05-19T07:00:55.505Z,1558249255.505 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.298416
2019-05-19T07:00:55.605Z,1558249255.605 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.398679
2019-05-19T07:00:55.705Z,1558249255.705 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.498420
2019-05-19T07:00:55.805Z,1558249255.805 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.598426
2019-05-19T07:00:55.905Z,1558249255.905 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.698406
2019-05-19T07:00:56.005Z,1558249256.005 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.798486
2019-05-19T07:00:56.105Z,1558249256.105 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.898410
2019-05-19T07:00:56.205Z,1558249256.205 [CommandLine](FAULT): Executing 'sleep 10' elapsed 2.998515
2019-05-19T07:00:56.305Z,1558249256.305 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.098396
2019-05-19T07:00:56.405Z,1558249256.405 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.198587
2019-05-19T07:00:56.505Z,1558249256.505 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.298545
2019-05-19T07:00:56.605Z,1558249256.605 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.398406
2019-05-19T07:00:56.705Z,1558249256.705 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.498424
2019-05-19T07:00:56.793Z,1558249256.793 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:00:56.805Z,1558249256.805 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.598574
2019-05-19T07:00:56.905Z,1558249256.905 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.698467
2019-05-19T07:00:57.005Z,1558249257.005 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.798441
2019-05-19T07:00:57.108Z,1558249257.108 [CommandLine](FAULT): Executing 'sleep 10' elapsed 3.901713
2019-05-19T07:00:57.207Z,1558249257.207 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.000494
2019-05-19T07:00:57.305Z,1558249257.305 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.098406
2019-05-19T07:00:57.405Z,1558249257.405 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.198524
2019-05-19T07:00:57.505Z,1558249257.505 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.298433
2019-05-19T07:00:57.606Z,1558249257.606 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.399068
2019-05-19T07:00:57.705Z,1558249257.705 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.498471
2019-05-19T07:00:57.805Z,1558249257.805 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.598428
2019-05-19T07:00:57.905Z,1558249257.905 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.698474
2019-05-19T07:00:58.006Z,1558249258.006 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.799528
2019-05-19T07:00:58.105Z,1558249258.105 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.898463
2019-05-19T07:00:58.205Z,1558249258.205 [CommandLine](FAULT): Executing 'sleep 10' elapsed 4.998419
2019-05-19T07:00:58.305Z,1558249258.305 [CommandLine](FAULT): Executing 'sleep 10' elapsed 5.098469
2019-05-19T07:00:59.625Z,1558249259.625 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:01.645Z,1558249261.645 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:03.243Z,1558249263.243 [CommandLine](FAULT): Command sleep 10 timed out
2019-05-19T07:01:03.285Z,1558249263.285 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T07:01:03.285Z,1558249263.285 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-05-19T07:01:03.285Z,1558249263.285 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T07:01:03.299Z,1558249263.299 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T07:01:03.677Z,1558249263.677 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:03.712Z,1558249263.712 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T07:01:03.712Z,1558249263.712 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-05-19T07:01:06.909Z,1558249266.909 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:09.733Z,1558249269.733 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:12.973Z,1558249272.973 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:15.797Z,1558249275.797 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:19.029Z,1558249279.029 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:21.857Z,1558249281.857 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:24.681Z,1558249284.681 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:26.701Z,1558249286.701 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:29.933Z,1558249289.933 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:32.761Z,1558249292.761 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:36.001Z,1558249296.001 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:38.825Z,1558249298.825 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:42.061Z,1558249302.061 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:44.881Z,1558249304.881 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:47.713Z,1558249307.713 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:49.733Z,1558249309.733 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:52.965Z,1558249312.965 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:55.797Z,1558249315.797 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:01:59.025Z,1558249319.025 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:01.849Z,1558249321.849 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:05.085Z,1558249325.085 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:07.909Z,1558249327.909 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:10.741Z,1558249330.741 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:12.765Z,1558249332.765 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:15.993Z,1558249335.993 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:18.817Z,1558249338.817 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:22.053Z,1558249342.053 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:24.881Z,1558249344.881 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:28.113Z,1558249348.113 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:30.941Z,1558249350.941 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:33.765Z,1558249353.765 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:35.789Z,1558249355.789 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:37.809Z,1558249357.809 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:41.037Z,1558249361.037 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:43.873Z,1558249363.873 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:47.097Z,1558249367.097 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:49.929Z,1558249369.929 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:53.161Z,1558249373.161 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:55.989Z,1558249375.989 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:02:56.830Z,1558249376.830 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-05-19T07:02:56.831Z,1558249376.831 [RDI_Pathfinder] No Fault, FailCount= 1
2019-05-19T07:02:58.813Z,1558249378.813 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:00.833Z,1558249380.833 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:01.181Z,1558249381.181 [CommandLine](IMPORTANT): got command ! sleep 6
2019-05-19T07:03:01.195Z,1558249381.195 [CommandLine](FAULT): Executing 'sleep 6' with timout: 5.0000 should timeout at :1558249344.000000
2019-05-19T07:03:01.293Z,1558249381.293 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.097647
2019-05-19T07:03:01.393Z,1558249381.393 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.197561
2019-05-19T07:03:01.493Z,1558249381.493 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.297744
2019-05-19T07:03:01.593Z,1558249381.593 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.397626
2019-05-19T07:03:01.693Z,1558249381.693 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.498085
2019-05-19T07:03:01.793Z,1558249381.793 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.597634
2019-05-19T07:03:01.893Z,1558249381.893 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.697698
2019-05-19T07:03:01.993Z,1558249381.993 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.797565
2019-05-19T07:03:02.093Z,1558249382.093 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.897572
2019-05-19T07:03:02.193Z,1558249382.193 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.997579
2019-05-19T07:03:02.293Z,1558249382.293 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.097618
2019-05-19T07:03:02.393Z,1558249382.393 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.197643
2019-05-19T07:03:02.493Z,1558249382.493 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.297740
2019-05-19T07:03:02.593Z,1558249382.593 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.397726
2019-05-19T07:03:02.693Z,1558249382.693 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.497672
2019-05-19T07:03:02.793Z,1558249382.793 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.597668
2019-05-19T07:03:02.893Z,1558249382.893 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.697624
2019-05-19T07:03:02.993Z,1558249382.993 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.797673
2019-05-19T07:03:03.096Z,1558249383.096 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.900336
2019-05-19T07:03:03.193Z,1558249383.193 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.997595
2019-05-19T07:03:03.293Z,1558249383.293 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.097618
2019-05-19T07:03:03.393Z,1558249383.393 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.197589
2019-05-19T07:03:03.493Z,1558249383.493 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.297618
2019-05-19T07:03:03.593Z,1558249383.593 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.397629
2019-05-19T07:03:03.693Z,1558249383.693 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.498022
2019-05-19T07:03:03.793Z,1558249383.793 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.597692
2019-05-19T07:03:03.893Z,1558249383.893 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.697569
2019-05-19T07:03:03.993Z,1558249383.993 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.797596
2019-05-19T07:03:04.069Z,1558249384.069 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:04.093Z,1558249384.093 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.897577
2019-05-19T07:03:04.193Z,1558249384.193 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.997539
2019-05-19T07:03:04.293Z,1558249384.293 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.097537
2019-05-19T07:03:04.393Z,1558249384.393 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.197571
2019-05-19T07:03:04.493Z,1558249384.493 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.297638
2019-05-19T07:03:04.498Z,1558249384.498 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T07:03:04.498Z,1558249384.498 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-05-19T07:03:04.498Z,1558249384.498 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T07:03:04.508Z,1558249384.508 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T07:03:04.593Z,1558249384.593 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.397537
2019-05-19T07:03:04.693Z,1558249384.693 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.497571
2019-05-19T07:03:04.793Z,1558249384.793 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.597586
2019-05-19T07:03:04.894Z,1558249384.894 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.698588
2019-05-19T07:03:04.914Z,1558249384.914 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T07:03:04.914Z,1558249384.914 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-05-19T07:03:04.993Z,1558249384.993 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.797702
2019-05-19T07:03:05.093Z,1558249385.093 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.897661
2019-05-19T07:03:05.193Z,1558249385.193 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.997677
2019-05-19T07:03:05.293Z,1558249385.293 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.097968
2019-05-19T07:03:05.393Z,1558249385.393 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.197796
2019-05-19T07:03:05.493Z,1558249385.493 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.297618
2019-05-19T07:03:05.593Z,1558249385.593 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.397605
2019-05-19T07:03:05.693Z,1558249385.693 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.498053
2019-05-19T07:03:05.793Z,1558249385.793 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.597580
2019-05-19T07:03:05.893Z,1558249385.893 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.697560
2019-05-19T07:03:05.993Z,1558249385.993 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.797588
2019-05-19T07:03:06.097Z,1558249386.097 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.901731
2019-05-19T07:03:06.197Z,1558249386.197 [CommandLine](FAULT): Executing 'sleep 6' elapsed 5.001589
2019-05-19T07:03:06.901Z,1558249386.901 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:07.372Z,1558249387.372 [CommandLine](FAULT): Command sleep 6 timed out
2019-05-19T07:03:10.129Z,1558249390.129 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:12.957Z,1558249392.957 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:16.189Z,1558249396.189 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:19.017Z,1558249399.017 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:21.845Z,1558249401.845 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:23.865Z,1558249403.865 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:25.885Z,1558249405.885 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:29.117Z,1558249409.117 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:31.941Z,1558249411.941 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:33.842Z,1558249413.842 [CommandLine](IMPORTANT): got command ! sleep 6
2019-05-19T07:03:33.858Z,1558249413.858 [CommandLine](FAULT): Executing 'sleep 6' with timout: 5.0000 should timeout at :1558249472.000000
2019-05-19T07:03:33.957Z,1558249413.957 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.098507
2019-05-19T07:03:34.057Z,1558249414.057 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.198411
2019-05-19T07:03:34.157Z,1558249414.157 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.298440
2019-05-19T07:03:34.257Z,1558249414.257 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.398393
2019-05-19T07:03:34.357Z,1558249414.357 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.498415
2019-05-19T07:03:34.457Z,1558249414.457 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.598396
2019-05-19T07:03:34.557Z,1558249414.557 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.698450
2019-05-19T07:03:34.657Z,1558249414.657 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.798385
2019-05-19T07:03:34.761Z,1558249414.761 [CommandLine](FAULT): Executing 'sleep 6' elapsed 0.902509
2019-05-19T07:03:34.861Z,1558249414.861 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.002442
2019-05-19T07:03:34.961Z,1558249414.961 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.102434
2019-05-19T07:03:35.061Z,1558249415.061 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.202407
2019-05-19T07:03:35.163Z,1558249415.163 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.304501
2019-05-19T07:03:35.173Z,1558249415.173 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:35.261Z,1558249415.261 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.402584
2019-05-19T07:03:35.361Z,1558249415.361 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.502443
2019-05-19T07:03:35.461Z,1558249415.461 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.602402
2019-05-19T07:03:35.561Z,1558249415.561 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.702535
2019-05-19T07:03:35.661Z,1558249415.661 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.802606
2019-05-19T07:03:35.761Z,1558249415.761 [CommandLine](FAULT): Executing 'sleep 6' elapsed 1.902507
2019-05-19T07:03:35.864Z,1558249415.864 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.005689
2019-05-19T07:03:35.961Z,1558249415.961 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.102528
2019-05-19T07:03:36.061Z,1558249416.061 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.202406
2019-05-19T07:03:36.161Z,1558249416.161 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.302482
2019-05-19T07:03:36.261Z,1558249416.261 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.402453
2019-05-19T07:03:36.361Z,1558249416.361 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.502426
2019-05-19T07:03:36.461Z,1558249416.461 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.602413
2019-05-19T07:03:36.561Z,1558249416.561 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.702395
2019-05-19T07:03:36.661Z,1558249416.661 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.802395
2019-05-19T07:03:36.761Z,1558249416.761 [CommandLine](FAULT): Executing 'sleep 6' elapsed 2.902393
2019-05-19T07:03:36.861Z,1558249416.861 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.002399
2019-05-19T07:03:36.961Z,1558249416.961 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.102389
2019-05-19T07:03:37.061Z,1558249417.061 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.202387
2019-05-19T07:03:37.161Z,1558249417.161 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.302444
2019-05-19T07:03:37.261Z,1558249417.261 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.402392
2019-05-19T07:03:37.361Z,1558249417.361 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.502387
2019-05-19T07:03:37.461Z,1558249417.461 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.602400
2019-05-19T07:03:37.561Z,1558249417.561 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.702397
2019-05-19T07:03:37.661Z,1558249417.661 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.802515
2019-05-19T07:03:37.761Z,1558249417.761 [CommandLine](FAULT): Executing 'sleep 6' elapsed 3.902395
2019-05-19T07:03:37.861Z,1558249417.861 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.002402
2019-05-19T07:03:37.961Z,1558249417.961 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.102461
2019-05-19T07:03:38.001Z,1558249418.001 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:38.061Z,1558249418.061 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.202463
2019-05-19T07:03:38.161Z,1558249418.161 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.302408
2019-05-19T07:03:38.264Z,1558249418.264 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.405763
2019-05-19T07:03:38.361Z,1558249418.361 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.502387
2019-05-19T07:03:38.461Z,1558249418.461 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.602388
2019-05-19T07:03:38.561Z,1558249418.561 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.702389
2019-05-19T07:03:38.661Z,1558249418.661 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.802425
2019-05-19T07:03:38.761Z,1558249418.761 [CommandLine](FAULT): Executing 'sleep 6' elapsed 4.902389
2019-05-19T07:03:38.861Z,1558249418.861 [CommandLine](FAULT): Executing 'sleep 6' elapsed 5.002394
2019-05-19T07:03:39.908Z,1558249419.908 [CommandLine](FAULT): Command sleep 6 timed out
2019-05-19T07:03:41.233Z,1558249421.233 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:44.069Z,1558249424.069 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:46.893Z,1558249426.893 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:48.909Z,1558249428.909 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:52.141Z,1558249432.141 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:54.973Z,1558249434.973 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:03:58.201Z,1558249438.201 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:01.029Z,1558249441.029 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:04.261Z,1558249444.261 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:07.097Z,1558249447.097 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:09.921Z,1558249449.921 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:11.941Z,1558249451.941 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:13.961Z,1558249453.961 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:17.189Z,1558249457.189 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:20.021Z,1558249460.021 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:20.850Z,1558249460.850 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-19T06:59:20.7Z
2019-05-19T07:04:20.850Z,1558249460.850 [Default:CheckIn:Read_GPS] Stopped
2019-05-19T07:04:20.851Z,1558249460.851 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-19T07:04:23.253Z,1558249463.253 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:26.077Z,1558249466.077 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:27.028Z,1558249467.028 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190519T064446/Courier0007.lzma
2019-05-19T07:04:27.834Z,1558249467.834 [DataOverHttps](INFO): Moved sent file to Logs/20190519T064446/Courier0007.lzma.bak
2019-05-19T07:04:27.834Z,1558249467.834 [DataOverHttps](INFO): SBD MOMSN=11109858
2019-05-19T07:04:29.309Z,1558249469.309 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:32.137Z,1558249472.137 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:34.967Z,1558249474.967 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:36.985Z,1558249476.985 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:40.217Z,1558249480.217 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:43.045Z,1558249483.045 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:45.815Z,1558249485.815 [DataOverHttps](INFO): Sending 1860 bytes from file Logs/20190519T064446/Express0008.lzma
2019-05-19T07:04:46.285Z,1558249486.285 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:48.045Z,1558249488.045 [DataOverHttps](INFO): Moved sent file to Logs/20190519T064446/Express0008.lzma.bak
2019-05-19T07:04:48.045Z,1558249488.045 [DataOverHttps](INFO): SBD MOMSN=11109864
2019-05-19T07:04:49.109Z,1558249489.109 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:50.753Z,1558249490.753 [Default:CheckIn:Read_Iridium] Stopped
2019-05-19T07:04:50.753Z,1558249490.753 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-19T07:04:50.754Z,1558249490.754 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-19T07:04:52.341Z,1558249492.341 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:55.165Z,1558249495.165 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:04:57.997Z,1558249497.997 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:00.013Z,1558249500.013 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:02.033Z,1558249502.033 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:05.269Z,1558249505.269 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:05.697Z,1558249505.697 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T07:05:05.697Z,1558249505.697 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-05-19T07:05:05.697Z,1558249505.697 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T07:05:05.707Z,1558249505.707 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-19T07:05:06.113Z,1558249506.113 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-19T07:05:06.113Z,1558249506.113 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-05-19T07:05:08.097Z,1558249508.097 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:11.325Z,1558249511.325 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:14.157Z,1558249514.157 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:17.385Z,1558249517.385 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:20.217Z,1558249520.217 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:23.045Z,1558249523.045 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:25.061Z,1558249525.061 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:28.305Z,1558249528.305 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:31.121Z,1558249531.121 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:34.357Z,1558249534.357 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:37.181Z,1558249537.181 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:40.413Z,1558249540.413 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:43.245Z,1558249543.245 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:46.073Z,1558249546.073 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:48.093Z,1558249548.093 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:51.325Z,1558249551.325 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:54.157Z,1558249554.157 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:57.385Z,1558249557.385 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:05:58.206Z,1558249558.206 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2019-05-19T07:05:58.206Z,1558249558.206 [RDI_Pathfinder] Data Fault, FailCount= 1
2019-05-19T07:05:58.206Z,1558249558.206 [RDI_Pathfinder](ERROR): Data Fault
2019-05-19T07:05:58.226Z,1558249558.226 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2019-05-19T07:05:58.227Z,1558249558.227 [CBIT](CRITICAL): Data Fault in component: RDI_Pathfinder
2019-05-19T07:05:58.597Z,1558249558.597 [RDI_Pathfinder](INFO): Powering down
2019-05-19T07:05:58.633Z,1558249558.633 [CBIT](INFO): Critical error at 20190519T070558
2019-05-19T07:06:00.209Z,1558249560.209 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:06:03.445Z,1558249563.445 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:06:06.273Z,1558249566.273 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:06:09.101Z,1558249569.101 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:06:11.121Z,1558249571.121 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:06:13.141Z,1558249573.141 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:06:16.369Z,1558249576.369 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:06:19.197Z,1558249579.197 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:06:22.414Z,1558249582.414 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-05-19T07:06:22.414Z,1558249582.414 [NAL9602] Data Fault, FailCount= 2
2019-05-19T07:06:22.414Z,1558249582.414 [NAL9602](ERROR): Data Fault
2019-05-19T07:06:22.451Z,1558249582.451 [CBIT](ERROR): Data Fault in component: NAL9602
2019-05-19T07:06:22.818Z,1558249582.818 [NAL9602](INFO): Powering down
2019-05-19T07:06:23.659Z,1558249583.659 [CBIT](INFO): Clearing failed state for component NAL9602
2019-05-19T07:06:23.659Z,1558249583.659 [NAL9602] No Fault, FailCount= 2
2019-05-19T07:06:53.122Z,1558249613.122 [NAL9602](INFO): Powering up NAL9602
2019-05-19T07:07:04.031Z,1558249624.031 [NAL9602](INFO): NAL9602 initialized
2019-05-19T07:07:04.853Z,1558249624.853 [NAL9602](DEBUG): Fix Requested
2019-05-19T07:07:06.878Z,1558249626.878 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-19T07:07:06.879Z,1558249626.879 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-05-19T07:07:06.879Z,1558249626.879 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-19T07:07:06.915Z,1558249626.915 [CBIT](