2018-09-20T17:28:22.577Z,1537464502.577 [Supervisor](DEBUG): Initializing supervisor. 2018-09-20T17:28:22.590Z,1537464502.590 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-09-20T17:28:22.590Z,1537464502.590 [SyncHandler](INFO): Protected caller Thread ID is 800 2018-09-20T17:28:22.591Z,1537464502.591 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-09-20T17:28:22.602Z,1537464502.602 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-09-20T17:28:22.602Z,1537464502.602 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801 2018-09-20T17:28:22.605Z,1537464502.605 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-09-20T17:28:22.628Z,1537464502.628 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-09-20T17:28:22.638Z,1537464502.638 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-09-20T17:28:22.638Z,1537464502.638 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802 2018-09-20T17:28:22.639Z,1537464502.639 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-09-20T17:28:22.650Z,1537464502.650 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-09-20T17:28:22.650Z,1537464502.650 [logger ThreadHandler](INFO): Protected caller Thread ID is 803 2018-09-20T17:28:22.652Z,1537464502.652 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-09-20T17:28:22.653Z,1537464502.653 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-09-20T17:28:22.659Z,1537464502.659 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-09-20T17:28:22.862Z,1537464502.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-09-20T17:28:22.863Z,1537464502.863 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-09-20T17:28:23.016Z,1537464503.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-09-20T17:28:23.016Z,1537464503.016 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-09-20T17:28:23.398Z,1537464503.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-09-20T17:28:23.400Z,1537464503.400 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-09-20T17:28:23.547Z,1537464503.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-09-20T17:28:23.549Z,1537464503.549 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-09-20T17:28:23.631Z,1537464503.631 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-09-20T17:28:23.849Z,1537464503.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-09-20T17:28:23.851Z,1537464503.851 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-09-20T17:28:23.954Z,1537464503.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-09-20T17:28:23.954Z,1537464503.954 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-09-20T17:28:24.333Z,1537464504.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-09-20T17:28:24.335Z,1537464504.335 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-09-20T17:28:24.835Z,1537464504.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-09-20T17:28:24.836Z,1537464504.836 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-09-20T17:28:25.216Z,1537464505.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-09-20T17:28:25.217Z,1537464505.217 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-09-20T17:28:25.769Z,1537464505.769 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-09-20T17:28:25.770Z,1537464505.770 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-09-20T17:28:25.996Z,1537464505.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-09-20T17:28:25.997Z,1537464505.997 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-09-20T17:28:26.099Z,1537464506.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-09-20T17:28:26.100Z,1537464506.100 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-09-20T17:28:26.638Z,1537464506.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-09-20T17:28:26.640Z,1537464506.640 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-09-20T17:28:26.751Z,1537464506.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-09-20T17:28:26.753Z,1537464506.753 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-09-20T17:28:26.759Z,1537464506.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-09-20T17:28:27.002Z,1537464507.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-09-20T17:28:27.003Z,1537464507.003 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-09-20T17:28:27.105Z,1537464507.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-09-20T17:28:27.203Z,1537464507.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-09-20T17:28:27.310Z,1537464507.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-09-20T17:28:27.398Z,1537464507.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-09-20T17:28:27.542Z,1537464507.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-09-20T17:28:27.724Z,1537464507.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-09-20T17:28:27.840Z,1537464507.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-09-20T17:28:27.927Z,1537464507.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-09-20T17:28:28.013Z,1537464508.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-09-20T17:28:28.221Z,1537464508.221 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-09-20T17:28:28.233Z,1537464508.233 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-09-20T17:28:28.398Z,1537464508.398 [SBIT](DEBUG): Construct Startup Built In Test. 2018-09-20T17:28:28.431Z,1537464508.431 [SBIT] Loaded 2018-09-20T17:28:28.432Z,1537464508.432 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-09-20T17:28:28.432Z,1537464508.432 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-09-20T17:28:28.462Z,1537464508.462 [IBIT] Loaded 2018-09-20T17:28:28.462Z,1537464508.462 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-09-20T17:28:28.466Z,1537464508.466 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-09-20T17:28:28.636Z,1537464508.636 [CBIT] Loaded 2018-09-20T17:28:28.636Z,1537464508.636 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-09-20T17:28:28.637Z,1537464508.637 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-09-20T17:28:28.637Z,1537464508.637 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-09-20T17:28:28.732Z,1537464508.732 [VerticalControl](DEBUG): Construct VerticalControl. 2018-09-20T17:28:28.841Z,1537464508.841 [VerticalControl] Loaded 2018-09-20T17:28:28.841Z,1537464508.841 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-09-20T17:28:28.842Z,1537464508.842 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-09-20T17:28:28.909Z,1537464508.909 [HorizontalControl] Loaded 2018-09-20T17:28:28.909Z,1537464508.909 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-09-20T17:28:28.910Z,1537464508.910 [SpeedControl](DEBUG): Construct SpeedControl. 2018-09-20T17:28:28.916Z,1537464508.916 [SpeedControl] Loaded 2018-09-20T17:28:28.916Z,1537464508.916 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-09-20T17:28:28.917Z,1537464508.917 [LoopControl](DEBUG): Construct LoopControl. 2018-09-20T17:28:28.917Z,1537464508.917 [LoopControl] Loaded 2018-09-20T17:28:28.918Z,1537464508.918 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-09-20T17:28:28.918Z,1537464508.918 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-09-20T17:28:28.919Z,1537464508.919 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-09-20T17:28:28.978Z,1537464508.978 [DepthRateCalculator] Loaded 2018-09-20T17:28:28.978Z,1537464508.978 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-09-20T17:28:28.984Z,1537464508.984 [PitchRateCalculator] Loaded 2018-09-20T17:28:28.985Z,1537464508.985 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-09-20T17:28:29.002Z,1537464509.002 [SpeedCalculator] Loaded 2018-09-20T17:28:29.002Z,1537464509.002 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-09-20T17:28:29.025Z,1537464509.025 [TempGradientCalculator] Loaded 2018-09-20T17:28:29.025Z,1537464509.025 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-09-20T17:28:29.031Z,1537464509.031 [YawRateCalculator] Loaded 2018-09-20T17:28:29.031Z,1537464509.031 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-09-20T17:28:29.063Z,1537464509.063 [ElevatorOffsetCalculator] Loaded 2018-09-20T17:28:29.064Z,1537464509.064 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-09-20T17:28:29.064Z,1537464509.064 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-09-20T17:28:29.065Z,1537464509.065 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-09-20T17:28:29.173Z,1537464509.173 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-09-20T17:28:29.174Z,1537464509.174 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-09-20T17:28:29.476Z,1537464509.476 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-09-20T17:28:29.477Z,1537464509.477 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-09-20T17:28:29.598Z,1537464509.598 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-09-20T17:28:29.599Z,1537464509.599 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-09-20T17:28:29.658Z,1537464509.658 [DeadReckonUsingSpeedCalculator] Loaded 2018-09-20T17:28:29.659Z,1537464509.659 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-09-20T17:28:29.676Z,1537464509.676 [NavChart] Loaded 2018-09-20T17:28:29.677Z,1537464509.677 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-09-20T17:28:29.681Z,1537464509.681 [UniversalFixResidualReporter] Loaded 2018-09-20T17:28:29.681Z,1537464509.681 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-09-20T17:28:29.682Z,1537464509.682 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-09-20T17:28:29.683Z,1537464509.683 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-09-20T17:28:29.708Z,1537464509.708 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-09-20T17:28:29.709Z,1537464509.709 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-09-20T17:28:29.938Z,1537464509.938 [Aanderaa_O2] Loaded 2018-09-20T17:28:29.938Z,1537464509.938 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-09-20T17:28:29.988Z,1537464509.988 [WetLabsSeaOWL_UV_A] Loaded 2018-09-20T17:28:29.988Z,1537464509.988 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-09-20T17:28:29.990Z,1537464509.990 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406DB4E0 2018-09-20T17:28:29.990Z,1537464509.990 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 882 2018-09-20T17:28:29.991Z,1537464509.991 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-09-20T17:28:29.991Z,1537464509.991 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-09-20T17:28:30.639Z,1537464510.639 [AHRS_M2] Loaded 2018-09-20T17:28:30.639Z,1537464510.639 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-09-20T17:28:30.776Z,1537464510.776 [DataOverHttps] Loaded 2018-09-20T17:28:30.777Z,1537464510.777 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-09-20T17:28:30.792Z,1537464510.792 [Depth_Keller] Loaded 2018-09-20T17:28:30.792Z,1537464510.792 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-09-20T17:28:30.797Z,1537464510.797 [DropWeight] Loaded 2018-09-20T17:28:30.797Z,1537464510.797 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-09-20T17:28:30.896Z,1537464510.896 [NAL9602] Loaded 2018-09-20T17:28:30.896Z,1537464510.896 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-09-20T17:28:30.902Z,1537464510.902 [Onboard] Loaded 2018-09-20T17:28:30.902Z,1537464510.902 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-09-20T17:28:30.910Z,1537464510.910 [Radio_Surface] Loaded 2018-09-20T17:28:30.910Z,1537464510.910 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-09-20T17:28:30.911Z,1537464510.911 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4093C4E0 2018-09-20T17:28:30.911Z,1537464510.911 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883 2018-09-20T17:28:30.943Z,1537464510.943 [RDI_Pathfinder] Loaded 2018-09-20T17:28:30.943Z,1537464510.943 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-09-20T17:28:30.967Z,1537464510.967 [RDI_PathfinderUp] Loaded 2018-09-20T17:28:30.968Z,1537464510.968 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2018-09-20T17:28:30.974Z,1537464510.974 [SCPI] Loaded 2018-09-20T17:28:30.975Z,1537464510.975 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-09-20T17:28:32.937Z,1537464512.937 [BPC1] Loaded 2018-09-20T17:28:32.938Z,1537464512.938 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-09-20T17:28:32.938Z,1537464512.938 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-09-20T17:28:32.939Z,1537464512.939 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-09-20T17:28:33.132Z,1537464513.132 [BuoyancyServo] Loaded 2018-09-20T17:28:33.132Z,1537464513.132 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-09-20T17:28:33.145Z,1537464513.145 [ElevatorServo] Loaded 2018-09-20T17:28:33.145Z,1537464513.145 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-09-20T17:28:33.157Z,1537464513.157 [MassServo] Loaded 2018-09-20T17:28:33.157Z,1537464513.157 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-09-20T17:28:33.170Z,1537464513.170 [RudderServo] Loaded 2018-09-20T17:28:33.170Z,1537464513.170 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-09-20T17:28:33.182Z,1537464513.182 [ThrusterServo] Loaded 2018-09-20T17:28:33.182Z,1537464513.182 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-09-20T17:28:33.182Z,1537464513.182 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-09-20T17:28:33.183Z,1537464513.183 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-09-20T17:28:33.321Z,1537464513.321 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-09-20T17:28:33.321Z,1537464513.321 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-09-20T17:28:33.361Z,1537464513.361 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-09-20T17:28:33.365Z,1537464513.365 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-09-20T17:28:33.366Z,1537464513.366 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-09-20T17:28:33.373Z,1537464513.373 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-09-20T17:28:33.374Z,1537464513.374 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0 2018-09-20T17:28:33.374Z,1537464513.374 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 884 2018-09-20T17:28:33.379Z,1537464513.379 [Supervisor](INFO): Main Thread ID is 796 2018-09-20T17:28:33.379Z,1537464513.379 [Supervisor](DEBUG): Running supervisor. 2018-09-20T17:28:33.379Z,1537464513.379 [CommandLine ThreadHandler](INFO): Handler Thread ID is 885 2018-09-20T17:28:33.382Z,1537464513.382 [controlThread ThreadHandler](INFO): Handler Thread ID is 886 2018-09-20T17:28:33.382Z,1537464513.382 [controlThread](DEBUG): Initializing ControlThread 2018-09-20T17:28:33.383Z,1537464513.383 [SBIT](INFO): Initialize SBIT Component. 2018-09-20T17:28:33.383Z,1537464513.383 [SBIT](IMPORTANT): git: 2018-09-20 2018-09-20T17:28:33.384Z,1537464513.384 [SBIT](INFO): git hash: cc82e4f04ca47b19cdcc10b5d3e1760510b3ba6f 2018-09-20T17:28:33.384Z,1537464513.384 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-09-20T17:28:33.384Z,1537464513.384 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-09-20T17:28:33.386Z,1537464513.386 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-09-20T17:28:33.386Z,1537464513.386 [IBIT](INFO): Initialize IBIT Component. 2018-09-20T17:28:33.387Z,1537464513.387 [CBIT](DEBUG): Initialize CBIT Component. 2018-09-20T17:28:33.387Z,1537464513.387 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-09-20T17:28:33.388Z,1537464513.388 [logger ThreadHandler](INFO): Handler Thread ID is 887 2018-09-20T17:28:33.414Z,1537464513.414 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 888 2018-09-20T17:28:33.415Z,1537464513.415 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-09-20T17:28:33.454Z,1537464513.454 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889 2018-09-20T17:28:33.460Z,1537464513.460 [Radio_Surface](INFO): Powering up 2018-09-20T17:28:33.466Z,1537464513.466 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 890 2018-09-20T17:28:33.475Z,1537464513.475 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-09-20T17:28:33.475Z,1537464513.475 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-09-20T17:28:33.475Z,1537464513.475 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-09-20T17:28:33.475Z,1537464513.475 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-09-20T17:28:33.476Z,1537464513.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-09-20T17:28:33.476Z,1537464513.476 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-09-20T17:28:33.476Z,1537464513.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-09-20T17:28:33.476Z,1537464513.476 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-09-20T17:28:33.476Z,1537464513.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-09-20T17:28:33.477Z,1537464513.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-09-20T17:28:33.477Z,1537464513.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-09-20T17:28:33.477Z,1537464513.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-09-20T17:28:33.477Z,1537464513.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-09-20T17:28:33.478Z,1537464513.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-09-20T17:28:33.478Z,1537464513.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-09-20T17:28:33.478Z,1537464513.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-09-20T17:28:33.494Z,1537464513.494 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-09-20T17:28:33.496Z,1537464513.496 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-09-20T17:28:33.496Z,1537464513.496 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-09-20T17:28:33.497Z,1537464513.497 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-09-20T17:28:33.497Z,1537464513.497 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-09-20T17:28:33.498Z,1537464513.498 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-09-20T17:28:33.498Z,1537464513.498 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-09-20T17:28:33.498Z,1537464513.498 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-09-20T17:28:33.499Z,1537464513.499 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-09-20T17:28:33.499Z,1537464513.499 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-09-20T17:28:33.500Z,1537464513.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:28:33.501Z,1537464513.501 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:28:33.501Z,1537464513.501 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:28:33.502Z,1537464513.502 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:28:33.502Z,1537464513.502 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:28:33.503Z,1537464513.503 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:28:33.503Z,1537464513.503 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-09-20T17:28:33.503Z,1537464513.503 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-09-20T17:28:33.511Z,1537464513.511 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-09-20T17:28:33.552Z,1537464513.552 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-09-20T17:28:33.596Z,1537464513.596 [MissionManager](DEBUG): 2018-09-20T17:28:33.597Z,1537464513.597 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-09-20T17:28:33.672Z,1537464513.672 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-09-20T17:28:33.694Z,1537464513.694 [Default:A.Wait](DEBUG): Construct Wait. 2018-09-20T17:28:33.695Z,1537464513.695 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-09-20T17:28:33.746Z,1537464513.746 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-09-20T17:28:33.749Z,1537464513.749 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-09-20T17:28:33.766Z,1537464513.766 [Default:E.Execute](DEBUG): Construct Execute. 2018-09-20T17:28:33.770Z,1537464513.770 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-09-20T17:28:33.779Z,1537464513.779 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-09-20T17:28:33.838Z,1537464513.838 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-09-20T17:28:33.974Z,1537464513.974 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-09-20T17:28:33.997Z,1537464513.997 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-20T17:28:34.218Z,1537464514.218 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-20T17:28:34.302Z,1537464514.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:34.366Z,1537464514.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:34.474Z,1537464514.474 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-09-20T17:28:34.482Z,1537464514.482 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-09-20T17:28:34.500Z,1537464514.500 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-09-20T17:28:34.502Z,1537464514.502 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-09-20T17:28:34.508Z,1537464514.508 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-20T17:28:34.514Z,1537464514.514 [MassServo](DEBUG): Initializing MassServo. 2018-09-20T17:28:34.520Z,1537464514.520 [RudderServo](DEBUG): Initializing EZServoServo. 2018-09-20T17:28:34.530Z,1537464514.530 [RudderServo](DEBUG): Initializing RudderServo. 2018-09-20T17:28:34.535Z,1537464514.535 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-09-20T17:28:34.542Z,1537464514.542 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-09-20T17:28:34.818Z,1537464514.818 [RDI_PathfinderUp](INFO): Powering down 2018-09-20T17:28:34.874Z,1537464514.874 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-20T17:28:34.877Z,1537464514.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:34.886Z,1537464514.886 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:35.042Z,1537464515.042 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-20T17:28:35.045Z,1537464515.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:35.046Z,1537464515.046 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:35.378Z,1537464515.378 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-09-20T17:28:35.378Z,1537464515.378 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-09-20T17:28:35.378Z,1537464515.378 [BuoyancyServo] Communications Fault, FailCount= 1 2018-09-20T17:28:35.378Z,1537464515.378 [BuoyancyServo](ERROR): Communications Fault 2018-09-20T17:28:35.586Z,1537464515.586 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-09-20T17:28:35.629Z,1537464515.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:35.634Z,1537464515.634 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:35.656Z,1537464515.656 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-09-20T17:28:35.656Z,1537464515.656 [BuoyancyServo](INFO): Powering down 2018-09-20T17:28:36.022Z,1537464516.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:36.023Z,1537464516.023 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:36.425Z,1537464516.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:36.426Z,1537464516.426 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:36.821Z,1537464516.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:36.822Z,1537464516.822 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:37.314Z,1537464517.314 [Aanderaa_O2](INFO): Powering down 2018-09-20T17:28:37.437Z,1537464517.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:37.454Z,1537464517.454 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:37.658Z,1537464517.658 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-09-20T17:28:37.701Z,1537464517.701 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:37.703Z,1537464517.703 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-20T17:28:37.759Z,1537464517.759 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-09-20T17:28:37.759Z,1537464517.759 [BuoyancyServo] No Fault, FailCount= 1 2018-09-20T17:28:38.037Z,1537464518.037 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-09-20T17:28:38.155Z,1537464518.155 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-09-20T17:28:47.635Z,1537464527.635 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2018-09-20T17:28:58.052Z,1537464538.052 [SBIT](IMPORTANT): Beginning Startup BIT 2018-09-20T17:28:58.065Z,1537464538.065 [CBIT](IMPORTANT): Beginning ground fault scan 2018-09-20T17:29:00.920Z,1537464540.920 [NAL9602](INFO): Powering up NAL9602 2018-09-20T17:29:09.015Z,1537464549.015 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.009323 CHAN A1 (24V): -0.013087 CHAN A2 (12V): -0.007326 CHAN A3 (5V): -0.001989 CHAN B0 (3.3V): 0.000555 CHAN B1 (3.15aV): 0.001257 CHAN B2 (3.15bV): 0.000446 CHAN B3 (GND): 0.002372 OPEN: 0.004927 Full Scale Calc: 4.765 mA, -1.589 mA 2018-09-20T17:29:12.119Z,1537464552.119 [NAL9602](INFO): NAL9602 initialized 2018-09-20T17:29:52.136Z,1537464592.136 [SBIT](IMPORTANT): SBIT PASSED 2018-09-20T17:29:52.202Z,1537464592.202 [CommandLine](IMPORTANT): got command configSet list 2018-09-20T17:29:52.202Z,1537464592.202 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-09-20T17:29:52.203Z,1537464592.203 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 minute; 2018-09-20T17:29:52.203Z,1537464592.203 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=275 cubic_centimeter; 2018-09-20T17:29:52.204Z,1537464592.204 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter; 2018-09-20T17:29:52.546Z,1537464592.546 [MissionManager](IMPORTANT): Started mission Startup 2018-09-20T17:29:52.546Z,1537464592.546 [Startup] Running Loop=1 2018-09-20T17:29:52.546Z,1537464592.546 [Startup](DEBUG): Aggregate::initialize Startup 2018-09-20T17:29:52.546Z,1537464592.546 [Startup:A.GoToSurface] Running Loop=1 2018-09-20T17:29:52.546Z,1537464592.546 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-09-20T17:29:52.547Z,1537464592.547 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-09-20T17:29:52.548Z,1537464592.548 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-09-20T17:29:52.548Z,1537464592.548 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-09-20T17:29:52.548Z,1537464592.548 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-09-20T17:29:52.549Z,1537464592.549 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-09-20T17:29:52.567Z,1537464592.567 [Startup:StartupSatComms] Running Loop=1 2018-09-20T17:29:52.567Z,1537464592.567 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-09-20T17:29:52.567Z,1537464592.567 [Startup:StartupSatComms:A] Running Loop=1 2018-09-20T17:29:52.930Z,1537464592.930 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-09-20T17:30:52.926Z,1537464652.926 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-20T17:29:52.6Z 2018-09-20T17:30:52.926Z,1537464652.926 [Startup:StartupSatComms:A] Stopped 2018-09-20T17:30:52.926Z,1537464652.926 [Startup:StartupSatComms:B] Running Loop=1 2018-09-20T17:30:53.327Z,1537464653.327 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-09-20T17:31:04.686Z,1537464664.686 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004474 2018-09-20T17:31:08.863Z,1537464668.863 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T172031/Courier0007.lzma 2018-09-20T17:31:09.653Z,1537464669.653 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172031/Courier0007.lzma.bak 2018-09-20T17:31:09.653Z,1537464669.653 [DataOverHttps](INFO): SBD MOMSN=8548042 2018-09-20T17:31:18.983Z,1537464678.983 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20180920T172538/Courier0000.lzma 2018-09-20T17:31:19.757Z,1537464679.757 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172538/Courier0000.lzma.bak 2018-09-20T17:31:19.757Z,1537464679.757 [DataOverHttps](INFO): SBD MOMSN=8548044 2018-09-20T17:31:22.174Z,1537464682.174 [CommandLine](IMPORTANT): got command get CBIT.stopDepth 2018-09-20T17:31:22.175Z,1537464682.175 [CommandLine](IMPORTANT): CBIT.stopDepth 215.000000 m 2018-09-20T17:31:29.189Z,1537464689.189 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T172031/Express0008.lzma 2018-09-20T17:31:29.937Z,1537464689.937 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172031/Express0008.lzma.bak 2018-09-20T17:31:29.937Z,1537464689.937 [DataOverHttps](INFO): SBD MOMSN=8548048 2018-09-20T17:31:30.030Z,1537464690.030 [CommandLine](IMPORTANT): got command get CBIT.abortDepth 2018-09-20T17:31:30.031Z,1537464690.031 [CommandLine](IMPORTANT): CBIT.abortDepth 255.000000 m 2018-09-20T17:31:34.063Z,1537464694.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:31:34.063Z,1537464694.063 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-09-20T17:31:34.063Z,1537464694.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:31:34.065Z,1537464694.065 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:31:34.065Z,1537464694.065 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-09-20T17:31:34.065Z,1537464694.065 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:31:34.112Z,1537464694.112 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:31:34.112Z,1537464694.112 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:31:34.233Z,1537464694.233 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:31:34.233Z,1537464694.233 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-09-20T17:31:34.233Z,1537464694.233 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:31:34.233Z,1537464694.233 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-09-20T17:31:34.610Z,1537464694.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:31:34.611Z,1537464694.611 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:31:34.611Z,1537464694.611 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:31:34.612Z,1537464694.612 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:31:34.612Z,1537464694.612 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:31:34.612Z,1537464694.612 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:31:39.105Z,1537464699.105 [DataOverHttps](INFO): Sending 784 bytes from file Logs/20180920T172538/Express0001.lzma 2018-09-20T17:31:39.897Z,1537464699.897 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172538/Express0001.lzma.bak 2018-09-20T17:31:39.897Z,1537464699.897 [DataOverHttps](INFO): SBD MOMSN=8548050 2018-09-20T17:31:48.995Z,1537464708.995 [DataOverHttps](INFO): Sending 745 bytes from file Logs/20180920T172822/Express0001.lzma 2018-09-20T17:31:49.777Z,1537464709.777 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Express0001.lzma.bak 2018-09-20T17:31:49.777Z,1537464709.777 [DataOverHttps](INFO): SBD MOMSN=8548069 2018-09-20T17:31:50.234Z,1537464710.234 [Startup:StartupSatComms:B] Stopped 2018-09-20T17:31:50.234Z,1537464710.234 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-09-20T17:31:50.234Z,1537464710.234 [Startup:StartupSatComms] Stopped 2018-09-20T17:31:50.234Z,1537464710.234 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-09-20T17:31:50.235Z,1537464710.235 [Startup](INFO): Completed Startup 2018-09-20T17:31:50.235Z,1537464710.235 [MissionManager](INFO): Startup is completed. 2018-09-20T17:31:50.235Z,1537464710.235 [MissionManager](INFO): Uninitializing Mission Startup 2018-09-20T17:31:50.235Z,1537464710.235 [Startup] Stopped 2018-09-20T17:31:50.236Z,1537464710.236 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-09-20T17:31:50.236Z,1537464710.236 [Startup:A.GoToSurface] Stopped 2018-09-20T17:31:50.236Z,1537464710.236 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-09-20T17:31:50.636Z,1537464710.636 [MissionManager](IMPORTANT): Started mission Default 2018-09-20T17:31:50.636Z,1537464710.636 [Default] Running Loop=1 2018-09-20T17:31:50.637Z,1537464710.637 [Default](DEBUG): Aggregate::initialize Default 2018-09-20T17:31:50.637Z,1537464710.637 [Default:B.GoToSurface] Running Loop=1 2018-09-20T17:31:50.637Z,1537464710.637 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-09-20T17:31:50.637Z,1537464710.637 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-09-20T17:31:50.638Z,1537464710.638 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-09-20T17:31:50.638Z,1537464710.638 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-09-20T17:31:50.638Z,1537464710.638 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-09-20T17:31:50.639Z,1537464710.639 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-09-20T17:31:50.639Z,1537464710.639 [Default:A.Wait] Running Loop=1 2018-09-20T17:31:50.639Z,1537464710.639 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-09-20T17:32:03.670Z,1537464723.670 [Default:A.Wait](INFO): Done Waiting. 2018-09-20T17:32:03.670Z,1537464723.670 [Default:A.Wait] Stopped 2018-09-20T17:32:03.670Z,1537464723.670 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-09-20T17:32:04.022Z,1537464724.022 [Default:CheckIn] Running Loop=1 2018-09-20T17:32:04.022Z,1537464724.022 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-20T17:32:04.022Z,1537464724.022 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-20T17:32:04.423Z,1537464724.423 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-09-20T17:32:06.859Z,1537464726.859 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T17:32:46.438Z,1537464766.438 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T17:33:04.284Z,1537464784.284 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd 2018-09-20T17:33:04.285Z,1537464784.285 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 35.000000 mm 2018-09-20T17:33:15.652Z,1537464795.652 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft 2018-09-20T17:33:15.653Z,1537464795.653 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -22.000000 mm 2018-09-20T17:34:15.874Z,1537464855.874 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-09-20T17:34:34.816Z,1537464874.816 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:34:34.816Z,1537464874.816 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2018-09-20T17:34:34.816Z,1537464874.816 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:34:34.818Z,1537464874.818 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:34:34.818Z,1537464874.818 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-09-20T17:34:34.818Z,1537464874.818 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:34:34.832Z,1537464874.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:34:34.832Z,1537464874.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:34:35.164Z,1537464875.164 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:34:35.164Z,1537464875.164 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2018-09-20T17:34:35.165Z,1537464875.165 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:34:35.165Z,1537464875.165 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-09-20T17:34:35.555Z,1537464875.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:34:35.556Z,1537464875.556 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:34:35.556Z,1537464875.556 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:34:35.556Z,1537464875.556 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:34:35.557Z,1537464875.557 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:34:35.557Z,1537464875.557 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:36:54.670Z,1537465014.670 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-09-20T17:37:04.210Z,1537465024.210 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T17:32:04.0Z 2018-09-20T17:37:04.210Z,1537465024.210 [Default:CheckIn:Read_GPS] Stopped 2018-09-20T17:37:04.210Z,1537465024.210 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-20T17:37:04.605Z,1537465024.605 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-09-20T17:37:08.679Z,1537465028.679 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20180920T172822/Courier0004.lzma 2018-09-20T17:37:09.448Z,1537465029.448 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Courier0004.lzma.bak 2018-09-20T17:37:09.449Z,1537465029.449 [DataOverHttps](INFO): SBD MOMSN=8548098 2018-09-20T17:37:18.497Z,1537465038.497 [DataOverHttps](INFO): Sending 322 bytes from file Logs/20180920T172822/Express0005.lzma 2018-09-20T17:37:19.263Z,1537465039.263 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Express0005.lzma.bak 2018-09-20T17:37:19.263Z,1537465039.263 [DataOverHttps](INFO): SBD MOMSN=8548132 2018-09-20T17:37:19.709Z,1537465039.709 [Default:CheckIn:Read_Iridium] Stopped 2018-09-20T17:37:19.714Z,1537465039.714 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-20T17:37:19.714Z,1537465039.714 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-20T17:37:35.756Z,1537465055.756 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:37:35.756Z,1537465055.756 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2018-09-20T17:37:35.756Z,1537465055.756 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:37:35.758Z,1537465055.758 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:37:35.758Z,1537465055.758 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-09-20T17:37:35.758Z,1537465055.758 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:37:35.795Z,1537465055.795 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:37:35.795Z,1537465055.795 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:37:36.155Z,1537465056.155 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:37:36.156Z,1537465056.156 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2018-09-20T17:37:36.156Z,1537465056.156 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:37:36.156Z,1537465056.156 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-09-20T17:37:36.546Z,1537465056.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:37:36.547Z,1537465056.547 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:37:36.547Z,1537465056.547 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:37:36.548Z,1537465056.548 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:37:36.548Z,1537465056.548 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:37:36.548Z,1537465056.548 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:39:14.592Z,1537465154.592 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-20T17:39:14.592Z,1537465154.592 [NAL9602] Data Fault, FailCount= 1 2018-09-20T17:39:14.592Z,1537465154.592 [NAL9602](ERROR): Data Fault 2018-09-20T17:39:14.682Z,1537465154.682 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-20T17:39:14.991Z,1537465154.991 [NAL9602](INFO): Powering down 2018-09-20T17:39:15.856Z,1537465155.856 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-20T17:39:15.856Z,1537465155.856 [NAL9602] No Fault, FailCount= 1 2018-09-20T17:39:45.063Z,1537465185.063 [NAL9602](INFO): Powering up NAL9602 2018-09-20T17:39:56.267Z,1537465196.267 [NAL9602](INFO): NAL9602 initialized 2018-09-20T17:40:36.815Z,1537465236.815 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:40:36.815Z,1537465236.815 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2018-09-20T17:40:36.815Z,1537465236.815 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:40:36.817Z,1537465236.817 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:40:36.817Z,1537465236.817 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-09-20T17:40:36.817Z,1537465236.817 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:40:36.848Z,1537465236.848 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:40:36.848Z,1537465236.848 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:40:37.227Z,1537465237.227 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:40:37.227Z,1537465237.227 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2018-09-20T17:40:37.228Z,1537465237.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:40:37.228Z,1537465237.228 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-09-20T17:40:37.605Z,1537465237.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:40:37.605Z,1537465237.605 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:40:37.606Z,1537465237.606 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:40:37.606Z,1537465237.606 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:40:37.607Z,1537465237.607 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:40:37.607Z,1537465237.607 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:41:31.689Z,1537465291.689 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2018-09-20T17:41:31.689Z,1537465291.689 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.,763.97 2018-09-20T17:42:20.506Z,1537465340.506 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-20T17:42:20.507Z,1537465340.507 [Default:CheckIn:C.Wait] Stopped 2018-09-20T17:42:20.507Z,1537465340.507 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-20T17:42:20.507Z,1537465340.507 [Default:CheckIn:D] Running Loop=1 2018-09-20T17:42:23.664Z,1537465343.664 [Default:CheckIn:D] Stopped 2018-09-20T17:42:23.665Z,1537465343.665 [Default:CheckIn:E] Running Loop=1 2018-09-20T17:42:23.783Z,1537465343.783 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.550435 min 2018-09-20T17:42:23.785Z,1537465343.785 [Default:CheckIn:E] Stopped 2018-09-20T17:42:23.785Z,1537465343.785 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-20T17:42:23.785Z,1537465343.785 [Default:CheckIn] Stopped 2018-09-20T17:42:23.785Z,1537465343.785 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-20T17:42:23.785Z,1537465343.785 [Default:CheckIn](INFO): Running loop #2 2018-09-20T17:42:23.785Z,1537465343.785 [Default:CheckIn] Running Loop=2 2018-09-20T17:42:23.788Z,1537465343.788 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-20T17:42:23.788Z,1537465343.788 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-20T17:43:37.820Z,1537465417.820 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:43:37.820Z,1537465417.820 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2018-09-20T17:43:37.820Z,1537465417.820 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:43:37.822Z,1537465417.822 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:43:37.822Z,1537465417.822 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-09-20T17:43:37.822Z,1537465417.822 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:43:37.852Z,1537465417.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:43:37.852Z,1537465417.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:43:38.214Z,1537465418.214 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:43:38.214Z,1537465418.214 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2018-09-20T17:43:38.215Z,1537465418.215 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:43:38.215Z,1537465418.215 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-09-20T17:43:38.617Z,1537465418.617 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:43:38.622Z,1537465418.622 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:43:38.622Z,1537465418.622 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:43:38.622Z,1537465418.622 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:43:38.623Z,1537465418.623 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:43:38.623Z,1537465418.623 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:46:38.836Z,1537465598.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:46:38.836Z,1537465598.836 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2018-09-20T17:46:38.836Z,1537465598.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:46:38.838Z,1537465598.838 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:46:38.838Z,1537465598.838 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2018-09-20T17:46:38.838Z,1537465598.838 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:46:38.851Z,1537465598.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:46:38.852Z,1537465598.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:46:39.249Z,1537465599.249 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:46:39.249Z,1537465599.249 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2018-09-20T17:46:39.250Z,1537465599.250 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:46:39.250Z,1537465599.250 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2018-09-20T17:46:39.660Z,1537465599.660 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:46:39.661Z,1537465599.661 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:46:39.661Z,1537465599.661 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:46:39.662Z,1537465599.662 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:46:39.662Z,1537465599.662 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:46:39.663Z,1537465599.663 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:47:24.010Z,1537465644.010 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T17:42:23.8Z 2018-09-20T17:47:24.010Z,1537465644.010 [Default:CheckIn:Read_GPS] Stopped 2018-09-20T17:47:24.010Z,1537465644.010 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-20T17:47:33.875Z,1537465653.875 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T172822/Courier0007.lzma 2018-09-20T17:47:34.641Z,1537465654.641 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Courier0007.lzma.bak 2018-09-20T17:47:34.641Z,1537465654.641 [DataOverHttps](INFO): SBD MOMSN=8548180 2018-09-20T17:47:43.603Z,1537465663.603 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20180920T172822/Express0008.lzma 2018-09-20T17:47:44.365Z,1537465664.365 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Express0008.lzma.bak 2018-09-20T17:47:44.365Z,1537465664.365 [DataOverHttps](INFO): SBD MOMSN=8548182 2018-09-20T17:47:44.841Z,1537465664.841 [Default:CheckIn:Read_Iridium] Stopped 2018-09-20T17:47:44.841Z,1537465664.841 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-20T17:47:44.841Z,1537465664.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-20T17:48:14.641Z,1537465694.641 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T17:49:39.849Z,1537465779.849 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:49:39.849Z,1537465779.849 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2018-09-20T17:49:39.862Z,1537465779.862 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:49:39.864Z,1537465779.864 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:49:39.864Z,1537465779.864 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2018-09-20T17:49:39.864Z,1537465779.864 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:49:39.883Z,1537465779.883 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:49:39.884Z,1537465779.884 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:49:40.303Z,1537465780.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:49:40.303Z,1537465780.303 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2018-09-20T17:49:40.304Z,1537465780.304 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:49:40.304Z,1537465780.304 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2018-09-20T17:49:40.656Z,1537465780.656 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:49:40.656Z,1537465780.656 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:49:40.656Z,1537465780.656 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:49:40.657Z,1537465780.657 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:49:40.657Z,1537465780.657 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:49:40.670Z,1537465780.670 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:49:57.568Z,1537465797.568 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-20T17:49:57.568Z,1537465797.568 [NAL9602] Data Fault, FailCount= 2 2018-09-20T17:49:57.568Z,1537465797.568 [NAL9602](ERROR): Data Fault 2018-09-20T17:49:57.644Z,1537465797.644 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-20T17:49:57.959Z,1537465797.959 [NAL9602](INFO): Powering down 2018-09-20T17:49:58.810Z,1537465798.810 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-20T17:49:58.810Z,1537465798.810 [NAL9602] No Fault, FailCount= 2 2018-09-20T17:50:28.359Z,1537465828.359 [NAL9602](INFO): Powering up NAL9602 2018-09-20T17:50:28.846Z,1537465828.846 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T17:50:39.555Z,1537465839.555 [NAL9602](INFO): NAL9602 initialized 2018-09-20T17:51:27.685Z,1537465887.685 [RDI_Pathfinder](ERROR): Failed to parse: +0.00, +0.00, +0.00, 0.00,1359.92 2018-09-20T17:51:36.969Z,1537465896.969 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T17:51:50.272Z,1537465910.272 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T17:52:17.845Z,1537465937.845 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2018-09-20T17:52:40.000Z,1537465961.000 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:52:40.000Z,1537465961.000 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2018-09-20T17:52:40.000Z,1537465961.000 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:52:41.006Z,1537465961.006 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:52:41.006Z,1537465961.006 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2018-09-20T17:52:41.006Z,1537465961.006 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:52:41.032Z,1537465961.032 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:52:41.032Z,1537465961.032 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:52:41.423Z,1537465961.423 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:52:41.423Z,1537465961.423 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2018-09-20T17:52:41.424Z,1537465961.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:52:41.424Z,1537465961.424 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2018-09-20T17:52:41.794Z,1537465961.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:52:41.795Z,1537465961.795 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:52:41.795Z,1537465961.795 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:52:41.796Z,1537465961.796 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:52:41.796Z,1537465961.796 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:52:41.796Z,1537465961.796 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:52:44.260Z,1537465964.260 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2018-09-20T17:52:45.397Z,1537465965.397 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-20T17:52:45.398Z,1537465965.398 [Default:CheckIn:C.Wait] Stopped 2018-09-20T17:52:45.398Z,1537465965.398 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-20T17:52:45.398Z,1537465965.398 [Default:CheckIn:D] Running Loop=1 2018-09-20T17:52:45.806Z,1537465965.806 [Default:CheckIn:D] Stopped 2018-09-20T17:52:45.806Z,1537465965.806 [Default:CheckIn:E] Running Loop=1 2018-09-20T17:52:48.988Z,1537465968.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.919489 min 2018-09-20T17:52:48.988Z,1537465968.988 [Default:CheckIn:E] Stopped 2018-09-20T17:52:48.989Z,1537465968.989 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-20T17:52:48.989Z,1537465968.989 [Default:CheckIn] Stopped 2018-09-20T17:52:48.989Z,1537465968.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-20T17:52:48.989Z,1537465968.989 [Default:CheckIn](INFO): Running loop #3 2018-09-20T17:52:48.989Z,1537465968.989 [Default:CheckIn] Running Loop=3 2018-09-20T17:52:48.989Z,1537465968.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-20T17:52:48.989Z,1537465968.989 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-20T17:53:38.399Z,1537466018.399 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T17:55:02.714Z,1537466102.714 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-09-20T17:55:41.814Z,1537466141.814 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:55:41.814Z,1537466141.814 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2018-09-20T17:55:41.814Z,1537466141.814 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:55:41.816Z,1537466141.816 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:55:41.816Z,1537466141.816 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2018-09-20T17:55:41.816Z,1537466141.816 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:55:41.829Z,1537466141.829 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:55:41.830Z,1537466141.830 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:55:42.245Z,1537466142.245 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:55:42.245Z,1537466142.245 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2018-09-20T17:55:42.250Z,1537466142.250 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:55:42.250Z,1537466142.250 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2018-09-20T17:55:42.629Z,1537466142.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:55:42.629Z,1537466142.629 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:55:42.630Z,1537466142.630 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:55:42.630Z,1537466142.630 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:55:42.631Z,1537466142.631 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:55:42.631Z,1537466142.631 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:57:49.630Z,1537466269.630 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T17:52:48.0Z 2018-09-20T17:57:49.630Z,1537466269.630 [Default:CheckIn:Read_GPS] Stopped 2018-09-20T17:57:49.630Z,1537466269.630 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-20T17:57:54.612Z,1537466274.612 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180920T172822/Courier0010.lzma 2018-09-20T17:57:55.365Z,1537466275.365 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Courier0010.lzma.bak 2018-09-20T17:57:55.365Z,1537466275.365 [DataOverHttps](INFO): SBD MOMSN=8548223 2018-09-20T17:58:04.373Z,1537466284.373 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20180920T172822/Express0011.lzma 2018-09-20T17:58:05.136Z,1537466285.136 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Express0011.lzma.bak 2018-09-20T17:58:05.137Z,1537466285.137 [DataOverHttps](INFO): SBD MOMSN=8548225 2018-09-20T17:58:05.594Z,1537466285.594 [Default:CheckIn:Read_Iridium] Stopped 2018-09-20T17:58:05.594Z,1537466285.594 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-20T17:58:05.594Z,1537466285.594 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-20T17:58:42.861Z,1537466322.861 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:58:42.861Z,1537466322.861 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2018-09-20T17:58:42.861Z,1537466322.861 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T17:58:42.871Z,1537466322.871 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T17:58:42.871Z,1537466322.871 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2018-09-20T17:58:42.871Z,1537466322.871 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T17:58:42.897Z,1537466322.897 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T17:58:42.897Z,1537466322.897 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T17:58:43.276Z,1537466323.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T17:58:43.276Z,1537466323.276 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2018-09-20T17:58:43.277Z,1537466323.277 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T17:58:43.277Z,1537466323.277 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2018-09-20T17:58:43.685Z,1537466323.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T17:58:43.686Z,1537466323.686 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:58:43.686Z,1537466323.686 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:58:43.687Z,1537466323.687 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T17:58:43.687Z,1537466323.687 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T17:58:43.687Z,1537466323.687 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T17:58:59.641Z,1537466339.641 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2018-09-20T17:58:59.650Z,1537466339.650 [RDI_Pathfinder](ERROR): Failed to parse: :TS,18092011044218,35.0, -0.1, 0.0,1448.9,,-32768,-32768,-32768,-32768,V 2018-09-20T17:59:10.544Z,1537466350.544 [RDI_Pathfinder](ERROR): Failed to parse: 2018-09-20T18:00:41.003Z,1537466441.003 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-20T18:00:41.004Z,1537466441.004 [NAL9602] Data Fault, FailCount= 3 2018-09-20T18:00:41.004Z,1537466441.004 [NAL9602](ERROR): Data Fault 2018-09-20T18:00:41.069Z,1537466441.069 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-20T18:00:41.403Z,1537466441.403 [NAL9602](INFO): Powering down 2018-09-20T18:00:42.279Z,1537466442.279 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-20T18:00:42.279Z,1537466442.279 [NAL9602] No Fault, FailCount= 3 2018-09-20T18:01:11.515Z,1537466471.515 [NAL9602](INFO): Powering up NAL9602 2018-09-20T18:01:22.320Z,1537466482.320 [NAL9602](INFO): NAL9602 initialized 2018-09-20T18:01:43.968Z,1537466503.968 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:01:43.968Z,1537466503.968 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2018-09-20T18:01:43.968Z,1537466503.968 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:01:43.970Z,1537466503.970 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:01:43.970Z,1537466503.970 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2018-09-20T18:01:43.970Z,1537466503.970 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:01:43.996Z,1537466503.996 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:01:43.997Z,1537466503.997 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:01:44.396Z,1537466504.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:01:44.396Z,1537466504.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2018-09-20T18:01:44.397Z,1537466504.397 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:01:44.397Z,1537466504.397 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2018-09-20T18:01:44.770Z,1537466504.770 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:01:44.770Z,1537466504.770 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:01:44.770Z,1537466504.770 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:01:44.771Z,1537466504.771 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:01:44.771Z,1537466504.771 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:01:44.772Z,1537466504.772 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:03:06.055Z,1537466586.055 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-20T18:03:06.055Z,1537466586.055 [Default:CheckIn:C.Wait] Stopped 2018-09-20T18:03:06.055Z,1537466586.055 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-20T18:03:06.055Z,1537466586.055 [Default:CheckIn:D] Running Loop=1 2018-09-20T18:03:06.491Z,1537466586.491 [Default:CheckIn:D] Stopped 2018-09-20T18:03:06.491Z,1537466586.491 [Default:CheckIn:E] Running Loop=1 2018-09-20T18:03:09.658Z,1537466589.658 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.264235 min 2018-09-20T18:03:09.658Z,1537466589.658 [Default:CheckIn:E] Stopped 2018-09-20T18:03:09.658Z,1537466589.658 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-20T18:03:09.658Z,1537466589.658 [Default:CheckIn] Stopped 2018-09-20T18:03:09.658Z,1537466589.658 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-20T18:03:09.659Z,1537466589.659 [Default:CheckIn](INFO): Running loop #4 2018-09-20T18:03:09.659Z,1537466589.659 [Default:CheckIn] Running Loop=4 2018-09-20T18:03:09.659Z,1537466589.659 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-20T18:03:09.659Z,1537466589.659 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-20T18:04:45.032Z,1537466685.032 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:04:45.032Z,1537466685.032 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2018-09-20T18:04:45.033Z,1537466685.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:04:45.039Z,1537466685.039 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:04:45.039Z,1537466685.039 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2018-09-20T18:04:45.039Z,1537466685.039 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:04:45.069Z,1537466685.069 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:04:45.069Z,1537466685.069 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:04:45.436Z,1537466685.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:04:45.436Z,1537466685.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2018-09-20T18:04:45.437Z,1537466685.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:04:45.437Z,1537466685.437 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2018-09-20T18:04:45.827Z,1537466685.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:04:45.827Z,1537466685.827 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:04:45.827Z,1537466685.827 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:04:45.828Z,1537466685.828 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:04:45.828Z,1537466685.828 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:04:45.829Z,1537466685.829 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:06:59.632Z,1537466819.632 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T18:07:46.088Z,1537466866.088 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:07:46.089Z,1537466866.089 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2018-09-20T18:07:46.089Z,1537466866.089 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:07:46.095Z,1537466866.095 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:07:46.095Z,1537466866.095 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2018-09-20T18:07:46.095Z,1537466866.095 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:07:46.124Z,1537466866.124 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:07:46.125Z,1537466866.125 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:07:46.523Z,1537466866.523 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:07:46.523Z,1537466866.523 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2018-09-20T18:07:46.524Z,1537466866.524 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:07:46.524Z,1537466866.524 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2018-09-20T18:07:46.968Z,1537466866.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:07:46.969Z,1537466866.969 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:07:46.969Z,1537466866.969 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:07:46.970Z,1537466866.970 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:07:46.971Z,1537466866.971 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:07:46.971Z,1537466866.971 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:08:10.113Z,1537466890.113 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T18:03:09.7Z 2018-09-20T18:08:10.113Z,1537466890.113 [Default:CheckIn:Read_GPS] Stopped 2018-09-20T18:08:10.113Z,1537466890.113 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-20T18:08:15.153Z,1537466895.153 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T172822/Courier0013.lzma 2018-09-20T18:08:15.921Z,1537466895.921 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Courier0013.lzma.bak 2018-09-20T18:08:15.921Z,1537466895.921 [DataOverHttps](INFO): SBD MOMSN=8548273 2018-09-20T18:08:24.903Z,1537466904.903 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20180920T172822/Express0014.lzma 2018-09-20T18:08:25.681Z,1537466905.681 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Express0014.lzma.bak 2018-09-20T18:08:25.681Z,1537466905.681 [DataOverHttps](INFO): SBD MOMSN=8548275 2018-09-20T18:08:26.151Z,1537466906.151 [Default:CheckIn:Read_Iridium] Stopped 2018-09-20T18:08:26.151Z,1537466906.151 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-20T18:08:26.151Z,1537466906.151 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-20T18:08:42.794Z,1537466922.794 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T18:08:53.993Z,1537466933.993 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2018-09-20T18:09:49.273Z,1537466989.273 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2018-09-20T18:09:49.273Z,1537466989.273 [RDI_Pathfinder](ERROR): Failed to parse: :BD,+0.00, +0.0 +0.00, 0.00,2461.73 2018-09-20T18:10:09.739Z,1537467009.739 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2018-09-20T18:10:32.369Z,1537467032.369 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2018-09-20T18:10:47.270Z,1537467047.270 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:10:47.270Z,1537467047.270 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2018-09-20T18:10:47.270Z,1537467047.270 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:10:47.273Z,1537467047.273 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:10:47.273Z,1537467047.273 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2018-09-20T18:10:47.273Z,1537467047.273 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:10:47.311Z,1537467047.311 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:10:47.311Z,1537467047.311 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:10:47.715Z,1537467047.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:10:47.715Z,1537467047.715 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2018-09-20T18:10:47.715Z,1537467047.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:10:47.716Z,1537467047.716 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2018-09-20T18:10:48.058Z,1537467048.058 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:10:48.059Z,1537467048.059 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:10:48.059Z,1537467048.059 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:10:48.060Z,1537467048.060 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:10:48.060Z,1537467048.060 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:10:48.060Z,1537467048.060 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:11:23.612Z,1537467083.612 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-20T18:11:23.612Z,1537467083.612 [NAL9602] Data Fault, FailCount= 4 2018-09-20T18:11:23.612Z,1537467083.612 [NAL9602](ERROR): Data Fault 2018-09-20T18:11:23.695Z,1537467083.695 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-20T18:11:24.015Z,1537467084.015 [NAL9602](INFO): Powering down 2018-09-20T18:11:24.868Z,1537467084.868 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-20T18:11:24.868Z,1537467084.868 [NAL9602] No Fault, FailCount= 4 2018-09-20T18:11:54.143Z,1537467114.143 [NAL9602](INFO): Powering up NAL9602 2018-09-20T18:12:05.345Z,1537467125.345 [NAL9602](INFO): NAL9602 initialized 2018-09-20T18:12:50.647Z,1537467170.647 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-09-20T18:13:10.626Z,1537467190.626 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2018-09-20T18:13:10.626Z,1537467190.626 [RDI_Pathfinder](ERROR): Failed to parse: :TS,18092011185318,35.0, -0.1, 0.0, 0 2018-09-20T18:13:26.677Z,1537467206.677 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-20T18:13:26.682Z,1537467206.682 [Default:CheckIn:C.Wait] Stopped 2018-09-20T18:13:26.682Z,1537467206.682 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-20T18:13:26.682Z,1537467206.682 [Default:CheckIn:D] Running Loop=1 2018-09-20T18:13:27.049Z,1537467207.049 [Default:CheckIn:D] Stopped 2018-09-20T18:13:27.049Z,1537467207.049 [Default:CheckIn:E] Running Loop=1 2018-09-20T18:13:30.208Z,1537467210.208 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.606877 min 2018-09-20T18:13:30.209Z,1537467210.209 [Default:CheckIn:E] Stopped 2018-09-20T18:13:30.209Z,1537467210.209 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-20T18:13:30.209Z,1537467210.209 [Default:CheckIn] Stopped 2018-09-20T18:13:30.209Z,1537467210.209 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-20T18:13:30.246Z,1537467210.246 [Default:CheckIn](INFO): Running loop #5 2018-09-20T18:13:30.246Z,1537467210.246 [Default:CheckIn] Running Loop=5 2018-09-20T18:13:30.246Z,1537467210.246 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-20T18:13:30.247Z,1537467210.247 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-20T18:13:48.351Z,1537467228.351 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:13:48.351Z,1537467228.351 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2018-09-20T18:13:48.352Z,1537467228.352 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:13:48.358Z,1537467228.358 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:13:48.358Z,1537467228.358 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2018-09-20T18:13:48.358Z,1537467228.358 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:13:48.383Z,1537467228.383 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:13:48.383Z,1537467228.383 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:13:48.755Z,1537467228.755 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:13:48.755Z,1537467228.755 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2018-09-20T18:13:48.756Z,1537467228.756 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:13:48.756Z,1537467228.756 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2018-09-20T18:13:49.143Z,1537467229.143 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:13:49.144Z,1537467229.144 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:13:49.144Z,1537467229.144 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:13:49.144Z,1537467229.144 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:13:49.145Z,1537467229.145 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:13:49.145Z,1537467229.145 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:14:39.634Z,1537467279.634 [RDI_Pathfinder](ERROR): only read 3 of 5 data items 2018-09-20T18:14:39.634Z,1537467279.634 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-3768,-32768,V 2018-09-20T18:15:06.639Z,1537467306.639 [CommandLine](IMPORTANT): got command failComponent 2018-09-20T18:15:06.640Z,1537467306.639 [CommandLine](IMPORTANT): Failed components: 2018-09-20T18:15:06.640Z,1537467306.640 [CommandLine](IMPORTANT): No failed Components. 2018-09-20T18:16:01.069Z,1537467361.069 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage 2018-09-20T18:16:01.069Z,1537467361.069 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage 16.254303 V 2018-09-20T18:16:22.667Z,1537467382.667 [CommandLine](IMPORTANT): got command get platform_battery_voltage 2018-09-20T18:16:22.668Z,1537467382.668 [CommandLine](FAULT): Element has no value 2018-09-20T18:16:49.408Z,1537467409.408 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:16:49.408Z,1537467409.408 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2018-09-20T18:16:49.409Z,1537467409.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:16:49.410Z,1537467409.410 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:16:49.411Z,1537467409.411 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2018-09-20T18:16:49.411Z,1537467409.411 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:16:49.439Z,1537467409.439 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:16:49.439Z,1537467409.439 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:16:49.798Z,1537467409.798 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:16:49.799Z,1537467409.799 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2018-09-20T18:16:49.799Z,1537467409.799 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:16:49.799Z,1537467409.799 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2018-09-20T18:16:50.178Z,1537467410.178 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:16:50.178Z,1537467410.178 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:16:50.179Z,1537467410.179 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:16:50.179Z,1537467410.179 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:16:50.180Z,1537467410.180 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:16:50.180Z,1537467410.180 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:17:38.621Z,1537467458.621 [RDI_Pathfinder](ERROR): only read 3 of 5 data items 2018-09-20T18:17:38.621Z,1537467458.621 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-3268,-32768,-32768,V 2018-09-20T18:17:46.947Z,1537467466.947 [CommandLine](IMPORTANT): got command report touch platform_battery_voltage 2018-09-20T18:18:23.771Z,1537467503.771 [CommandLine](IMPORTANT): got command report touch BPC1.platform_battery_voltage 2018-09-20T18:18:30.624Z,1537467510.624 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T18:13:30.2Z 2018-09-20T18:18:30.624Z,1537467510.624 [Default:CheckIn:Read_GPS] Stopped 2018-09-20T18:18:30.624Z,1537467510.624 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-20T18:18:35.989Z,1537467515.989 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180920T172822/Courier0016.lzma 2018-09-20T18:18:36.707Z,1537467516.707 [CommandLine](IMPORTANT): got command get platform_battery_voltage 2018-09-20T18:18:36.708Z,1537467516.708 [CommandLine](FAULT): Element has no value 2018-09-20T18:18:36.773Z,1537467516.773 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Courier0016.lzma.bak 2018-09-20T18:18:36.773Z,1537467516.773 [DataOverHttps](INFO): SBD MOMSN=8548323 2018-09-20T18:18:45.899Z,1537467525.899 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20180920T172822/Express0017.lzma 2018-09-20T18:18:46.689Z,1537467526.689 [DataOverHttps](INFO): Moved sent file to Logs/20180920T172822/Express0017.lzma.bak 2018-09-20T18:18:46.689Z,1537467526.689 [DataOverHttps](INFO): SBD MOMSN=8548325 2018-09-20T18:18:47.158Z,1537467527.158 [Default:CheckIn:Read_Iridium] Stopped 2018-09-20T18:18:47.158Z,1537467527.158 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-20T18:18:47.158Z,1537467527.158 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-20T18:18:53.882Z,1537467533.882 [CommandLine](IMPORTANT): got command show best platform_battery_voltage 2018-09-20T18:18:53.883Z,1537467533.883 [CommandLine](IMPORTANT): platform_battery_voltage best is null 2018-09-20T18:19:51.020Z,1537467591.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:19:51.020Z,1537467591.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2018-09-20T18:19:51.020Z,1537467591.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:19:51.022Z,1537467591.022 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:19:51.022Z,1537467591.022 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17 2018-09-20T18:19:51.022Z,1537467591.022 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:19:51.035Z,1537467591.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:19:51.035Z,1537467591.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:19:51.208Z,1537467591.208 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:19:51.208Z,1537467591.208 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2018-09-20T18:19:51.209Z,1537467591.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:19:51.209Z,1537467591.209 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17 2018-09-20T18:19:51.515Z,1537467591.515 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:19:51.516Z,1537467591.516 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:19:51.516Z,1537467591.516 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:19:51.516Z,1537467591.516 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:19:51.517Z,1537467591.517 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:19:51.517Z,1537467591.517 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:21:52.523Z,1537467712.523 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffe, 14.893, -0.063, 0.000 2018-09-20T18:22:07.295Z,1537467727.295 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-20T18:22:07.296Z,1537467727.296 [NAL9602] Data Fault, FailCount= 5 2018-09-20T18:22:07.296Z,1537467727.296 [NAL9602](ERROR): Data Fault 2018-09-20T18:22:07.392Z,1537467727.392 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-20T18:22:07.392Z,1537467727.392 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-09-20T18:22:07.695Z,1537467727.695 [NAL9602](INFO): Powering down 2018-09-20T18:22:07.770Z,1537467727.770 [CommandLine](FAULT): Scheduling is paused 2018-09-20T18:22:07.771Z,1537467727.771 [CBIT](INFO): Critical error at 20180920T182207 2018-09-20T18:22:07.771Z,1537467727.771 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-09-20T18:22:51.840Z,1537467771.840 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:22:51.840Z,1537467771.840 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2018-09-20T18:22:51.840Z,1537467771.840 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:22:51.842Z,1537467771.842 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:22:51.842Z,1537467771.842 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18 2018-09-20T18:22:51.842Z,1537467771.842 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:22:51.855Z,1537467771.855 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:22:51.856Z,1537467771.856 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:22:52.249Z,1537467772.249 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:22:52.249Z,1537467772.249 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2018-09-20T18:22:52.250Z,1537467772.250 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:22:52.251Z,1537467772.251 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18 2018-09-20T18:22:52.619Z,1537467772.619 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:22:52.619Z,1537467772.619 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:22:52.619Z,1537467772.619 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:22:52.620Z,1537467772.620 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:22:52.620Z,1537467772.620 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:22:52.621Z,1537467772.621 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:23:03.108Z,1537467783.108 [CommandLine](IMPORTANT): got command show best platform_battery_charge 2018-09-20T18:23:03.108Z,1537467783.108 [CommandLine](IMPORTANT): platform_battery_charge best is null 2018-09-20T18:23:47.805Z,1537467827.805 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-20T18:23:47.806Z,1537467827.806 [Default:CheckIn:C.Wait] Stopped 2018-09-20T18:23:47.806Z,1537467827.806 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-20T18:23:47.806Z,1537467827.806 [Default:CheckIn:D] Running Loop=1 2018-09-20T18:23:48.238Z,1537467828.238 [Default:CheckIn:D] Stopped 2018-09-20T18:23:48.239Z,1537467828.239 [Default:CheckIn:E] Running Loop=1 2018-09-20T18:23:51.400Z,1537467831.400 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.959749 min 2018-09-20T18:23:51.400Z,1537467831.400 [Default:CheckIn:E] Stopped 2018-09-20T18:23:51.400Z,1537467831.400 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-20T18:23:51.400Z,1537467831.400 [Default:CheckIn] Stopped 2018-09-20T18:23:51.400Z,1537467831.400 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-20T18:23:51.400Z,1537467831.400 [Default:CheckIn](INFO): Running loop #6 2018-09-20T18:23:51.401Z,1537467831.401 [Default:CheckIn] Running Loop=6 2018-09-20T18:23:51.401Z,1537467831.401 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-20T18:23:51.401Z,1537467831.401 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-20T18:24:07.513Z,1537467847.513 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-20T18:24:07.513Z,1537467847.513 [NAL9602] No Fault, FailCount= 5 2018-09-20T18:24:07.832Z,1537467847.832 [NAL9602](INFO): Powering up NAL9602 2018-09-20T18:24:19.032Z,1537467859.032 [NAL9602](INFO): NAL9602 initialized 2018-09-20T18:25:35.807Z,1537467935.807 [CommandLine](IMPORTANT): got command show variable threshold 2018-09-20T18:25:35.823Z,1537467935.823 [CommandLine](IMPORTANT): CBIT.humidityThreshold (percent) 2018-09-20T18:25:35.823Z,1537467935.823 [CommandLine](IMPORTANT): CBIT.pressureThreshold (pound_per_square_inch) 2018-09-20T18:25:35.824Z,1537467935.824 [CommandLine](IMPORTANT): CBIT.tempThreshold (fahrenheit) 2018-09-20T18:25:35.825Z,1537467935.825 [CommandLine](IMPORTANT): CBIT.battTempThreshold (celsius) 2018-09-20T18:25:35.825Z,1537467935.825 [CommandLine](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere) 2018-09-20T18:25:35.826Z,1537467935.826 [CommandLine](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere) 2018-09-20T18:25:35.826Z,1537467935.826 [CommandLine](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere) 2018-09-20T18:25:35.827Z,1537467935.827 [CommandLine](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere) 2018-09-20T18:25:35.827Z,1537467935.827 [CommandLine](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere) 2018-09-20T18:25:35.828Z,1537467935.828 [CommandLine](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere) 2018-09-20T18:25:35.828Z,1537467935.828 [CommandLine](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere) 2018-09-20T18:25:35.828Z,1537467935.828 [CommandLine](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere) 2018-09-20T18:25:35.830Z,1537467935.830 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold (ampere_hour) 2018-09-20T18:25:35.831Z,1537467935.831 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold (volt) 2018-09-20T18:25:35.845Z,1537467935.845 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold (meter) 2018-09-20T18:25:35.849Z,1537467935.849 [CommandLine](IMPORTANT): StratificationFrontDetector.threshold (celsius) 2018-09-20T18:25:35.850Z,1537467935.850 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.tempHoriGradThreshold (celsius) 2018-09-20T18:25:35.850Z,1537467935.850 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.cntThreshold (count) 2018-09-20T18:25:35.909Z,1537467935.909 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold (count) 2018-09-20T18:25:35.958Z,1537467935.958 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count) 2018-09-20T18:25:52.836Z,1537467952.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:25:52.836Z,1537467952.836 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2018-09-20T18:25:52.836Z,1537467952.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-20T18:25:52.842Z,1537467952.842 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-20T18:25:52.842Z,1537467952.842 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19 2018-09-20T18:25:52.842Z,1537467952.842 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-20T18:25:52.868Z,1537467952.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-20T18:25:52.868Z,1537467952.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-20T18:25:53.275Z,1537467953.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-20T18:25:53.275Z,1537467953.275 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2018-09-20T18:25:53.276Z,1537467953.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-20T18:25:53.276Z,1537467953.276 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19 2018-09-20T18:25:53.662Z,1537467953.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-20T18:25:53.663Z,1537467953.663 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:25:53.663Z,1537467953.663 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:25:53.663Z,1537467953.663 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-20T18:25:53.664Z,1537467953.664 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-20T18:25:53.664Z,1537467953.664 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-20T18:26:06.377Z,1537467966.377 [CommandLine](IMPORTANT): got command configSet IBIT.batteryVoltageThreshold 17.000000 volt 2018-09-20T18:26:12.480Z,1537467972.480 [CommandLine](IMPORTANT): got command configSet IBIT.batteryVoltageThreshold 17.000000 volt persist 2018-09-20T18:28:35.456Z,1537468115.456 [CommandLine](IMPORTANT): got command restart application 2018-09-20T18:28:36.465Z,1537468116.465 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-20T18:28:36.465Z,1537468116.465 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:36.606Z,1537468116.606 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-09-20T18:28:36.606Z,1537468116.606 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:36.606Z,1537468116.606 [CommandLine](INFO): Join timeout helper Thread ID is 990 2018-09-20T18:28:36.610Z,1537468116.610 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-09-20T18:28:36.610Z,1537468116.610 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:36.610Z,1537468116.610 [NavChartDb](INFO): Join timeout helper Thread ID is 991 2018-09-20T18:28:36.934Z,1537468116.934 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-20T18:28:36.934Z,1537468116.934 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:36.950Z,1537468116.950 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-09-20T18:28:36.950Z,1537468116.950 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:36.950Z,1537468116.950 [Radio_Surface](INFO): Join timeout helper Thread ID is 992 2018-09-20T18:28:37.038Z,1537468117.038 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-20T18:28:37.038Z,1537468117.038 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.054Z,1537468117.054 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2018-09-20T18:28:37.054Z,1537468117.054 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.054Z,1537468117.054 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 993 2018-09-20T18:28:37.386Z,1537468117.386 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-20T18:28:37.386Z,1537468117.386 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-09-20T18:28:37.387Z,1537468117.387 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.398Z,1537468117.398 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-09-20T18:28:37.398Z,1537468117.398 [logger ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.398Z,1537468117.398 [logger](INFO): Join timeout helper Thread ID is 994 2018-09-20T18:28:37.434Z,1537468117.434 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-20T18:28:37.434Z,1537468117.434 [logger ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.435Z,1537468117.435 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-09-20T18:28:37.435Z,1537468117.435 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.436Z,1537468117.436 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-09-20T18:28:37.436Z,1537468117.436 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.436Z,1537468117.436 [controlThread](INFO): Join timeout helper Thread ID is 995 2018-09-20T18:28:37.465Z,1537468117.465 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-20T18:28:37.466Z,1537468117.466 [controlThread](DEBUG): Uninitializing ControlThread 2018-09-20T18:28:37.466Z,1537468117.466 [Aanderaa_O2](INFO): Powering down 2018-09-20T18:28:37.467Z,1537468117.467 [AHRS_M2](INFO): Powering down 2018-09-20T18:28:37.539Z,1537468117.539 [NAL9602](INFO): Powering down 2018-09-20T18:28:37.610Z,1537468117.610 [RDI_Pathfinder](INFO): Powering down 2018-09-20T18:28:37.611Z,1537468117.611 [RDI_PathfinderUp](INFO): Powering down 2018-09-20T18:28:37.613Z,1537468117.613 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-09-20T18:28:37.614Z,1537468117.614 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-09-20T18:28:37.614Z,1537468117.614 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-09-20T18:28:37.615Z,1537468117.615 [MissionManager](INFO): Uninitializing Mission Default 2018-09-20T18:28:37.615Z,1537468117.615 [Default] Stopped 2018-09-20T18:28:37.615Z,1537468117.615 [Default](DEBUG): Aggregate::uninitialize Default 2018-09-20T18:28:37.615Z,1537468117.615 [Default:B.GoToSurface] Stopped 2018-09-20T18:28:37.615Z,1537468117.615 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-09-20T18:28:37.615Z,1537468117.615 [Default:CheckIn] Stopped 2018-09-20T18:28:37.616Z,1537468117.616 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-20T18:28:37.616Z,1537468117.616 [Default:CheckIn:Read_GPS] Stopped 2018-09-20T18:28:37.619Z,1537468117.619 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-09-20T18:28:37.619Z,1537468117.619 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-09-20T18:28:37.620Z,1537468117.620 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-09-20T18:28:37.620Z,1537468117.620 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-09-20T18:28:37.620Z,1537468117.620 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-09-20T18:28:37.620Z,1537468117.620 [BuoyancyServo](INFO): Powering down 2018-09-20T18:28:37.634Z,1537468117.634 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-09-20T18:28:37.634Z,1537468117.634 [ElevatorServo](INFO): Powering down 2018-09-20T18:28:37.635Z,1537468117.635 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-20T18:28:37.635Z,1537468117.635 [MassServo](INFO): Powering down 2018-09-20T18:28:37.636Z,1537468117.636 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-09-20T18:28:37.636Z,1537468117.636 [RudderServo](INFO): Powering down 2018-09-20T18:28:37.637Z,1537468117.637 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-09-20T18:28:37.637Z,1537468117.637 [ThrusterServo](INFO): Powering down 2018-09-20T18:28:37.638Z,1537468117.638 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-09-20T18:28:37.638Z,1537468117.638 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-09-20T18:28:37.639Z,1537468117.639 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-09-20T18:28:37.640Z,1537468117.640 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.700Z,1537468117.700 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.762Z,1537468117.762 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.805Z,1537468117.805 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-09-20T18:28:37.865Z,1537468117.865 [logger ThreadHandler](INFO): Thread cancelled.