2019-05-20T17:29:07.592Z,1558373347.592 [Supervisor](DEBUG): Initializing supervisor. 2019-05-20T17:29:07.595Z,1558373347.595 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-05-20T17:29:07.596Z,1558373347.596 [SyncHandler](INFO): Protected caller Thread ID is 968 2019-05-20T17:29:07.596Z,1558373347.596 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-05-20T17:29:07.597Z,1558373347.597 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-05-20T17:29:07.598Z,1558373347.598 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 969 2019-05-20T17:29:07.601Z,1558373347.601 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-05-20T17:29:07.614Z,1558373347.614 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-05-20T17:29:07.615Z,1558373347.615 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-05-20T17:29:07.615Z,1558373347.615 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 970 2019-05-20T17:29:07.616Z,1558373347.616 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-05-20T17:29:07.617Z,1558373347.617 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-05-20T17:29:07.617Z,1558373347.617 [logger ThreadHandler](INFO): Protected caller Thread ID is 971 2019-05-20T17:29:07.619Z,1558373347.619 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-05-20T17:29:07.620Z,1558373347.620 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-05-20T17:29:07.621Z,1558373347.621 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-05-20T17:29:07.716Z,1558373347.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-05-20T17:29:07.717Z,1558373347.717 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-05-20T17:29:07.918Z,1558373347.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-05-20T17:29:07.919Z,1558373347.919 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-05-20T17:29:08.055Z,1558373348.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-05-20T17:29:08.056Z,1558373348.056 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-05-20T17:29:08.676Z,1558373348.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-05-20T17:29:08.676Z,1558373348.676 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-05-20T17:29:09.131Z,1558373349.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-05-20T17:29:09.132Z,1558373349.132 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-05-20T17:29:09.600Z,1558373349.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-05-20T17:29:09.601Z,1558373349.601 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-05-20T17:29:09.906Z,1558373349.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-05-20T17:29:09.907Z,1558373349.907 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-05-20T17:29:10.240Z,1558373350.240 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-05-20T17:29:10.241Z,1558373350.241 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-05-20T17:29:10.635Z,1558373350.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-05-20T17:29:10.636Z,1558373350.636 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-05-20T17:29:10.787Z,1558373350.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-05-20T17:29:10.787Z,1558373350.787 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-05-20T17:29:10.892Z,1558373350.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-05-20T17:29:10.893Z,1558373350.893 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-05-20T17:29:10.973Z,1558373350.973 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-05-20T17:29:11.075Z,1558373351.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-05-20T17:29:11.075Z,1558373351.075 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-05-20T17:29:11.271Z,1558373351.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-05-20T17:29:11.271Z,1558373351.271 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-05-20T17:29:11.478Z,1558373351.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-05-20T17:29:11.480Z,1558373351.480 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-05-20T17:29:11.481Z,1558373351.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-05-20T17:29:11.564Z,1558373351.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-05-20T17:29:11.791Z,1558373351.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-05-20T17:29:11.792Z,1558373351.792 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-05-20T17:29:11.886Z,1558373351.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-05-20T17:29:12.051Z,1558373352.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-05-20T17:29:12.254Z,1558373352.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-05-20T17:29:12.339Z,1558373352.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-05-20T17:29:12.437Z,1558373352.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-05-20T17:29:12.529Z,1558373352.529 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-05-20T17:29:12.682Z,1558373352.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-05-20T17:29:12.786Z,1558373352.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-05-20T17:29:12.886Z,1558373352.886 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2019-05-20T17:29:12.886Z,1558373352.886 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-05-20T17:29:12.897Z,1558373352.897 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-05-20T17:29:12.997Z,1558373352.997 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-05-20T17:29:12.998Z,1558373352.998 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-05-20T17:29:13.134Z,1558373353.134 [BuoyancyServo] Loaded 2019-05-20T17:29:13.134Z,1558373353.134 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-05-20T17:29:13.149Z,1558373353.149 [ElevatorServo] Loaded 2019-05-20T17:29:13.150Z,1558373353.150 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-05-20T17:29:13.164Z,1558373353.164 [MassServo] Loaded 2019-05-20T17:29:13.165Z,1558373353.165 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-05-20T17:29:13.180Z,1558373353.180 [RudderServo] Loaded 2019-05-20T17:29:13.180Z,1558373353.180 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-05-20T17:29:13.195Z,1558373353.195 [ThrusterServo] Loaded 2019-05-20T17:29:13.195Z,1558373353.195 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-05-20T17:29:13.196Z,1558373353.196 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-05-20T17:29:13.196Z,1558373353.196 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-05-20T17:29:13.334Z,1558373353.334 [SBIT](DEBUG): Construct Startup Built In Test. 2019-05-20T17:29:13.364Z,1558373353.364 [SBIT] Loaded 2019-05-20T17:29:13.365Z,1558373353.365 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-05-20T17:29:13.365Z,1558373353.365 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-05-20T17:29:13.393Z,1558373353.393 [IBIT] Loaded 2019-05-20T17:29:13.393Z,1558373353.393 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-05-20T17:29:13.397Z,1558373353.397 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-05-20T17:29:13.791Z,1558373353.791 [CBIT] Loaded 2019-05-20T17:29:13.791Z,1558373353.791 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-05-20T17:29:13.792Z,1558373353.792 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-05-20T17:29:13.792Z,1558373353.792 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-05-20T17:29:14.141Z,1558373354.141 [ESPComponent] Loaded 2019-05-20T17:29:14.142Z,1558373354.142 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-05-20T17:29:14.210Z,1558373354.210 [PAR_Licor] Loaded 2019-05-20T17:29:14.210Z,1558373354.210 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-05-20T17:29:14.259Z,1558373354.259 [WetLabsBB2FL] Loaded 2019-05-20T17:29:14.260Z,1558373354.260 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-05-20T17:29:14.261Z,1558373354.261 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4066A4E0 2019-05-20T17:29:14.261Z,1558373354.261 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1050 2019-05-20T17:29:14.262Z,1558373354.262 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-05-20T17:29:14.262Z,1558373354.262 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-05-20T17:29:14.423Z,1558373354.423 [DepthRateCalculator] Loaded 2019-05-20T17:29:14.424Z,1558373354.424 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-05-20T17:29:14.429Z,1558373354.429 [PitchRateCalculator] Loaded 2019-05-20T17:29:14.430Z,1558373354.430 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-05-20T17:29:14.486Z,1558373354.486 [SpeedCalculator] Loaded 2019-05-20T17:29:14.486Z,1558373354.486 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-05-20T17:29:14.525Z,1558373354.525 [TempGradientCalculator] Loaded 2019-05-20T17:29:14.525Z,1558373354.525 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-05-20T17:29:14.531Z,1558373354.531 [YawRateCalculator] Loaded 2019-05-20T17:29:14.531Z,1558373354.531 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-05-20T17:29:14.565Z,1558373354.565 [ElevatorOffsetCalculator] Loaded 2019-05-20T17:29:14.565Z,1558373354.565 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-05-20T17:29:14.566Z,1558373354.566 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-05-20T17:29:14.566Z,1558373354.566 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-05-20T17:29:14.599Z,1558373354.599 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-05-20T17:29:14.600Z,1558373354.600 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-05-20T17:29:14.704Z,1558373354.704 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-05-20T17:29:14.704Z,1558373354.704 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-05-20T17:29:14.725Z,1558373354.725 [NavChart] Loaded 2019-05-20T17:29:14.726Z,1558373354.726 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-05-20T17:29:14.730Z,1558373354.730 [UniversalFixResidualReporter] Loaded 2019-05-20T17:29:14.730Z,1558373354.730 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-05-20T17:29:14.730Z,1558373354.730 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-05-20T17:29:14.731Z,1558373354.731 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-05-20T17:29:15.086Z,1558373355.086 [AHRS_M2] Loaded 2019-05-20T17:29:15.087Z,1558373355.087 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-05-20T17:29:15.166Z,1558373355.166 [DataOverHttps] Loaded 2019-05-20T17:29:15.166Z,1558373355.166 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-05-20T17:29:15.167Z,1558373355.167 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409434E0 2019-05-20T17:29:15.167Z,1558373355.167 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1051 2019-05-20T17:29:15.181Z,1558373355.181 [Depth_Keller] Loaded 2019-05-20T17:29:15.181Z,1558373355.181 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-05-20T17:29:15.274Z,1558373355.274 [NAL9602] Loaded 2019-05-20T17:29:15.275Z,1558373355.275 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-05-20T17:29:15.280Z,1558373355.280 [Onboard] Loaded 2019-05-20T17:29:15.280Z,1558373355.280 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-05-20T17:29:15.284Z,1558373355.284 [Radio_Surface] Loaded 2019-05-20T17:29:15.284Z,1558373355.284 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-05-20T17:29:15.285Z,1558373355.285 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409734E0 2019-05-20T17:29:15.285Z,1558373355.285 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1052 2019-05-20T17:29:15.413Z,1558373355.413 [DAT] Loaded 2019-05-20T17:29:15.414Z,1558373355.414 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-05-20T17:29:16.711Z,1558373356.711 [BPC1] Loaded 2019-05-20T17:29:16.712Z,1558373356.712 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-05-20T17:29:16.712Z,1558373356.712 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-05-20T17:29:16.713Z,1558373356.713 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-05-20T17:29:16.783Z,1558373356.783 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-05-20T17:29:16.783Z,1558373356.783 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-05-20T17:29:16.851Z,1558373356.851 [VerticalControl](DEBUG): Construct VerticalControl. 2019-05-20T17:29:16.937Z,1558373356.937 [VerticalControl] Loaded 2019-05-20T17:29:16.938Z,1558373356.938 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-05-20T17:29:16.938Z,1558373356.938 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-05-20T17:29:16.996Z,1558373356.996 [HorizontalControl] Loaded 2019-05-20T17:29:16.997Z,1558373356.997 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-05-20T17:29:16.997Z,1558373356.997 [SpeedControl](DEBUG): Construct SpeedControl. 2019-05-20T17:29:16.999Z,1558373356.999 [SpeedControl] Loaded 2019-05-20T17:29:16.999Z,1558373357.000 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-05-20T17:29:17.000Z,1558373357.000 [LoopControl](DEBUG): Construct LoopControl. 2019-05-20T17:29:17.001Z,1558373357.001 [LoopControl] Loaded 2019-05-20T17:29:17.001Z,1558373357.001 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-05-20T17:29:17.001Z,1558373357.001 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-05-20T17:29:17.002Z,1558373357.002 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-05-20T17:29:17.015Z,1558373357.015 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-05-20T17:29:17.016Z,1558373357.016 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-05-20T17:29:17.313Z,1558373357.313 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-05-20T17:29:17.317Z,1558373357.317 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-05-20T17:29:17.317Z,1558373357.317 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-05-20T17:29:17.324Z,1558373357.324 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-05-20T17:29:17.325Z,1558373357.325 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AAE4E0 2019-05-20T17:29:17.326Z,1558373357.326 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1053 2019-05-20T17:29:17.330Z,1558373357.330 [Supervisor](INFO): Main Thread ID is 807 2019-05-20T17:29:17.330Z,1558373357.330 [Supervisor](DEBUG): Running supervisor. 2019-05-20T17:29:17.331Z,1558373357.331 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1054 2019-05-20T17:29:17.334Z,1558373357.334 [controlThread ThreadHandler](INFO): Handler Thread ID is 1055 2019-05-20T17:29:17.334Z,1558373357.334 [controlThread](DEBUG): Initializing ControlThread 2019-05-20T17:29:17.336Z,1558373357.336 [SBIT](INFO): Initialize SBIT Component. 2019-05-20T17:29:17.336Z,1558373357.336 [SBIT](IMPORTANT): git: 2019-05-20 2019-05-20T17:29:17.336Z,1558373357.336 [SBIT](INFO): git hash: 2a8aa54ec5933316883a1fda1f3ce53eb72b62ac 2019-05-20T17:29:17.337Z,1558373357.337 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-05-20T17:29:17.338Z,1558373357.338 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-05-20T17:29:17.339Z,1558373357.339 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-05-20T17:29:17.340Z,1558373357.340 [IBIT](INFO): Initialize IBIT Component. 2019-05-20T17:29:17.340Z,1558373357.340 [CBIT](DEBUG): Initialize CBIT Component. 2019-05-20T17:29:17.341Z,1558373357.341 [logger ThreadHandler](INFO): Handler Thread ID is 1056 2019-05-20T17:29:17.351Z,1558373357.351 [CBIT](DEBUG): Initialized mux pins. 2019-05-20T17:29:17.351Z,1558373357.351 [CBIT](DEBUG): Initializing the watchdog timer. 2019-05-20T17:29:17.360Z,1558373357.360 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1057 2019-05-20T17:29:17.361Z,1558373357.361 [WetLabsBB2FL](INFO): Powering down 2019-05-20T17:29:17.376Z,1558373357.376 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-05-20T17:29:17.376Z,1558373357.376 [CBIT](DEBUG): Initializing heartbeat. 2019-05-20T17:29:17.392Z,1558373357.392 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1058 2019-05-20T17:29:17.393Z,1558373357.393 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-05-20T17:29:17.404Z,1558373357.404 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1059 2019-05-20T17:29:17.424Z,1558373357.424 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1060 2019-05-20T17:29:17.427Z,1558373357.427 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-05-20T17:29:17.427Z,1558373357.427 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-05-20T17:29:17.427Z,1558373357.427 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-05-20T17:29:17.427Z,1558373357.427 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-05-20T17:29:17.427Z,1558373357.427 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-05-20T17:29:17.428Z,1558373357.428 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-05-20T17:29:17.428Z,1558373357.428 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-05-20T17:29:17.428Z,1558373357.428 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-05-20T17:29:17.428Z,1558373357.428 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-05-20T17:29:17.428Z,1558373357.428 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-05-20T17:29:17.428Z,1558373357.428 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-05-20T17:29:17.429Z,1558373357.429 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-05-20T17:29:17.429Z,1558373357.429 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-05-20T17:29:17.429Z,1558373357.429 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-05-20T17:29:17.429Z,1558373357.429 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-05-20T17:29:17.429Z,1558373357.429 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-05-20T17:29:17.447Z,1558373357.447 [CBIT](DEBUG): Deactivating GF circuits. 2019-05-20T17:29:17.447Z,1558373357.447 [CBIT](DEBUG): Deactivating emergency mode. 2019-05-20T17:29:17.483Z,1558373357.483 [CBIT](DEBUG): Backplane powered. 2019-05-20T17:29:17.488Z,1558373357.488 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-05-20T17:29:17.488Z,1558373357.488 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-05-20T17:29:17.488Z,1558373357.488 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-05-20T17:29:17.489Z,1558373357.489 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-05-20T17:29:17.489Z,1558373357.489 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-05-20T17:29:17.489Z,1558373357.489 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-05-20T17:29:17.491Z,1558373357.491 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-05-20T17:29:17.491Z,1558373357.491 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-05-20T17:29:17.501Z,1558373357.501 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-05-20T17:29:17.502Z,1558373357.502 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-05-20T17:29:17.503Z,1558373357.503 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-05-20T17:29:17.512Z,1558373357.512 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-05-20T17:29:17.512Z,1558373357.512 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-05-20T17:29:17.547Z,1558373357.547 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-20T17:29:17.575Z,1558373357.575 [MissionManager](DEBUG): 2019-05-20T17:29:17.588Z,1558373357.588 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-05-20T17:29:17.678Z,1558373357.678 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-05-20T17:29:17.703Z,1558373357.703 [Default:A.Wait](DEBUG): Construct Wait. 2019-05-20T17:29:17.705Z,1558373357.705 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-20T17:29:17.729Z,1558373357.729 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-05-20T17:29:17.764Z,1558373357.764 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-05-20T17:29:17.769Z,1558373357.769 [Default:E.Execute](DEBUG): Construct Execute. 2019-05-20T17:29:17.789Z,1558373357.789 [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 2019-05-20T17:29:17.793Z,1558373357.793 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-05-20T17:29:17.807Z,1558373357.807 [Radio_Surface](INFO): Powering up 2019-05-20T17:29:17.832Z,1558373357.832 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-05-20T17:29:17.986Z,1558373357.986 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-05-20T17:29:17.986Z,1558373357.986 [DAT](INFO): Powering up 2019-05-20T17:29:17.986Z,1558373357.986 [DAT](DEBUG): Initializing DAT. 2019-05-20T17:29:18.032Z,1558373358.032 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-05-20T17:29:18.131Z,1558373358.131 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-05-20T17:29:18.140Z,1558373358.140 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-05-20T17:29:18.141Z,1558373358.141 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-05-20T17:29:18.148Z,1558373358.148 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-05-20T17:29:18.149Z,1558373358.149 [MassServo](DEBUG): Initializing EZServoServo. 2019-05-20T17:29:18.156Z,1558373358.156 [MassServo](DEBUG): Initializing MassServo. 2019-05-20T17:29:18.157Z,1558373358.157 [RudderServo](DEBUG): Initializing EZServoServo. 2019-05-20T17:29:18.164Z,1558373358.164 [RudderServo](DEBUG): Initializing RudderServo. 2019-05-20T17:29:18.165Z,1558373358.165 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-05-20T17:29:18.172Z,1558373358.172 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-05-20T17:29:33.267Z,1558373373.267 [DAT](INFO): setting local address to 3 2019-05-20T17:29:33.668Z,1558373373.668 [DAT](INFO): set local address to 3 2019-05-20T17:29:41.777Z,1558373381.777 [SBIT](IMPORTANT): Beginning Startup BIT 2019-05-20T17:29:41.785Z,1558373381.785 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-20T17:29:45.593Z,1558373385.593 [NAL9602](INFO): Powering up NAL9602 2019-05-20T17:29:52.576Z,1558373392.576 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008241 CHAN A1 (24V): -0.023747 CHAN A2 (12V): -0.005523 CHAN A3 (5V): -0.002502 CHAN B0 (3.3V): -0.001131 CHAN B1 (3.15aV): -0.001328 CHAN B2 (3.15bV): -0.001721 CHAN B3 (GND): -0.000236 OPEN: 0.006631 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-20T17:29:56.513Z,1558373396.513 [NAL9602](INFO): NAL9602 initialized 2019-05-20T17:29:57.319Z,1558373397.319 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:30:35.542Z,1558373435.542 [SBIT](IMPORTANT): SBIT PASSED 2019-05-20T17:30:35.560Z,1558373435.560 [CommandLine](IMPORTANT): got command configSet list 2019-05-20T17:30:35.560Z,1558373435.560 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-05-20T17:30:35.561Z,1558373435.561 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2019-05-20T17:30:35.561Z,1558373435.561 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.loadAtStartup=0 bool; 2019-05-20T17:30:35.561Z,1558373435.561 [CommandLine](IMPORTANT): BR_Ping1D.verbosity=1 count; 2019-05-20T17:30:35.561Z,1558373435.561 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2019-05-20T17:30:35.561Z,1558373435.561 [CommandLine](IMPORTANT): DAT.ignoreElevationAngle=1 bool; 2019-05-20T17:30:35.561Z,1558373435.561 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-05-20T17:30:35.561Z,1558373435.561 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-05-20T17:30:35.562Z,1558373435.562 [CommandLine](IMPORTANT): PAR_Licor.adcCal=8.765e-06 microampere_per_count; 2019-05-20T17:30:35.562Z,1558373435.562 [CommandLine](IMPORTANT): PAR_Licor.darkCount=0 count; 2019-05-20T17:30:35.562Z,1558373435.562 [CommandLine](IMPORTANT): PAR_Licor.multiplier=190.3 micromole_per_second_per_square_meter_per_microampere; 2019-05-20T17:30:35.562Z,1558373435.562 [CommandLine](IMPORTANT): PNI_TCM.loadAtStartup=0 bool; 2019-05-20T17:30:35.562Z,1558373435.562 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool; 2019-05-20T17:30:35.921Z,1558373435.921 [MissionManager](IMPORTANT): Started mission Startup 2019-05-20T17:30:35.921Z,1558373435.921 [Startup] Running Loop=1 2019-05-20T17:30:35.921Z,1558373435.921 [Startup](DEBUG): Aggregate::initialize Startup 2019-05-20T17:30:35.921Z,1558373435.921 [Startup:A.GoToSurface] Running Loop=1 2019-05-20T17:30:35.921Z,1558373435.921 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-05-20T17:30:35.922Z,1558373435.922 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-05-20T17:30:35.922Z,1558373435.922 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-05-20T17:30:35.923Z,1558373435.923 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-05-20T17:30:35.923Z,1558373435.923 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-05-20T17:30:35.924Z,1558373435.924 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-05-20T17:30:35.925Z,1558373435.925 [Startup:StartupSatComms] Running Loop=1 2019-05-20T17:30:35.925Z,1558373435.925 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-05-20T17:30:35.925Z,1558373435.925 [Startup:StartupSatComms:A] Running Loop=1 2019-05-20T17:30:36.333Z,1558373436.333 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-05-20T17:31:36.117Z,1558373496.117 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-20T17:30:35.9Z 2019-05-20T17:31:36.117Z,1558373496.117 [Startup:StartupSatComms:A] Stopped 2019-05-20T17:31:36.117Z,1558373496.117 [Startup:StartupSatComms:B] Running Loop=1 2019-05-20T17:31:36.511Z,1558373496.511 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-05-20T17:32:17.740Z,1558373537.740 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-05-20T17:32:17.740Z,1558373537.740 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T17:32:17.750Z,1558373537.750 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T17:32:18.130Z,1558373538.130 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T17:32:18.130Z,1558373538.130 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-05-20T17:32:36.317Z,1558373556.317 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-20T17:31:36.1Z 2019-05-20T17:32:36.317Z,1558373556.317 [Startup:StartupSatComms:B] Stopped 2019-05-20T17:32:36.317Z,1558373556.317 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-05-20T17:32:36.317Z,1558373556.317 [Startup:StartupSatComms] Stopped 2019-05-20T17:32:36.317Z,1558373556.317 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-05-20T17:32:36.318Z,1558373556.318 [Startup](INFO): Completed Startup 2019-05-20T17:32:36.318Z,1558373556.318 [MissionManager](INFO): Startup is completed. 2019-05-20T17:32:36.318Z,1558373556.318 [MissionManager](INFO): Uninitializing Mission Startup 2019-05-20T17:32:36.319Z,1558373556.319 [Startup] Stopped 2019-05-20T17:32:36.319Z,1558373556.319 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-05-20T17:32:36.319Z,1558373556.319 [Startup:A.GoToSurface] Stopped 2019-05-20T17:32:36.319Z,1558373556.319 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-05-20T17:32:36.724Z,1558373556.724 [MissionManager](IMPORTANT): Started mission Default 2019-05-20T17:32:36.724Z,1558373556.724 [Default] Running Loop=1 2019-05-20T17:32:36.725Z,1558373556.725 [Default](DEBUG): Aggregate::initialize Default 2019-05-20T17:32:36.725Z,1558373556.725 [Default:B.GoToSurface] Running Loop=1 2019-05-20T17:32:36.725Z,1558373556.725 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-05-20T17:32:36.725Z,1558373556.725 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-05-20T17:32:36.725Z,1558373556.725 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-05-20T17:32:36.726Z,1558373556.726 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-05-20T17:32:36.726Z,1558373556.726 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-05-20T17:32:36.726Z,1558373556.726 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-05-20T17:32:36.727Z,1558373556.727 [Default:A.Wait] Running Loop=1 2019-05-20T17:32:36.727Z,1558373556.727 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-05-20T17:32:50.042Z,1558373570.042 [Default:A.Wait](INFO): Done Waiting. 2019-05-20T17:32:50.042Z,1558373570.042 [Default:A.Wait] Stopped 2019-05-20T17:32:50.042Z,1558373570.042 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-05-20T17:32:50.442Z,1558373570.442 [Default:CheckIn] Running Loop=1 2019-05-20T17:32:50.442Z,1558373570.442 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-20T17:32:50.442Z,1558373570.442 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-20T17:32:50.847Z,1558373570.847 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-05-20T17:32:57.741Z,1558373577.741 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004848 2019-05-20T17:34:59.707Z,1558373699.707 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-05-20T17:35:00.527Z,1558373700.527 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:02.947Z,1558373702.947 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:05.779Z,1558373705.779 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:08.203Z,1558373708.203 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:11.027Z,1558373711.027 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:14.255Z,1558373714.255 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:17.091Z,1558373717.091 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:18.712Z,1558373718.712 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-05-20T17:35:18.712Z,1558373718.712 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T17:35:18.723Z,1558373718.723 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T17:35:19.127Z,1558373719.127 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T17:35:19.127Z,1558373719.127 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-05-20T17:35:19.915Z,1558373719.915 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:22.743Z,1558373722.743 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:24.763Z,1558373724.763 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:27.999Z,1558373727.999 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:30.831Z,1558373730.831 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:34.063Z,1558373734.063 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:36.891Z,1558373736.891 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:40.123Z,1558373740.123 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:42.955Z,1558373742.955 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:45.779Z,1558373745.779 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:47.799Z,1558373747.799 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:50.223Z,1558373750.223 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:53.063Z,1558373753.063 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:56.279Z,1558373756.279 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:35:59.107Z,1558373759.107 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:02.339Z,1558373762.339 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:05.175Z,1558373765.175 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:07.995Z,1558373767.995 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:10.823Z,1558373770.823 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:14.059Z,1558373774.059 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:16.883Z,1558373776.883 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:20.115Z,1558373780.115 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:22.979Z,1558373782.979 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:26.187Z,1558373786.187 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:29.003Z,1558373789.003 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:32.239Z,1558373792.239 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:35.067Z,1558373795.067 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:38.299Z,1558373798.299 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:41.131Z,1558373801.131 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:42.752Z,1558373802.752 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-20T17:36:44.359Z,1558373804.359 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:47.187Z,1558373807.187 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:50.019Z,1558373810.019 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:53.247Z,1558373813.247 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:56.087Z,1558373816.087 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:36:58.903Z,1558373818.903 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:02.135Z,1558373822.135 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:04.963Z,1558373824.963 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:08.195Z,1558373828.195 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:11.023Z,1558373831.023 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:14.263Z,1558373834.263 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:17.083Z,1558373837.083 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:20.315Z,1558373840.315 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:23.143Z,1558373843.143 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:26.375Z,1558373846.375 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:29.203Z,1558373849.203 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:32.435Z,1558373852.435 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:35.268Z,1558373855.268 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:38.495Z,1558373858.495 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:41.323Z,1558373861.323 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:44.171Z,1558373864.171 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:46.983Z,1558373866.983 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:50.211Z,1558373870.211 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:50.653Z,1558373870.653 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-20T17:32:50.4Z 2019-05-20T17:37:50.653Z,1558373870.653 [Default:CheckIn:Read_GPS] Stopped 2019-05-20T17:37:50.653Z,1558373870.653 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-20T17:37:51.049Z,1558373871.049 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-05-20T17:37:53.035Z,1558373873.035 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:56.267Z,1558373876.267 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:37:57.571Z,1558373877.571 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190520T172907/Courier0004.lzma 2019-05-20T17:37:58.377Z,1558373878.377 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Courier0004.lzma.bak 2019-05-20T17:37:58.377Z,1558373878.377 [DataOverHttps](INFO): SBD MOMSN=11121539 2019-05-20T17:37:59.095Z,1558373879.095 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:02.327Z,1558373882.327 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:02.766Z,1558373882.766 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-05-20T17:38:02.767Z,1558373882.767 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 5. 2019-05-20T17:38:02.770Z,1558373882.770 [BPC1](INFO): Received data from all battery sticks. 2019-05-20T17:38:05.163Z,1558373885.163 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:08.387Z,1558373888.387 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:10.547Z,1558373890.547 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190520T172907/Courier0007.lzma 2019-05-20T17:38:11.223Z,1558373891.223 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:11.353Z,1558373891.353 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Courier0007.lzma.bak 2019-05-20T17:38:11.353Z,1558373891.353 [DataOverHttps](INFO): SBD MOMSN=11121542 2019-05-20T17:38:14.447Z,1558373894.447 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:17.279Z,1558373897.279 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:19.723Z,1558373899.723 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-05-20T17:38:19.723Z,1558373899.723 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T17:38:19.733Z,1558373899.733 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T17:38:20.156Z,1558373900.156 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T17:38:20.157Z,1558373900.157 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-05-20T17:38:20.507Z,1558373900.507 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:23.339Z,1558373903.339 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:25.123Z,1558373905.123 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190520T173239/Courier0004.lzma 2019-05-20T17:38:25.929Z,1558373905.929 [DataOverHttps](INFO): Moved sent file to Logs/20190520T173239/Courier0004.lzma.bak 2019-05-20T17:38:25.929Z,1558373905.929 [DataOverHttps](INFO): SBD MOMSN=11121544 2019-05-20T17:38:26.567Z,1558373906.567 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:29.396Z,1558373909.396 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:32.223Z,1558373912.223 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:35.059Z,1558373915.059 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:38.283Z,1558373918.283 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:39.612Z,1558373919.612 [DataOverHttps](INFO): Sending 839 bytes from file Logs/20190520T172907/Express0001.lzma 2019-05-20T17:38:40.417Z,1558373920.417 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Express0001.lzma.bak 2019-05-20T17:38:40.417Z,1558373920.417 [DataOverHttps](INFO): SBD MOMSN=11121547 2019-05-20T17:38:41.124Z,1558373921.124 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:44.343Z,1558373924.343 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:47.171Z,1558373927.171 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:50.407Z,1558373930.407 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:53.231Z,1558373933.231 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:55.483Z,1558373935.483 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190520T172907/Express0005.lzma 2019-05-20T17:38:56.289Z,1558373936.289 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Express0005.lzma.bak 2019-05-20T17:38:56.289Z,1558373936.289 [DataOverHttps](INFO): SBD MOMSN=11121572 2019-05-20T17:38:56.470Z,1558373936.470 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:38:59.299Z,1558373939.299 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:02.523Z,1558373942.523 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:05.355Z,1558373945.355 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:08.594Z,1558373948.594 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:10.315Z,1558373950.315 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190520T172907/Express0008.lzma 2019-05-20T17:39:11.121Z,1558373951.121 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Express0008.lzma.bak 2019-05-20T17:39:11.121Z,1558373951.121 [DataOverHttps](INFO): SBD MOMSN=11121576 2019-05-20T17:39:11.415Z,1558373951.415 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:14.643Z,1558373954.643 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:17.471Z,1558373957.471 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:20.307Z,1558373960.307 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:23.127Z,1558373963.127 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:24.675Z,1558373964.675 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190520T172938/Express0001.lzma 2019-05-20T17:39:25.481Z,1558373965.481 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172938/Express0001.lzma.bak 2019-05-20T17:39:25.481Z,1558373965.481 [DataOverHttps](INFO): SBD MOMSN=11121578 2019-05-20T17:39:26.359Z,1558373966.359 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:29.187Z,1558373969.187 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:32.444Z,1558373972.444 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:35.271Z,1558373975.271 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:38.483Z,1558373978.483 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:40.997Z,1558373980.997 [DataOverHttps](INFO): Sending 834 bytes from file Logs/20190520T173037/Express0001.lzma 2019-05-20T17:39:41.307Z,1558373981.307 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:41.801Z,1558373981.801 [DataOverHttps](INFO): Moved sent file to Logs/20190520T173037/Express0001.lzma.bak 2019-05-20T17:39:41.801Z,1558373981.801 [DataOverHttps](INFO): SBD MOMSN=11121581 2019-05-20T17:39:44.543Z,1558373984.543 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:47.367Z,1558373987.367 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:50.607Z,1558373990.607 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:53.427Z,1558373993.427 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:56.427Z,1558373996.427 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190520T173037/Express0005.lzma 2019-05-20T17:39:56.663Z,1558373996.663 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:39:57.233Z,1558373997.233 [DataOverHttps](INFO): Moved sent file to Logs/20190520T173037/Express0005.lzma.bak 2019-05-20T17:39:57.233Z,1558373997.233 [DataOverHttps](INFO): SBD MOMSN=11121606 2019-05-20T17:39:59.477Z,1558373999.477 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-05-20T17:39:59.477Z,1558373999.477 [NAL9602] Data Fault, FailCount= 1 2019-05-20T17:39:59.477Z,1558373999.477 [NAL9602](ERROR): Data Fault 2019-05-20T17:39:59.558Z,1558373999.558 [CBIT](ERROR): Data Fault in component: NAL9602 2019-05-20T17:39:59.880Z,1558373999.880 [NAL9602](INFO): Powering down 2019-05-20T17:40:00.737Z,1558374000.737 [CBIT](INFO): Clearing failed state for component NAL9602 2019-05-20T17:40:00.737Z,1558374000.737 [NAL9602] No Fault, FailCount= 1 2019-05-20T17:40:10.661Z,1558374010.661 [DataOverHttps](INFO): Sending 839 bytes from file Logs/20190520T173239/Express0001.lzma 2019-05-20T17:40:11.465Z,1558374011.465 [DataOverHttps](INFO): Moved sent file to Logs/20190520T173239/Express0001.lzma.bak 2019-05-20T17:40:11.465Z,1558374011.465 [DataOverHttps](INFO): SBD MOMSN=11121608 2019-05-20T17:40:26.315Z,1558374026.315 [DataOverHttps](INFO): Sending 88 bytes from file Logs/20190520T173239/Express0005.lzma 2019-05-20T17:40:27.121Z,1558374027.121 [DataOverHttps](INFO): Moved sent file to Logs/20190520T173239/Express0005.lzma.bak 2019-05-20T17:40:27.121Z,1558374027.121 [DataOverHttps](INFO): SBD MOMSN=11121633 2019-05-20T17:40:30.180Z,1558374030.180 [NAL9602](INFO): Powering up NAL9602 2019-05-20T17:40:30.632Z,1558374030.632 [Default:CheckIn:Read_Iridium] Stopped 2019-05-20T17:40:30.632Z,1558374030.632 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-20T17:40:30.632Z,1558374030.632 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-20T17:40:41.093Z,1558374041.093 [NAL9602](INFO): NAL9602 initialized 2019-05-20T17:40:41.911Z,1558374041.911 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:40:44.335Z,1558374044.335 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:40:47.571Z,1558374047.571 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:40:50.399Z,1558374050.399 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:40:53.275Z,1558374053.275 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:40:55.251Z,1558374055.251 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:40:58.483Z,1558374058.483 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:01.303Z,1558374061.303 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:04.539Z,1558374064.539 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:07.363Z,1558374067.363 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:10.611Z,1558374070.611 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:13.423Z,1558374073.423 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:16.258Z,1558374076.258 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:18.271Z,1558374078.271 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:20.709Z,1558374080.709 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-05-20T17:41:20.709Z,1558374080.709 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T17:41:20.719Z,1558374080.719 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T17:41:21.125Z,1558374081.125 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T17:41:21.125Z,1558374081.125 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-05-20T17:41:21.507Z,1558374081.507 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:24.335Z,1558374084.335 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:27.579Z,1558374087.579 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:30.395Z,1558374090.395 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:32.819Z,1558374092.819 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:35.647Z,1558374095.647 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:38.475Z,1558374098.475 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:41.307Z,1558374101.307 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:43.335Z,1558374103.335 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:45.343Z,1558374105.343 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:48.571Z,1558374108.571 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:51.407Z,1558374111.407 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:54.635Z,1558374114.635 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:41:57.459Z,1558374117.459 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:00.691Z,1558374120.691 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:03.523Z,1558374123.523 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:06.351Z,1558374126.351 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:08.775Z,1558374128.775 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:11.599Z,1558374131.599 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:14.831Z,1558374134.831 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:17.659Z,1558374137.659 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:20.491Z,1558374140.491 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:23.719Z,1558374143.719 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:26.551Z,1558374146.551 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:29.391Z,1558374149.391 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:32.611Z,1558374152.611 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:35.443Z,1558374155.443 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:38.679Z,1558374158.679 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:41.495Z,1558374161.495 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:44.728Z,1558374164.728 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:47.555Z,1558374167.555 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:50.800Z,1558374170.800 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:53.619Z,1558374173.619 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:56.851Z,1558374176.851 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:42:59.675Z,1558374179.675 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:02.911Z,1558374182.911 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:05.739Z,1558374185.739 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:08.971Z,1558374188.971 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:11.795Z,1558374191.795 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:14.623Z,1558374194.623 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:17.479Z,1558374197.479 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:20.695Z,1558374200.695 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:23.519Z,1558374203.519 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:26.747Z,1558374206.747 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:29.575Z,1558374209.575 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:32.803Z,1558374212.803 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:35.635Z,1558374215.635 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:38.863Z,1558374218.863 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:43:39.262Z,1558374219.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174336.00,A,3648.16366,N,12147.29191,W,0.505,0.00,200519,,,A*79 2019-05-20T17:43:39.266Z,1558374219.266 [NAL9602](INFO): GPS fix at 20190520T174336: (36.802728, -121.788198) 2019-05-20T17:44:11.586Z,1558374251.586 [NAL9602](INFO): Powering down 2019-05-20T17:45:31.196Z,1558374331.196 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-20T17:45:31.196Z,1558374331.196 [Default:CheckIn:C.Wait] Stopped 2019-05-20T17:45:31.196Z,1558374331.196 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-20T17:45:31.196Z,1558374331.196 [Default:CheckIn:D] Running Loop=1 2019-05-20T17:45:31.610Z,1558374331.610 [Default:CheckIn:D] Stopped 2019-05-20T17:45:31.610Z,1558374331.610 [Default:CheckIn:E] Running Loop=1 2019-05-20T17:45:32.019Z,1558374332.019 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.914760 min 2019-05-20T17:45:32.019Z,1558374332.019 [Default:CheckIn:E] Stopped 2019-05-20T17:45:32.019Z,1558374332.019 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-20T17:45:32.019Z,1558374332.019 [Default:CheckIn] Stopped 2019-05-20T17:45:32.023Z,1558374332.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-20T17:45:32.023Z,1558374332.023 [Default:CheckIn](INFO): Running loop #2 2019-05-20T17:45:32.023Z,1558374332.023 [Default:CheckIn] Running Loop=2 2019-05-20T17:45:32.024Z,1558374332.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-20T17:45:32.024Z,1558374332.024 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-20T17:45:32.786Z,1558374332.786 [NAL9602](INFO): Powering up 2019-05-20T17:45:43.702Z,1558374343.702 [NAL9602](INFO): NAL9602 initialized 2019-05-20T17:45:44.531Z,1558374344.531 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:49:44.086Z,1558374584.086 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:49:44.086Z,1558374584.086 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:50:12.370Z,1558374612.370 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:50:12.370Z,1558374612.370 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:50:32.194Z,1558374632.194 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-20T17:45:32.0Z 2019-05-20T17:50:32.194Z,1558374632.194 [Default:CheckIn:Read_GPS] Stopped 2019-05-20T17:50:32.194Z,1558374632.194 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-20T17:50:37.830Z,1558374637.830 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:50:37.830Z,1558374637.830 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:50:40.135Z,1558374640.135 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190520T172907/Courier0010.lzma 2019-05-20T17:50:40.941Z,1558374640.941 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Courier0010.lzma.bak 2019-05-20T17:50:40.941Z,1558374640.941 [DataOverHttps](INFO): SBD MOMSN=11121691 2019-05-20T17:50:53.979Z,1558374653.979 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:50:53.980Z,1558374653.980 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:50:54.384Z,1558374654.384 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-05-20T17:50:56.595Z,1558374656.595 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190520T172907/Express0011.lzma 2019-05-20T17:50:57.401Z,1558374657.401 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Express0011.lzma.bak 2019-05-20T17:50:57.401Z,1558374657.401 [DataOverHttps](INFO): SBD MOMSN=11121693 2019-05-20T17:51:00.886Z,1558374660.886 [Default:CheckIn:Read_Iridium] Stopped 2019-05-20T17:51:00.886Z,1558374660.886 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-20T17:51:00.886Z,1558374660.886 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-20T17:51:25.085Z,1558374685.085 [NAL9602](INFO): Powering down 2019-05-20T17:56:01.452Z,1558374961.452 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-20T17:56:01.452Z,1558374961.452 [Default:CheckIn:C.Wait] Stopped 2019-05-20T17:56:01.452Z,1558374961.452 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-20T17:56:01.452Z,1558374961.452 [Default:CheckIn:D] Running Loop=1 2019-05-20T17:56:01.859Z,1558374961.859 [Default:CheckIn:D] Stopped 2019-05-20T17:56:01.859Z,1558374961.859 [Default:CheckIn:E] Running Loop=1 2019-05-20T17:56:02.262Z,1558374962.262 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.418901 min 2019-05-20T17:56:02.262Z,1558374962.262 [Default:CheckIn:E] Stopped 2019-05-20T17:56:02.262Z,1558374962.262 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-20T17:56:02.262Z,1558374962.262 [Default:CheckIn] Stopped 2019-05-20T17:56:02.262Z,1558374962.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-20T17:56:02.263Z,1558374962.263 [Default:CheckIn](INFO): Running loop #3 2019-05-20T17:56:02.263Z,1558374962.263 [Default:CheckIn] Running Loop=3 2019-05-20T17:56:02.263Z,1558374962.263 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-20T17:56:02.263Z,1558374962.263 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-20T17:56:03.038Z,1558374963.038 [NAL9602](INFO): Powering up 2019-05-20T17:56:13.960Z,1558374973.960 [NAL9602](INFO): NAL9602 initialized 2019-05-20T17:56:14.772Z,1558374974.772 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-05-20T17:56:14.772Z,1558374974.772 [NAL9602] Data Fault, FailCount= 2 2019-05-20T17:56:14.772Z,1558374974.772 [NAL9602](ERROR): Data Fault 2019-05-20T17:56:14.824Z,1558374974.824 [CBIT](ERROR): Data Fault in component: NAL9602 2019-05-20T17:56:15.168Z,1558374975.168 [NAL9602](INFO): Powering down 2019-05-20T17:56:16.037Z,1558374976.037 [CBIT](INFO): Clearing failed state for component NAL9602 2019-05-20T17:56:16.037Z,1558374976.037 [NAL9602] No Fault, FailCount= 2 2019-05-20T17:56:45.480Z,1558375005.480 [NAL9602](INFO): Powering up NAL9602 2019-05-20T17:56:56.392Z,1558375016.392 [NAL9602](INFO): NAL9602 initialized 2019-05-20T17:56:57.211Z,1558375017.211 [NAL9602](DEBUG): Fix Requested 2019-05-20T17:58:11.539Z,1558375091.539 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:58:11.539Z,1558375091.539 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:58:25.649Z,1558375105.649 [CommandLine](IMPORTANT): got command failComponent 2019-05-20T17:58:25.649Z,1558375105.649 [CommandLine](IMPORTANT): Failed components: 2019-05-20T17:58:25.649Z,1558375105.649 [CommandLine](IMPORTANT): No failed Components. 2019-05-20T17:58:29.047Z,1558375109.047 [CommandLine](IMPORTANT): got command gfscan 2019-05-20T17:58:29.357Z,1558375109.357 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-20T17:58:32.147Z,1558375112.147 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:58:32.148Z,1558375112.148 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:58:40.290Z,1558375120.290 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.109578 CHAN A1 (24V): -0.023534 CHAN A2 (12V): -0.005058 CHAN A3 (5V): -0.002769 CHAN B0 (3.3V): -0.001116 CHAN B1 (3.15aV): -0.001052 CHAN B2 (3.15bV): -0.001412 CHAN B3 (GND): -0.000147 OPEN: 0.003928 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-20T17:58:55.064Z,1558375135.064 [CommandLine](IMPORTANT): got command gfscan 2019-05-20T17:58:55.211Z,1558375135.211 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-20T17:59:06.122Z,1558375146.122 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.109561 CHAN A1 (24V): -0.023893 CHAN A2 (12V): -0.005472 CHAN A3 (5V): -0.002496 CHAN B0 (3.3V): -0.000967 CHAN B1 (3.15aV): -0.001331 CHAN B2 (3.15bV): -0.001185 CHAN B3 (GND): -0.000333 OPEN: 0.003684 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-20T17:59:08.097Z,1558375148.098 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:59:08.098Z,1558375148.098 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:59:41.634Z,1558375181.634 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:59:41.634Z,1558375181.634 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:59:50.516Z,1558375190.516 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:59:50.516Z,1558375190.516 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T17:59:59.404Z,1558375199.404 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T17:59:59.404Z,1558375199.404 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:00:25.664Z,1558375225.664 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:00:25.664Z,1558375225.664 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:00:34.962Z,1558375234.962 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:00:34.962Z,1558375234.962 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:00:43.037Z,1558375243.037 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:00:43.037Z,1558375243.037 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:00:51.533Z,1558375251.533 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:00:51.533Z,1558375251.533 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:01:00.426Z,1558375260.426 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:01:00.426Z,1558375260.426 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:01:02.467Z,1558375262.467 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-20T17:56:02.3Z 2019-05-20T18:01:02.467Z,1558375262.467 [Default:CheckIn:Read_GPS] Stopped 2019-05-20T18:01:02.467Z,1558375262.467 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-20T18:01:08.918Z,1558375268.918 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:01:08.918Z,1558375268.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:01:09.546Z,1558375269.546 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190520T172907/Courier0013.lzma 2019-05-20T18:01:10.353Z,1558375270.353 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Courier0013.lzma.bak 2019-05-20T18:01:10.353Z,1558375270.353 [DataOverHttps](INFO): SBD MOMSN=11121741 2019-05-20T18:01:26.651Z,1558375286.651 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190520T172907/Express0014.lzma 2019-05-20T18:01:27.083Z,1558375287.083 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:01:27.084Z,1558375287.084 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:01:27.457Z,1558375287.457 [DataOverHttps](INFO): Moved sent file to Logs/20190520T172907/Express0014.lzma.bak 2019-05-20T18:01:27.457Z,1558375287.457 [DataOverHttps](INFO): SBD MOMSN=11121743 2019-05-20T18:01:31.186Z,1558375291.186 [Default:CheckIn:Read_Iridium] Stopped 2019-05-20T18:01:31.186Z,1558375291.186 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-20T18:01:31.186Z,1558375291.186 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-20T18:01:44.054Z,1558375304.054 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:01:44.054Z,1558375304.054 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:01:52.948Z,1558375312.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:01:52.948Z,1558375312.948 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:02:11.121Z,1558375331.121 [NAL9602](INFO): SBD MO Status=2, MOMSN=25382, MT Status=2, MTMSN=0 2019-05-20T18:02:11.122Z,1558375331.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-20T18:02:11.525Z,1558375331.525 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-05-20T18:02:42.240Z,1558375362.240 [NAL9602](INFO): Powering down 2019-05-20T18:03:48.906Z,1558375428.906 [CommandLine](IMPORTANT): got command ibit 2019-05-20T18:03:48.927Z,1558375428.927 [IBIT](IMPORTANT): Beginning Initiated BIT 2019-05-20T18:03:48.927Z,1558375428.927 [IBIT](IMPORTANT): Beginning control surface checks. 2019-05-20T18:03:48.930Z,1558375428.930 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-20T18:03:49.296Z,1558375429.296 [NAL9602](INFO): Powering up 2019-05-20T18:03:59.845Z,1558375439.845 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.109558 CHAN A1 (24V): -0.023905 CHAN A2 (12V): -0.005160 CHAN A3 (5V): -0.002242 CHAN B0 (3.3V): -0.001193 CHAN B1 (3.15aV): -0.001034 CHAN B2 (3.15bV): -0.001264 CHAN B3 (GND): 0.000380 OPEN: 0.003564 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-20T18:04:00.204Z,1558375440.204 [NAL9602](INFO): NAL9602 initialized 2019-05-20T18:04:01.023Z,1558375441.023 [NAL9602](DEBUG): Fix Requested 2019-05-20T18:04:21.618Z,1558375461.618 [NAL9602](INFO): SBD MO Status=0, MOMSN=25382, MT Status=0, MTMSN=0 2019-05-20T18:04:21.618Z,1558375461.618 [NAL9602](INFO): No messages in MT queue 2019-05-20T18:04:22.435Z,1558375462.435 [NAL9602](DEBUG): Fix Requested 2019-05-20T18:04:25.667Z,1558375465.667 [NAL9602](DEBUG): Fix Requested 2019-05-20T18:04:28.895Z,1558375468.895 [NAL9602](DEBUG): Fix Requested 2019-05-20T18:04:31.727Z,1558375471.727 [NAL9602](DEBUG): Fix Requested 2019-05-20T18:04:34.959Z,1558375474.959 [NAL9602](DEBUG): Fix Requested 2019-05-20T18:04:37.795Z,1558375477.795 [NAL9602](DEBUG): Fix Requested