2020-01-08T17:53:17.970Z,1578505997.970 [Supervisor](DEBUG): Initializing supervisor. 2020-01-08T17:53:17.973Z,1578505997.973 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-01-08T17:53:17.973Z,1578505997.973 [SyncHandler](INFO): Protected caller Thread ID is 7112 2020-01-08T17:53:17.974Z,1578505997.974 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-01-08T17:53:17.975Z,1578505997.975 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-01-08T17:53:17.975Z,1578505997.975 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7113 2020-01-08T17:53:17.978Z,1578505997.978 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-01-08T17:53:17.989Z,1578505997.989 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-01-08T17:53:17.990Z,1578505997.990 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-01-08T17:53:17.990Z,1578505997.990 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7114 2020-01-08T17:53:17.991Z,1578505997.991 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-01-08T17:53:17.992Z,1578505997.992 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-01-08T17:53:17.992Z,1578505997.992 [logger ThreadHandler](INFO): Protected caller Thread ID is 7115 2020-01-08T17:53:17.994Z,1578505997.994 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-01-08T17:53:17.995Z,1578505997.995 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-01-08T17:53:17.996Z,1578505997.996 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-01-08T17:53:18.413Z,1578505998.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-01-08T17:53:18.414Z,1578505998.414 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-01-08T17:53:18.507Z,1578505998.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-01-08T17:53:18.508Z,1578505998.508 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-01-08T17:53:18.818Z,1578505998.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-01-08T17:53:18.818Z,1578505998.818 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-01-08T17:53:18.953Z,1578505998.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-01-08T17:53:18.953Z,1578505998.953 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-01-08T17:53:19.136Z,1578505999.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-01-08T17:53:19.137Z,1578505999.137 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-01-08T17:53:19.572Z,1578505999.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-01-08T17:53:19.573Z,1578505999.573 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-01-08T17:53:19.775Z,1578505999.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-01-08T17:53:19.775Z,1578505999.775 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-01-08T17:53:19.988Z,1578505999.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-01-08T17:53:19.989Z,1578505999.989 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-01-08T17:53:20.169Z,1578506000.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-01-08T17:53:20.170Z,1578506000.170 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-01-08T17:53:20.262Z,1578506000.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-01-08T17:53:20.263Z,1578506000.263 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-01-08T17:53:20.551Z,1578506000.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-01-08T17:53:20.552Z,1578506000.552 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-01-08T17:53:20.630Z,1578506000.630 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-01-08T17:53:20.729Z,1578506000.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-01-08T17:53:20.730Z,1578506000.730 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-01-08T17:53:21.349Z,1578506001.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-01-08T17:53:21.349Z,1578506001.349 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-01-08T17:53:21.748Z,1578506001.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-01-08T17:53:21.750Z,1578506001.750 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2020-01-08T17:53:21.751Z,1578506001.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2020-01-08T17:53:21.947Z,1578506001.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2020-01-08T17:53:22.042Z,1578506002.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2020-01-08T17:53:22.136Z,1578506002.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2020-01-08T17:53:22.358Z,1578506002.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-01-08T17:53:22.358Z,1578506002.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2020-01-08T17:53:22.440Z,1578506002.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2020-01-08T17:53:22.530Z,1578506002.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2020-01-08T17:53:22.622Z,1578506002.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2020-01-08T17:53:22.794Z,1578506002.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2020-01-08T17:53:23.030Z,1578506003.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2020-01-08T17:53:23.460Z,1578506003.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2020-01-08T17:53:23.687Z,1578506003.687 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2020-01-08T17:53:23.687Z,1578506003.687 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-01-08T17:53:23.706Z,1578506003.706 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-01-08T17:53:23.988Z,1578506003.988 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-01-08T17:53:23.993Z,1578506003.993 [AHRS_M2](INFO): created writer for : platform_orientation 2020-01-08T17:53:23.995Z,1578506003.995 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-01-08T17:53:24.000Z,1578506004.000 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-01-08T17:53:24.001Z,1578506004.001 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-01-08T17:53:24.006Z,1578506004.006 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-01-08T17:53:24.007Z,1578506004.007 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-01-08T17:53:24.012Z,1578506004.012 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-01-08T17:53:24.081Z,1578506004.081 [AHRS_M2] Loaded 2020-01-08T17:53:24.082Z,1578506004.082 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-01-08T17:53:24.161Z,1578506004.161 [DataOverHttps] Loaded 2020-01-08T17:53:24.161Z,1578506004.161 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-01-08T17:53:24.162Z,1578506004.162 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0 2020-01-08T17:53:24.162Z,1578506004.162 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7195 2020-01-08T17:53:24.186Z,1578506004.186 [DDM] Loaded 2020-01-08T17:53:24.186Z,1578506004.186 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread. 2020-01-08T17:53:24.199Z,1578506004.199 [Depth_Keller] Loaded 2020-01-08T17:53:24.200Z,1578506004.200 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-01-08T17:53:24.205Z,1578506004.205 [DropWeight] Loaded 2020-01-08T17:53:24.205Z,1578506004.205 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-01-08T17:53:24.266Z,1578506004.266 [DUSBL_Hydroid] Loaded 2020-01-08T17:53:24.266Z,1578506004.266 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2020-01-08T17:53:24.312Z,1578506004.312 [Micromodem] Loaded 2020-01-08T17:53:24.312Z,1578506004.312 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread. 2020-01-08T17:53:24.409Z,1578506004.409 [NAL9602] Loaded 2020-01-08T17:53:24.409Z,1578506004.409 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-01-08T17:53:24.446Z,1578506004.446 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-01-08T17:53:24.447Z,1578506004.447 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-01-08T17:53:24.447Z,1578506004.447 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-01-08T17:53:24.448Z,1578506004.448 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-01-08T17:53:24.448Z,1578506004.448 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-01-08T17:53:24.449Z,1578506004.449 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-01-08T17:53:24.449Z,1578506004.449 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-01-08T17:53:24.450Z,1578506004.450 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-01-08T17:53:24.450Z,1578506004.450 [Onboard] Loaded 2020-01-08T17:53:24.450Z,1578506004.450 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-01-08T17:53:24.456Z,1578506004.456 [PowerOnly] Loaded 2020-01-08T17:53:24.456Z,1578506004.456 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2020-01-08T17:53:24.463Z,1578506004.463 [Radio_Surface] Loaded 2020-01-08T17:53:24.463Z,1578506004.463 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-01-08T17:53:24.464Z,1578506004.464 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0 2020-01-08T17:53:24.464Z,1578506004.464 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7196 2020-01-08T17:53:24.507Z,1578506004.507 [RDI_Pathfinder] Loaded 2020-01-08T17:53:24.508Z,1578506004.508 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-01-08T17:53:25.991Z,1578506005.991 [BPC1] Loaded 2020-01-08T17:53:25.991Z,1578506005.991 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-01-08T17:53:25.992Z,1578506005.992 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-01-08T17:53:25.992Z,1578506005.992 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-01-08T17:53:26.006Z,1578506006.006 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-01-08T17:53:26.006Z,1578506006.006 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-01-08T17:53:26.109Z,1578506006.109 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-01-08T17:53:26.109Z,1578506006.109 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-01-08T17:53:26.129Z,1578506006.129 [NavChart] Loaded 2020-01-08T17:53:26.130Z,1578506006.130 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-01-08T17:53:26.134Z,1578506006.134 [UniversalFixResidualReporter] Loaded 2020-01-08T17:53:26.134Z,1578506006.134 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-01-08T17:53:26.134Z,1578506006.134 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-01-08T17:53:26.135Z,1578506006.135 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-01-08T17:53:26.241Z,1578506006.241 [BuoyancyServo] Loaded 2020-01-08T17:53:26.241Z,1578506006.241 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-01-08T17:53:26.256Z,1578506006.256 [ElevatorServo] Loaded 2020-01-08T17:53:26.257Z,1578506006.257 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-01-08T17:53:26.271Z,1578506006.271 [MassServo] Loaded 2020-01-08T17:53:26.271Z,1578506006.271 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-01-08T17:53:26.286Z,1578506006.286 [RudderServo] Loaded 2020-01-08T17:53:26.286Z,1578506006.286 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-01-08T17:53:26.301Z,1578506006.301 [ThrusterServo] Loaded 2020-01-08T17:53:26.301Z,1578506006.301 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-01-08T17:53:26.301Z,1578506006.301 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-01-08T17:53:26.302Z,1578506006.302 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-01-08T17:53:26.556Z,1578506006.556 [CTD_NeilBrown] Loaded 2020-01-08T17:53:26.556Z,1578506006.556 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2020-01-08T17:53:26.557Z,1578506006.557 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0 2020-01-08T17:53:26.558Z,1578506006.558 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 7197 2020-01-08T17:53:26.572Z,1578506006.572 [PAR_Licor] Loaded 2020-01-08T17:53:26.572Z,1578506006.572 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-01-08T17:53:26.615Z,1578506006.615 [WetLabsSeaOWL_UV_A] Loaded 2020-01-08T17:53:26.615Z,1578506006.615 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2020-01-08T17:53:26.616Z,1578506006.616 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0 2020-01-08T17:53:26.617Z,1578506006.617 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7198 2020-01-08T17:53:26.617Z,1578506006.617 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-01-08T17:53:26.618Z,1578506006.618 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-01-08T17:53:26.923Z,1578506006.923 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-01-08T17:53:26.924Z,1578506006.924 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-01-08T17:53:26.964Z,1578506006.964 [DepthRateCalculator] Loaded 2020-01-08T17:53:26.964Z,1578506006.964 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-01-08T17:53:26.970Z,1578506006.970 [PitchRateCalculator] Loaded 2020-01-08T17:53:26.970Z,1578506006.970 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-01-08T17:53:26.982Z,1578506006.982 [SpeedCalculator] Loaded 2020-01-08T17:53:26.982Z,1578506006.982 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-01-08T17:53:27.003Z,1578506007.003 [TempGradientCalculator] Loaded 2020-01-08T17:53:27.003Z,1578506007.003 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-01-08T17:53:27.009Z,1578506007.009 [YawRateCalculator] Loaded 2020-01-08T17:53:27.009Z,1578506007.009 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-01-08T17:53:27.049Z,1578506007.049 [ElevatorOffsetCalculator] Loaded 2020-01-08T17:53:27.049Z,1578506007.049 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-01-08T17:53:27.049Z,1578506007.049 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-01-08T17:53:27.050Z,1578506007.050 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-01-08T17:53:27.185Z,1578506007.185 [SBIT](DEBUG): Construct Startup Built In Test. 2020-01-08T17:53:27.206Z,1578506007.206 [SBIT] Loaded 2020-01-08T17:53:27.207Z,1578506007.207 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-01-08T17:53:27.207Z,1578506007.207 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-01-08T17:53:27.220Z,1578506007.220 [IBIT] Loaded 2020-01-08T17:53:27.220Z,1578506007.220 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-01-08T17:53:27.223Z,1578506007.223 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-01-08T17:53:27.360Z,1578506007.360 [CBIT] Loaded 2020-01-08T17:53:27.360Z,1578506007.360 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-01-08T17:53:27.361Z,1578506007.361 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-01-08T17:53:27.361Z,1578506007.361 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-01-08T17:53:27.443Z,1578506007.443 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-01-08T17:53:27.443Z,1578506007.443 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-01-08T17:53:27.539Z,1578506007.539 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-01-08T17:53:27.539Z,1578506007.539 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-01-08T17:53:27.587Z,1578506007.587 [VerticalControl](DEBUG): Construct VerticalControl. 2020-01-08T17:53:27.668Z,1578506007.668 [VerticalControl] Loaded 2020-01-08T17:53:27.669Z,1578506007.669 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-01-08T17:53:27.669Z,1578506007.669 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-01-08T17:53:27.739Z,1578506007.739 [HorizontalControl] Loaded 2020-01-08T17:53:27.739Z,1578506007.739 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-01-08T17:53:27.740Z,1578506007.740 [SpeedControl](DEBUG): Construct SpeedControl. 2020-01-08T17:53:27.742Z,1578506007.742 [SpeedControl] Loaded 2020-01-08T17:53:27.742Z,1578506007.742 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-01-08T17:53:27.742Z,1578506007.742 [LoopControl](DEBUG): Construct LoopControl. 2020-01-08T17:53:27.743Z,1578506007.743 [LoopControl] Loaded 2020-01-08T17:53:27.743Z,1578506007.743 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-01-08T17:53:27.744Z,1578506007.744 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-01-08T17:53:27.744Z,1578506007.744 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-01-08T17:53:27.776Z,1578506007.776 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-01-08T17:53:27.779Z,1578506007.779 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-01-08T17:53:27.780Z,1578506007.780 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-01-08T17:53:27.787Z,1578506007.787 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-01-08T17:53:27.788Z,1578506007.788 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0 2020-01-08T17:53:27.788Z,1578506007.788 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7199 2020-01-08T17:53:27.842Z,1578506007.842 [Supervisor](INFO): Main Thread ID is 7111 2020-01-08T17:53:27.842Z,1578506007.842 [Supervisor](DEBUG): Running supervisor. 2020-01-08T17:53:27.842Z,1578506007.842 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7200 2020-01-08T17:53:27.845Z,1578506007.845 [controlThread ThreadHandler](INFO): Handler Thread ID is 7201 2020-01-08T17:53:27.846Z,1578506007.846 [controlThread](DEBUG): Initializing ControlThread 2020-01-08T17:53:27.852Z,1578506007.852 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-01-08T17:53:27.852Z,1578506007.852 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-01-08T17:53:27.857Z,1578506007.857 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-01-08T17:53:27.857Z,1578506007.857 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-01-08T17:53:27.858Z,1578506007.858 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-01-08T17:53:27.858Z,1578506007.858 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-01-08T17:53:27.858Z,1578506007.858 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-01-08T17:53:27.859Z,1578506007.859 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-01-08T17:53:27.859Z,1578506007.859 [SBIT](INFO): Initialize SBIT Component. 2020-01-08T17:53:27.860Z,1578506007.860 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94 2020-01-08T17:53:27.860Z,1578506007.860 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36 2020-01-08T17:53:27.860Z,1578506007.860 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-01-08T17:53:27.862Z,1578506007.862 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019 2020-01-08T17:53:27.863Z,1578506007.863 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2020-01-08T17:53:27.863Z,1578506007.863 [IBIT](INFO): Initialize IBIT Component. 2020-01-08T17:53:27.864Z,1578506007.864 [CBIT](DEBUG): Initialize CBIT Component. 2020-01-08T17:53:27.867Z,1578506007.867 [logger ThreadHandler](INFO): Handler Thread ID is 7202 2020-01-08T17:53:27.877Z,1578506007.877 [CBIT](DEBUG): Initialized mux pins. 2020-01-08T17:53:27.877Z,1578506007.877 [CBIT](DEBUG): Initializing the watchdog timer. 2020-01-08T17:53:27.882Z,1578506007.882 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7203 2020-01-08T17:53:27.883Z,1578506007.883 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-01-08T17:53:27.889Z,1578506007.889 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7204 2020-01-08T17:53:27.891Z,1578506007.891 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 7205 2020-01-08T17:53:27.892Z,1578506007.892 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7206 2020-01-08T17:53:27.893Z,1578506007.893 [CTD_NeilBrown](INFO): Powering down 2020-01-08T17:53:27.901Z,1578506007.901 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2020-01-08T17:53:27.901Z,1578506007.901 [CBIT](DEBUG): Initializing heartbeat. 2020-01-08T17:53:27.913Z,1578506007.913 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-01-08T17:53:27.932Z,1578506007.932 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7207 2020-01-08T17:53:27.960Z,1578506007.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-01-08T17:53:27.960Z,1578506007.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-01-08T17:53:27.960Z,1578506007.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-01-08T17:53:27.960Z,1578506007.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-01-08T17:53:27.961Z,1578506007.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-01-08T17:53:27.961Z,1578506007.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-01-08T17:53:27.961Z,1578506007.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-01-08T17:53:27.961Z,1578506007.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-01-08T17:53:27.962Z,1578506007.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-01-08T17:53:27.962Z,1578506007.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-01-08T17:53:27.962Z,1578506007.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-01-08T17:53:27.962Z,1578506007.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-01-08T17:53:27.962Z,1578506007.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-01-08T17:53:27.963Z,1578506007.963 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-01-08T17:53:27.963Z,1578506007.963 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-01-08T17:53:27.963Z,1578506007.963 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-01-08T17:53:27.973Z,1578506007.973 [CBIT](DEBUG): Deactivating GF circuits. 2020-01-08T17:53:27.973Z,1578506007.973 [CBIT](DEBUG): Deactivating emergency mode. 2020-01-08T17:53:28.009Z,1578506008.009 [CBIT](DEBUG): Backplane powered. 2020-01-08T17:53:28.009Z,1578506008.009 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-01-08T17:53:28.011Z,1578506008.011 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-01-08T17:53:28.011Z,1578506008.011 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-01-08T17:53:28.012Z,1578506008.012 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-01-08T17:53:28.013Z,1578506008.013 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-01-08T17:53:28.023Z,1578506008.023 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-01-08T17:53:28.042Z,1578506008.042 [MissionManager](DEBUG): 2020-01-08T17:53:28.042Z,1578506008.042 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-01-08T17:53:28.115Z,1578506008.115 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-01-08T17:53:28.133Z,1578506008.133 [Default:A.Wait](DEBUG): Construct Wait. 2020-01-08T17:53:28.134Z,1578506008.134 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-01-08T17:53:28.158Z,1578506008.158 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-01-08T17:53:28.185Z,1578506008.185 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-01-08T17:53:28.190Z,1578506008.190 [Default:E.Execute](DEBUG): Construct Execute. 2020-01-08T17:53:28.210Z,1578506008.210 [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 2020-01-08T17:53:28.215Z,1578506008.215 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T17:53:28.220Z,1578506008.220 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-01-08T17:53:28.254Z,1578506008.254 [DDM](INFO): Powering up 2020-01-08T17:53:28.254Z,1578506008.254 [DDM](DEBUG): Initializing DDM. 2020-01-08T17:53:28.279Z,1578506008.279 [DUSBL_Hydroid](INFO): Powering up 2020-01-08T17:53:28.279Z,1578506008.279 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2020-01-08T17:53:28.293Z,1578506008.293 [Radio_Surface](INFO): Powering up 2020-01-08T17:53:28.470Z,1578506008.470 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-01-08T17:53:28.481Z,1578506008.481 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-01-08T17:53:28.482Z,1578506008.482 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-01-08T17:53:28.489Z,1578506008.489 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-01-08T17:53:28.490Z,1578506008.490 [MassServo](DEBUG): Initializing EZServoServo. 2020-01-08T17:53:28.497Z,1578506008.497 [MassServo](DEBUG): Initializing MassServo. 2020-01-08T17:53:28.498Z,1578506008.498 [RudderServo](DEBUG): Initializing EZServoServo. 2020-01-08T17:53:28.509Z,1578506008.509 [RudderServo](DEBUG): Initializing RudderServo. 2020-01-08T17:53:28.510Z,1578506008.510 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-01-08T17:53:28.517Z,1578506008.517 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-01-08T17:53:28.519Z,1578506008.519 [CommandLine](FAULT): Scheduling is paused 2020-01-08T17:53:28.519Z,1578506008.519 [CBIT](INFO): Critical error at 20200108T175327 2020-01-08T17:53:28.520Z,1578506008.520 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-01-08T17:53:28.642Z,1578506008.642 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-01-08T17:53:28.642Z,1578506008.642 [DropWeight] Hardware Fault, FailCount= 1 2020-01-08T17:53:28.642Z,1578506008.642 [DropWeight](ERROR): Hardware Fault 2020-01-08T17:53:28.644Z,1578506008.644 [Micromodem](INFO): Powering up 2020-01-08T17:53:28.644Z,1578506008.644 [Micromodem](DEBUG): Initializing Micromodem. 2020-01-08T17:53:28.718Z,1578506008.718 [CBIT](INFO): Critical error at 20200108T175328 2020-01-08T17:53:28.721Z,1578506008.721 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-01-08T17:53:28.721Z,1578506008.721 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-01-08T17:53:29.389Z,1578506009.389 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-01-08T17:53:29.389Z,1578506009.389 [RudderServo](FAULT): Rudder failed to initialize 2020-01-08T17:53:29.389Z,1578506009.389 [RudderServo] Communications Fault, FailCount= 1 2020-01-08T17:53:29.389Z,1578506009.389 [RudderServo](ERROR): Communications Fault 2020-01-08T17:53:29.498Z,1578506009.498 [CBIT](INFO): Critical error at 20200108T175328 2020-01-08T17:53:29.501Z,1578506009.501 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-01-08T17:53:29.706Z,1578506009.706 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-01-08T17:53:29.706Z,1578506009.706 [RudderServo](INFO): Powering down 2020-01-08T17:53:30.383Z,1578506010.383 [RudderServo](DEBUG): Initializing EZServoServo. 2020-01-08T17:53:30.502Z,1578506010.502 [RudderServo](DEBUG): Initializing RudderServo. 2020-01-08T17:53:30.506Z,1578506010.506 [CBIT](INFO): Clearing failed state for component RudderServo 2020-01-08T17:53:30.506Z,1578506010.506 [RudderServo] No Fault, FailCount= 1 2020-01-08T17:53:33.589Z,1578506013.589 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-01-08T17:53:33.995Z,1578506013.995 [Micromodem](INFO): Nmea in: $CATMG,2020-01-08T17:53:34.019965Z,RTC,RTC*5C 2020-01-08T17:53:33.996Z,1578506013.996 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-08T17:53:34.019965Z,RTC,RTC*5C 2020-01-08T17:53:34.404Z,1578506014.404 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-01-08T17:53:34.404Z,1578506014.404 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-01-08T17:53:34.815Z,1578506014.815 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-01-08T17:53:34.815Z,1578506014.815 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-01-08T17:53:35.219Z,1578506015.219 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-01-08T17:53:35.220Z,1578506015.220 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-01-08T17:53:35.627Z,1578506015.627 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-01-08T17:53:35.627Z,1578506015.627 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-01-08T17:53:36.031Z,1578506016.031 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-01-08T17:53:36.031Z,1578506016.031 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-01-08T17:53:36.427Z,1578506016.427 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-01-08T17:53:36.427Z,1578506016.427 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-01-08T17:53:36.839Z,1578506016.839 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-01-08T17:53:36.839Z,1578506016.839 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-01-08T17:53:37.215Z,1578506017.215 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-01-08T17:53:37.216Z,1578506017.216 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,08,17,53,38*46 2020-01-08T17:53:37.716Z,1578506017.716 [Micromodem](INFO): Nmea in: $CACLK,2020,1,8,17,53,38*44 2020-01-08T17:53:37.730Z,1578506017.730 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-08T17:53:39Z*75 2020-01-08T17:53:37.731Z,1578506017.731 [Micromodem](INFO): Nmea in: $CATMG,2020-01-08T17:53:39.028473Z,USER_CMD,RTC*18 2020-01-08T17:53:38.842Z,1578506018.842 [DDM](INFO): Dynamic Docking Module:D 2020-01-08T17:53:41.693Z,1578506021.693 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-01-08T17:53:43.495Z,1578506023.495 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005319 2020-01-08T17:53:46.090Z,1578506026.090 [DUSBL_Hydroid](INFO): DUSBL Version:O 2020-01-08T17:53:54.579Z,1578506034.579 [NAL9602](INFO): Powering up NAL9602 2020-01-08T17:54:05.499Z,1578506045.499 [NAL9602](INFO): NAL9602 initialized 2020-01-08T17:54:07.646Z,1578506047.646 [SBIT](IMPORTANT): Beginning Startup BIT 2020-01-08T17:54:07.651Z,1578506047.651 [CBIT](IMPORTANT): Beginning ground fault scan 2020-01-08T17:54:18.929Z,1578506058.929 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.015180 CHAN A1 (24V): -0.028703 CHAN A2 (12V): -0.007782 CHAN A3 (5V): -0.002145 CHAN B0 (3.3V): 0.000116 CHAN B1 (3.15aV): 0.000554 CHAN B2 (3.15bV): 0.000732 CHAN B3 (GND): 0.002534 OPEN: 0.005052 Full Scale Calc: 4.765 mA, -1.589 mA 2020-01-08T17:55:01.263Z,1578506101.263 [SBIT](IMPORTANT): SBIT PASSED 2020-01-08T17:55:01.341Z,1578506101.341 [CommandLine](IMPORTANT): got command configSet list 2020-01-08T17:55:01.341Z,1578506101.341 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-01-08T17:55:01.342Z,1578506101.342 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool; 2020-01-08T17:55:01.342Z,1578506101.342 [CommandLine](IMPORTANT): DDM.verbosity=3 count; 2020-01-08T17:55:01.342Z,1578506101.342 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): Express all DDM.cablePresent; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter; 2020-01-08T17:55:01.343Z,1578506101.343 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter; 2020-01-08T17:55:01.344Z,1578506101.344 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter; 2020-01-08T17:55:01.673Z,1578506101.673 [MissionManager](IMPORTANT): Started mission Startup 2020-01-08T17:55:01.673Z,1578506101.673 [Startup] Running Loop=1 2020-01-08T17:55:01.673Z,1578506101.673 [Startup](DEBUG): Aggregate::initialize Startup 2020-01-08T17:55:01.673Z,1578506101.673 [Startup:A.GoToSurface] Running Loop=1 2020-01-08T17:55:01.674Z,1578506101.674 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-01-08T17:55:01.674Z,1578506101.674 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-01-08T17:55:01.675Z,1578506101.675 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-01-08T17:55:01.675Z,1578506101.675 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-01-08T17:55:01.675Z,1578506101.675 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-01-08T17:55:01.676Z,1578506101.676 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-01-08T17:55:01.685Z,1578506101.685 [Startup:StartupSatComms] Running Loop=1 2020-01-08T17:55:01.685Z,1578506101.685 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-01-08T17:55:01.686Z,1578506101.686 [Startup:StartupSatComms:A] Running Loop=1 2020-01-08T17:55:02.063Z,1578506102.063 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-01-08T17:55:19.006Z,1578506119.006 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T17:55:19.006Z,1578506119.006 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010811070, -0.1, 0.0,1448.9, 0 2020-01-08T17:55:32.447Z,1578506132.447 [CommandLine](IMPORTANT): got command configSet Courier linearApproximation depth 5.000000 meter persist 2020-01-08T17:55:32.457Z,1578506132.457 [CommandLine](IMPORTANT): got command configSet Courier linearApproximation longitude 0.000500 angular_degree persist 2020-01-08T17:55:32.482Z,1578506132.482 [CommandLine](IMPORTANT): got command configSet Courier linearApproximation latitude 0.000500 angular_degree persist 2020-01-08T17:55:38.254Z,1578506138.254 [CommandLine](IMPORTANT): got command show variable threshold 2020-01-08T17:55:38.288Z,1578506138.288 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold (meter) 2020-01-08T17:55:38.291Z,1578506138.291 [CommandLine](IMPORTANT): StratificationFrontDetector.threshold (celsius) 2020-01-08T17:55:38.292Z,1578506138.292 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.cntThreshold (count) 2020-01-08T17:55:38.292Z,1578506138.292 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.tempHoriGradThreshold (celsius) 2020-01-08T17:55:38.294Z,1578506138.294 [CommandLine](IMPORTANT): CBIT.humidityThreshold (percent) 2020-01-08T17:55:38.294Z,1578506138.294 [CommandLine](IMPORTANT): CBIT.pressureThreshold (pound_per_square_inch) 2020-01-08T17:55:38.294Z,1578506138.294 [CommandLine](IMPORTANT): CBIT.tempThreshold (fahrenheit) 2020-01-08T17:55:38.295Z,1578506138.295 [CommandLine](IMPORTANT): CBIT.battTempThreshold (celsius) 2020-01-08T17:55:38.296Z,1578506138.296 [CommandLine](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere) 2020-01-08T17:55:38.296Z,1578506138.296 [CommandLine](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere) 2020-01-08T17:55:38.297Z,1578506138.297 [CommandLine](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere) 2020-01-08T17:55:38.297Z,1578506138.297 [CommandLine](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere) 2020-01-08T17:55:38.298Z,1578506138.298 [CommandLine](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere) 2020-01-08T17:55:38.298Z,1578506138.298 [CommandLine](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere) 2020-01-08T17:55:38.299Z,1578506138.299 [CommandLine](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere) 2020-01-08T17:55:38.299Z,1578506138.299 [CommandLine](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere) 2020-01-08T17:55:38.300Z,1578506138.300 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold (ampere_hour) 2020-01-08T17:55:38.301Z,1578506138.301 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold (volt) 2020-01-08T17:55:38.411Z,1578506138.411 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.surfaceThreshold (meter) 2020-01-08T17:55:38.412Z,1578506138.412 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold (count) 2020-01-08T17:55:38.422Z,1578506138.422 [CommandLine](IMPORTANT): DAT.surfaceThreshold (meter) 2020-01-08T17:55:38.424Z,1578506138.424 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count) 2020-01-08T17:55:38.431Z,1578506138.431 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold (meter) 2020-01-08T17:55:49.257Z,1578506149.257 [CommandLine](IMPORTANT): got command get Micromodem.surfaceThreshold 2020-01-08T17:55:49.258Z,1578506149.258 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold 2.000000 m 2020-01-08T17:56:01.856Z,1578506161.856 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-08T17:55:01.7Z 2020-01-08T17:56:01.856Z,1578506161.856 [Startup:StartupSatComms:A] Stopped 2020-01-08T17:56:01.856Z,1578506161.856 [Startup:StartupSatComms:B] Running Loop=1 2020-01-08T17:56:01.871Z,1578506161.871 [CommandLine](IMPORTANT): got command configSet Micromodem.surfaceThreshold 0.000000 meter persist 2020-01-08T17:56:02.239Z,1578506162.239 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-01-08T17:56:03.060Z,1578506163.060 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 7A31155E000000005D00000400C20000000000000000151D80030C5669BF31555EAE1409A708CE248A694289805E92F8B20B7420A5EA58D0BD6090070960E597 2020-01-08T17:56:03.060Z,1578506163.060 [Micromodem](INFO): Outgoing frame #2, 64 bytes: E1B3D6F241BACC2D6393CD214CAE567DC72C4A8177A275243D795B2E48C9EE1B47C76AAE7F7BED79459E9ACF5000C8B323EF60B5114C8122BF2359E418FEAB54 2020-01-08T17:56:03.061Z,1578506163.061 [Micromodem](INFO): Outgoing frame #3, 57 bytes: C73027BE4125ED6E506D6292D7860CC9DB9433EF258623D48ACEC2AA8EE2A45A43F40D416D866DA2F610B29C35F02F3EFC525BF7DA6AAF4D00 2020-01-08T17:56:03.061Z,1578506163.061 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53 2020-01-08T17:56:03.667Z,1578506163.667 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51 2020-01-08T17:56:04.080Z,1578506164.080 [Micromodem](INFO): Nmea in: $CADRQ,175604,1,9,0,64,1*4F 2020-01-08T17:56:04.081Z,1578506164.081 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,7A31155E000000005D00000400C20000000000000000151D80030C5669BF31555EAE1409A708CE248A694289805E92F8B20B7420A5EA58D0BD6090070960E597*79 2020-01-08T17:56:04.483Z,1578506164.483 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T17:56:04.887Z,1578506164.887 [Micromodem](INFO): Nmea in: $CADRQ,175605,1,9,0,64,2*4D 2020-01-08T17:56:04.887Z,1578506164.887 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,E1B3D6F241BACC2D6393CD214CAE567DC72C4A8177A275243D795B2E48C9EE1B47C76AAE7F7BED79459E9ACF5000C8B323EF60B5114C8122BF2359E418FEAB54*05 2020-01-08T17:56:05.287Z,1578506165.287 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T17:56:05.687Z,1578506165.687 [Micromodem](INFO): Nmea in: $CADRQ,175606,1,9,0,64,3*4F 2020-01-08T17:56:05.687Z,1578506165.687 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,C73027BE4125ED6E506D6292D7860CC9DB9433EF258623D48ACEC2AA8EE2A45A43F40D416D866DA2F610B29C35F02F3EFC525BF7DA6AAF4D00*7C 2020-01-08T17:56:06.090Z,1578506166.090 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,57*71 2020-01-08T17:56:06.494Z,1578506166.494 [Micromodem](INFO): Nmea in: $CATXP,185*4E 2020-01-08T17:56:09.301Z,1578506169.301 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20200108T013346/Courier0000.lzma 2020-01-08T17:56:10.131Z,1578506170.131 [Micromodem](INFO): Nmea in: $CATXF,185*58 2020-01-08T17:56:10.536Z,1578506170.536 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,175607.283456,3,0,200,4000,10000,1,1,9,1,3,3,3,185*43 2020-01-08T17:56:11.306Z,1578506171.306 [DataOverHttps](INFO): Moved sent file to Logs/20200108T013346/Courier0000.lzma.bak 2020-01-08T17:56:11.307Z,1578506171.307 [DataOverHttps](INFO): SBD MOMSN=12186434 2020-01-08T17:56:27.937Z,1578506187.937 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-01-08T17:56:27.937Z,1578506187.937 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-08T17:56:27.983Z,1578506187.983 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-08T17:56:28.387Z,1578506188.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-08T17:56:28.387Z,1578506188.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-01-08T17:56:30.237Z,1578506190.237 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20200108T175317/Courier0000.lzma 2020-01-08T17:56:32.242Z,1578506192.242 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0000.lzma.bak 2020-01-08T17:56:32.242Z,1578506192.242 [DataOverHttps](INFO): SBD MOMSN=12186440 2020-01-08T17:56:40.511Z,1578506200.511 [CommandLine](IMPORTANT): got command show variable micromodem 2020-01-08T17:56:40.573Z,1578506200.573 [CommandLine](IMPORTANT): Micromodem.loadAtStartup (bool) 2020-01-08T17:56:40.574Z,1578506200.574 [CommandLine](IMPORTANT): Micromodem.simulateHardware (bool) 2020-01-08T17:56:40.574Z,1578506200.574 [CommandLine](IMPORTANT): Micromodem.localAddress (enum) 2020-01-08T17:56:40.574Z,1578506200.574 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum) 2020-01-08T17:56:40.575Z,1578506200.575 [CommandLine](IMPORTANT): Micromodem.dataRate (enum) 2020-01-08T17:56:40.575Z,1578506200.575 [CommandLine](IMPORTANT): Micromodem.sendExpress (bool) 2020-01-08T17:56:40.575Z,1578506200.575 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold (meter) 2020-01-08T17:56:40.576Z,1578506200.576 [CommandLine](IMPORTANT): Micromodem.pwrampTXLevel (enum) 2020-01-08T17:56:40.576Z,1578506200.576 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (none) 2020-01-08T17:56:40.589Z,1578506200.589 [CommandLine](IMPORTANT): Micromodem.loadControl (none) 2020-01-08T17:56:40.589Z,1578506200.589 [CommandLine](IMPORTANT): Micromodem.loadControl2 (none) 2020-01-08T17:56:40.590Z,1578506200.590 [CommandLine](IMPORTANT): Micromodem.uart (none) 2020-01-08T17:56:40.590Z,1578506200.590 [CommandLine](IMPORTANT): Micromodem.baud (bit_per_second) 2020-01-08T17:56:40.623Z,1578506200.623 [CommandLine](IMPORTANT): Micromodem.dusblPingCodeRequested (enum) 2020-01-08T17:56:40.624Z,1578506200.624 [CommandLine](IMPORTANT): Micromodem.acoustic_wakeup (count) 2020-01-08T17:56:40.624Z,1578506200.624 [CommandLine](IMPORTANT): Micromodem.acoustic_receive_time (epoch_second) 2020-01-08T17:56:40.625Z,1578506200.625 [CommandLine](IMPORTANT): Micromodem.acoustic_transmit_time (epoch_second) 2020-01-08T17:56:40.625Z,1578506200.625 [CommandLine](IMPORTANT): Micromodem.range_request (count) 2020-01-08T17:56:40.626Z,1578506200.626 [CommandLine](IMPORTANT): Micromodem.remote_address (count) 2020-01-08T17:56:40.626Z,1578506200.626 [CommandLine](IMPORTANT): Micromodem.local_address (count) 2020-01-08T17:56:40.626Z,1578506200.626 [CommandLine](IMPORTANT): Micromodem.range (meter) 2020-01-08T17:56:40.627Z,1578506200.627 [CommandLine](IMPORTANT): Micromodem.platform_communications (bool) 2020-01-08T17:56:40.683Z,1578506200.683 [CommandLine](IMPORTANT): Micromodem.component_voltage (volt) 2020-01-08T17:56:40.683Z,1578506200.683 [CommandLine](IMPORTANT): Micromodem.component_avgVoltage (volt) 2020-01-08T17:56:40.684Z,1578506200.684 [CommandLine](IMPORTANT): Micromodem.component_current (milliampere) 2020-01-08T17:56:40.684Z,1578506200.684 [CommandLine](IMPORTANT): Micromodem.component_avgCurrent (milliampere) 2020-01-08T17:56:52.534Z,1578506212.534 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20200108T012624/Express0005.lzma 2020-01-08T17:56:54.538Z,1578506214.538 [DataOverHttps](INFO): Moved sent file to Logs/20200108T012624/Express0005.lzma.bak 2020-01-08T17:56:54.539Z,1578506214.539 [DataOverHttps](INFO): SBD MOMSN=12186446 2020-01-08T17:56:55.641Z,1578506215.641 [CommandLine](IMPORTANT): got command get Micromodem.destinationAddress 2020-01-08T17:56:55.641Z,1578506215.641 [CommandLine](IMPORTANT): Micromodem.destinationAddress 9 enum 2020-01-08T17:57:01.900Z,1578506221.900 [Startup:StartupSatComms:B](INFO): Timed out from 2020-01-08T17:56:01.9Z 2020-01-08T17:57:01.900Z,1578506221.900 [Startup:StartupSatComms:B] Stopped 2020-01-08T17:57:01.905Z,1578506221.905 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-01-08T17:57:01.905Z,1578506221.905 [Startup:StartupSatComms] Stopped 2020-01-08T17:57:01.905Z,1578506221.905 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-01-08T17:57:01.906Z,1578506221.906 [Startup](INFO): Completed Startup 2020-01-08T17:57:01.906Z,1578506221.906 [MissionManager](INFO): Startup is completed. 2020-01-08T17:57:01.906Z,1578506221.906 [MissionManager](INFO): Uninitializing Mission Startup 2020-01-08T17:57:01.906Z,1578506221.906 [Startup] Stopped 2020-01-08T17:57:01.906Z,1578506221.906 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-01-08T17:57:01.906Z,1578506221.906 [Startup:A.GoToSurface] Stopped 2020-01-08T17:57:01.906Z,1578506221.906 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-01-08T17:57:02.302Z,1578506222.302 [MissionManager](IMPORTANT): Started mission Default 2020-01-08T17:57:02.302Z,1578506222.302 [Default] Running Loop=1 2020-01-08T17:57:02.302Z,1578506222.302 [Default](DEBUG): Aggregate::initialize Default 2020-01-08T17:57:02.302Z,1578506222.302 [Default:B.GoToSurface] Running Loop=1 2020-01-08T17:57:02.302Z,1578506222.302 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-01-08T17:57:02.303Z,1578506222.303 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-01-08T17:57:02.303Z,1578506222.303 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-01-08T17:57:02.303Z,1578506222.303 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-01-08T17:57:02.303Z,1578506222.303 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-01-08T17:57:02.304Z,1578506222.304 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-01-08T17:57:02.304Z,1578506222.304 [Default:A.Wait] Running Loop=1 2020-01-08T17:57:02.304Z,1578506222.304 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-01-08T17:57:13.223Z,1578506233.223 [DataOverHttps](INFO): Sending 1332 bytes from file Logs/20200108T013346/Express0001.lzma 2020-01-08T17:57:15.230Z,1578506235.230 [DataOverHttps](INFO): Moved sent file to Logs/20200108T013346/Express0001.lzma.bak 2020-01-08T17:57:15.231Z,1578506235.231 [DataOverHttps](INFO): SBD MOMSN=12186451 2020-01-08T17:57:15.623Z,1578506235.623 [Default:A.Wait](INFO): Done Waiting. 2020-01-08T17:57:15.623Z,1578506235.623 [Default:A.Wait] Stopped 2020-01-08T17:57:15.623Z,1578506235.623 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T17:57:16.022Z,1578506236.022 [Default:CheckIn] Running Loop=1 2020-01-08T17:57:16.022Z,1578506236.022 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T17:57:16.022Z,1578506236.022 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T17:57:16.511Z,1578506236.511 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-01-08T17:57:38.656Z,1578506258.656 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-01-08T17:57:38.656Z,1578506258.656 [RDI_Pathfinder](ERROR): Failed to parse: :BD, + +0.00, +0.00, 0.00,237.00 2020-01-08T17:58:48.917Z,1578506328.917 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T17:58:48.917Z,1578506328.917 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T17:59:09.115Z,1578506349.115 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T17:59:10.337Z,1578506350.337 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175853.00,A,3648.16567,N,12147.27015,W,1.730,255.15,080120,,,A*73 2020-01-08T17:59:10.340Z,1578506350.340 [NAL9602](INFO): GPS fix at 20200108T175853: (36.802761, -121.787836) 2020-01-08T17:59:10.385Z,1578506350.385 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T17:59:10.385Z,1578506350.385 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T17:59:10.807Z,1578506350.807 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-01-08T17:59:11.139Z,1578506351.139 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T17:59:12.330Z,1578506352.330 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E040001005D00000400360300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567 2020-01-08T17:59:12.331Z,1578506352.331 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31 2020-01-08T17:59:12.331Z,1578506352.331 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3 2020-01-08T17:59:12.331Z,1578506352.331 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53 2020-01-08T17:59:12.579Z,1578506352.579 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51 2020-01-08T17:59:12.954Z,1578506352.954 [Micromodem](INFO): Nmea in: $CADRQ,175913,1,9,0,64,1*46 2020-01-08T17:59:12.955Z,1578506352.955 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E040001005D00000400360300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*0D 2020-01-08T17:59:13.355Z,1578506353.355 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T17:59:13.758Z,1578506353.758 [Micromodem](INFO): Nmea in: $CADRQ,175914,1,9,0,64,2*42 2020-01-08T17:59:13.759Z,1578506353.759 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31*06 2020-01-08T17:59:14.162Z,1578506354.162 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T17:59:14.567Z,1578506354.567 [Micromodem](INFO): Nmea in: $CADRQ,175915,1,9,0,64,3*42 2020-01-08T17:59:14.567Z,1578506354.567 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3*03 2020-01-08T17:59:14.970Z,1578506354.970 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T17:59:15.378Z,1578506355.378 [Micromodem](INFO): Nmea in: $CATXP,192*48 2020-01-08T17:59:19.015Z,1578506359.015 [Micromodem](INFO): Nmea in: $CATXF,192*5E 2020-01-08T17:59:19.416Z,1578506359.416 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,175916.168208,3,0,200,4000,10000,1,1,9,1,3,3,3,192*41 2020-01-08T17:59:21.487Z,1578506361.487 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-01-08T17:59:21.487Z,1578506361.487 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768V 2020-01-08T17:59:22.085Z,1578506362.085 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20200108T175317/Courier0004.lzma 2020-01-08T17:59:24.090Z,1578506364.090 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0004.lzma.bak 2020-01-08T17:59:24.090Z,1578506364.090 [DataOverHttps](INFO): SBD MOMSN=12186489 2020-01-08T17:59:42.851Z,1578506382.851 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T17:59:44.168Z,1578506384.168 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20200108T013346/Express0005.lzma 2020-01-08T17:59:46.166Z,1578506386.166 [DataOverHttps](INFO): Moved sent file to Logs/20200108T013346/Express0005.lzma.bak 2020-01-08T17:59:46.166Z,1578506386.166 [DataOverHttps](INFO): SBD MOMSN=12186492 2020-01-08T18:00:05.398Z,1578506405.398 [DataOverHttps](INFO): Sending 1694 bytes from file Logs/20200108T175317/Express0001.lzma 2020-01-08T18:00:07.398Z,1578506407.398 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0001.lzma.bak 2020-01-08T18:00:07.398Z,1578506407.398 [DataOverHttps](INFO): SBD MOMSN=12186496 2020-01-08T18:00:17.604Z,1578506417.604 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T18:00:18.454Z,1578506418.454 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:00:18.454Z,1578506418.454 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:00:18.454Z,1578506418.454 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:00:27.005Z,1578506427.005 [DataOverHttps](INFO): Sending 767 bytes from file Logs/20200108T175317/Express0005.lzma 2020-01-08T18:00:29.010Z,1578506429.010 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0005.lzma.bak 2020-01-08T18:00:29.010Z,1578506429.010 [DataOverHttps](INFO): SBD MOMSN=12186561 2020-01-08T18:00:52.478Z,1578506452.478 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-01-08T18:00:52.487Z,1578506452.487 [BPC1](INFO): Received data from all battery sticks. 2020-01-08T18:02:52.852Z,1578506572.852 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T18:04:02.743Z,1578506642.743 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2020-01-08T18:05:18.708Z,1578506718.708 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-01-08T18:05:18.709Z,1578506718.709 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00 +0.00, +0.00, 0.00,697.04 2020-01-08T18:05:18.718Z,1578506718.718 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:05:18.718Z,1578506718.718 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:05:18.718Z,1578506718.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:05:18.718Z,1578506718.718 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:05:19.090Z,1578506719.090 [Default:CheckIn:D] Stopped 2020-01-08T18:05:19.090Z,1578506719.090 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.279802 min 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn:E] Stopped 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn] Stopped 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn](INFO): Running loop #2 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn] Running Loop=2 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:05:19.495Z,1578506719.495 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:05:21.488Z,1578506721.488 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180504.00,A,3648.16409,N,12147.28834,W,0.039,95.93,080120,,,A*44 2020-01-08T18:05:21.495Z,1578506721.495 [NAL9602](INFO): GPS fix at 20200108T180504: (36.802735, -121.788139) 2020-01-08T18:05:21.574Z,1578506721.574 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T18:05:21.575Z,1578506721.575 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T18:05:23.292Z,1578506723.292 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E070001005D00000400AD0100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A 2020-01-08T18:05:23.293Z,1578506723.293 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F 2020-01-08T18:05:23.293Z,1578506723.293 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D4B94FBF8329AB0E0C2F1D69453247BE5D 2020-01-08T18:05:23.294Z,1578506723.294 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53 2020-01-08T18:05:23.471Z,1578506723.471 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51 2020-01-08T18:05:23.842Z,1578506723.842 [Micromodem](INFO): Nmea in: $CADRQ,180524,1,9,0,64,1*44 2020-01-08T18:05:23.843Z,1578506723.843 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E070001005D00000400AD0100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*75 2020-01-08T18:05:24.250Z,1578506724.250 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:05:24.647Z,1578506724.647 [Micromodem](INFO): Nmea in: $CADRQ,180525,1,9,0,64,2*46 2020-01-08T18:05:24.647Z,1578506724.647 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*07 2020-01-08T18:05:25.046Z,1578506725.046 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:05:25.462Z,1578506725.462 [Micromodem](INFO): Nmea in: $CADRQ,180526,1,9,0,64,3*44 2020-01-08T18:05:25.463Z,1578506725.463 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D4B94FBF8329AB0E0C2F1D69453247BE5D*75 2020-01-08T18:05:25.561Z,1578506725.561 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T18:05:25.561Z,1578506725.561 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T18:05:25.854Z,1578506725.854 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:05:26.262Z,1578506726.262 [Micromodem](INFO): Nmea in: $CATXP,192*48 2020-01-08T18:05:29.589Z,1578506729.589 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20200108T175317/Courier0007.lzma 2020-01-08T18:05:29.903Z,1578506729.903 [Micromodem](INFO): Nmea in: $CATXF,192*5E 2020-01-08T18:05:30.300Z,1578506730.300 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,180527.067402,3,0,200,4000,10000,1,1,9,1,3,3,3,192*47 2020-01-08T18:05:31.594Z,1578506731.594 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0007.lzma.bak 2020-01-08T18:05:31.595Z,1578506731.595 [DataOverHttps](INFO): SBD MOMSN=12186597 2020-01-08T18:05:50.493Z,1578506750.493 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200108T175317/Express0008.lzma 2020-01-08T18:05:52.115Z,1578506752.115 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-01-08T18:05:52.193Z,1578506752.193 [NAL9602](FAULT): received: +CSQ:1 OK145, 2, 0, 0, 0 OK 2020-01-08T18:05:52.193Z,1578506752.193 [NAL9602] Data Fault, FailCount= 1 2020-01-08T18:05:52.193Z,1578506752.193 [NAL9602](ERROR): Data Fault 2020-01-08T18:05:52.265Z,1578506752.265 [CBIT](ERROR): Data Fault in component: NAL9602 2020-01-08T18:05:52.510Z,1578506752.510 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0008.lzma.bak 2020-01-08T18:05:52.510Z,1578506752.510 [DataOverHttps](INFO): SBD MOMSN=12186600 2020-01-08T18:05:52.533Z,1578506752.533 [NAL9602](INFO): Powering down 2020-01-08T18:05:53.365Z,1578506753.365 [CBIT](INFO): Clearing failed state for component NAL9602 2020-01-08T18:05:53.365Z,1578506753.365 [NAL9602] No Fault, FailCount= 1 2020-01-08T18:05:54.973Z,1578506754.973 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:05:54.973Z,1578506754.973 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:05:54.973Z,1578506754.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:06:22.819Z,1578506782.819 [NAL9602](INFO): Powering up NAL9602 2020-01-08T18:06:33.727Z,1578506793.727 [NAL9602](INFO): NAL9602 initialized 2020-01-08T18:07:04.838Z,1578506824.838 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T18:08:28.914Z,1578506908.914 [CBIT](INFO): Clearing failed state for component DropWeight 2020-01-08T18:08:28.914Z,1578506908.914 [DropWeight] No Fault, FailCount= 1 2020-01-08T18:10:55.606Z,1578507055.606 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:10:55.606Z,1578507055.606 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:10:55.606Z,1578507055.606 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:10:55.606Z,1578507055.606 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:10:55.961Z,1578507055.961 [Default:CheckIn:D] Stopped 2020-01-08T18:10:55.961Z,1578507055.961 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:10:56.360Z,1578507056.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.894313 min 2020-01-08T18:10:56.360Z,1578507056.360 [Default:CheckIn:E] Stopped 2020-01-08T18:10:56.360Z,1578507056.360 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:10:56.361Z,1578507056.361 [Default:CheckIn] Stopped 2020-01-08T18:10:56.361Z,1578507056.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:10:56.361Z,1578507056.361 [Default:CheckIn](INFO): Running loop #3 2020-01-08T18:10:56.361Z,1578507056.361 [Default:CheckIn] Running Loop=3 2020-01-08T18:10:56.361Z,1578507056.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:10:56.361Z,1578507056.361 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:10:58.352Z,1578507058.352 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181041.00,A,3648.16182,N,12147.28922,W,0.894,0.00,080120,,,A*78 2020-01-08T18:10:58.355Z,1578507058.355 [NAL9602](INFO): GPS fix at 20200108T181041: (36.802697, -121.788154) 2020-01-08T18:10:58.403Z,1578507058.403 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T18:10:58.403Z,1578507058.403 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T18:10:59.153Z,1578507059.153 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T18:11:00.312Z,1578507060.312 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E0A0000005D00000400610000000000000000151D80030C5669C08C34EE7E7E2926804C333F93DBE52D235BA3B1B95AEE1670AA47D192AD73E2E521FF 2020-01-08T18:11:00.317Z,1578507060.317 [Micromodem](INFO): Outgoing frame #2, 28 bytes: 594C48F4D7E3741838CF4D369BBC0253A870D12A0BA21479124AE000 2020-01-08T18:11:00.317Z,1578507060.317 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T18:11:00.577Z,1578507060.577 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T18:11:01.329Z,1578507061.329 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T18:11:01.727Z,1578507061.727 [Micromodem](INFO): Nmea in: $CADRQ,181101,1,9,0,64,1*46 2020-01-08T18:11:01.727Z,1578507061.727 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E0A0000005D00000400610000000000000000151D80030C5669C08C34EE7E7E2926804C333F93DBE52D235BA3B1B95AEE1670AA47D192AD73E2E521FF*72 2020-01-08T18:11:02.130Z,1578507062.130 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:11:02.535Z,1578507062.535 [Micromodem](INFO): Nmea in: $CADRQ,181103,1,9,0,64,2*47 2020-01-08T18:11:02.535Z,1578507062.535 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,594C48F4D7E3741838CF4D369BBC0253A870D12A0BA21479124AE000*0F 2020-01-08T18:11:02.943Z,1578507062.943 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,28*79 2020-01-08T18:11:03.382Z,1578507063.382 [Micromodem](INFO): Nmea in: $CATXP,92*79 2020-01-08T18:11:05.779Z,1578507065.779 [Micromodem](INFO): Nmea in: $CATXF,92*6F 2020-01-08T18:11:06.179Z,1578507066.179 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,181104.085094,3,0,200,4000,10000,1,1,9,1,2,2,3,92*75 2020-01-08T18:11:07.120Z,1578507067.120 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200108T175317/Courier0010.lzma 2020-01-08T18:11:09.127Z,1578507069.127 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0010.lzma.bak 2020-01-08T18:11:09.127Z,1578507069.127 [DataOverHttps](INFO): SBD MOMSN=12186629 2020-01-08T18:11:28.844Z,1578507088.844 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20200108T175317/Express0011.lzma 2020-01-08T18:11:30.853Z,1578507090.853 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0011.lzma.bak 2020-01-08T18:11:30.854Z,1578507090.854 [DataOverHttps](INFO): SBD MOMSN=12186632 2020-01-08T18:11:31.223Z,1578507091.223 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T18:11:33.292Z,1578507093.292 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:11:33.292Z,1578507093.292 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:11:33.292Z,1578507093.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:12:35.882Z,1578507155.882 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2020-01-08T18:16:33.864Z,1578507393.864 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:16:33.869Z,1578507393.869 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:16:33.869Z,1578507393.869 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:16:33.869Z,1578507393.869 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:16:34.248Z,1578507394.248 [Default:CheckIn:D] Stopped 2020-01-08T18:16:34.248Z,1578507394.248 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:16:34.670Z,1578507394.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.532434 min 2020-01-08T18:16:34.670Z,1578507394.670 [Default:CheckIn:E] Stopped 2020-01-08T18:16:34.670Z,1578507394.670 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:16:34.670Z,1578507394.670 [Default:CheckIn] Stopped 2020-01-08T18:16:34.670Z,1578507394.670 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:16:34.670Z,1578507394.670 [Default:CheckIn](INFO): Running loop #4 2020-01-08T18:16:34.671Z,1578507394.671 [Default:CheckIn] Running Loop=4 2020-01-08T18:16:34.671Z,1578507394.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:16:34.671Z,1578507394.671 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:16:36.649Z,1578507396.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181620.00,A,3648.17074,N,12147.25807,W,0.583,0.00,080120,,,A*70 2020-01-08T18:16:36.651Z,1578507396.651 [NAL9602](INFO): GPS fix at 20200108T181620: (36.802846, -121.787634) 2020-01-08T18:16:36.708Z,1578507396.708 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T18:16:36.708Z,1578507396.708 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T18:16:37.452Z,1578507397.452 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T18:16:38.708Z,1578507398.708 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E0D0000005D00000400700000000000000000151D80030C5669C010E4BA25B09E9BC935176A0296448C00159E45DB3E4BEED8841806F126D2E858D3B8 2020-01-08T18:16:38.708Z,1578507398.708 [Micromodem](INFO): Outgoing frame #2, 37 bytes: 38F3A10C19133C55BC4D3D3757635F3256A0A56F38DD1CDA159EC0F3603EC059562D6C2400 2020-01-08T18:16:38.709Z,1578507398.709 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T18:16:38.929Z,1578507398.929 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T18:16:39.320Z,1578507399.320 [Micromodem](INFO): Nmea in: $CADRQ,181640,1,9,0,64,1*44 2020-01-08T18:16:39.320Z,1578507399.320 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E0D0000005D00000400700000000000000000151D80030C5669C010E4BA25B09E9BC935176A0296448C00159E45DB3E4BEED8841806F126D2E858D3B8*06 2020-01-08T18:16:39.727Z,1578507399.727 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:16:40.123Z,1578507400.123 [Micromodem](INFO): Nmea in: $CADRQ,181640,1,9,0,64,2*47 2020-01-08T18:16:40.123Z,1578507400.123 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,38F3A10C19133C55BC4D3D3757635F3256A0A56F38DD1CDA159EC0F3603EC059562D6C2400*7C 2020-01-08T18:16:40.531Z,1578507400.531 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,37*77 2020-01-08T18:16:40.942Z,1578507400.942 [Micromodem](INFO): Nmea in: $CATXP,101*42 2020-01-08T18:16:43.365Z,1578507403.365 [Micromodem](INFO): Nmea in: $CATXF,101*54 2020-01-08T18:16:44.572Z,1578507404.572 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,181641.684964,3,0,200,4000,10000,1,1,9,1,2,2,3,101*49 2020-01-08T18:16:46.529Z,1578507406.529 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20200108T175317/Courier0013.lzma 2020-01-08T18:16:48.530Z,1578507408.530 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0013.lzma.bak 2020-01-08T18:16:48.530Z,1578507408.530 [DataOverHttps](INFO): SBD MOMSN=12186674 2020-01-08T18:17:07.551Z,1578507427.551 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20200108T175317/Express0014.lzma 2020-01-08T18:17:09.550Z,1578507429.550 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0014.lzma.bak 2020-01-08T18:17:09.550Z,1578507429.550 [DataOverHttps](INFO): SBD MOMSN=12186677 2020-01-08T18:17:12.113Z,1578507432.113 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:17:12.113Z,1578507432.113 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:17:12.113Z,1578507432.113 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:19:50.864Z,1578507590.864 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T18:19:50.864Z,1578507590.864 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T18:21:39.091Z,1578507699.091 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T18:22:09.795Z,1578507729.795 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T18:22:12.684Z,1578507732.684 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:22:12.684Z,1578507732.684 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:22:12.684Z,1578507732.684 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:22:12.685Z,1578507732.685 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:22:13.061Z,1578507733.061 [Default:CheckIn:D] Stopped 2020-01-08T18:22:13.061Z,1578507733.061 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:22:13.476Z,1578507733.476 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.179309 min 2020-01-08T18:22:13.476Z,1578507733.476 [Default:CheckIn:E] Stopped 2020-01-08T18:22:13.477Z,1578507733.477 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:22:13.477Z,1578507733.477 [Default:CheckIn] Stopped 2020-01-08T18:22:13.478Z,1578507733.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:22:13.478Z,1578507733.478 [Default:CheckIn](INFO): Running loop #5 2020-01-08T18:22:13.478Z,1578507733.478 [Default:CheckIn] Running Loop=5 2020-01-08T18:22:13.479Z,1578507733.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:22:13.479Z,1578507733.479 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:22:15.456Z,1578507735.456 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182158.00,A,3648.16393,N,12147.27687,W,0.350,297.72,080120,,,A*75 2020-01-08T18:22:15.459Z,1578507735.459 [NAL9602](INFO): GPS fix at 20200108T182158: (36.802732, -121.787948) 2020-01-08T18:22:15.546Z,1578507735.546 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T18:22:15.546Z,1578507735.546 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T18:22:16.254Z,1578507736.254 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T18:22:17.613Z,1578507737.613 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E100000005D00000400610000000000000000151D80030C5669C08C34EE554AC05E7B1EC1D33407DCCE8D768BD775253455A11946C49A32DF9AB9DBD2 2020-01-08T18:22:17.613Z,1578507737.613 [Micromodem](INFO): Outgoing frame #2, 29 bytes: AD27E42E4311B3A5146C9D7BF4732AE2536F26DA46BEF556B2C8ECB000 2020-01-08T18:22:17.614Z,1578507737.614 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T18:22:17.791Z,1578507737.791 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T18:22:18.155Z,1578507738.155 [Micromodem](INFO): Nmea in: $CADRQ,182219,1,9,0,64,1*4F 2020-01-08T18:22:18.158Z,1578507738.158 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E100000005D00000400610000000000000000151D80030C5669C08C34EE554AC05E7B1EC1D33407DCCE8D768BD775253455A11946C49A32DF9AB9DBD2*0D 2020-01-08T18:22:18.553Z,1578507738.553 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:22:18.950Z,1578507738.950 [Micromodem](INFO): Nmea in: $CADRQ,182219,1,9,0,64,2*4C 2020-01-08T18:22:18.951Z,1578507738.951 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,AD27E42E4311B3A5146C9D7BF4732AE2536F26DA46BEF556B2C8ECB000*02 2020-01-08T18:22:19.355Z,1578507739.355 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,29*78 2020-01-08T18:22:19.758Z,1578507739.758 [Micromodem](INFO): Nmea in: $CATXP,93*78 2020-01-08T18:22:22.187Z,1578507742.187 [Micromodem](INFO): Nmea in: $CATXF,93*6E 2020-01-08T18:22:22.587Z,1578507742.587 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,182220.502801,3,0,200,4000,10000,1,1,9,1,2,2,3,93*7C 2020-01-08T18:22:23.864Z,1578507743.864 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20200108T175317/Courier0016.lzma 2020-01-08T18:22:25.870Z,1578507745.870 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0016.lzma.bak 2020-01-08T18:22:25.871Z,1578507745.871 [DataOverHttps](INFO): SBD MOMSN=12186681 2020-01-08T18:22:44.881Z,1578507764.881 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20200108T175317/Express0017.lzma 2020-01-08T18:22:46.023Z,1578507766.023 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-01-08T18:22:46.101Z,1578507766.101 [NAL9602](FAULT): received: +CSQ:0 OK145, 2, 0, 0, 0 OK 2020-01-08T18:22:46.101Z,1578507766.101 [NAL9602] Data Fault, FailCount= 1 2020-01-08T18:22:46.101Z,1578507766.101 [NAL9602](ERROR): Data Fault 2020-01-08T18:22:46.188Z,1578507766.188 [CBIT](ERROR): Data Fault in component: NAL9602 2020-01-08T18:22:46.422Z,1578507766.422 [NAL9602](INFO): Powering down 2020-01-08T18:22:46.878Z,1578507766.878 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0017.lzma.bak 2020-01-08T18:22:46.879Z,1578507766.879 [DataOverHttps](INFO): SBD MOMSN=12186684 2020-01-08T18:22:47.274Z,1578507767.274 [CBIT](INFO): Clearing failed state for component NAL9602 2020-01-08T18:22:47.274Z,1578507767.274 [NAL9602] No Fault, FailCount= 1 2020-01-08T18:22:50.101Z,1578507770.101 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:22:50.101Z,1578507770.101 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:22:50.101Z,1578507770.101 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:23:16.727Z,1578507796.727 [NAL9602](INFO): Powering up NAL9602 2020-01-08T18:23:17.583Z,1578507797.583 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-01-08T18:23:17.583Z,1578507797.583 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,32768,V 2020-01-08T18:23:27.637Z,1578507807.637 [NAL9602](INFO): NAL9602 initialized 2020-01-08T18:23:58.743Z,1578507838.743 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T18:27:50.701Z,1578508070.701 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:27:50.701Z,1578508070.701 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:27:50.701Z,1578508070.701 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:27:50.701Z,1578508070.701 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:27:51.131Z,1578508071.131 [Default:CheckIn:D] Stopped 2020-01-08T18:27:51.132Z,1578508071.132 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:27:51.551Z,1578508071.551 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.813822 min 2020-01-08T18:27:51.551Z,1578508071.551 [Default:CheckIn:E] Stopped 2020-01-08T18:27:51.551Z,1578508071.551 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:27:51.551Z,1578508071.551 [Default:CheckIn] Stopped 2020-01-08T18:27:51.551Z,1578508071.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:27:51.551Z,1578508071.551 [Default:CheckIn](INFO): Running loop #6 2020-01-08T18:27:51.551Z,1578508071.551 [Default:CheckIn] Running Loop=6 2020-01-08T18:27:51.552Z,1578508071.552 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:27:51.552Z,1578508071.552 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:27:55.099Z,1578508075.099 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T18:28:22.991Z,1578508102.991 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182805.00,A,3648.16739,N,12147.28702,W,1.050,278.64,080120,,,A*77 2020-01-08T18:28:22.993Z,1578508102.993 [NAL9602](INFO): GPS fix at 20200108T182805: (36.802790, -121.788117) 2020-01-08T18:28:23.024Z,1578508103.024 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T18:28:23.024Z,1578508103.024 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T18:28:24.349Z,1578508104.349 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T18:28:25.649Z,1578508105.649 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E130000005D00000400320000000000000000151D80030C5669C08C34EE77EBA9A2635957C75E86A70ABD9C179D20F5302A02104E48501E6969DFDA22 2020-01-08T18:28:25.649Z,1578508105.649 [Micromodem](INFO): Outgoing frame #2, 6 bytes: A8327F2A8200 2020-01-08T18:28:25.650Z,1578508105.650 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T18:28:25.889Z,1578508105.889 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T18:28:26.253Z,1578508106.253 [Micromodem](INFO): Nmea in: $CADRQ,182827,1,9,0,64,1*48 2020-01-08T18:28:26.253Z,1578508106.253 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E130000005D00000400320000000000000000151D80030C5669C08C34EE77EBA9A2635957C75E86A70ABD9C179D20F5302A02104E48501E6969DFDA22*0C 2020-01-08T18:28:26.443Z,1578508106.443 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200108T175317/Courier0019.lzma 2020-01-08T18:28:26.657Z,1578508106.657 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:28:27.063Z,1578508107.063 [Micromodem](INFO): Nmea in: $CADRQ,182827,1,9,0,64,2*4B 2020-01-08T18:28:27.063Z,1578508107.063 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,A8327F2A8200*01 2020-01-08T18:28:27.463Z,1578508107.463 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,6*45 2020-01-08T18:28:27.534Z,1578508107.534 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0019.lzma.bak 2020-01-08T18:28:27.535Z,1578508107.535 [DataOverHttps](INFO): SBD MOMSN=12186694 2020-01-08T18:28:27.866Z,1578508107.866 [Micromodem](INFO): Nmea in: $CATXP,70*75 2020-01-08T18:28:30.293Z,1578508110.293 [Micromodem](INFO): Nmea in: $CATXF,70*63 2020-01-08T18:28:30.691Z,1578508110.691 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,182828.588632,3,0,200,4000,10000,1,1,9,1,2,2,3,70*7F 2020-01-08T18:28:46.381Z,1578508126.381 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20200108T175317/Express0020.lzma 2020-01-08T18:28:48.386Z,1578508128.386 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0020.lzma.bak 2020-01-08T18:28:48.387Z,1578508128.387 [DataOverHttps](INFO): SBD MOMSN=12186696 2020-01-08T18:28:50.541Z,1578508130.541 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:28:50.541Z,1578508130.541 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:28:50.541Z,1578508130.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:28:56.151Z,1578508136.151 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T18:31:24.863Z,1578508284.863 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T18:31:24.863Z,1578508284.863 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200108115.0, -0.1, 0.0,1448.9, 0 2020-01-08T18:33:51.148Z,1578508431.148 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:33:51.148Z,1578508431.148 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:33:51.148Z,1578508431.148 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:33:51.148Z,1578508431.148 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:33:51.604Z,1578508431.604 [Default:CheckIn:D] Stopped 2020-01-08T18:33:51.604Z,1578508431.604 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:33:51.945Z,1578508431.945 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.821700 min 2020-01-08T18:33:51.945Z,1578508431.945 [Default:CheckIn:E] Stopped 2020-01-08T18:33:51.945Z,1578508431.945 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:33:51.945Z,1578508431.945 [Default:CheckIn] Stopped 2020-01-08T18:33:51.946Z,1578508431.946 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:33:51.946Z,1578508431.946 [Default:CheckIn](INFO): Running loop #7 2020-01-08T18:33:51.946Z,1578508431.946 [Default:CheckIn] Running Loop=7 2020-01-08T18:33:51.946Z,1578508431.946 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:33:51.946Z,1578508431.946 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:33:53.933Z,1578508433.933 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183337.00,A,3648.16114,N,12147.28039,W,0.175,278.64,080120,,,A*7D 2020-01-08T18:33:53.935Z,1578508433.935 [NAL9602](INFO): GPS fix at 20200108T183337: (36.802686, -121.788006) 2020-01-08T18:33:53.988Z,1578508433.988 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T18:33:53.988Z,1578508433.988 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T18:33:54.732Z,1578508434.732 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T18:33:56.051Z,1578508436.051 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E160000005D00000400610000000000000000151D80030C5669C08C34EE572208CB581477C7A0D46F209EC30F2F6A6933130627935081E06D9149BE56 2020-01-08T18:33:56.052Z,1578508436.052 [Micromodem](INFO): Outgoing frame #2, 28 bytes: 58C11925B820382446C560B20C4232EBFDF6799638A756D4721F2D00 2020-01-08T18:33:56.052Z,1578508436.052 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T18:33:56.235Z,1578508436.235 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T18:33:56.603Z,1578508436.603 [Micromodem](INFO): Nmea in: $CADRQ,183357,1,9,0,64,1*45 2020-01-08T18:33:56.603Z,1578508436.603 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E160000005D00000400610000000000000000151D80030C5669C08C34EE572208CB581477C7A0D46F209EC30F2F6A6933130627935081E06D9149BE56*78 2020-01-08T18:33:57.013Z,1578508437.013 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:33:57.415Z,1578508437.415 [Micromodem](INFO): Nmea in: $CADRQ,183358,1,9,0,64,2*49 2020-01-08T18:33:57.415Z,1578508437.415 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,58C11925B820382446C560B20C4232EBFDF6799638A756D4721F2D00*7C 2020-01-08T18:33:57.811Z,1578508437.811 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,28*79 2020-01-08T18:33:58.215Z,1578508438.215 [Micromodem](INFO): Nmea in: $CATXP,92*79 2020-01-08T18:34:00.643Z,1578508440.643 [Micromodem](INFO): Nmea in: $CATXF,92*6F 2020-01-08T18:34:01.047Z,1578508441.047 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,183358.970405,3,0,200,4000,10000,1,1,9,1,2,2,3,92*73 2020-01-08T18:34:02.392Z,1578508442.392 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200108T175317/Courier0022.lzma 2020-01-08T18:34:04.398Z,1578508444.398 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0022.lzma.bak 2020-01-08T18:34:04.398Z,1578508444.398 [DataOverHttps](INFO): SBD MOMSN=12186703 2020-01-08T18:34:08.720Z,1578508448.720 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T18:34:08.720Z,1578508448.720 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T18:34:23.656Z,1578508463.656 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200108T175317/Express0023.lzma 2020-01-08T18:34:24.880Z,1578508464.880 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T18:34:24.880Z,1578508464.880 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T18:34:25.662Z,1578508465.662 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0023.lzma.bak 2020-01-08T18:34:25.663Z,1578508465.663 [DataOverHttps](INFO): SBD MOMSN=12186710 2020-01-08T18:34:27.798Z,1578508467.798 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:34:27.798Z,1578508467.798 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:34:27.798Z,1578508467.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:34:42.261Z,1578508482.261 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T18:34:42.261Z,1578508482.261 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T18:34:59.636Z,1578508499.636 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T18:34:59.636Z,1578508499.636 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T18:35:54.576Z,1578508554.576 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T18:35:54.576Z,1578508554.576 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T18:36:28.512Z,1578508588.512 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T18:36:28.512Z,1578508588.512 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T18:38:22.863Z,1578508702.863 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T18:38:30.125Z,1578508710.125 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-01-08T18:38:30.125Z,1578508710.125 [DropWeight] Hardware Fault, FailCount= 1 2020-01-08T18:38:30.125Z,1578508710.125 [DropWeight](ERROR): Hardware Fault 2020-01-08T18:38:30.168Z,1578508710.168 [CBIT](INFO): Critical error at 20200108T183830 2020-01-08T18:38:30.202Z,1578508710.202 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-01-08T18:38:30.202Z,1578508710.202 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-01-08T18:38:30.556Z,1578508710.556 [CBIT](INFO): Critical error at 20200108T183830 2020-01-08T18:38:56.379Z,1578508736.379 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T18:39:27.079Z,1578508767.079 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T18:39:28.330Z,1578508768.330 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:39:28.331Z,1578508768.331 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:39:28.331Z,1578508768.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:39:28.331Z,1578508768.331 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:39:28.785Z,1578508768.785 [Default:CheckIn:D] Stopped 2020-01-08T18:39:28.785Z,1578508768.785 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:39:29.122Z,1578508769.122 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.441382 min 2020-01-08T18:39:29.122Z,1578508769.122 [Default:CheckIn:E] Stopped 2020-01-08T18:39:29.123Z,1578508769.123 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:39:29.123Z,1578508769.123 [Default:CheckIn] Stopped 2020-01-08T18:39:29.123Z,1578508769.123 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:39:29.123Z,1578508769.123 [Default:CheckIn](INFO): Running loop #8 2020-01-08T18:39:29.123Z,1578508769.123 [Default:CheckIn] Running Loop=8 2020-01-08T18:39:29.123Z,1578508769.123 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:39:29.123Z,1578508769.123 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:39:31.119Z,1578508771.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183914.00,A,3648.16112,N,12147.27827,W,0.078,0.00,080120,,,A*7B 2020-01-08T18:39:31.133Z,1578508771.133 [NAL9602](INFO): GPS fix at 20200108T183914: (36.802685, -121.787971) 2020-01-08T18:39:31.184Z,1578508771.184 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T18:39:31.184Z,1578508771.184 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T18:39:32.030Z,1578508772.030 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T18:39:33.025Z,1578508773.025 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E190000005D00000400B10000000000000000151D80030C5669BF7A7A1586A90D6220578868B225DA5DD026B8807A068800C707E1C6E83B3EB97F72D4 2020-01-08T18:39:33.026Z,1578508773.026 [Micromodem](INFO): Outgoing frame #2, 64 bytes: FCFC0C32A3BDBDFC3C0A974DF7690E1918EA8DED8917307D5D0882729BAC341057D7F98AA549A1A7C8FACA5448D509C9F1EF09B51DFF2E02C9F8E12A2B612CD3 2020-01-08T18:39:33.026Z,1578508773.026 [Micromodem](INFO): Outgoing frame #3, 40 bytes: 888442D3A9B6A7CD3DE7140E30BD12DB7E45045531769FDF809DC92D13D28DB1409E758BC8934900 2020-01-08T18:39:33.026Z,1578508773.026 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53 2020-01-08T18:39:33.226Z,1578508773.226 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51 2020-01-08T18:39:33.577Z,1578508773.577 [Micromodem](INFO): Nmea in: $CADRQ,183934,1,9,0,64,1*4A 2020-01-08T18:39:33.577Z,1578508773.577 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E190000005D00000400B10000000000000000151D80030C5669BF7A7A1586A90D6220578868B225DA5DD026B8807A068800C707E1C6E83B3EB97F72D4*71 2020-01-08T18:39:33.986Z,1578508773.986 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:39:34.394Z,1578508774.394 [Micromodem](INFO): Nmea in: $CADRQ,183935,1,9,0,64,2*48 2020-01-08T18:39:34.394Z,1578508774.394 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,FCFC0C32A3BDBDFC3C0A974DF7690E1918EA8DED8917307D5D0882729BAC341057D7F98AA549A1A7C8FACA5448D509C9F1EF09B51DFF2E02C9F8E12A2B612CD3*07 2020-01-08T18:39:34.794Z,1578508774.794 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:39:35.195Z,1578508775.195 [Micromodem](INFO): Nmea in: $CADRQ,183935,1,9,0,64,3*49 2020-01-08T18:39:35.195Z,1578508775.195 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,888442D3A9B6A7CD3DE7140E30BD12DB7E45045531769FDF809DC92D13D28DB1409E758BC8934900*73 2020-01-08T18:39:35.602Z,1578508775.602 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,40*77 2020-01-08T18:39:36.014Z,1578508776.014 [Micromodem](INFO): Nmea in: $CATXP,168*4D 2020-01-08T18:39:39.165Z,1578508779.165 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20200108T175317/Courier0025.lzma 2020-01-08T18:39:39.642Z,1578508779.642 [Micromodem](INFO): Nmea in: $CATXF,168*5B 2020-01-08T18:39:40.862Z,1578508780.862 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,183936.774391,3,0,200,4000,10000,1,1,9,1,3,3,3,168*45 2020-01-08T18:39:41.170Z,1578508781.170 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0025.lzma.bak 2020-01-08T18:39:41.170Z,1578508781.170 [DataOverHttps](INFO): SBD MOMSN=12186713 2020-01-08T18:40:00.205Z,1578508800.205 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20200108T175317/Express0026.lzma 2020-01-08T18:40:01.859Z,1578508801.859 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-01-08T18:40:01.933Z,1578508801.933 [NAL9602](FAULT): received: +CSQ:0 OK145, 2, 0, 0, 0 OK 2020-01-08T18:40:01.933Z,1578508801.933 [NAL9602] Data Fault, FailCount= 1 2020-01-08T18:40:01.933Z,1578508801.933 [NAL9602](ERROR): Data Fault 2020-01-08T18:40:02.033Z,1578508802.033 [CBIT](ERROR): Data Fault in component: NAL9602 2020-01-08T18:40:02.210Z,1578508802.210 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0026.lzma.bak 2020-01-08T18:40:02.211Z,1578508802.211 [DataOverHttps](INFO): SBD MOMSN=12186718 2020-01-08T18:40:02.263Z,1578508802.263 [NAL9602](INFO): Powering down 2020-01-08T18:40:03.133Z,1578508803.133 [CBIT](INFO): Clearing failed state for component NAL9602 2020-01-08T18:40:03.133Z,1578508803.133 [NAL9602] No Fault, FailCount= 1 2020-01-08T18:40:04.332Z,1578508804.332 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:40:04.332Z,1578508804.332 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:40:04.332Z,1578508804.332 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:40:32.562Z,1578508832.562 [NAL9602](INFO): Powering up NAL9602 2020-01-08T18:40:43.503Z,1578508843.503 [NAL9602](INFO): NAL9602 initialized 2020-01-08T18:41:14.582Z,1578508874.582 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T18:42:07.938Z,1578508927.938 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T18:42:07.939Z,1578508927.939 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200108115.0, -0.1, 0.0,1448.9, 0 2020-01-08T18:44:01.886Z,1578509041.886 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T18:44:01.886Z,1578509041.886 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010811560, -0.1, 0.0,1448.9, 0 2020-01-08T18:44:22.503Z,1578509062.503 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T18:44:22.503Z,1578509062.503 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, I,-32768,-32768,-32768,-32768,V 2020-01-08T18:44:47.938Z,1578509087.938 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T18:44:47.938Z,1578509087.938 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T18:45:04.916Z,1578509104.916 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:45:04.916Z,1578509104.916 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:45:04.916Z,1578509104.916 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:45:04.916Z,1578509104.916 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:45:05.332Z,1578509105.332 [Default:CheckIn:D] Stopped 2020-01-08T18:45:05.332Z,1578509105.332 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:45:05.783Z,1578509105.783 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.050496 min 2020-01-08T18:45:05.783Z,1578509105.783 [Default:CheckIn:E] Stopped 2020-01-08T18:45:05.783Z,1578509105.783 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:45:05.783Z,1578509105.783 [Default:CheckIn] Stopped 2020-01-08T18:45:05.783Z,1578509105.783 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:45:05.783Z,1578509105.783 [Default:CheckIn](INFO): Running loop #9 2020-01-08T18:45:05.783Z,1578509105.783 [Default:CheckIn] Running Loop=9 2020-01-08T18:45:05.784Z,1578509105.784 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:45:05.784Z,1578509105.784 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:45:09.732Z,1578509109.732 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T18:45:42.883Z,1578509142.883 [RDI_Pathfinder](ERROR): Failed to parse: 00, 0.00, 0.00, 0.00, 0.-32768,-32768,-32768,-32768,V 2020-01-08T18:46:22.507Z,1578509182.507 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T18:47:23.500Z,1578509243.500 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, +0.00, +0.00, 0.00,3221.61 2020-01-08T18:48:07.925Z,1578509287.925 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T18:48:07.926Z,1578509287.926 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010812004034., 0 2020-01-08T18:49:46.524Z,1578509386.524 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T18:50:05.887Z,1578509405.887 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-08T18:45:05.8Z 2020-01-08T18:50:05.887Z,1578509405.887 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T18:50:05.887Z,1578509405.887 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T18:50:06.664Z,1578509406.664 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T18:50:07.822Z,1578509407.822 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E1C0000005D00000400320000000000000000151D80030C5669C08C34EE44C475E697717EADC565F112E65FEE61021E2A0953CADC58C1533FBB93E8C8 2020-01-08T18:50:07.822Z,1578509407.822 [Micromodem](INFO): Outgoing frame #2, 5 bytes: EB15299E00 2020-01-08T18:50:07.823Z,1578509407.823 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T18:50:07.982Z,1578509407.982 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T18:50:08.346Z,1578509408.346 [Micromodem](INFO): Nmea in: $CADRQ,185009,1,9,0,64,1*4B 2020-01-08T18:50:08.346Z,1578509408.346 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E1C0000005D00000400320000000000000000151D80030C5669C08C34EE44C475E697717EADC565F112E65FEE61021E2A0953CADC58C1533FBB93E8C8*08 2020-01-08T18:50:08.746Z,1578509408.746 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T18:50:09.146Z,1578509409.146 [Micromodem](INFO): Nmea in: $CADRQ,185009,1,9,0,64,2*48 2020-01-08T18:50:09.147Z,1578509409.147 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,EB15299E00*05 2020-01-08T18:50:09.550Z,1578509409.550 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,5*46 2020-01-08T18:50:09.962Z,1578509409.962 [Micromodem](INFO): Nmea in: $CATXP,69*7D 2020-01-08T18:50:12.378Z,1578509412.378 [Micromodem](INFO): Nmea in: $CATXF,69*6B 2020-01-08T18:50:13.591Z,1578509413.591 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,185010.670762,3,0,200,4000,10000,1,1,9,1,2,2,3,69*73 2020-01-08T18:50:15.640Z,1578509415.640 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20200108T175317/Courier0028.lzma 2020-01-08T18:50:17.647Z,1578509417.647 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0028.lzma.bak 2020-01-08T18:50:17.647Z,1578509417.647 [DataOverHttps](INFO): SBD MOMSN=12186728 2020-01-08T18:50:36.613Z,1578509436.613 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20200108T175317/Express0029.lzma 2020-01-08T18:50:38.618Z,1578509438.618 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0029.lzma.bak 2020-01-08T18:50:38.619Z,1578509438.619 [DataOverHttps](INFO): SBD MOMSN=12186730 2020-01-08T18:50:39.446Z,1578509439.446 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T18:50:41.123Z,1578509441.123 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T18:50:41.124Z,1578509441.124 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T18:50:41.124Z,1578509441.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T18:52:47.571Z,1578509567.571 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-01-08T18:52:47.572Z,1578509567.572 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768-32768,V 2020-01-08T18:53:30.389Z,1578509610.389 [CBIT](INFO): Clearing failed state for component DropWeight 2020-01-08T18:53:30.389Z,1578509610.389 [DropWeight] No Fault, FailCount= 1 2020-01-08T18:54:39.042Z,1578509679.042 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T18:54:39.042Z,1578509679.042 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010812071134,35.0, -0.1, 0.0,1448.9A, 0.00, 0.00, 0.00, 0.00, 0.00 2020-01-08T18:55:41.686Z,1578509741.686 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T18:55:41.686Z,1578509741.686 [Default:CheckIn:C.Wait] Stopped 2020-01-08T18:55:41.686Z,1578509741.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T18:55:41.686Z,1578509741.686 [Default:CheckIn:D] Running Loop=1 2020-01-08T18:55:42.081Z,1578509742.081 [Default:CheckIn:D] Stopped 2020-01-08T18:55:42.081Z,1578509742.081 [Default:CheckIn:E] Running Loop=1 2020-01-08T18:55:42.525Z,1578509742.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.662984 min 2020-01-08T18:55:42.525Z,1578509742.525 [Default:CheckIn:E] Stopped 2020-01-08T18:55:42.525Z,1578509742.525 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T18:55:42.525Z,1578509742.525 [Default:CheckIn] Stopped 2020-01-08T18:55:42.525Z,1578509742.525 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T18:55:42.525Z,1578509742.525 [Default:CheckIn](INFO): Running loop #10 2020-01-08T18:55:42.525Z,1578509742.525 [Default:CheckIn] Running Loop=10 2020-01-08T18:55:42.526Z,1578509742.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T18:55:42.526Z,1578509742.526 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T18:55:44.067Z,1578509744.067 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-01-08T18:55:44.067Z,1578509744.067 [NAL9602] Data Fault, FailCount= 1 2020-01-08T18:55:44.067Z,1578509744.067 [NAL9602](ERROR): Data Fault 2020-01-08T18:55:44.145Z,1578509744.145 [CBIT](ERROR): Data Fault in component: NAL9602 2020-01-08T18:55:44.492Z,1578509744.492 [NAL9602](INFO): Powering down 2020-01-08T18:55:45.316Z,1578509745.316 [CBIT](INFO): Clearing failed state for component NAL9602 2020-01-08T18:55:45.316Z,1578509745.316 [NAL9602] No Fault, FailCount= 1 2020-01-08T18:56:14.767Z,1578509774.767 [NAL9602](INFO): Powering up NAL9602 2020-01-08T18:56:25.683Z,1578509785.683 [NAL9602](INFO): NAL9602 initialized 2020-01-08T18:56:38.672Z,1578509798.672 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T18:58:43.495Z,1578509923.495 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T18:59:06.511Z,1578509946.511 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T18:59:06.511Z,1578509946.511 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32BE,-32768,-32768,-32768,V 2020-01-08T18:59:33.940Z,1578509973.940 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T18:59:33.941Z,1578509973.941 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T18:59:42.495Z,1578509982.495 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T18:59:42.495Z,1578509982.495 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010812121534,35.0, -0.1,448.9, 0 2020-01-08T18:59:56.572Z,1578509996.572 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T18:59:56.573Z,1578509996.573 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T19:00:42.671Z,1578510042.671 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-08T18:55:42.5Z 2020-01-08T19:00:42.671Z,1578510042.671 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T19:00:42.671Z,1578510042.671 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T19:00:43.430Z,1578510043.430 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T19:00:44.576Z,1578510044.576 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E1F0000005D00000400320000000000000000151D80030C5669C08C34EE4A7322BD9210D360CB73E5CEA2A7CB1DCB5F27FE953B2C18B30590D0C7E1A1 2020-01-08T19:00:44.577Z,1578510044.577 [Micromodem](INFO): Outgoing frame #2, 6 bytes: 9EA0EED42D00 2020-01-08T19:00:44.577Z,1578510044.578 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T19:00:44.743Z,1578510044.743 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T19:00:45.149Z,1578510045.149 [Micromodem](INFO): Nmea in: $CADRQ,190046,1,9,0,64,1*44 2020-01-08T19:00:45.150Z,1578510045.150 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E1F0000005D00000400320000000000000000151D80030C5669C08C34EE4A7322BD9210D360CB73E5CEA2A7CB1DCB5F27FE953B2C18B30590D0C7E1A1*7B 2020-01-08T19:00:45.515Z,1578510045.515 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T19:00:45.919Z,1578510045.919 [Micromodem](INFO): Nmea in: $CADRQ,190046,1,9,0,64,2*47 2020-01-08T19:00:45.919Z,1578510045.919 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,9EA0EED42D00*7A 2020-01-08T19:00:46.322Z,1578510046.322 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,6*45 2020-01-08T19:00:46.730Z,1578510046.730 [Micromodem](INFO): Nmea in: $CATXP,70*75 2020-01-08T19:00:47.564Z,1578510047.564 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T19:00:47.564Z,1578510047.564 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T19:00:49.150Z,1578510049.150 [Micromodem](INFO): Nmea in: $CATXF,70*63 2020-01-08T19:00:49.559Z,1578510049.559 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,190047.447074,3,0,200,4000,10000,1,1,9,1,2,2,3,70*7B 2020-01-08T19:00:50.912Z,1578510050.912 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200108T175317/Courier0031.lzma 2020-01-08T19:00:52.919Z,1578510052.919 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0031.lzma.bak 2020-01-08T19:00:52.919Z,1578510052.919 [DataOverHttps](INFO): SBD MOMSN=12186743 2020-01-08T19:00:56.880Z,1578510056.880 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T19:00:56.881Z,1578510056.881 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:01:11.830Z,1578510071.830 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20200108T175317/Express0032.lzma 2020-01-08T19:01:13.962Z,1578510073.962 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0032.lzma.bak 2020-01-08T19:01:13.962Z,1578510073.962 [DataOverHttps](INFO): SBD MOMSN=12186745 2020-01-08T19:01:16.273Z,1578510076.273 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T19:01:16.273Z,1578510076.273 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T19:01:16.273Z,1578510076.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T19:01:41.276Z,1578510101.276 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T19:01:41.276Z,1578510101.276 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T19:01:41.671Z,1578510101.671 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T19:02:12.378Z,1578510132.378 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T19:02:27.764Z,1578510147.764 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2020-01-08T19:06:07.934Z,1578510367.934 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T19:06:07.935Z,1578510367.935 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:06:16.860Z,1578510376.860 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T19:06:16.877Z,1578510376.877 [Default:CheckIn:C.Wait] Stopped 2020-01-08T19:06:16.877Z,1578510376.877 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T19:06:16.877Z,1578510376.877 [Default:CheckIn:D] Running Loop=1 2020-01-08T19:06:17.257Z,1578510377.257 [Default:CheckIn:D] Stopped 2020-01-08T19:06:17.257Z,1578510377.257 [Default:CheckIn:E] Running Loop=1 2020-01-08T19:06:17.685Z,1578510377.685 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.249243 min 2020-01-08T19:06:17.685Z,1578510377.685 [Default:CheckIn:E] Stopped 2020-01-08T19:06:17.685Z,1578510377.685 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T19:06:17.686Z,1578510377.686 [Default:CheckIn] Stopped 2020-01-08T19:06:17.686Z,1578510377.686 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T19:06:17.686Z,1578510377.686 [Default:CheckIn](INFO): Running loop #11 2020-01-08T19:06:17.686Z,1578510377.686 [Default:CheckIn] Running Loop=11 2020-01-08T19:06:17.686Z,1578510377.686 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T19:06:17.686Z,1578510377.686 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T19:08:16.927Z,1578510496.927 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-01-08T19:08:16.930Z,1578510496.930 [BPC1](INFO): Received data from all battery sticks. 2020-01-08T19:08:52.044Z,1578510532.044 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T19:08:52.044Z,1578510532.044 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T19:09:04.969Z,1578510544.969 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T19:09:04.969Z,1578510544.969 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T19:09:17.546Z,1578510557.546 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T19:09:17.546Z,1578510557.546 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010812210, -0.1, 0.0,1448.9, 0 2020-01-08T19:09:32.447Z,1578510572.447 [NAL9602](INFO): SBD MO Status=2, MOMSN=10145, MT Status=2, MTMSN=0 2020-01-08T19:09:32.447Z,1578510572.447 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-08T19:10:52.883Z,1578510652.883 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-01-08T19:10:52.883Z,1578510652.883 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3268,-32768,V 2020-01-08T19:11:17.923Z,1578510677.923 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-08T19:06:17.7Z 2020-01-08T19:11:17.923Z,1578510677.923 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T19:11:17.923Z,1578510677.923 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T19:11:18.737Z,1578510678.737 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T19:11:19.578Z,1578510679.578 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E220000005D000004004A0000000000000000151D80030C5669C08C34EE4A2CE381E70BC476645FC01546792547ECF3417913D43F8119EB2F0C9B24FC 2020-01-08T19:11:19.579Z,1578510679.579 [Micromodem](INFO): Outgoing frame #2, 23 bytes: E3DB8018E17166020D5420C36618D93858E544A6BEC100 2020-01-08T19:11:19.579Z,1578510679.579 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T19:11:19.745Z,1578510679.745 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T19:11:20.111Z,1578510680.111 [Micromodem](INFO): Nmea in: $CADRQ,191121,1,9,0,64,1*45 2020-01-08T19:11:20.111Z,1578510680.111 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E220000005D000004004A0000000000000000151D80030C5669C08C34EE4A2CE381E70BC476645FC01546792547ECF3417913D43F8119EB2F0C9B24FC*7B 2020-01-08T19:11:20.519Z,1578510680.519 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T19:11:20.915Z,1578510680.915 [Micromodem](INFO): Nmea in: $CADRQ,191121,1,9,0,64,2*46 2020-01-08T19:11:20.915Z,1578510680.915 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,E3DB8018E17166020D5420C36618D93858E544A6BEC100*77 2020-01-08T19:11:21.319Z,1578510681.319 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,23*72 2020-01-08T19:11:21.730Z,1578510681.730 [Micromodem](INFO): Nmea in: $CATXP,87*7D 2020-01-08T19:11:22.127Z,1578510682.127 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T19:11:24.151Z,1578510684.151 [Micromodem](INFO): Nmea in: $CATXF,87*6B 2020-01-08T19:11:25.363Z,1578510685.363 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,191122.465413,3,0,200,4000,10000,1,1,9,1,2,2,3,87*75 2020-01-08T19:11:26.044Z,1578510686.044 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20200108T175317/Courier0034.lzma 2020-01-08T19:11:28.851Z,1578510688.851 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0034.lzma.bak 2020-01-08T19:11:28.851Z,1578510688.851 [DataOverHttps](INFO): SBD MOMSN=12186755 2020-01-08T19:11:46.933Z,1578510706.933 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20200108T175317/Express0035.lzma 2020-01-08T19:11:48.938Z,1578510708.938 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0035.lzma.bak 2020-01-08T19:11:48.939Z,1578510708.939 [DataOverHttps](INFO): SBD MOMSN=12186757 2020-01-08T19:11:51.264Z,1578510711.264 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T19:11:51.264Z,1578510711.264 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T19:11:51.264Z,1578510711.264 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T19:11:52.831Z,1578510712.831 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T19:12:32.850Z,1578510752.850 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T19:12:32.851Z,1578510752.851 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:12:34.498Z,1578510754.498 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-01-08T19:12:34.498Z,1578510754.498 [RDI_Pathfinder](ERROR): Failed to parse: :BD, , +0.00, +0.00, 0.00,4732.08 2020-01-08T19:12:51.040Z,1578510771.040 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2020-01-08T19:12:54.674Z,1578510774.674 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2020-01-08T19:13:16.516Z,1578510796.516 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-01-08T19:13:16.516Z,1578510796.516 [RDI_Pathfinder](ERROR): Failed to parse: :BD, +0.00, , +0.00, 0.00,4774.06 2020-01-08T19:14:09.837Z,1578510849.837 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T19:14:09.838Z,1578510849.838 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:14:13.847Z,1578510853.847 [RDI_Pathfinder](ERROR): Failed to parse: :R, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:15:16.502Z,1578510916.502 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T19:16:51.840Z,1578511011.840 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T19:16:51.845Z,1578511011.845 [Default:CheckIn:C.Wait] Stopped 2020-01-08T19:16:51.845Z,1578511011.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T19:16:51.845Z,1578511011.845 [Default:CheckIn:D] Running Loop=1 2020-01-08T19:16:52.229Z,1578511012.229 [Default:CheckIn:D] Stopped 2020-01-08T19:16:52.229Z,1578511012.229 [Default:CheckIn:E] Running Loop=1 2020-01-08T19:16:52.644Z,1578511012.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.832113 min 2020-01-08T19:16:52.644Z,1578511012.644 [Default:CheckIn:E] Stopped 2020-01-08T19:16:52.645Z,1578511012.645 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T19:16:52.645Z,1578511012.645 [Default:CheckIn] Stopped 2020-01-08T19:16:52.645Z,1578511012.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T19:16:52.645Z,1578511012.645 [Default:CheckIn](INFO): Running loop #12 2020-01-08T19:16:52.645Z,1578511012.645 [Default:CheckIn] Running Loop=12 2020-01-08T19:16:52.645Z,1578511012.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T19:16:52.645Z,1578511012.645 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T19:16:54.216Z,1578511014.216 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-01-08T19:16:54.217Z,1578511014.217 [NAL9602] Data Fault, FailCount= 2 2020-01-08T19:16:54.217Z,1578511014.217 [NAL9602](ERROR): Data Fault 2020-01-08T19:16:54.273Z,1578511014.273 [CBIT](ERROR): Data Fault in component: NAL9602 2020-01-08T19:16:54.623Z,1578511014.623 [NAL9602](INFO): Powering down 2020-01-08T19:16:55.519Z,1578511015.519 [CBIT](INFO): Clearing failed state for component NAL9602 2020-01-08T19:16:55.519Z,1578511015.519 [NAL9602] No Fault, FailCount= 2 2020-01-08T19:17:16.870Z,1578511036.870 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T19:17:16.870Z,1578511036.870 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:17:24.919Z,1578511044.919 [NAL9602](INFO): Powering up NAL9602 2020-01-08T19:17:35.479Z,1578511055.479 [RDI_Pathfinder](ERROR): Failed to parse: 68,-32768,-32768,V 2020-01-08T19:17:35.833Z,1578511055.833 [NAL9602](INFO): NAL9602 initialized 2020-01-08T19:18:34.842Z,1578511114.842 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T19:18:34.842Z,1578511114.842 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200108,35.0, -0.1, 0.0,10 2020-01-08T19:19:54.838Z,1578511194.838 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T19:19:54.839Z,1578511194.839 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010812322734,35.0, -0.1, 0.0,14 2020-01-08T19:21:52.816Z,1578511312.816 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-08T19:16:52.6Z 2020-01-08T19:21:52.816Z,1578511312.816 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T19:21:52.816Z,1578511312.816 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T19:21:53.582Z,1578511313.582 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T19:21:54.871Z,1578511314.871 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E250000005D00000400320000000000000000151D80030C5669C08C34EE72EA0A4703D3EA2959B88EA6D959470FDE8477B49785A5A9782A69E906D38D 2020-01-08T19:21:54.871Z,1578511314.871 [Micromodem](INFO): Outgoing frame #2, 6 bytes: CC30F98AD600 2020-01-08T19:21:54.871Z,1578511314.871 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T19:21:55.827Z,1578511315.827 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T19:21:56.231Z,1578511316.231 [Micromodem](INFO): Nmea in: $CADRQ,192156,1,9,0,64,1*46 2020-01-08T19:21:56.231Z,1578511316.231 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E250000005D00000400320000000000000000151D80030C5669C08C34EE72EA0A4703D3EA2959B88EA6D959470FDE8477B49785A5A9782A69E906D38D*0B 2020-01-08T19:21:56.635Z,1578511316.635 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T19:21:57.043Z,1578511317.043 [Micromodem](INFO): Nmea in: $CADRQ,192157,1,9,0,64,2*44 2020-01-08T19:21:57.043Z,1578511317.043 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,CC30F98AD600*06 2020-01-08T19:21:57.442Z,1578511317.442 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,6*45 2020-01-08T19:21:57.847Z,1578511317.847 [Micromodem](INFO): Nmea in: $CATXP,70*75 2020-01-08T19:22:00.271Z,1578511320.271 [Micromodem](INFO): Nmea in: $CATXF,70*63 2020-01-08T19:22:00.293Z,1578511320.293 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200108T175317/Courier0037.lzma 2020-01-08T19:22:00.679Z,1578511320.679 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,192158.569738,3,0,200,4000,10000,1,1,9,1,2,2,3,70*74 2020-01-08T19:22:02.302Z,1578511322.302 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0037.lzma.bak 2020-01-08T19:22:02.303Z,1578511322.303 [DataOverHttps](INFO): SBD MOMSN=12186767 2020-01-08T19:22:16.866Z,1578511336.866 [RDI_Pathfinder](ERROR): Failed to parse: :R, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:22:21.269Z,1578511341.269 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20200108T175317/Express0038.lzma 2020-01-08T19:22:23.268Z,1578511343.268 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0038.lzma.bak 2020-01-08T19:22:23.268Z,1578511343.268 [DataOverHttps](INFO): SBD MOMSN=12186769 2020-01-08T19:22:25.785Z,1578511345.785 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T19:22:25.785Z,1578511345.785 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T19:22:25.785Z,1578511345.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T19:22:39.459Z,1578511359.459 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T19:23:10.175Z,1578511390.175 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T19:23:31.573Z,1578511411.573 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-01-08T19:23:31.573Z,1578511411.573 [DropWeight] Hardware Fault, FailCount= 1 2020-01-08T19:23:31.574Z,1578511411.574 [DropWeight](ERROR): Hardware Fault 2020-01-08T19:23:31.615Z,1578511411.615 [CBIT](INFO): Critical error at 20200108T192331 2020-01-08T19:23:31.618Z,1578511411.618 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-01-08T19:23:31.618Z,1578511411.618 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-01-08T19:23:32.023Z,1578511412.023 [CBIT](INFO): Critical error at 20200108T192331 2020-01-08T19:23:53.823Z,1578511433.823 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:27:26.343Z,1578511646.343 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T19:27:26.344Z,1578511646.344 [Default:CheckIn:C.Wait] Stopped 2020-01-08T19:27:26.344Z,1578511646.344 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T19:27:26.344Z,1578511646.344 [Default:CheckIn:D] Running Loop=1 2020-01-08T19:27:26.749Z,1578511646.749 [Default:CheckIn:D] Stopped 2020-01-08T19:27:26.749Z,1578511646.749 [Default:CheckIn:E] Running Loop=1 2020-01-08T19:27:27.149Z,1578511647.149 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.407446 min 2020-01-08T19:27:27.149Z,1578511647.149 [Default:CheckIn:E] Stopped 2020-01-08T19:27:27.149Z,1578511647.149 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T19:27:27.150Z,1578511647.150 [Default:CheckIn] Stopped 2020-01-08T19:27:27.150Z,1578511647.150 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T19:27:27.150Z,1578511647.150 [Default:CheckIn](INFO): Running loop #13 2020-01-08T19:27:27.150Z,1578511647.150 [Default:CheckIn] Running Loop=13 2020-01-08T19:27:27.150Z,1578511647.150 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T19:27:27.150Z,1578511647.150 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T19:27:29.137Z,1578511649.137 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192712.00,A,3648.15947,N,12147.28264,W,0.486,100.78,080120,,,A*71 2020-01-08T19:27:29.139Z,1578511649.139 [NAL9602](INFO): GPS fix at 20200108T192712: (36.802658, -121.788044) 2020-01-08T19:27:29.194Z,1578511649.194 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T19:27:29.194Z,1578511649.194 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T19:27:29.932Z,1578511649.932 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T19:27:31.356Z,1578511651.356 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E280000005D00000400B10000000000000000151D80030C5669BF7A7A1573BDCED801A663E4C631FF63FEA3FB2D5904B580ABEDC4CCDB4D7CC1BA567B 2020-01-08T19:27:31.357Z,1578511651.357 [Micromodem](INFO): Outgoing frame #2, 64 bytes: CAEF70E0BA06008ECBC8535D77BF4E77B03DDD4766CEEBD79FDCADF5053183444F7854E8519592D9B58016E2085364EDD78493DFE7D045F752506329277ADD9F 2020-01-08T19:27:31.357Z,1578511651.357 [Micromodem](INFO): Outgoing frame #3, 39 bytes: DB4F2E4E5267DEA44BEF9BD86C7D15617B56C3729EB290CEE159DD28C1CBA62500810E7CF56B5E 2020-01-08T19:27:31.358Z,1578511651.358 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53 2020-01-08T19:27:31.575Z,1578511651.575 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51 2020-01-08T19:27:31.946Z,1578511651.946 [Micromodem](INFO): Nmea in: $CADRQ,192732,1,9,0,64,1*42 2020-01-08T19:27:31.946Z,1578511651.946 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E280000005D00000400B10000000000000000151D80030C5669BF7A7A1573BDCED801A663E4C631FF63FEA3FB2D5904B580ABEDC4CCDB4D7CC1BA567B*71 2020-01-08T19:27:32.353Z,1578511652.353 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T19:27:32.755Z,1578511652.755 [Micromodem](INFO): Nmea in: $CADRQ,192733,1,9,0,64,2*40 2020-01-08T19:27:32.755Z,1578511652.755 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,CAEF70E0BA06008ECBC8535D77BF4E77B03DDD4766CEEBD79FDCADF5053183444F7854E8519592D9B58016E2085364EDD78493DFE7D045F752506329277ADD9F*08 2020-01-08T19:27:32.846Z,1578511652.846 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:27:33.150Z,1578511653.150 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T19:27:33.567Z,1578511653.567 [Micromodem](INFO): Nmea in: $CADRQ,192734,1,9,0,64,3*46 2020-01-08T19:27:33.568Z,1578511653.568 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,DB4F2E4E5267DEA44BEF9BD86C7D15617B56C3729EB290CEE159DD28C1CBA62500810E7CF56B5E*08 2020-01-08T19:27:33.958Z,1578511653.958 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,39*79 2020-01-08T19:27:34.362Z,1578511654.362 [Micromodem](INFO): Nmea in: $CATXP,167*42 2020-01-08T19:27:37.927Z,1578511657.927 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20200108T175317/Courier0040.lzma 2020-01-08T19:27:38.006Z,1578511658.006 [Micromodem](INFO): Nmea in: $CATXF,167*54 2020-01-08T19:27:38.417Z,1578511658.417 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,192735.143819,3,0,200,4000,10000,1,1,9,1,3,3,3,167*4E 2020-01-08T19:27:39.930Z,1578511659.930 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0040.lzma.bak 2020-01-08T19:27:39.931Z,1578511659.931 [DataOverHttps](INFO): SBD MOMSN=12186780 2020-01-08T19:28:03.741Z,1578511683.741 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20200108T175317/Express0041.lzma 2020-01-08T19:28:05.322Z,1578511685.322 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0041.lzma.bak 2020-01-08T19:28:05.323Z,1578511685.323 [DataOverHttps](INFO): SBD MOMSN=12186786 2020-01-08T19:28:07.968Z,1578511687.968 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T19:28:07.968Z,1578511687.968 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T19:28:07.968Z,1578511687.968 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T19:29:18.651Z,1578511758.651 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-01-08T19:29:18.651Z,1578511758.651 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3268,-32768,V 2020-01-08T19:30:47.896Z,1578511847.896 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T19:30:47.897Z,1578511847.897 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010812432034,35.0, -0.1, 0.0,14 2020-01-08T19:32:31.707Z,1578511951.707 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T19:32:43.466Z,1578511963.466 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T19:33:02.413Z,1578511982.413 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T19:33:08.550Z,1578511988.550 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T19:33:08.551Z,1578511988.551 [Default:CheckIn:C.Wait] Stopped 2020-01-08T19:33:08.551Z,1578511988.551 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T19:33:08.551Z,1578511988.551 [Default:CheckIn:D] Running Loop=1 2020-01-08T19:33:08.945Z,1578511988.945 [Default:CheckIn:D] Stopped 2020-01-08T19:33:08.945Z,1578511988.945 [Default:CheckIn:E] Running Loop=1 2020-01-08T19:33:09.349Z,1578511989.349 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.110718 min 2020-01-08T19:33:09.349Z,1578511989.349 [Default:CheckIn:E] Stopped 2020-01-08T19:33:09.350Z,1578511989.350 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T19:33:09.350Z,1578511989.350 [Default:CheckIn] Stopped 2020-01-08T19:33:09.350Z,1578511989.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T19:33:09.350Z,1578511989.350 [Default:CheckIn](INFO): Running loop #14 2020-01-08T19:33:09.350Z,1578511989.350 [Default:CheckIn] Running Loop=14 2020-01-08T19:33:09.350Z,1578511989.350 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T19:33:09.350Z,1578511989.350 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T19:33:11.300Z,1578511991.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193254.00,A,3648.16269,N,12147.28409,W,0.175,109.57,080120,,,A*73 2020-01-08T19:33:11.302Z,1578511991.302 [NAL9602](INFO): GPS fix at 20200108T193254: (36.802712, -121.788068) 2020-01-08T19:33:11.368Z,1578511991.368 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T19:33:11.368Z,1578511991.368 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T19:33:12.287Z,1578511992.287 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T19:33:13.703Z,1578511993.703 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E2B0000005D00000400610000000000000000151D80030C5669C08C34EE72985E33EDF0D15D9674F068464F0017CDD6134D5E35FBDB3BD0587D8FB6F9 2020-01-08T19:33:13.703Z,1578511993.703 [Micromodem](INFO): Outgoing frame #2, 29 bytes: FCD11EA63CBE933DC4A821570B67AB8F65BFCEF4DCEFBAA0F28D9AD600 2020-01-08T19:33:13.703Z,1578511993.704 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T19:33:13.908Z,1578511993.908 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T19:33:14.122Z,1578511994.122 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20200108T175317/Courier0043.lzma 2020-01-08T19:33:14.263Z,1578511994.263 [Micromodem](INFO): Nmea in: $CADRQ,193315,1,9,0,64,1*42 2020-01-08T19:33:14.264Z,1578511994.264 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E2B0000005D00000400610000000000000000151D80030C5669C08C34EE72985E33EDF0D15D9674F068464F0017CDD6134D5E35FBDB3BD0587D8FB6F9*04 2020-01-08T19:33:14.673Z,1578511994.673 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T19:33:15.071Z,1578511995.071 [Micromodem](INFO): Nmea in: $CADRQ,193315,1,9,0,64,2*41 2020-01-08T19:33:15.071Z,1578511995.071 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,FCD11EA63CBE933DC4A821570B67AB8F65BFCEF4DCEFBAA0F28D9AD600*0E 2020-01-08T19:33:15.473Z,1578511995.473 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,29*78 2020-01-08T19:33:15.870Z,1578511995.870 [Micromodem](INFO): Nmea in: $CATXP,93*78 2020-01-08T19:33:16.130Z,1578511996.130 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0043.lzma.bak 2020-01-08T19:33:16.131Z,1578511996.131 [DataOverHttps](INFO): SBD MOMSN=12186790 2020-01-08T19:33:18.302Z,1578511998.302 [Micromodem](INFO): Nmea in: $CATXF,93*6E 2020-01-08T19:33:19.519Z,1578511999.519 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,193316.626154,3,0,200,4000,10000,1,1,9,1,2,2,3,93*74 2020-01-08T19:33:36.475Z,1578512016.475 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20200108T175317/Express0044.lzma 2020-01-08T19:33:38.475Z,1578512018.475 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0044.lzma.bak 2020-01-08T19:33:38.475Z,1578512018.475 [DataOverHttps](INFO): SBD MOMSN=12186793 2020-01-08T19:33:40.991Z,1578512020.991 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T19:33:40.991Z,1578512020.991 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T19:33:40.991Z,1578512020.991 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T19:33:51.851Z,1578512031.851 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T19:33:51.852Z,1578512031.852 [RDI_Pathfinder](ERROR): Failed to parse: :TS,200108125.0, -0.1, 0.0,1448.9, 0 2020-01-08T19:33:57.534Z,1578512037.534 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-01-08T19:33:57.534Z,1578512037.534 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00 2020-01-08T19:34:37.912Z,1578512077.912 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-08T19:35:48.640Z,1578512148.640 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-01-08T19:35:48.640Z,1578512148.640 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:37:45.555Z,1578512265.555 [CommandLine](IMPORTANT): got command failComponent 2020-01-08T19:37:45.555Z,1578512265.555 [CommandLine](IMPORTANT): Failed components: 2020-01-08T19:37:45.555Z,1578512265.555 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-01-08T19:38:12.843Z,1578512292.843 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-01-08T19:38:12.843Z,1578512292.843 [RDI_Pathfinder](ERROR): Failed to parse: :TS,20010812504534,35, 0.0,1448.9, 0 2020-01-08T19:38:14.023Z,1578512294.023 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-01-08T19:38:31.856Z,1578512311.856 [CBIT](INFO): Clearing failed state for component DropWeight 2020-01-08T19:38:31.856Z,1578512311.856 [DropWeight] No Fault, FailCount= 1 2020-01-08T19:38:33.472Z,1578512313.472 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-01-08T19:38:33.472Z,1578512313.472 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-3276,-32768,V 2020-01-08T19:38:41.535Z,1578512321.535 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T19:38:41.535Z,1578512321.535 [Default:CheckIn:C.Wait] Stopped 2020-01-08T19:38:41.535Z,1578512321.535 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T19:38:41.535Z,1578512321.535 [Default:CheckIn:D] Running Loop=1 2020-01-08T19:38:41.931Z,1578512321.931 [Default:CheckIn:D] Stopped 2020-01-08T19:38:41.931Z,1578512321.931 [Default:CheckIn:E] Running Loop=1 2020-01-08T19:38:42.345Z,1578512322.345 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.660482 min 2020-01-08T19:38:42.345Z,1578512322.345 [Default:CheckIn:E] Stopped 2020-01-08T19:38:42.345Z,1578512322.345 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T19:38:42.345Z,1578512322.345 [Default:CheckIn] Stopped 2020-01-08T19:38:42.346Z,1578512322.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T19:38:42.346Z,1578512322.346 [Default:CheckIn](INFO): Running loop #15 2020-01-08T19:38:42.346Z,1578512322.346 [Default:CheckIn] Running Loop=15 2020-01-08T19:38:42.346Z,1578512322.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T19:38:42.346Z,1578512322.346 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T19:38:44.331Z,1578512324.331 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193827.00,A,3648.16386,N,12147.28256,W,0.272,109.57,080120,,,A*75 2020-01-08T19:38:44.333Z,1578512324.333 [NAL9602](INFO): GPS fix at 20200108T193827: (36.802731, -121.788043) 2020-01-08T19:38:44.358Z,1578512324.358 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T19:38:44.358Z,1578512324.358 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T19:38:45.146Z,1578512325.146 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T19:38:46.268Z,1578512326.268 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E2E0000005D00000400610000000000000000151D80030C5669C08C34EE77EA6245ADF60E8B100052AF5CC910B4DA566DA2709E04C721EB42DD65D2C7 2020-01-08T19:38:46.269Z,1578512326.269 [Micromodem](INFO): Outgoing frame #2, 28 bytes: 65E1A661A47F3B355BC482B661A51272F0B48B5064C0BB520ABEFA00 2020-01-08T19:38:46.269Z,1578512326.269 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52 2020-01-08T19:38:46.473Z,1578512326.473 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50 2020-01-08T19:38:46.811Z,1578512326.811 [Micromodem](INFO): Nmea in: $CADRQ,193847,1,9,0,64,1*4E 2020-01-08T19:38:46.811Z,1578512326.811 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,0D17165E2E0000005D00000400610000000000000000151D80030C5669C08C34EE77EA6245ADF60E8B100052AF5CC910B4DA566DA2709E04C721EB42DD65D2C7*75 2020-01-08T19:38:47.219Z,1578512327.219 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71 2020-01-08T19:38:47.619Z,1578512327.619 [Micromodem](INFO): Nmea in: $CADRQ,193848,1,9,0,64,2*42 2020-01-08T19:38:47.619Z,1578512327.619 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,65E1A661A47F3B355BC482B661A51272F0B48B5064C0BB520ABEFA00*76 2020-01-08T19:38:48.023Z,1578512328.023 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,28*79 2020-01-08T19:38:48.426Z,1578512328.426 [Micromodem](INFO): Nmea in: $CATXP,92*79 2020-01-08T19:38:50.851Z,1578512330.851 [Micromodem](INFO): Nmea in: $CATXF,92*6F 2020-01-08T19:38:52.062Z,1578512332.062 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,193849.167909,3,0,200,4000,10000,1,1,9,1,2,2,3,92*76 2020-01-08T19:38:52.392Z,1578512332.392 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200108T175317/Courier0046.lzma 2020-01-08T19:38:54.400Z,1578512334.400 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Courier0046.lzma.bak 2020-01-08T19:38:54.401Z,1578512334.401 [DataOverHttps](INFO): SBD MOMSN=12186797 2020-01-08T19:39:11.998Z,1578512351.998 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20200108T175317/Express0047.lzma 2020-01-08T19:39:14.003Z,1578512354.003 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0047.lzma.bak 2020-01-08T19:39:14.003Z,1578512354.003 [DataOverHttps](INFO): SBD MOMSN=12186800 2020-01-08T19:39:16.337Z,1578512356.337 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T19:39:16.337Z,1578512356.337 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T19:39:16.337Z,1578512356.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T19:39:16.707Z,1578512356.707 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T19:40:40.792Z,1578512440.792 [CommandLine](IMPORTANT): got command show variable address 2020-01-08T19:40:40.797Z,1578512440.797 [CommandLine](IMPORTANT): acoustic_contact_address (unknown) 2020-01-08T19:40:40.000Z,1578512441.000 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.localAddress (count) 2020-01-08T19:40:41.000Z,1578512441.000 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum) 2020-01-08T19:40:41.005Z,1578512441.005 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.transponderAddress (enum) 2020-01-08T19:40:41.007Z,1578512441.007 [CommandLine](IMPORTANT): DAT.localAddress (count) 2020-01-08T19:40:41.008Z,1578512441.008 [CommandLine](IMPORTANT): DAT.sbdAddress (enum) 2020-01-08T19:40:41.008Z,1578512441.008 [CommandLine](IMPORTANT): DAT.transponderAddress (enum) 2020-01-08T19:40:41.071Z,1578512441.071 [CommandLine](IMPORTANT): Micromodem.localAddress (enum) 2020-01-08T19:40:41.072Z,1578512441.072 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum) 2020-01-08T19:40:41.248Z,1578512441.248 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_address (enum) 2020-01-08T19:40:41.277Z,1578512441.277 [CommandLine](IMPORTANT): Micromodem.remote_address (count) 2020-01-08T19:40:41.278Z,1578512441.278 [CommandLine](IMPORTANT): Micromodem.local_address (count) 2020-01-08T19:41:03.603Z,1578512463.603 [CommandLine](IMPORTANT): got command get Micromodem.destinationAddress 2020-01-08T19:41:03.603Z,1578512463.603 [CommandLine](IMPORTANT): Micromodem.destinationAddress 9 enum 2020-01-08T19:42:00.426Z,1578512520.426 [CommandLine](IMPORTANT): got command configSet Micromodem.destinationAddress 12.000000 enum persist 2020-01-08T19:42:05.611Z,1578512525.611 [CommandLine](IMPORTANT): got command failComponent hardware Micromodem 2020-01-08T19:42:05.611Z,1578512525.611 [Micromodem] Hardware Fault, FailCount= 1 2020-01-08T19:42:05.611Z,1578512525.611 [Micromodem](ERROR): Hardware Fault 2020-01-08T19:42:05.611Z,1578512525.611 [CommandLine](IMPORTANT): Micromodem failureMode is Hardware Fault 2020-01-08T19:42:05.638Z,1578512525.638 [CBIT](ERROR): Hardware Fault in component: Micromodem 2020-01-08T19:42:05.982Z,1578512525.982 [Micromodem](INFO): Powering down 2020-01-08T19:42:06.846Z,1578512526.846 [CBIT](INFO): Clearing failed state for component Micromodem 2020-01-08T19:42:06.846Z,1578512526.846 [Micromodem] No Fault, FailCount= 1 2020-01-08T19:42:09.622Z,1578512529.622 [Micromodem](INFO): Powering up 2020-01-08T19:42:09.622Z,1578512529.622 [Micromodem](DEBUG): Initializing Micromodem. 2020-01-08T19:42:14.472Z,1578512534.472 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-01-08T19:42:14.871Z,1578512534.871 [Micromodem](INFO): Nmea in: $CATMG,2020-01-08T19:42:14.984910Z,RTC,RTC*5F 2020-01-08T19:42:14.871Z,1578512534.871 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-08T19:42:14.984910Z,RTC,RTC*5F 2020-01-08T19:42:15.274Z,1578512535.274 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-01-08T19:42:15.275Z,1578512535.275 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-01-08T19:42:15.582Z,1578512535.582 [CommandLine](IMPORTANT): got command get Micromodem.destinationAddress 2020-01-08T19:42:15.582Z,1578512535.582 [CommandLine](IMPORTANT): Micromodem.destinationAddress 12 enum 2020-01-08T19:42:15.678Z,1578512535.678 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-01-08T19:42:15.679Z,1578512535.679 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-01-08T19:42:16.083Z,1578512536.083 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-01-08T19:42:16.083Z,1578512536.083 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-01-08T19:42:16.491Z,1578512536.491 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-01-08T19:42:16.491Z,1578512536.491 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-01-08T19:42:16.899Z,1578512536.899 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-01-08T19:42:16.899Z,1578512536.899 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-01-08T19:42:17.299Z,1578512537.299 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-01-08T19:42:17.299Z,1578512537.299 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-01-08T19:42:17.699Z,1578512537.699 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-01-08T19:42:17.699Z,1578512537.699 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-01-08T19:42:18.112Z,1578512538.112 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-01-08T19:42:18.113Z,1578512538.113 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,08,19,42,19*4B 2020-01-08T19:42:18.511Z,1578512538.511 [Micromodem](INFO): Nmea in: $CACLK,2020,1,8,19,42,19*49 2020-01-08T19:42:18.915Z,1578512538.915 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-08T19:42:20Z*73 2020-01-08T19:42:18.916Z,1578512538.916 [Micromodem](INFO): Nmea in: $CATMG,2020-01-08T19:42:20.026189Z,USER_CMD,RTC*10 2020-01-08T19:42:25.451Z,1578512545.451 [CommandLine](IMPORTANT): got command failComponent 2020-01-08T19:42:25.451Z,1578512545.451 [CommandLine](IMPORTANT): Failed components: 2020-01-08T19:42:25.452Z,1578512545.452 [CommandLine](IMPORTANT): No failed Components. 2020-01-08T19:42:43.562Z,1578512563.562 [CommandLine](IMPORTANT): got command configSet list 2020-01-08T19:42:43.562Z,1578512563.562 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-01-08T19:42:43.563Z,1578512563.563 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter; 2020-01-08T19:42:43.563Z,1578512563.563 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree; 2020-01-08T19:42:43.563Z,1578512563.563 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree; 2020-01-08T19:42:43.563Z,1578512563.563 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): DDM.verbosity=3 count; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): Express all DDM.cablePresent; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter; 2020-01-08T19:42:43.564Z,1578512563.564 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree; 2020-01-08T19:42:43.565Z,1578512563.565 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter; 2020-01-08T19:42:43.565Z,1578512563.565 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree; 2020-01-08T19:42:43.565Z,1578512563.565 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-08T19:42:43.565Z,1578512563.565 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-08T19:42:43.565Z,1578512563.565 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second; 2020-01-08T19:42:43.565Z,1578512563.565 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none; 2020-01-08T19:42:43.565Z,1578512563.565 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree; 2020-01-08T19:42:43.565Z,1578512563.565 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum; 2020-01-08T19:42:43.566Z,1578512563.566 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter; 2020-01-08T19:42:43.566Z,1578512563.566 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second; 2020-01-08T19:42:43.566Z,1578512563.566 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree; 2020-01-08T19:42:43.566Z,1578512563.566 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter; 2020-01-08T19:42:43.566Z,1578512563.566 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter; 2020-01-08T19:42:55.947Z,1578512575.947 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1578512512.000000 second 2020-01-08T19:43:28.635Z,1578512608.635 [RDI_Pathfinder](ERROR): Failed to parse:.00, 0.00, 0.00, 0.00, 0.00 2020-01-08T19:43:37.900Z,1578512617.900 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-01-08T19:43:37.900Z,1578512617.900 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768-32768,V 2020-01-08T19:43:43.578Z,1578512623.578 [CommandLine](IMPORTANT): got command get latitude 2020-01-08T19:43:43.578Z,1578512623.578 [CommandLine](IMPORTANT): latitude 36.802731 arcdeg 2020-01-08T19:44:10.891Z,1578512650.891 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree 2020-01-08T19:44:10.893Z,1578512650.893 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2020-01-08T19:44:10.893Z,1578512650.893 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree 2020-01-08T19:44:10.895Z,1578512650.895 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2020-01-08T19:44:11.072Z,1578512651.072 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T19:44:17.104Z,1578512657.104 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-01-08T19:44:17.104Z,1578512657.104 [Default:CheckIn:C.Wait] Stopped 2020-01-08T19:44:17.104Z,1578512657.104 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T19:44:17.104Z,1578512657.104 [Default:CheckIn:D] Running Loop=1 2020-01-08T19:44:17.607Z,1578512657.607 [Default:CheckIn:D] Stopped 2020-01-08T19:44:17.607Z,1578512657.607 [Default:CheckIn:E] Running Loop=1 2020-01-08T19:44:17.930Z,1578512657.930 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.255078 min 2020-01-08T19:44:17.931Z,1578512657.931 [Default:CheckIn:E] Stopped 2020-01-08T19:44:17.931Z,1578512657.931 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-01-08T19:44:17.931Z,1578512657.931 [Default:CheckIn] Stopped 2020-01-08T19:44:17.931Z,1578512657.931 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T19:44:17.931Z,1578512657.931 [Default:CheckIn](INFO): Running loop #16 2020-01-08T19:44:17.931Z,1578512657.931 [Default:CheckIn] Running Loop=16 2020-01-08T19:44:17.931Z,1578512657.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-08T19:44:17.931Z,1578512657.931 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-08T19:44:19.892Z,1578512659.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194403.00,A,3648.16096,N,12147.29210,W,0.272,240.91,080120,,,A*7D 2020-01-08T19:44:19.895Z,1578512659.895 [NAL9602](INFO): GPS fix at 20200108T194403: (36.802683, -121.788202) 2020-01-08T19:44:19.930Z,1578512659.930 [Default:CheckIn:Read_GPS] Stopped 2020-01-08T19:44:19.930Z,1578512659.930 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-01-08T19:44:20.701Z,1578512660.701 [Micromodem](ERROR): Buffer fill timout failure. 2020-01-08T19:44:21.683Z,1578512661.683 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 0D17165E310000005D000004007F0000000000000000151D80030C5669BFD2BCB008510F13609B42361EFC466F862314C40322C94D91A3287D2D025A36DA2148 2020-01-08T19:44:21.684Z,1578512661.684 [Micromodem](INFO): Outgoing frame #2, 51 bytes: DC31209412A970A66A34464FDE4F43EC4FB68E02F047AB9AEC0689819E55ACE80D44C0057374A87989E9A2E84AFB610E22F092 2020-01-08T19:44:21.684Z,1578512661.684 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68 2020-01-08T19:44:21.874Z,1578512661.874 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A 2020-01-08T19:44:22.235Z,1578512662.235 [Micromodem](INFO): Nmea in: $CADRQ,194423,1,12,0,64,1*7D 2020-01-08T19:44:22.235Z,1578512662.235 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,0D17165E310000005D000004007F0000000000000000151D80030C5669BFD2BCB008510F13609B42361EFC466F862314C40322C94D91A3287D2D025A36DA2148*31 2020-01-08T19:44:22.643Z,1578512662.643 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-08T19:44:23.047Z,1578512663.047 [Micromodem](INFO): Nmea in: $CADRQ,194423,1,12,0,64,2*7E 2020-01-08T19:44:23.047Z,1578512663.047 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DC31209412A970A66A34464FDE4F43EC4FB68E02F047AB9AEC0689819E55ACE80D44C0057374A87989E9A2E84AFB610E22F092*3B 2020-01-08T19:44:23.463Z,1578512663.463 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,51*4D 2020-01-08T19:44:23.851Z,1578512663.851 [Micromodem](INFO): Nmea in: $CATXP,115*47 2020-01-08T19:44:26.298Z,1578512666.298 [Micromodem](INFO): Nmea in: $CATXF,115*51 2020-01-08T19:44:27.506Z,1578512667.506 [Micromodem](INFO): Nmea in: $CAXST,6,20200108,194424.633501,3,0,200,4000,10000,1,1,12,1,2,2,3,115*70 2020-01-08T19:44:27.844Z,1578512667.844 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20200108T175317/Courier0049.lzma 2020-01-08T19:44:27.891Z,1578512667.891 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79 2020-01-08T19:44:28.299Z,1578512668.299 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A 2020-01-08T19:44:28.300Z,1578512668.300 [Micromodem](INFO): Sent 107 bytes from file Logs/20200108T175317/Courier0049.lzma.parts 2020-01-08T19:44:28.300Z,1578512668.300 [Micromodem](INFO): Packets left to send: 0 2020-01-08T19:44:28.706Z,1578512668.706 [Micromodem](INFO): Nmea in: $CACST,6,0,20200108194427.906099,06,143,21,0111,0150,119,02,02,00,04,1,012,001,0,4,1,0,150,21.2,10.00,20,-7.00,-01,-0.02,30,10000,4000*41 2020-01-08T19:44:30.582Z,1578512670.582 [DataOverHttps](FAULT): Server acknowledged file Logs/20200108T175317/Courier0049.lzma, notLogs/20200108T175317/Express0050.lzma 2020-01-08T19:44:30.582Z,1578512670.582 [DataOverHttps](INFO): SBD MOMSN=12186807 2020-01-08T19:44:51.361Z,1578512691.361 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-01-08T19:44:51.441Z,1578512691.441 [NAL9602](FAULT): received: +CSQ:0 OK145, 2, 0, 0, 0 OK 2020-01-08T19:44:51.442Z,1578512691.442 [NAL9602] Data Fault, FailCount= 3 2020-01-08T19:44:51.442Z,1578512691.442 [NAL9602](ERROR): Data Fault 2020-01-08T19:44:51.612Z,1578512691.612 [CBIT](ERROR): Data Fault in component: NAL9602 2020-01-08T19:44:52.394Z,1578512692.394 [NAL9602](INFO): Powering down 2020-01-08T19:44:53.222Z,1578512693.222 [DataOverHttps](INFO): Sending 1199 bytes from file Logs/20200108T175317/Express0050.lzma 2020-01-08T19:44:53.634Z,1578512693.634 [CBIT](INFO): Clearing failed state for component NAL9602 2020-01-08T19:44:53.634Z,1578512693.634 [NAL9602] No Fault, FailCount= 3 2020-01-08T19:44:55.226Z,1578512695.226 [DataOverHttps](INFO): Moved sent file to Logs/20200108T175317/Express0050.lzma.bak 2020-01-08T19:44:55.227Z,1578512695.227 [DataOverHttps](INFO): SBD MOMSN=12186811 2020-01-08T19:44:59.132Z,1578512699.132 [Default:CheckIn:Read_Iridium] Stopped 2020-01-08T19:44:59.132Z,1578512699.132 [Default:CheckIn:C.Wait] Running Loop=1 2020-01-08T19:44:59.132Z,1578512699.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-01-08T19:45:02.697Z,1578512702.697 [CommandLine](IMPORTANT): got command maintain clear 2020-01-08T19:45:02.929Z,1578512702.929 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T19:45:22.623Z,1578512722.623 [NAL9602](INFO): Powering up NAL9602 2020-01-08T19:45:33.530Z,1578512733.530 [NAL9602](INFO): NAL9602 initialized 2020-01-08T19:45:42.395Z,1578512742.395 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1578512768.000000 second 2020-01-08T19:45:56.754Z,1578512756.754 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree 2020-01-08T19:45:56.755Z,1578512756.755 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2020-01-08T19:45:56.756Z,1578512756.756 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree 2020-01-08T19:45:56.756Z,1578512756.756 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2020-01-08T19:45:57.014Z,1578512757.014 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T19:46:04.475Z,1578512764.475 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1578512768.000000 second 2020-01-08T19:46:04.649Z,1578512764.649 [NAL9602](INFO): Not Powering down - fast GPS 2020-01-08T19:46:08.451Z,1578512768.451 [CommandLine](IMPORTANT): got command maintain clear 2020-01-08T19:46:08.748Z,1578512768.748 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T19:46:12.905Z,1578512772.905 [CommandLine](IMPORTANT): got command configSet list 2020-01-08T19:46:12.906Z,1578512772.906 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-01-08T19:46:12.906Z,1578512772.906 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): DDM.verbosity=3 count; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): Express all DDM.cablePresent; 2020-01-08T19:46:12.907Z,1578512772.907 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second; 2020-01-08T19:46:12.908Z,1578512772.908 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none; 2020-01-08T19:46:12.909Z,1578512772.909 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree; 2020-01-08T19:46:12.909Z,1578512772.909 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum; 2020-01-08T19:46:12.909Z,1578512772.909 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter; 2020-01-08T19:46:12.909Z,1578512772.909 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second; 2020-01-08T19:46:12.909Z,1578512772.909 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree; 2020-01-08T19:46:12.909Z,1578512772.909 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter; 2020-01-08T19:46:12.909Z,1578512772.909 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter; 2020-01-08T19:46:38.827Z,1578512798.827 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 10.000000 meter 2020-01-08T19:46:38.828Z,1578512798.828 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2020-01-08T19:46:39.039Z,1578512799.039 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T19:46:39.853Z,1578512799.853 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (1 active estimators). 2020-01-08T19:46:45.148Z,1578512805.148 [CommandLine](IMPORTANT): got command maintain clear 2020-01-08T19:46:45.285Z,1578512805.285 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T19:46:49.976Z,1578512809.976 [CommandLine](IMPORTANT): got command get depth 2020-01-08T19:46:49.976Z,1578512809.976 [CommandLine](IMPORTANT): depth 0.154335 m 2020-01-08T19:46:58.266Z,1578512818.266 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 10.000000 meter 2020-01-08T19:46:58.267Z,1578512818.267 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2020-01-08T19:46:58.530Z,1578512818.530 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T19:47:03.267Z,1578512823.267 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 9.000000 meter 2020-01-08T19:47:06.591Z,1578512826.591 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 8.000000 meter 2020-01-08T19:47:09.451Z,1578512829.451 [CommandLine](IMPORTANT): got command maintain clear 2020-01-08T19:47:09.614Z,1578512829.614 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-01-08T19:47:31.714Z,1578512851.714 [CommandLine](IMPORTANT): got command quit 2020-01-08T19:47:32.721Z,1578512852.721 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-08T19:47:32.721Z,1578512852.721 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:32.722Z,1578512852.722 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-01-08T19:47:32.722Z,1578512852.722 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:32.722Z,1578512852.722 [CommandLine](INFO): Join timeout helper Thread ID is 7331 2020-01-08T19:47:32.723Z,1578512852.723 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-01-08T19:47:32.723Z,1578512852.723 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:32.723Z,1578512852.723 [NavChartDb](INFO): Join timeout helper Thread ID is 7332 2020-01-08T19:47:33.029Z,1578512853.029 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-08T19:47:33.029Z,1578512853.029 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:33.041Z,1578512853.041 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2020-01-08T19:47:33.041Z,1578512853.041 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:33.041Z,1578512853.041 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7333 2020-01-08T19:47:33.385Z,1578512853.385 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-08T19:47:33.385Z,1578512853.385 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-01-08T19:47:33.386Z,1578512853.386 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:33.389Z,1578512853.389 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2020-01-08T19:47:33.389Z,1578512853.389 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:33.389Z,1578512853.389 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7334 2020-01-08T19:47:33.434Z,1578512853.434 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-08T19:47:33.434Z,1578512853.434 [CTD_NeilBrown](INFO): Powering down 2020-01-08T19:47:33.449Z,1578512853.449 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:33.457Z,1578512853.457 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-01-08T19:47:33.457Z,1578512853.457 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:33.457Z,1578512853.457 [Radio_Surface](INFO): Join timeout helper Thread ID is 7335 2020-01-08T19:47:33.789Z,1578512853.789 [Radio_Surface](INFO): Powering down 2020-01-08T19:47:33.790Z,1578512853.790 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-08T19:47:33.790Z,1578512853.790 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:33.810Z,1578512853.810 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-01-08T19:47:33.810Z,1578512853.810 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:33.810Z,1578512853.810 [DataOverHttps](INFO): Join timeout helper Thread ID is 7336 2020-01-08T19:47:34.009Z,1578512854.009 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-08T19:47:34.010Z,1578512854.010 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.017Z,1578512854.017 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-01-08T19:47:34.018Z,1578512854.018 [logger ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.018Z,1578512854.018 [logger](INFO): Join timeout helper Thread ID is 7337 2020-01-08T19:47:34.053Z,1578512854.053 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-08T19:47:34.053Z,1578512854.053 [logger ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.057Z,1578512854.057 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-01-08T19:47:34.058Z,1578512854.058 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.058Z,1578512854.058 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-01-08T19:47:34.058Z,1578512854.058 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.058Z,1578512854.058 [controlThread](INFO): Join timeout helper Thread ID is 7338 2020-01-08T19:47:34.097Z,1578512854.097 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-08T19:47:34.097Z,1578512854.097 [controlThread](DEBUG): Uninitializing ControlThread 2020-01-08T19:47:34.098Z,1578512854.098 [AHRS_M2](INFO): Powering down 2020-01-08T19:47:34.169Z,1578512854.169 [DDM](INFO): Powering down 2020-01-08T19:47:34.261Z,1578512854.261 [DUSBL_Hydroid](INFO): Powering down 2020-01-08T19:47:34.353Z,1578512854.353 [Micromodem](INFO): Powering down 2020-01-08T19:47:34.449Z,1578512854.449 [NAL9602](INFO): Powering down 2020-01-08T19:47:34.521Z,1578512854.521 [RDI_Pathfinder](INFO): Powering down 2020-01-08T19:47:34.522Z,1578512854.522 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-01-08T19:47:34.523Z,1578512854.523 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-01-08T19:47:34.524Z,1578512854.524 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-01-08T19:47:34.524Z,1578512854.524 [MissionManager](INFO): Uninitializing Mission Default 2020-01-08T19:47:34.524Z,1578512854.524 [Default] Stopped 2020-01-08T19:47:34.524Z,1578512854.524 [Default](DEBUG): Aggregate::uninitialize Default 2020-01-08T19:47:34.524Z,1578512854.524 [Default:B.GoToSurface] Stopped 2020-01-08T19:47:34.525Z,1578512854.525 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-01-08T19:47:34.525Z,1578512854.525 [Default:CheckIn] Stopped 2020-01-08T19:47:34.525Z,1578512854.525 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-08T19:47:34.525Z,1578512854.525 [Default:CheckIn:C.Wait] Stopped 2020-01-08T19:47:34.525Z,1578512854.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-01-08T19:47:34.527Z,1578512854.527 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-01-08T19:47:34.527Z,1578512854.527 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-01-08T19:47:34.528Z,1578512854.528 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-01-08T19:47:34.528Z,1578512854.528 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-01-08T19:47:34.528Z,1578512854.528 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-01-08T19:47:34.528Z,1578512854.528 [BuoyancyServo](INFO): Powering down 2020-01-08T19:47:34.541Z,1578512854.541 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-01-08T19:47:34.541Z,1578512854.541 [ElevatorServo](INFO): Powering down 2020-01-08T19:47:34.542Z,1578512854.542 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-01-08T19:47:34.542Z,1578512854.542 [MassServo](INFO): Powering down 2020-01-08T19:47:34.543Z,1578512854.543 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-01-08T19:47:34.543Z,1578512854.543 [RudderServo](INFO): Powering down 2020-01-08T19:47:34.544Z,1578512854.544 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-01-08T19:47:34.544Z,1578512854.544 [ThrusterServo](INFO): Powering down 2020-01-08T19:47:34.544Z,1578512854.544 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-01-08T19:47:34.545Z,1578512854.545 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-01-08T19:47:34.545Z,1578512854.545 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-01-08T19:47:34.545Z,1578512854.545 [CBIT](DEBUG): Powering off loads. 2020-01-08T19:47:34.557Z,1578512854.557 [CBIT](DEBUG): Disabling WDT. 2020-01-08T19:47:34.569Z,1578512854.569 [CBIT](DEBUG): Opening all GF detection circuits. 2020-01-08T19:47:34.570Z,1578512854.570 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.616Z,1578512854.616 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.626Z,1578512854.626 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.668Z,1578512854.668 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.671Z,1578512854.671 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.730Z,1578512854.730 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-08T19:47:34.811Z,1578512854.811 [logger ThreadHandler](INFO): Thread cancelled.