2020-02-27T15:47:29.693Z,1582818449.693 [Supervisor](DEBUG): Initializing supervisor. 2020-02-27T15:47:29.695Z,1582818449.695 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-02-27T15:47:29.696Z,1582818449.696 [SyncHandler](INFO): Protected caller Thread ID is 4001 2020-02-27T15:47:29.696Z,1582818449.696 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-02-27T15:47:29.697Z,1582818449.697 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-02-27T15:47:29.698Z,1582818449.698 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4002 2020-02-27T15:47:29.701Z,1582818449.701 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-02-27T15:47:29.714Z,1582818449.714 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-02-27T15:47:29.715Z,1582818449.715 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-02-27T15:47:29.715Z,1582818449.715 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4003 2020-02-27T15:47:29.716Z,1582818449.716 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-02-27T15:47:29.717Z,1582818449.717 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-02-27T15:47:29.717Z,1582818449.717 [logger ThreadHandler](INFO): Protected caller Thread ID is 4004 2020-02-27T15:47:29.719Z,1582818449.719 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-02-27T15:47:29.720Z,1582818449.720 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-02-27T15:47:29.722Z,1582818449.722 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-02-27T15:47:29.815Z,1582818449.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-02-27T15:47:29.815Z,1582818449.815 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-02-27T15:47:30.401Z,1582818450.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-02-27T15:47:30.402Z,1582818450.402 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-02-27T15:47:30.497Z,1582818450.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-02-27T15:47:30.498Z,1582818450.498 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-02-27T15:47:30.597Z,1582818450.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-02-27T15:47:30.598Z,1582818450.598 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-02-27T15:47:30.676Z,1582818450.676 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-02-27T15:47:30.814Z,1582818450.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-02-27T15:47:30.815Z,1582818450.815 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-02-27T15:47:31.106Z,1582818451.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-02-27T15:47:31.106Z,1582818451.106 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-02-27T15:47:31.564Z,1582818451.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-02-27T15:47:31.565Z,1582818451.565 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-02-27T15:47:31.707Z,1582818451.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-02-27T15:47:31.708Z,1582818451.708 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-02-27T15:47:31.894Z,1582818451.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-02-27T15:47:31.895Z,1582818451.895 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-02-27T15:47:32.367Z,1582818452.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-02-27T15:47:32.368Z,1582818452.368 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-02-27T15:47:32.585Z,1582818452.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-02-27T15:47:32.585Z,1582818452.585 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-02-27T15:47:32.783Z,1582818452.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-02-27T15:47:32.784Z,1582818452.784 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-02-27T15:47:33.196Z,1582818453.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-02-27T15:47:33.197Z,1582818453.197 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-02-27T15:47:33.523Z,1582818453.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-02-27T15:47:33.525Z,1582818453.525 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-02-27T15:47:33.526Z,1582818453.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-02-27T15:47:33.607Z,1582818453.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-02-27T15:47:33.766Z,1582818453.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-02-27T15:47:33.873Z,1582818453.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-02-27T15:47:33.957Z,1582818453.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-02-27T15:47:34.049Z,1582818454.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-02-27T15:47:34.254Z,1582818454.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-02-27T15:47:34.488Z,1582818454.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-02-27T15:47:34.488Z,1582818454.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-02-27T15:47:34.579Z,1582818454.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-02-27T15:47:34.713Z,1582818454.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-02-27T15:47:35.032Z,1582818455.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-02-27T15:47:35.340Z,1582818455.340 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-02-27T15:47:35.342Z,1582818455.342 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-02-27T15:47:35.392Z,1582818455.392 [VerticalControl](DEBUG): Construct VerticalControl. 2020-02-27T15:47:35.511Z,1582818455.511 [VerticalControl] Loaded 2020-02-27T15:47:35.512Z,1582818455.512 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-02-27T15:47:35.512Z,1582818455.512 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-02-27T15:47:35.597Z,1582818455.597 [HorizontalControl] Loaded 2020-02-27T15:47:35.598Z,1582818455.598 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-02-27T15:47:35.598Z,1582818455.598 [SpeedControl](DEBUG): Construct SpeedControl. 2020-02-27T15:47:35.604Z,1582818455.604 [SpeedControl] Loaded 2020-02-27T15:47:35.604Z,1582818455.604 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-02-27T15:47:35.605Z,1582818455.605 [LoopControl](DEBUG): Construct LoopControl. 2020-02-27T15:47:35.606Z,1582818455.606 [LoopControl] Loaded 2020-02-27T15:47:35.606Z,1582818455.606 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-02-27T15:47:35.606Z,1582818455.606 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-02-27T15:47:35.607Z,1582818455.607 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-02-27T15:47:35.648Z,1582818455.648 [DepthRateCalculator] Loaded 2020-02-27T15:47:35.648Z,1582818455.648 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-02-27T15:47:35.654Z,1582818455.654 [PitchRateCalculator] Loaded 2020-02-27T15:47:35.654Z,1582818455.654 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-02-27T15:47:35.671Z,1582818455.671 [SpeedCalculator] Loaded 2020-02-27T15:47:35.671Z,1582818455.671 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-02-27T15:47:35.693Z,1582818455.693 [TempGradientCalculator] Loaded 2020-02-27T15:47:35.693Z,1582818455.693 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-02-27T15:47:35.699Z,1582818455.699 [YawRateCalculator] Loaded 2020-02-27T15:47:35.699Z,1582818455.699 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-02-27T15:47:35.730Z,1582818455.730 [ElevatorOffsetCalculator] Loaded 2020-02-27T15:47:35.731Z,1582818455.731 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-02-27T15:47:35.731Z,1582818455.731 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-02-27T15:47:35.732Z,1582818455.732 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-02-27T15:47:35.765Z,1582818455.765 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-02-27T15:47:35.765Z,1582818455.765 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-02-27T15:47:35.847Z,1582818455.847 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-02-27T15:47:35.848Z,1582818455.848 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-02-27T15:47:36.163Z,1582818456.163 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-02-27T15:47:36.164Z,1582818456.164 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-02-27T15:47:36.263Z,1582818456.263 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-02-27T15:47:36.264Z,1582818456.264 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-02-27T15:47:36.558Z,1582818456.558 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-02-27T15:47:36.563Z,1582818456.563 [AHRS_M2](INFO): created writer for : platform_orientation 2020-02-27T15:47:36.565Z,1582818456.565 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-02-27T15:47:36.570Z,1582818456.570 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-02-27T15:47:36.571Z,1582818456.571 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-02-27T15:47:36.576Z,1582818456.576 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-02-27T15:47:36.577Z,1582818456.577 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-02-27T15:47:36.582Z,1582818456.582 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-02-27T15:47:36.658Z,1582818456.658 [AHRS_M2] Loaded 2020-02-27T15:47:36.658Z,1582818456.658 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-02-27T15:47:36.738Z,1582818456.738 [DataOverHttps] Loaded 2020-02-27T15:47:36.738Z,1582818456.738 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-02-27T15:47:36.740Z,1582818456.740 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408E44E0 2020-02-27T15:47:36.740Z,1582818456.740 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4083 2020-02-27T15:47:36.754Z,1582818456.754 [Depth_Keller] Loaded 2020-02-27T15:47:36.754Z,1582818456.754 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-02-27T15:47:36.760Z,1582818456.760 [DropWeight] Loaded 2020-02-27T15:47:36.760Z,1582818456.760 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-02-27T15:47:36.864Z,1582818456.864 [NAL9602] Loaded 2020-02-27T15:47:36.865Z,1582818456.865 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-02-27T15:47:36.904Z,1582818456.904 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-02-27T15:47:36.905Z,1582818456.905 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-02-27T15:47:36.905Z,1582818456.905 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-02-27T15:47:36.906Z,1582818456.906 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-02-27T15:47:36.906Z,1582818456.906 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-02-27T15:47:36.907Z,1582818456.907 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-02-27T15:47:36.907Z,1582818456.907 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-02-27T15:47:36.908Z,1582818456.908 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-02-27T15:47:36.908Z,1582818456.908 [Onboard] Loaded 2020-02-27T15:47:36.908Z,1582818456.908 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-02-27T15:47:36.912Z,1582818456.912 [Radio_Surface] Loaded 2020-02-27T15:47:36.912Z,1582818456.912 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-02-27T15:47:36.913Z,1582818456.913 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409144E0 2020-02-27T15:47:36.913Z,1582818456.913 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4084 2020-02-27T15:47:36.961Z,1582818456.961 [RDI_Pathfinder] Loaded 2020-02-27T15:47:36.961Z,1582818456.961 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-02-27T15:47:37.102Z,1582818457.102 [DAT] Loaded 2020-02-27T15:47:37.102Z,1582818457.102 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-02-27T15:47:38.685Z,1582818458.685 [BPC1] Loaded 2020-02-27T15:47:38.686Z,1582818458.686 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-02-27T15:47:38.686Z,1582818458.686 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-02-27T15:47:38.687Z,1582818458.687 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-02-27T15:47:38.793Z,1582818458.793 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-02-27T15:47:38.793Z,1582818458.793 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-02-27T15:47:38.814Z,1582818458.814 [NavChart] Loaded 2020-02-27T15:47:38.815Z,1582818458.815 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-02-27T15:47:38.819Z,1582818458.819 [UniversalFixResidualReporter] Loaded 2020-02-27T15:47:38.819Z,1582818458.819 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-02-27T15:47:38.820Z,1582818458.820 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-02-27T15:47:38.820Z,1582818458.820 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-02-27T15:47:38.960Z,1582818458.960 [SBIT](DEBUG): Construct Startup Built In Test. 2020-02-27T15:47:38.971Z,1582818458.971 [SBIT] Loaded 2020-02-27T15:47:38.972Z,1582818458.972 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-02-27T15:47:38.972Z,1582818458.972 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-02-27T15:47:38.985Z,1582818458.985 [IBIT] Loaded 2020-02-27T15:47:38.986Z,1582818458.986 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-02-27T15:47:38.989Z,1582818458.989 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-02-27T15:47:39.133Z,1582818459.133 [CBIT] Loaded 2020-02-27T15:47:39.133Z,1582818459.133 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-02-27T15:47:39.134Z,1582818459.134 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-02-27T15:47:39.134Z,1582818459.134 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-02-27T15:47:39.240Z,1582818459.240 [BuoyancyServo] Loaded 2020-02-27T15:47:39.240Z,1582818459.240 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-02-27T15:47:39.252Z,1582818459.252 [ElevatorServo] Loaded 2020-02-27T15:47:39.252Z,1582818459.252 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-02-27T15:47:39.264Z,1582818459.264 [MassServo] Loaded 2020-02-27T15:47:39.264Z,1582818459.264 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-02-27T15:47:39.276Z,1582818459.276 [RudderServo] Loaded 2020-02-27T15:47:39.276Z,1582818459.276 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-02-27T15:47:39.287Z,1582818459.287 [ThrusterServo] Loaded 2020-02-27T15:47:39.288Z,1582818459.288 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-02-27T15:47:39.288Z,1582818459.288 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-02-27T15:47:39.289Z,1582818459.289 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-02-27T15:47:39.302Z,1582818459.302 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-02-27T15:47:39.302Z,1582818459.302 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-02-27T15:47:39.522Z,1582818459.522 [BackSeatDriver] Loaded 2020-02-27T15:47:39.522Z,1582818459.522 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-02-27T15:47:39.523Z,1582818459.523 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0 2020-02-27T15:47:39.524Z,1582818459.524 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 4085 2020-02-27T15:47:39.533Z,1582818459.533 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-02-27T15:47:39.539Z,1582818459.539 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-02-27T15:47:39.539Z,1582818459.539 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-02-27T15:47:39.545Z,1582818459.545 [CTD_Seabird](INFO): created writer for : depth 2020-02-27T15:47:39.545Z,1582818459.545 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-02-27T15:47:39.550Z,1582818459.550 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-02-27T15:47:39.551Z,1582818459.551 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-02-27T15:47:39.557Z,1582818459.557 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-02-27T15:47:39.557Z,1582818459.557 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-02-27T15:47:39.563Z,1582818459.563 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-02-27T15:47:39.564Z,1582818459.564 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-02-27T15:47:39.569Z,1582818459.569 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-02-27T15:47:39.570Z,1582818459.570 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-02-27T15:47:39.575Z,1582818459.575 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-02-27T15:47:39.604Z,1582818459.604 [CTD_Seabird] Loaded 2020-02-27T15:47:39.605Z,1582818459.605 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-02-27T15:47:39.606Z,1582818459.606 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0 2020-02-27T15:47:39.606Z,1582818459.606 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4086 2020-02-27T15:47:39.613Z,1582818459.613 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-02-27T15:47:39.613Z,1582818459.613 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-02-27T15:47:39.618Z,1582818459.618 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-02-27T15:47:39.618Z,1582818459.618 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-02-27T15:47:39.623Z,1582818459.623 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-02-27T15:47:39.623Z,1582818459.623 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-02-27T15:47:39.627Z,1582818459.627 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-02-27T15:47:39.628Z,1582818459.628 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-02-27T15:47:39.632Z,1582818459.632 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-02-27T15:47:39.632Z,1582818459.632 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-02-27T15:47:39.637Z,1582818459.637 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-02-27T15:47:39.637Z,1582818459.637 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-02-27T15:47:39.642Z,1582818459.642 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-02-27T15:47:39.642Z,1582818459.642 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-02-27T15:47:39.646Z,1582818459.646 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-02-27T15:47:39.651Z,1582818459.651 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-02-27T15:47:39.652Z,1582818459.652 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-02-27T15:47:39.652Z,1582818459.652 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-02-27T15:47:39.657Z,1582818459.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-02-27T15:47:39.657Z,1582818459.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-02-27T15:47:39.661Z,1582818459.661 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-02-27T15:47:39.661Z,1582818459.661 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-02-27T15:47:39.666Z,1582818459.666 [WetLabsBB2FL] Loaded 2020-02-27T15:47:39.666Z,1582818459.666 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-02-27T15:47:39.667Z,1582818459.667 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0 2020-02-27T15:47:39.668Z,1582818459.668 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4087 2020-02-27T15:47:39.681Z,1582818459.681 [WetLabsUBAT..SerialDriver](INFO): Created logger 2020-02-27T15:47:39.682Z,1582818459.682 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2020-02-27T15:47:39.682Z,1582818459.682 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2020-02-27T15:47:39.687Z,1582818459.687 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2020-02-27T15:47:39.687Z,1582818459.687 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2020-02-27T15:47:39.688Z,1582818459.688 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2020-02-27T15:47:39.692Z,1582818459.692 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2020-02-27T15:47:39.692Z,1582818459.692 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2020-02-27T15:47:39.697Z,1582818459.697 [WetLabsUBAT] Loaded 2020-02-27T15:47:39.697Z,1582818459.697 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2020-02-27T15:47:39.698Z,1582818459.698 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B2A4E0 2020-02-27T15:47:39.699Z,1582818459.699 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 4088 2020-02-27T15:47:39.699Z,1582818459.699 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-02-27T15:47:39.708Z,1582818459.708 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-02-27T15:47:39.709Z,1582818459.709 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-02-27T15:47:39.774Z,1582818459.774 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-02-27T15:47:39.775Z,1582818459.775 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5A4E0 2020-02-27T15:47:39.775Z,1582818459.775 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4089 2020-02-27T15:47:39.780Z,1582818459.780 [Supervisor](INFO): Main Thread ID is 4000 2020-02-27T15:47:39.780Z,1582818459.780 [Supervisor](DEBUG): Running supervisor. 2020-02-27T15:47:39.783Z,1582818459.783 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4090 2020-02-27T15:47:39.786Z,1582818459.786 [controlThread ThreadHandler](INFO): Handler Thread ID is 4091 2020-02-27T15:47:39.786Z,1582818459.786 [controlThread](DEBUG): Initializing ControlThread 2020-02-27T15:47:39.787Z,1582818459.787 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-02-27T15:47:39.789Z,1582818459.789 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-02-27T15:47:39.789Z,1582818459.789 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-02-27T15:47:39.790Z,1582818459.790 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-02-27T15:47:39.790Z,1582818459.790 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-02-27T15:47:39.791Z,1582818459.791 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-02-27T15:47:39.791Z,1582818459.791 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-02-27T15:47:39.791Z,1582818459.791 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-02-27T15:47:39.792Z,1582818459.792 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-02-27T15:47:39.792Z,1582818459.792 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-02-27T15:47:39.800Z,1582818459.800 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-02-27T15:47:39.801Z,1582818459.801 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-02-27T15:47:39.801Z,1582818459.801 [SBIT](INFO): Initialize SBIT Component. 2020-02-27T15:47:39.802Z,1582818459.802 [SBIT](IMPORTANT): git: 2020-02-26 2020-02-27T15:47:39.802Z,1582818459.802 [SBIT](INFO): git hash: de1c7d7324ed84a0a2d3b39115edd3b5f312562f 2020-02-27T15:47:39.802Z,1582818459.802 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-02-27T15:47:39.802Z,1582818459.802 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2020-02-27T15:47:39.803Z,1582818459.803 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2020-02-27T15:47:39.804Z,1582818459.804 [IBIT](INFO): Initialize IBIT Component. 2020-02-27T15:47:39.805Z,1582818459.805 [CBIT](DEBUG): Initialize CBIT Component. 2020-02-27T15:47:39.806Z,1582818459.806 [logger ThreadHandler](INFO): Handler Thread ID is 4092 2020-02-27T15:47:39.817Z,1582818459.817 [CBIT](DEBUG): Initialized mux pins. 2020-02-27T15:47:39.817Z,1582818459.817 [CBIT](DEBUG): Initializing the watchdog timer. 2020-02-27T15:47:39.825Z,1582818459.825 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4093 2020-02-27T15:47:39.826Z,1582818459.826 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-02-27T15:47:39.837Z,1582818459.837 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4094 2020-02-27T15:47:39.841Z,1582818459.841 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-02-27T15:47:39.841Z,1582818459.841 [CBIT](DEBUG): Initializing heartbeat. 2020-02-27T15:47:39.849Z,1582818459.849 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 4095 2020-02-27T15:47:39.869Z,1582818459.869 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4096 2020-02-27T15:47:39.870Z,1582818459.870 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-02-27T15:47:39.873Z,1582818459.873 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-02-27T15:47:39.874Z,1582818459.874 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4098 2020-02-27T15:47:39.875Z,1582818459.875 [WetLabsBB2FL](INFO): Powering down 2020-02-27T15:47:39.905Z,1582818459.905 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 4099 2020-02-27T15:47:39.913Z,1582818459.913 [CBIT](DEBUG): Deactivating GF circuits. 2020-02-27T15:47:39.913Z,1582818459.913 [CBIT](DEBUG): Deactivating emergency mode. 2020-02-27T15:47:39.914Z,1582818459.914 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4100 2020-02-27T15:47:39.918Z,1582818459.918 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-02-27T15:47:39.918Z,1582818459.918 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-02-27T15:47:39.918Z,1582818459.918 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-02-27T15:47:39.918Z,1582818459.918 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-02-27T15:47:39.919Z,1582818459.919 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-02-27T15:47:39.919Z,1582818459.919 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-02-27T15:47:39.919Z,1582818459.919 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-02-27T15:47:39.919Z,1582818459.919 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-02-27T15:47:39.919Z,1582818459.919 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-02-27T15:47:39.919Z,1582818459.919 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-02-27T15:47:39.920Z,1582818459.920 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-02-27T15:47:39.920Z,1582818459.920 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-02-27T15:47:39.920Z,1582818459.920 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-02-27T15:47:39.920Z,1582818459.920 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-02-27T15:47:39.921Z,1582818459.921 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-02-27T15:47:39.921Z,1582818459.921 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-02-27T15:47:39.949Z,1582818459.949 [CBIT](DEBUG): Backplane powered. 2020-02-27T15:47:39.950Z,1582818459.950 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-02-27T15:47:39.961Z,1582818459.961 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-02-27T15:47:39.971Z,1582818459.971 [MissionManager](DEBUG): 2020-02-27T15:47:39.972Z,1582818459.972 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-02-27T15:47:40.032Z,1582818460.032 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-02-27T15:47:40.050Z,1582818460.050 [Default:A.Wait](DEBUG): Construct Wait. 2020-02-27T15:47:40.051Z,1582818460.051 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-02-27T15:47:40.078Z,1582818460.078 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-02-27T15:47:40.097Z,1582818460.097 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-02-27T15:47:40.102Z,1582818460.102 [Default:E.Execute](DEBUG): Construct Execute. 2020-02-27T15:47:40.126Z,1582818460.126 [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-02-27T15:47:40.130Z,1582818460.130 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-02-27T15:47:40.141Z,1582818460.141 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-02-27T15:47:40.206Z,1582818460.206 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-02-27T15:47:40.240Z,1582818460.240 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-02-27T15:47:40.240Z,1582818460.240 [DAT](INFO): Powering up 2020-02-27T15:47:40.240Z,1582818460.240 [DAT](DEBUG): Initializing DAT. 2020-02-27T15:47:40.241Z,1582818460.241 [Radio_Surface](INFO): Powering up 2020-02-27T15:47:40.290Z,1582818460.290 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-02-27T15:47:40.325Z,1582818460.325 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-02-27T15:47:40.362Z,1582818460.362 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-02-27T15:47:40.365Z,1582818460.365 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-02-27T15:47:40.367Z,1582818460.367 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-02-27T15:47:40.381Z,1582818460.381 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-02-27T15:47:40.382Z,1582818460.382 [MassServo](DEBUG): Initializing EZServoServo. 2020-02-27T15:47:40.393Z,1582818460.393 [MassServo](DEBUG): Initializing MassServo. 2020-02-27T15:47:40.394Z,1582818460.394 [RudderServo](DEBUG): Initializing EZServoServo. 2020-02-27T15:47:40.405Z,1582818460.405 [RudderServo](DEBUG): Initializing RudderServo. 2020-02-27T15:47:40.406Z,1582818460.406 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-02-27T15:47:40.413Z,1582818460.413 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-02-27T15:47:41.281Z,1582818461.281 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-02-27T15:47:41.281Z,1582818461.281 [RudderServo](FAULT): Rudder failed to initialize 2020-02-27T15:47:41.281Z,1582818461.281 [RudderServo] Communications Fault, FailCount= 1 2020-02-27T15:47:41.281Z,1582818461.281 [RudderServo](ERROR): Communications Fault 2020-02-27T15:47:41.392Z,1582818461.392 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-02-27T15:47:41.570Z,1582818461.570 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-02-27T15:47:41.570Z,1582818461.570 [RudderServo](INFO): Powering down 2020-02-27T15:47:42.236Z,1582818462.236 [RudderServo](DEBUG): Initializing EZServoServo. 2020-02-27T15:47:42.354Z,1582818462.354 [RudderServo](DEBUG): Initializing RudderServo. 2020-02-27T15:47:42.358Z,1582818462.358 [CBIT](INFO): Clearing failed state for component RudderServo 2020-02-27T15:47:42.358Z,1582818462.358 [RudderServo] No Fault, FailCount= 1 2020-02-27T15:47:50.725Z,1582818470.725 [DAT](DEBUG): 2020-02-27T15:47:50.727Z,1582818470.727 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-02-27T15:47:51.922Z,1582818471.922 [DAT](DEBUG): MF Frequency Band 2020-02-27T15:47:51.923Z,1582818471.923 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21 2020-02-27T15:47:51.924Z,1582818471.924 [DAT](DEBUG): Feb 27 2020 15:47:31 2020-02-27T15:47:52.782Z,1582818472.782 [DAT](DEBUG): Features enabled [Bearing] 2020-02-27T15:47:52.783Z,1582818472.783 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-02-27T15:47:52.784Z,1582818472.784 [DAT](INFO): commRate: 800 2020-02-27T15:47:53.133Z,1582818473.133 [DAT](INFO): entering command mode 2020-02-27T15:47:53.555Z,1582818473.555 [DAT](DEBUG): checking for command mode acknowledgment 2020-02-27T15:47:53.997Z,1582818473.997 [DAT](DEBUG): checking for command mode acknowledgment 2020-02-27T15:47:53.998Z,1582818473.998 [DAT](INFO): command mode acknowledged 2020-02-27T15:47:53.998Z,1582818473.998 [DAT](INFO): setting verbose to 3 2020-02-27T15:47:54.345Z,1582818474.345 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-02-27T15:47:54.346Z,1582818474.346 [DAT](INFO): set verbose to 3 2020-02-27T15:47:54.779Z,1582818474.779 [DAT](INFO): setting transmit power to 8 2020-02-27T15:47:55.164Z,1582818475.164 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-02-27T15:47:55.556Z,1582818475.556 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-02-27T15:47:55.557Z,1582818475.557 [DAT](INFO): set transmit power to 8 2020-02-27T15:47:55.962Z,1582818475.962 [DAT](INFO): setting local address to 9 2020-02-27T15:47:56.376Z,1582818476.376 [DAT](DEBUG): checking for local address setting acknowledgment 2020-02-27T15:47:56.769Z,1582818476.769 [DAT](DEBUG): checking for local address setting acknowledgment 2020-02-27T15:47:56.770Z,1582818476.770 [DAT](INFO): set local address to 9 2020-02-27T15:47:57.042Z,1582818477.042 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004427 2020-02-27T15:48:07.258Z,1582818487.258 [NAL9602](INFO): Powering up NAL9602 2020-02-27T15:48:18.166Z,1582818498.166 [NAL9602](INFO): NAL9602 initialized 2020-02-27T15:48:19.427Z,1582818499.427 [SBIT](IMPORTANT): Beginning Startup BIT 2020-02-27T15:48:19.436Z,1582818499.436 [CBIT](IMPORTANT): Beginning ground fault scan 2020-02-27T15:48:30.513Z,1582818510.513 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.025169 CHAN A1 (24V): 0.081540 CHAN A2 (12V): -0.007414 CHAN A3 (5V): -0.002478 CHAN B0 (3.3V): 0.000194 CHAN B1 (3.15aV): -0.000123 CHAN B2 (3.15bV): -0.000007 CHAN B3 (GND): 0.001998 OPEN: 0.005326 Full Scale Calc: 4.765 mA, -1.589 mA 2020-02-27T15:48:47.595Z,1582818527.595 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-02-27T15:48:59.368Z,1582818539.368 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-02-27T15:49:13.150Z,1582818553.150 [SBIT](IMPORTANT): SBIT PASSED 2020-02-27T15:49:13.150Z,1582818553.150 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-02-27T15:49:13.151Z,1582818553.151 [SBIT](IMPORTANT): No configSet variables persisted 2020-02-27T15:49:13.542Z,1582818553.542 [MissionManager](IMPORTANT): Started mission Startup 2020-02-27T15:49:13.542Z,1582818553.542 [Startup] Running Loop=1 2020-02-27T15:49:13.542Z,1582818553.542 [Startup](DEBUG): Aggregate::initialize Startup 2020-02-27T15:49:13.542Z,1582818553.542 [Startup:A.GoToSurface] Running Loop=1 2020-02-27T15:49:13.542Z,1582818553.542 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-02-27T15:49:13.543Z,1582818553.543 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-02-27T15:49:13.543Z,1582818553.543 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-02-27T15:49:13.544Z,1582818553.544 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-02-27T15:49:13.544Z,1582818553.544 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-02-27T15:49:13.544Z,1582818553.544 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-02-27T15:49:13.546Z,1582818553.546 [Startup:StartupSatComms] Running Loop=1 2020-02-27T15:49:13.546Z,1582818553.546 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-02-27T15:49:13.547Z,1582818553.547 [Startup:StartupSatComms:A] Running Loop=1 2020-02-27T15:49:13.970Z,1582818553.970 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-02-27T15:49:38.989Z,1582818578.989 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-27T15:49:46.698Z,1582818586.698 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-02-27T15:50:13.738Z,1582818613.738 [Startup:StartupSatComms:A](INFO): Timed out from 2020-02-27T15:49:13.5Z 2020-02-27T15:50:13.738Z,1582818613.738 [Startup:StartupSatComms:A] Stopped 2020-02-27T15:50:13.738Z,1582818613.738 [Startup:StartupSatComms:B] Running Loop=1 2020-02-27T15:50:14.137Z,1582818614.137 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-02-27T15:50:21.169Z,1582818621.169 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20200227T153204/Express0005.lzma 2020-02-27T15:50:23.175Z,1582818623.175 [DataOverHttps](INFO): Moved sent file to Logs/20200227T153204/Express0005.lzma.bak 2020-02-27T15:50:23.175Z,1582818623.175 [DataOverHttps](INFO): SBD MOMSN=12272192 2020-02-27T15:50:39.990Z,1582818639.990 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-02-27T15:50:39.990Z,1582818639.990 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T15:50:39.000Z,1582818640.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T15:50:40.384Z,1582818640.384 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T15:50:40.385Z,1582818640.385 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-02-27T15:50:46.442Z,1582818646.442 [DataOverHttps](INFO): Sending 686 bytes from file Logs/20200227T154729/Express0001.lzma 2020-02-27T15:50:48.446Z,1582818648.446 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0001.lzma.bak 2020-02-27T15:50:48.447Z,1582818648.447 [DataOverHttps](INFO): SBD MOMSN=12272212 2020-02-27T15:50:49.690Z,1582818649.690 [Startup:StartupSatComms:B] Stopped 2020-02-27T15:50:49.691Z,1582818649.691 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-02-27T15:50:49.691Z,1582818649.691 [Startup:StartupSatComms] Stopped 2020-02-27T15:50:49.691Z,1582818649.691 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-02-27T15:50:49.691Z,1582818649.691 [Startup](INFO): Completed Startup 2020-02-27T15:50:49.692Z,1582818649.692 [MissionManager](INFO): Startup is completed. 2020-02-27T15:50:49.692Z,1582818649.692 [MissionManager](INFO): Uninitializing Mission Startup 2020-02-27T15:50:49.692Z,1582818649.692 [Startup] Stopped 2020-02-27T15:50:49.692Z,1582818649.692 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-02-27T15:50:49.692Z,1582818649.692 [Startup:A.GoToSurface] Stopped 2020-02-27T15:50:49.692Z,1582818649.692 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-02-27T15:50:50.091Z,1582818650.091 [MissionManager](IMPORTANT): Started mission Default 2020-02-27T15:50:50.091Z,1582818650.091 [Default] Running Loop=1 2020-02-27T15:50:50.092Z,1582818650.092 [Default](DEBUG): Aggregate::initialize Default 2020-02-27T15:50:50.092Z,1582818650.092 [Default:B.GoToSurface] Running Loop=1 2020-02-27T15:50:50.092Z,1582818650.092 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-02-27T15:50:50.092Z,1582818650.092 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-02-27T15:50:50.092Z,1582818650.092 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-02-27T15:50:50.093Z,1582818650.093 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-02-27T15:50:50.093Z,1582818650.093 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-02-27T15:50:50.094Z,1582818650.094 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-02-27T15:50:50.094Z,1582818650.094 [Default:A.Wait] Running Loop=1 2020-02-27T15:50:50.094Z,1582818650.094 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-02-27T15:51:03.434Z,1582818663.434 [Default:A.Wait](INFO): Done Waiting. 2020-02-27T15:51:03.434Z,1582818663.434 [Default:A.Wait] Stopped 2020-02-27T15:51:03.434Z,1582818663.434 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T15:51:03.841Z,1582818663.841 [Default:CheckIn] Running Loop=1 2020-02-27T15:51:03.841Z,1582818663.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T15:51:03.841Z,1582818663.841 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T15:51:04.238Z,1582818664.238 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-02-27T15:51:28.940Z,1582818688.940 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-02-27T15:52:25.886Z,1582818745.886 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-02-27T15:52:37.600Z,1582818757.600 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-02-27T15:53:21.555Z,1582818801.555 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-27T15:53:40.979Z,1582818820.979 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-02-27T15:53:40.979Z,1582818820.979 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T15:53:41.006Z,1582818821.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T15:53:41.387Z,1582818821.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T15:53:41.387Z,1582818821.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-02-27T15:53:50.014Z,1582818830.014 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-02-27T15:54:02.071Z,1582818842.071 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-02-27T15:55:01.876Z,1582818901.876 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-02-27T15:55:21.175Z,1582818921.175 [BPC1](ERROR): BPC1B failed to parse battery stick message. 2020-02-27T15:55:23.199Z,1582818923.199 [BPC1](ERROR): BPC1A: No match for serial number FFFF in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2020-02-27T15:55:49.497Z,1582818949.497 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-02-27T15:55:51.140Z,1582818951.140 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-02-27T15:56:03.188Z,1582818963.188 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2020-02-27T15:56:04.030Z,1582818964.030 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-02-27T15:51:03.8Z 2020-02-27T15:56:04.030Z,1582818964.030 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T15:56:04.030Z,1582818964.030 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T15:56:04.394Z,1582818964.394 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-02-27T15:56:12.905Z,1582818972.905 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-02-27T15:56:12.908Z,1582818972.908 [BPC1](INFO): Received data from all battery sticks. 2020-02-27T15:56:14.608Z,1582818974.608 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20200227T154729/Express0005.lzma 2020-02-27T15:56:16.615Z,1582818976.615 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0005.lzma.bak 2020-02-27T15:56:16.615Z,1582818976.615 [DataOverHttps](INFO): SBD MOMSN=12272244 2020-02-27T15:56:17.725Z,1582818977.725 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T15:56:17.725Z,1582818977.725 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T15:56:17.725Z,1582818977.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T15:56:41.987Z,1582819001.987 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-02-27T15:56:41.987Z,1582819001.987 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T15:56:42.017Z,1582819002.017 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T15:56:42.376Z,1582819002.376 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T15:56:42.376Z,1582819002.376 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-02-27T15:58:19.302Z,1582819099.302 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-02-27T15:58:19.302Z,1582819099.302 [NAL9602] Data Fault, FailCount= 1 2020-02-27T15:58:19.302Z,1582819099.302 [NAL9602](ERROR): Data Fault 2020-02-27T15:58:19.364Z,1582819099.364 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-27T15:58:19.704Z,1582819099.704 [NAL9602](INFO): Powering down 2020-02-27T15:58:20.555Z,1582819100.555 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-27T15:58:20.555Z,1582819100.555 [NAL9602] No Fault, FailCount= 1 2020-02-27T15:58:50.011Z,1582819130.011 [NAL9602](INFO): Powering up NAL9602 2020-02-27T15:59:00.910Z,1582819140.910 [NAL9602](INFO): NAL9602 initialized 2020-02-27T15:59:42.967Z,1582819182.967 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-02-27T15:59:42.967Z,1582819182.967 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T15:59:43.009Z,1582819183.009 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T15:59:43.362Z,1582819183.362 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T15:59:43.362Z,1582819183.362 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-02-27T16:01:02.139Z,1582819262.139 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-27T16:01:18.325Z,1582819278.325 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:01:18.325Z,1582819278.325 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:01:18.325Z,1582819278.325 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:01:18.326Z,1582819278.326 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:01:18.724Z,1582819278.724 [Default:CheckIn:D] Stopped 2020-02-27T16:01:18.724Z,1582819278.724 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:01:19.125Z,1582819279.125 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.477203 min 2020-02-27T16:01:19.125Z,1582819279.125 [Default:CheckIn:E] Stopped 2020-02-27T16:01:19.126Z,1582819279.126 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:01:19.126Z,1582819279.126 [Default:CheckIn] Stopped 2020-02-27T16:01:19.126Z,1582819279.126 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:01:19.126Z,1582819279.126 [Default:CheckIn](INFO): Running loop #2 2020-02-27T16:01:19.126Z,1582819279.126 [Default:CheckIn] Running Loop=2 2020-02-27T16:01:19.126Z,1582819279.126 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:01:19.126Z,1582819279.126 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:02:43.966Z,1582819363.966 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2020-02-27T16:02:43.966Z,1582819363.966 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T16:02:43.976Z,1582819363.976 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T16:02:44.382Z,1582819364.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T16:02:44.382Z,1582819364.382 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2020-02-27T16:05:44.973Z,1582819544.973 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2020-02-27T16:05:44.973Z,1582819544.973 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T16:05:44.983Z,1582819544.983 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T16:05:45.385Z,1582819545.385 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T16:05:45.385Z,1582819545.385 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2020-02-27T16:06:19.298Z,1582819579.298 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-02-27T16:01:19.1Z 2020-02-27T16:06:19.299Z,1582819579.299 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:06:19.299Z,1582819579.299 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:06:29.397Z,1582819589.397 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20200227T154729/Courier0007.lzma 2020-02-27T16:06:31.402Z,1582819591.402 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0007.lzma.bak 2020-02-27T16:06:31.402Z,1582819591.402 [DataOverHttps](INFO): SBD MOMSN=12272261 2020-02-27T16:06:54.432Z,1582819614.432 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20200227T154729/Express0008.lzma 2020-02-27T16:06:56.435Z,1582819616.435 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0008.lzma.bak 2020-02-27T16:06:56.435Z,1582819616.435 [DataOverHttps](INFO): SBD MOMSN=12272267 2020-02-27T16:06:57.685Z,1582819617.685 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:06:57.685Z,1582819617.685 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:06:57.685Z,1582819617.685 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:07:23.118Z,1582819643.118 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-02-27T16:08:45.947Z,1582819725.947 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2020-02-27T16:08:45.947Z,1582819725.947 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T16:08:45.982Z,1582819725.982 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T16:08:46.354Z,1582819726.354 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T16:08:46.354Z,1582819726.354 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2020-02-27T16:09:03.686Z,1582819743.686 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-02-27T16:09:03.686Z,1582819743.686 [NAL9602] Data Fault, FailCount= 2 2020-02-27T16:09:03.686Z,1582819743.686 [NAL9602](ERROR): Data Fault 2020-02-27T16:09:03.750Z,1582819743.750 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-27T16:09:04.092Z,1582819744.092 [NAL9602](INFO): Powering down 2020-02-27T16:09:04.938Z,1582819744.938 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-27T16:09:04.938Z,1582819744.938 [NAL9602] No Fault, FailCount= 2 2020-02-27T16:09:34.386Z,1582819774.386 [NAL9602](INFO): Powering up NAL9602 2020-02-27T16:09:45.298Z,1582819785.298 [NAL9602](INFO): NAL9602 initialized 2020-02-27T16:11:46.937Z,1582819906.937 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2020-02-27T16:11:46.937Z,1582819906.937 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T16:11:46.981Z,1582819906.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T16:11:47.362Z,1582819907.362 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T16:11:47.362Z,1582819907.362 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2020-02-27T16:11:58.250Z,1582819918.250 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:11:58.250Z,1582819918.250 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:11:58.250Z,1582819918.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:11:58.250Z,1582819918.250 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:11:58.658Z,1582819918.658 [Default:CheckIn:D] Stopped 2020-02-27T16:11:58.658Z,1582819918.658 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:11:59.072Z,1582819919.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.142769 min 2020-02-27T16:11:59.072Z,1582819919.072 [Default:CheckIn:E] Stopped 2020-02-27T16:11:59.073Z,1582819919.073 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:11:59.073Z,1582819919.073 [Default:CheckIn] Stopped 2020-02-27T16:11:59.073Z,1582819919.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:11:59.073Z,1582819919.073 [Default:CheckIn](INFO): Running loop #3 2020-02-27T16:11:59.073Z,1582819919.073 [Default:CheckIn] Running Loop=3 2020-02-27T16:11:59.073Z,1582819919.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:11:59.074Z,1582819919.074 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:14:47.933Z,1582820087.933 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2020-02-27T16:14:47.933Z,1582820087.933 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T16:14:47.968Z,1582820087.968 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T16:14:48.369Z,1582820088.369 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T16:14:48.369Z,1582820088.369 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2020-02-27T16:16:59.234Z,1582820219.234 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-02-27T16:11:59.1Z 2020-02-27T16:16:59.234Z,1582820219.234 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:16:59.234Z,1582820219.234 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:17:06.116Z,1582820226.116 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200227T154729/Express0011.lzma 2020-02-27T16:17:08.122Z,1582820228.122 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0011.lzma.bak 2020-02-27T16:17:08.122Z,1582820228.122 [DataOverHttps](INFO): SBD MOMSN=12272281 2020-02-27T16:17:09.382Z,1582820229.382 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:17:09.382Z,1582820229.382 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:17:09.382Z,1582820229.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:17:48.989Z,1582820268.989 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2020-02-27T16:17:48.989Z,1582820268.989 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T16:17:48.999Z,1582820268.999 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T16:17:49.386Z,1582820269.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T16:17:49.386Z,1582820269.386 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2020-02-27T16:19:46.113Z,1582820386.113 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-02-27T16:19:46.113Z,1582820386.113 [NAL9602] Data Fault, FailCount= 3 2020-02-27T16:19:46.113Z,1582820386.113 [NAL9602](ERROR): Data Fault 2020-02-27T16:19:46.172Z,1582820386.172 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-27T16:19:46.518Z,1582820386.518 [NAL9602](INFO): Powering down 2020-02-27T16:19:47.362Z,1582820387.362 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-27T16:19:47.362Z,1582820387.362 [NAL9602] No Fault, FailCount= 3 2020-02-27T16:20:16.826Z,1582820416.826 [NAL9602](INFO): Powering up NAL9602 2020-02-27T16:20:27.734Z,1582820427.734 [NAL9602](INFO): NAL9602 initialized 2020-02-27T16:20:50.006Z,1582820450.006 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2020-02-27T16:20:50.006Z,1582820450.006 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T16:20:50.016Z,1582820450.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T16:20:50.393Z,1582820450.393 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T16:20:50.394Z,1582820450.394 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2020-02-27T16:22:09.997Z,1582820529.997 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:22:09.997Z,1582820529.997 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:22:09.997Z,1582820529.997 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:22:09.997Z,1582820529.997 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:22:10.378Z,1582820530.378 [Default:CheckIn:D] Stopped 2020-02-27T16:22:10.378Z,1582820530.378 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:22:10.803Z,1582820530.803 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.338104 min 2020-02-27T16:22:10.803Z,1582820530.803 [Default:CheckIn:E] Stopped 2020-02-27T16:22:10.803Z,1582820530.803 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:22:10.803Z,1582820530.803 [Default:CheckIn] Stopped 2020-02-27T16:22:10.804Z,1582820530.804 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:22:10.804Z,1582820530.804 [Default:CheckIn](INFO): Running loop #4 2020-02-27T16:22:10.804Z,1582820530.804 [Default:CheckIn] Running Loop=4 2020-02-27T16:22:10.804Z,1582820530.804 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:22:10.804Z,1582820530.804 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:23:50.996Z,1582820630.996 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2020-02-27T16:23:50.996Z,1582820630.996 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-27T16:23:51.006Z,1582820631.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-27T16:23:51.402Z,1582820631.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-27T16:23:51.402Z,1582820631.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2020-02-27T16:24:51.148Z,1582820691.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162450.00,A,3648.15437,N,12147.25371,W,3.771,195.26,270220,,,A*78 2020-02-27T16:24:51.159Z,1582820691.159 [NAL9602](INFO): GPS fix at 20200227T162450: (36.802573, -121.787562) 2020-02-27T16:24:51.188Z,1582820691.188 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:24:51.188Z,1582820691.188 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:25:01.529Z,1582820701.529 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20200227T154729/Courier0013.lzma 2020-02-27T16:25:03.534Z,1582820703.534 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0013.lzma.bak 2020-02-27T16:25:03.535Z,1582820703.535 [DataOverHttps](INFO): SBD MOMSN=12272298 2020-02-27T16:25:21.849Z,1582820721.849 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20200227T154729/Express0014.lzma 2020-02-27T16:25:23.470Z,1582820723.470 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T16:25:23.865Z,1582820723.865 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0014.lzma.bak 2020-02-27T16:25:23.865Z,1582820723.865 [DataOverHttps](INFO): SBD MOMSN=12272301 2020-02-27T16:25:25.134Z,1582820725.134 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:25:25.134Z,1582820725.134 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:25:25.134Z,1582820725.134 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:27:20.346Z,1582820840.346 [DataOverHttps](IMPORTANT): SBD MTMSN=20200227T162719 2020-02-27T16:27:28.073Z,1582820848.073 [DataOverHttps](INFO): Received command:ibit 2020-02-27T16:27:28.078Z,1582820848.078 [CommandLine](IMPORTANT): got command ibit 2020-02-27T16:27:28.366Z,1582820848.366 [IBIT](IMPORTANT): Beginning Initiated BIT 2020-02-27T16:27:28.366Z,1582820848.366 [IBIT](IMPORTANT): Beginning control surface checks. 2020-02-27T16:27:28.369Z,1582820848.369 [CBIT](IMPORTANT): Beginning ground fault scan 2020-02-27T16:27:29.940Z,1582820849.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162730.00,A,3648.13871,N,12147.23592,W,0.156,35.58,270220,,,A*4A 2020-02-27T16:27:29.942Z,1582820849.942 [NAL9602](INFO): GPS fix at 20200227T162730: (36.802312, -121.787265) 2020-02-27T16:27:39.266Z,1582820859.266 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.009157 CHAN A1 (24V): 0.092476 CHAN A2 (12V): -0.001848 CHAN A3 (5V): 0.000801 CHAN B0 (3.3V): 0.001665 CHAN B1 (3.15aV): 0.001550 CHAN B2 (3.15bV): 0.001532 CHAN B3 (GND): 0.001736 OPEN: 0.005315 Full Scale Calc: 4.765 mA, -1.589 mA 2020-02-27T16:27:40.851Z,1582820860.851 [NAL9602](INFO): SBD MO Status=0, MOMSN=6610, MT Status=0, MTMSN=0 2020-02-27T16:27:40.851Z,1582820860.851 [NAL9602](INFO): No messages in MT queue 2020-02-27T16:27:42.064Z,1582820862.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162742.00,A,3648.13963,N,12147.23526,W,0.019,35.58,270220,,,A*48 2020-02-27T16:27:42.066Z,1582820862.066 [NAL9602](INFO): GPS fix at 20200227T162742: (36.802327, -121.787254) 2020-02-27T16:27:45.292Z,1582820865.292 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162745.00,A,3648.14003,N,12147.23518,W,0.097,35.58,270220,,,A*4C 2020-02-27T16:27:45.302Z,1582820865.302 [NAL9602](INFO): GPS fix at 20200227T162745: (36.802334, -121.787253) 2020-02-27T16:27:45.335Z,1582820865.335 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-27T16:27:48.119Z,1582820868.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162748.00,A,3648.14058,N,12147.23497,W,0.019,35.58,270220,,,A*4F 2020-02-27T16:27:48.121Z,1582820868.121 [NAL9602](INFO): GPS fix at 20200227T162748: (36.802343, -121.787250) 2020-02-27T16:27:51.352Z,1582820871.352 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162751.00,A,3648.14102,N,12147.23467,W,0.039,35.58,270220,,,A*44 2020-02-27T16:27:51.355Z,1582820871.355 [NAL9602](INFO): GPS fix at 20200227T162751: (36.802350, -121.787244) 2020-02-27T16:27:54.179Z,1582820874.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162754.00,A,3648.14110,N,12147.23469,W,0.058,35.58,270220,,,A*4B 2020-02-27T16:27:54.181Z,1582820874.181 [NAL9602](INFO): GPS fix at 20200227T162754: (36.802352, -121.787245) 2020-02-27T16:27:57.012Z,1582820877.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162757.00,A,3648.14117,N,12147.23462,W,0.000,35.58,270220,,,A*49 2020-02-27T16:27:57.014Z,1582820877.014 [NAL9602](INFO): GPS fix at 20200227T162757: (36.802353, -121.787244) 2020-02-27T16:28:00.240Z,1582820880.240 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162800.00,A,3648.14142,N,12147.23447,W,0.019,35.58,270220,,,A*4B 2020-02-27T16:28:00.246Z,1582820880.246 [NAL9602](INFO): GPS fix at 20200227T162800: (36.802357, -121.787241) 2020-02-27T16:28:03.076Z,1582820883.076 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162803.00,A,3648.14147,N,12147.23436,W,0.000,35.58,270220,,,A*43 2020-02-27T16:28:03.079Z,1582820883.079 [NAL9602](INFO): GPS fix at 20200227T162803: (36.802358, -121.787239) 2020-02-27T16:28:06.300Z,1582820886.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162806.00,A,3648.14127,N,12147.23428,W,0.039,35.58,270220,,,A*45 2020-02-27T16:28:06.311Z,1582820886.311 [NAL9602](INFO): GPS fix at 20200227T162806: (36.802354, -121.787238) 2020-02-27T16:28:09.140Z,1582820889.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162809.00,A,3648.14066,N,12147.23414,W,0.019,35.58,270220,,,A*43 2020-02-27T16:28:09.142Z,1582820889.142 [NAL9602](INFO): GPS fix at 20200227T162809: (36.802344, -121.787236) 2020-02-27T16:28:12.371Z,1582820892.371 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162812.00,A,3648.14009,N,12147.23424,W,0.078,35.58,270220,,,A*44 2020-02-27T16:28:12.374Z,1582820892.374 [NAL9602](INFO): GPS fix at 20200227T162812: (36.802335, -121.787237) 2020-02-27T16:28:14.874Z,1582820894.874 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802334 Longitude: -121.787239 2020-02-27T16:28:15.216Z,1582820895.216 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162815.00,A,3648.13976,N,12147.23405,W,0.019,35.58,270220,,,A*41 2020-02-27T16:28:15.219Z,1582820895.219 [NAL9602](INFO): GPS fix at 20200227T162815: (36.802329, -121.787234) 2020-02-27T16:28:15.261Z,1582820895.261 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 0.000000 2020-02-27T16:28:15.262Z,1582820895.262 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2020-02-27T16:28:15.262Z,1582820895.262 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2020-02-27T16:28:15.663Z,1582820895.663 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2020-02-27T16:28:15.664Z,1582820895.664 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2020-02-27T16:28:15.664Z,1582820895.664 [IBIT](IMPORTANT): Pressure:7.487114 PSI 2020-02-27T16:28:15.664Z,1582820895.664 [IBIT](IMPORTANT): Humidity:14.351118 % 2020-02-27T16:28:16.057Z,1582820896.057 [IBIT](IMPORTANT): Vehicle Pitch:-0.265787 degrees 2020-02-27T16:28:16.057Z,1582820896.057 [IBIT](IMPORTANT): Vehicle Roll:1.625936 degrees 2020-02-27T16:28:16.057Z,1582820896.057 [IBIT](IMPORTANT): Vehicle Heading:185.770447 degrees 2020-02-27T16:28:16.469Z,1582820896.469 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2020-02-27T16:28:16.469Z,1582820896.469 [IBIT](IMPORTANT): buoyancyNeutral: 225.000000 cc 2020-02-27T16:28:16.470Z,1582820896.470 [IBIT](IMPORTANT): massDefault: 0.500000 cm 2020-02-27T16:28:16.470Z,1582820896.470 [IBIT](IMPORTANT): stopDepth: 215.000000 m 2020-02-27T16:28:16.470Z,1582820896.470 [IBIT](IMPORTANT): abortDepth: 255.000000 m 2020-02-27T16:28:16.470Z,1582820896.470 [IBIT](IMPORTANT): IBIT PASSED 2020-02-27T16:28:47.517Z,1582820927.517 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T16:30:25.756Z,1582821025.756 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:30:25.756Z,1582821025.756 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:30:25.756Z,1582821025.756 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:30:25.756Z,1582821025.756 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:30:26.161Z,1582821026.161 [Default:CheckIn:D] Stopped 2020-02-27T16:30:26.161Z,1582821026.161 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:30:26.537Z,1582821026.537 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.601160 min 2020-02-27T16:30:26.537Z,1582821026.537 [Default:CheckIn:E] Stopped 2020-02-27T16:30:26.537Z,1582821026.537 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:30:26.537Z,1582821026.537 [Default:CheckIn] Stopped 2020-02-27T16:30:26.537Z,1582821026.537 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:30:26.538Z,1582821026.538 [Default:CheckIn](INFO): Running loop #5 2020-02-27T16:30:26.538Z,1582821026.538 [Default:CheckIn] Running Loop=5 2020-02-27T16:30:26.538Z,1582821026.538 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:30:26.538Z,1582821026.538 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:30:28.532Z,1582821028.532 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163028.00,A,3648.13904,N,12147.23118,W,0.058,35.58,270220,,,A*4F 2020-02-27T16:30:28.534Z,1582821028.534 [NAL9602](INFO): GPS fix at 20200227T163028: (36.802317, -121.787186) 2020-02-27T16:30:28.576Z,1582821028.576 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:30:28.576Z,1582821028.576 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:30:35.916Z,1582821035.916 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Courier0016.lzma 2020-02-27T16:30:37.922Z,1582821037.922 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0016.lzma.bak 2020-02-27T16:30:37.923Z,1582821037.923 [DataOverHttps](INFO): SBD MOMSN=12272341 2020-02-27T16:30:51.966Z,1582821051.966 [NAL9602](INFO): SBD MO Status=0, MOMSN=6611, MT Status=0, MTMSN=0 2020-02-27T16:30:51.966Z,1582821051.966 [NAL9602](INFO): No messages in MT queue 2020-02-27T16:30:58.194Z,1582821058.194 [DataOverHttps](INFO): Sending 799 bytes from file Logs/20200227T154729/Express0017.lzma 2020-02-27T16:31:00.201Z,1582821060.201 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0017.lzma.bak 2020-02-27T16:31:00.202Z,1582821060.202 [DataOverHttps](INFO): SBD MOMSN=12272347 2020-02-27T16:31:17.464Z,1582821077.464 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20200227T154729/Express0021.lzma 2020-02-27T16:31:19.470Z,1582821079.470 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0021.lzma.bak 2020-02-27T16:31:19.471Z,1582821079.471 [DataOverHttps](INFO): SBD MOMSN=12272377 2020-02-27T16:31:20.665Z,1582821080.665 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:31:20.665Z,1582821080.665 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:31:20.665Z,1582821080.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:31:22.662Z,1582821082.662 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T16:35:03.738Z,1582821303.738 [DataOverHttps](IMPORTANT): SBD MTMSN=20200227T163502 2020-02-27T16:35:11.665Z,1582821311.665 [DataOverHttps](INFO): Received command:failc 2020-02-27T16:35:11.710Z,1582821311.710 [CommandLine](IMPORTANT): got command failComponent 2020-02-27T16:35:11.710Z,1582821311.710 [CommandLine](IMPORTANT): Failed components: 2020-02-27T16:35:11.711Z,1582821311.711 [CommandLine](IMPORTANT): No failed Components. 2020-02-27T16:36:21.276Z,1582821381.276 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:36:21.276Z,1582821381.276 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:36:21.276Z,1582821381.276 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:36:21.276Z,1582821381.276 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:36:21.659Z,1582821381.659 [Default:CheckIn:D] Stopped 2020-02-27T16:36:21.659Z,1582821381.659 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:36:22.072Z,1582821382.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.526119 min 2020-02-27T16:36:22.072Z,1582821382.072 [Default:CheckIn:E] Stopped 2020-02-27T16:36:22.072Z,1582821382.072 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:36:22.073Z,1582821382.073 [Default:CheckIn] Stopped 2020-02-27T16:36:22.073Z,1582821382.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:36:22.073Z,1582821382.073 [Default:CheckIn](INFO): Running loop #6 2020-02-27T16:36:22.073Z,1582821382.073 [Default:CheckIn] Running Loop=6 2020-02-27T16:36:22.073Z,1582821382.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:36:22.073Z,1582821382.073 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:36:24.058Z,1582821384.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163624.00,A,3648.13693,N,12147.23419,W,0.039,7.20,270220,,,A*79 2020-02-27T16:36:24.060Z,1582821384.060 [NAL9602](INFO): GPS fix at 20200227T163624: (36.802282, -121.787237) 2020-02-27T16:36:24.108Z,1582821384.108 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:36:24.108Z,1582821384.108 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:36:30.927Z,1582821390.927 [NAL9602](INFO): SBD MO Status=0, MOMSN=6612, MT Status=0, MTMSN=0 2020-02-27T16:36:30.927Z,1582821390.927 [NAL9602](INFO): No messages in MT queue 2020-02-27T16:36:34.024Z,1582821394.024 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Courier0021.lzma 2020-02-27T16:36:36.030Z,1582821396.030 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0021.lzma.bak 2020-02-27T16:36:36.031Z,1582821396.031 [DataOverHttps](INFO): SBD MOMSN=12272384 2020-02-27T16:36:56.449Z,1582821416.449 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20200227T154729/Express0023.lzma 2020-02-27T16:36:58.454Z,1582821418.454 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0023.lzma.bak 2020-02-27T16:36:58.455Z,1582821418.455 [DataOverHttps](INFO): SBD MOMSN=12272387 2020-02-27T16:37:01.702Z,1582821421.702 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T16:37:17.861Z,1582821437.861 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Express0026.lzma 2020-02-27T16:37:19.866Z,1582821439.866 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0026.lzma.bak 2020-02-27T16:37:19.867Z,1582821439.867 [DataOverHttps](INFO): SBD MOMSN=12272395 2020-02-27T16:37:21.117Z,1582821441.117 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:37:21.117Z,1582821441.117 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:37:21.117Z,1582821441.117 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:37:59.750Z,1582821479.750 [CommandLine](INFO): End of History 2020-02-27T16:37:59.872Z,1582821479.872 [CommandLine](INFO): End of History 2020-02-27T16:37:59.898Z,1582821479.898 [CommandLine](INFO): End of History 2020-02-27T16:37:59.948Z,1582821479.948 [CommandLine](INFO): End of History 2020-02-27T16:37:59.975Z,1582821479.975 [CommandLine](INFO): End of History 2020-02-27T16:38:00.038Z,1582821480.038 [CommandLine](INFO): End of History 2020-02-27T16:38:00.040Z,1582821480.040 [CommandLine](INFO): End of History 2020-02-27T16:38:00.042Z,1582821480.042 [CommandLine](INFO): End of History 2020-02-27T16:38:00.044Z,1582821480.044 [CommandLine](INFO): End of History 2020-02-27T16:38:00.118Z,1582821480.118 [CommandLine](INFO): End of History 2020-02-27T16:38:00.120Z,1582821480.120 [CommandLine](INFO): End of History 2020-02-27T16:38:00.122Z,1582821480.122 [CommandLine](INFO): End of History 2020-02-27T16:38:00.124Z,1582821480.124 [CommandLine](INFO): End of History 2020-02-27T16:38:43.074Z,1582821523.074 [CommandLine](IMPORTANT): got command ibit 2020-02-27T16:38:43.155Z,1582821523.155 [IBIT](IMPORTANT): Beginning Initiated BIT 2020-02-27T16:38:43.155Z,1582821523.155 [IBIT](IMPORTANT): Beginning control surface checks. 2020-02-27T16:38:43.158Z,1582821523.158 [CBIT](IMPORTANT): Beginning ground fault scan 2020-02-27T16:38:44.731Z,1582821524.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163844.00,A,3648.14382,N,12147.23135,W,0.019,7.20,270220,,,A*7A 2020-02-27T16:38:44.733Z,1582821524.733 [NAL9602](INFO): GPS fix at 20200227T163844: (36.802397, -121.787189) 2020-02-27T16:38:54.083Z,1582821534.083 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.008197 CHAN A1 (24V): 0.089824 CHAN A2 (12V): -0.002280 CHAN A3 (5V): -0.000164 CHAN B0 (3.3V): 0.001184 CHAN B1 (3.15aV): 0.000707 CHAN B2 (3.15bV): 0.000799 CHAN B3 (GND): 0.002515 OPEN: 0.005420 Full Scale Calc: 4.765 mA, -1.589 mA 2020-02-27T16:39:01.716Z,1582821541.716 [NAL9602](INFO): SBD MO Status=0, MOMSN=6613, MT Status=0, MTMSN=0 2020-02-27T16:39:01.716Z,1582821541.716 [NAL9602](INFO): No messages in MT queue 2020-02-27T16:39:02.902Z,1582821542.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163902.00,A,3648.14420,N,12147.23072,W,0.350,7.20,270220,,,A*7A 2020-02-27T16:39:02.904Z,1582821542.904 [NAL9602](INFO): GPS fix at 20200227T163902: (36.802403, -121.787179) 2020-02-27T16:39:06.136Z,1582821546.136 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163906.00,A,3648.14398,N,12147.23067,W,0.039,7.20,270220,,,A*72 2020-02-27T16:39:06.138Z,1582821546.138 [NAL9602](INFO): GPS fix at 20200227T163906: (36.802400, -121.787178) 2020-02-27T16:39:09.367Z,1582821549.367 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163909.00,A,3648.14407,N,12147.23071,W,0.117,7.20,270220,,,A*76 2020-02-27T16:39:09.370Z,1582821549.370 [NAL9602](INFO): GPS fix at 20200227T163909: (36.802401, -121.787178) 2020-02-27T16:39:12.200Z,1582821552.200 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163912.00,A,3648.14408,N,12147.23076,W,0.058,7.20,270220,,,A*7E 2020-02-27T16:39:12.202Z,1582821552.202 [NAL9602](INFO): GPS fix at 20200227T163912: (36.802401, -121.787179) 2020-02-27T16:39:15.022Z,1582821555.022 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163915.00,A,3648.14414,N,12147.23082,W,0.078,7.20,270220,,,A*7D 2020-02-27T16:39:15.024Z,1582821555.024 [NAL9602](INFO): GPS fix at 20200227T163915: (36.802402, -121.787180) 2020-02-27T16:39:18.254Z,1582821558.254 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163918.00,A,3648.14420,N,12147.23082,W,0.019,7.20,270220,,,A*70 2020-02-27T16:39:18.256Z,1582821558.256 [NAL9602](INFO): GPS fix at 20200227T163918: (36.802403, -121.787180) 2020-02-27T16:39:21.088Z,1582821561.088 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163921.00,A,3648.14417,N,12147.23084,W,0.039,7.20,270220,,,A*7A 2020-02-27T16:39:21.094Z,1582821561.094 [NAL9602](INFO): GPS fix at 20200227T163921: (36.802403, -121.787181) 2020-02-27T16:39:24.328Z,1582821564.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163924.00,A,3648.14417,N,12147.23080,W,0.019,7.20,270220,,,A*79 2020-02-27T16:39:24.331Z,1582821564.331 [NAL9602](INFO): GPS fix at 20200227T163924: (36.802403, -121.787180) 2020-02-27T16:39:27.156Z,1582821567.156 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163927.00,A,3648.14399,N,12147.23075,W,0.097,7.20,270220,,,A*77 2020-02-27T16:39:27.158Z,1582821567.158 [NAL9602](INFO): GPS fix at 20200227T163927: (36.802400, -121.787179) 2020-02-27T16:39:29.614Z,1582821569.614 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802399 Longitude: -121.787178 2020-02-27T16:39:30.028Z,1582821570.028 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 0.000000 2020-02-27T16:39:30.029Z,1582821570.029 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2020-02-27T16:39:30.029Z,1582821570.029 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2020-02-27T16:39:30.388Z,1582821570.388 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163930.00,A,3648.14393,N,12147.23069,W,0.078,7.20,270220,,,A*77 2020-02-27T16:39:30.390Z,1582821570.390 [NAL9602](INFO): GPS fix at 20200227T163930: (36.802399, -121.787178) 2020-02-27T16:39:30.442Z,1582821570.442 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2020-02-27T16:39:30.442Z,1582821570.442 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2020-02-27T16:39:30.442Z,1582821570.442 [IBIT](IMPORTANT): Pressure:7.487114 PSI 2020-02-27T16:39:30.443Z,1582821570.443 [IBIT](IMPORTANT): Humidity:14.320596 % 2020-02-27T16:39:30.864Z,1582821570.864 [IBIT](IMPORTANT): Vehicle Pitch:-0.261728 degrees 2020-02-27T16:39:30.897Z,1582821570.897 [IBIT](IMPORTANT): Vehicle Roll:1.627642 degrees 2020-02-27T16:39:30.897Z,1582821570.897 [IBIT](IMPORTANT): Vehicle Heading:185.765717 degrees 2020-02-27T16:39:31.218Z,1582821571.218 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2020-02-27T16:39:31.219Z,1582821571.219 [IBIT](IMPORTANT): buoyancyNeutral: 225.000000 cc 2020-02-27T16:39:31.219Z,1582821571.219 [IBIT](IMPORTANT): massDefault: 0.500000 cm 2020-02-27T16:39:31.219Z,1582821571.219 [IBIT](IMPORTANT): stopDepth: 215.000000 m 2020-02-27T16:39:31.220Z,1582821571.220 [IBIT](IMPORTANT): abortDepth: 255.000000 m 2020-02-27T16:39:31.220Z,1582821571.220 [IBIT](IMPORTANT): IBIT PASSED 2020-02-27T16:40:02.729Z,1582821602.729 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T16:42:21.724Z,1582821741.724 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:42:21.724Z,1582821741.724 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:42:21.724Z,1582821741.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:42:21.724Z,1582821741.724 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:42:22.135Z,1582821742.135 [Default:CheckIn:D] Stopped 2020-02-27T16:42:22.135Z,1582821742.135 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:42:22.521Z,1582821742.521 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.534054 min 2020-02-27T16:42:22.521Z,1582821742.521 [Default:CheckIn:E] Stopped 2020-02-27T16:42:22.521Z,1582821742.521 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:42:22.521Z,1582821742.521 [Default:CheckIn] Stopped 2020-02-27T16:42:22.521Z,1582821742.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:42:22.521Z,1582821742.521 [Default:CheckIn](INFO): Running loop #7 2020-02-27T16:42:22.521Z,1582821742.521 [Default:CheckIn] Running Loop=7 2020-02-27T16:42:22.522Z,1582821742.522 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:42:22.522Z,1582821742.522 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:42:24.520Z,1582821744.520 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164224.00,A,3648.14105,N,12147.23238,W,0.000,7.20,270220,,,A*7A 2020-02-27T16:42:24.530Z,1582821744.530 [NAL9602](INFO): GPS fix at 20200227T164224: (36.802351, -121.787206) 2020-02-27T16:42:24.565Z,1582821744.565 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:42:24.565Z,1582821744.565 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:42:31.884Z,1582821751.884 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Courier0028.lzma 2020-02-27T16:42:33.890Z,1582821753.890 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0028.lzma.bak 2020-02-27T16:42:33.891Z,1582821753.891 [DataOverHttps](INFO): SBD MOMSN=12272425 2020-02-27T16:42:36.635Z,1582821756.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=6614, MT Status=0, MTMSN=0 2020-02-27T16:42:36.635Z,1582821756.635 [NAL9602](INFO): No messages in MT queue 2020-02-27T16:42:51.210Z,1582821771.210 [DataOverHttps](INFO): Sending 735 bytes from file Logs/20200227T154729/Express0029.lzma 2020-02-27T16:42:53.207Z,1582821773.207 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0029.lzma.bak 2020-02-27T16:42:53.207Z,1582821773.207 [DataOverHttps](INFO): SBD MOMSN=12272432 2020-02-27T16:42:54.432Z,1582821774.432 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:42:54.432Z,1582821774.432 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:42:54.432Z,1582821774.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:43:07.342Z,1582821787.342 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T16:47:55.074Z,1582822075.074 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:47:55.074Z,1582822075.074 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:47:55.074Z,1582822075.074 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:47:55.075Z,1582822075.075 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:47:55.413Z,1582822075.413 [Default:CheckIn:D] Stopped 2020-02-27T16:47:55.413Z,1582822075.413 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:47:55.832Z,1582822075.832 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.088696 min 2020-02-27T16:47:55.832Z,1582822075.832 [Default:CheckIn:E] Stopped 2020-02-27T16:47:55.833Z,1582822075.833 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:47:55.833Z,1582822075.833 [Default:CheckIn] Stopped 2020-02-27T16:47:55.833Z,1582822075.833 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:47:55.833Z,1582822075.833 [Default:CheckIn](INFO): Running loop #8 2020-02-27T16:47:55.833Z,1582822075.833 [Default:CheckIn] Running Loop=8 2020-02-27T16:47:55.833Z,1582822075.833 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:47:55.833Z,1582822075.833 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:47:57.814Z,1582822077.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164757.00,A,3648.14272,N,12147.22958,W,0.039,7.20,270220,,,A*7E 2020-02-27T16:47:57.816Z,1582822077.816 [NAL9602](INFO): GPS fix at 20200227T164757: (36.802379, -121.787160) 2020-02-27T16:47:57.882Z,1582822077.882 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:47:57.882Z,1582822077.882 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:48:04.745Z,1582822084.745 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-27T16:48:05.905Z,1582822085.905 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20200227T154729/Courier0031.lzma 2020-02-27T16:48:07.910Z,1582822087.910 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0031.lzma.bak 2020-02-27T16:48:07.910Z,1582822087.910 [DataOverHttps](INFO): SBD MOMSN=12272464 2020-02-27T16:48:21.246Z,1582822101.246 [NAL9602](INFO): SBD MO Status=2, MOMSN=6615, MT Status=2, MTMSN=0 2020-02-27T16:48:21.246Z,1582822101.246 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T16:48:28.118Z,1582822108.118 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200227T154729/Express0032.lzma 2020-02-27T16:48:30.122Z,1582822110.122 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0032.lzma.bak 2020-02-27T16:48:30.123Z,1582822110.123 [DataOverHttps](INFO): SBD MOMSN=12272467 2020-02-27T16:48:31.378Z,1582822111.378 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:48:31.378Z,1582822111.378 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:48:31.379Z,1582822111.379 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:48:37.009Z,1582822117.009 [NAL9602](INFO): SBD MO Status=2, MOMSN=6615, MT Status=2, MTMSN=0 2020-02-27T16:48:37.009Z,1582822117.009 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T16:48:43.901Z,1582822123.901 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-02-27T16:49:12.151Z,1582822152.151 [NAL9602](INFO): SBD MO Status=0, MOMSN=6615, MT Status=0, MTMSN=0 2020-02-27T16:49:12.151Z,1582822152.151 [NAL9602](INFO): No messages in MT queue 2020-02-27T16:49:42.858Z,1582822182.858 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T16:53:31.973Z,1582822411.973 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:53:31.973Z,1582822411.973 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:53:31.973Z,1582822411.973 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:53:31.973Z,1582822411.973 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:53:32.385Z,1582822412.385 [Default:CheckIn:D] Stopped 2020-02-27T16:53:32.386Z,1582822412.386 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.704899 min 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn:E] Stopped 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn] Stopped 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn](INFO): Running loop #9 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn] Running Loop=9 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:53:32.799Z,1582822412.799 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:53:34.771Z,1582822414.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165334.00,A,3648.13878,N,12147.23022,W,0.019,7.20,270220,,,A*7E 2020-02-27T16:53:34.773Z,1582822414.773 [NAL9602](INFO): GPS fix at 20200227T165334: (36.802313, -121.787170) 2020-02-27T16:53:34.808Z,1582822414.808 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:53:34.809Z,1582822414.809 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:53:42.141Z,1582822422.141 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20200227T154729/Courier0034.lzma 2020-02-27T16:53:44.146Z,1582822424.146 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0034.lzma.bak 2020-02-27T16:53:44.147Z,1582822424.147 [DataOverHttps](INFO): SBD MOMSN=12272486 2020-02-27T16:54:01.465Z,1582822441.465 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200227T154729/Express0035.lzma 2020-02-27T16:54:03.471Z,1582822443.471 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0035.lzma.bak 2020-02-27T16:54:03.471Z,1582822443.471 [DataOverHttps](INFO): SBD MOMSN=12272489 2020-02-27T16:54:04.713Z,1582822444.713 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:54:04.713Z,1582822444.713 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:54:04.713Z,1582822444.713 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:54:08.356Z,1582822448.356 [NAL9602](INFO): SBD MO Status=2, MOMSN=6616, MT Status=2, MTMSN=0 2020-02-27T16:54:08.356Z,1582822448.356 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T16:54:18.447Z,1582822458.447 [NAL9602](INFO): SBD MO Status=0, MOMSN=6616, MT Status=0, MTMSN=0 2020-02-27T16:54:18.448Z,1582822458.448 [NAL9602](INFO): No messages in MT queue 2020-02-27T16:54:49.154Z,1582822489.154 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T16:59:05.347Z,1582822745.347 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T16:59:05.347Z,1582822745.347 [Default:CheckIn:C.Wait] Stopped 2020-02-27T16:59:05.347Z,1582822745.347 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T16:59:05.347Z,1582822745.347 [Default:CheckIn:D] Running Loop=1 2020-02-27T16:59:05.746Z,1582822745.746 [Default:CheckIn:D] Stopped 2020-02-27T16:59:05.746Z,1582822745.746 [Default:CheckIn:E] Running Loop=1 2020-02-27T16:59:06.134Z,1582822746.134 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.260909 min 2020-02-27T16:59:06.134Z,1582822746.134 [Default:CheckIn:E] Stopped 2020-02-27T16:59:06.134Z,1582822746.134 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T16:59:06.134Z,1582822746.134 [Default:CheckIn] Stopped 2020-02-27T16:59:06.134Z,1582822746.134 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T16:59:06.135Z,1582822746.135 [Default:CheckIn](INFO): Running loop #10 2020-02-27T16:59:06.135Z,1582822746.135 [Default:CheckIn] Running Loop=10 2020-02-27T16:59:06.135Z,1582822746.135 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T16:59:06.135Z,1582822746.135 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T16:59:08.110Z,1582822748.110 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165908.00,A,3648.16213,N,12147.21382,W,2.430,23.31,270220,,,A*49 2020-02-27T16:59:08.113Z,1582822748.113 [NAL9602](INFO): GPS fix at 20200227T165908: (36.802702, -121.786897) 2020-02-27T16:59:08.144Z,1582822748.144 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T16:59:08.144Z,1582822748.144 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T16:59:15.925Z,1582822755.925 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Courier0037.lzma 2020-02-27T16:59:17.931Z,1582822757.931 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0037.lzma.bak 2020-02-27T16:59:17.931Z,1582822757.931 [DataOverHttps](INFO): SBD MOMSN=12272501 2020-02-27T16:59:28.719Z,1582822768.719 [NAL9602](INFO): SBD MO Status=0, MOMSN=6617, MT Status=0, MTMSN=0 2020-02-27T16:59:28.720Z,1582822768.720 [NAL9602](INFO): No messages in MT queue 2020-02-27T16:59:35.485Z,1582822775.485 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200227T154729/Express0038.lzma 2020-02-27T16:59:37.490Z,1582822777.490 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0038.lzma.bak 2020-02-27T16:59:37.491Z,1582822777.491 [DataOverHttps](INFO): SBD MOMSN=12272504 2020-02-27T16:59:38.844Z,1582822778.844 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T16:59:38.844Z,1582822778.844 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T16:59:38.844Z,1582822778.844 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T16:59:59.422Z,1582822799.422 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:00:34.587Z,1582822834.587 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-27T17:03:45.322Z,1582823025.322 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-02-27T17:03:45.324Z,1582823025.324 [BPC1](INFO): Received data from all battery sticks. 2020-02-27T17:04:39.469Z,1582823079.469 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:04:39.469Z,1582823079.469 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:04:39.469Z,1582823079.469 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:04:39.469Z,1582823079.469 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:04:39.904Z,1582823079.904 [Default:CheckIn:D] Stopped 2020-02-27T17:04:39.904Z,1582823079.904 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:04:40.263Z,1582823080.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.830200 min 2020-02-27T17:04:40.264Z,1582823080.264 [Default:CheckIn:E] Stopped 2020-02-27T17:04:40.264Z,1582823080.264 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:04:40.264Z,1582823080.264 [Default:CheckIn] Stopped 2020-02-27T17:04:40.264Z,1582823080.264 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:04:40.264Z,1582823080.264 [Default:CheckIn](INFO): Running loop #11 2020-02-27T17:04:40.264Z,1582823080.264 [Default:CheckIn] Running Loop=11 2020-02-27T17:04:40.264Z,1582823080.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:04:40.264Z,1582823080.264 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:04:42.260Z,1582823082.260 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170442.00,A,3648.16526,N,12147.21458,W,0.019,23.58,270220,,,A*4D 2020-02-27T17:04:42.262Z,1582823082.262 [NAL9602](INFO): GPS fix at 20200227T170442: (36.802754, -121.786910) 2020-02-27T17:04:42.320Z,1582823082.320 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:04:42.320Z,1582823082.320 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:04:49.965Z,1582823089.965 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20200227T154729/Courier0040.lzma 2020-02-27T17:04:51.971Z,1582823091.971 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0040.lzma.bak 2020-02-27T17:04:51.972Z,1582823091.972 [DataOverHttps](INFO): SBD MOMSN=12272583 2020-02-27T17:04:59.631Z,1582823099.631 [NAL9602](INFO): SBD MO Status=0, MOMSN=6618, MT Status=0, MTMSN=0 2020-02-27T17:04:59.632Z,1582823099.632 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:05:09.252Z,1582823109.252 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200227T154729/Express0041.lzma 2020-02-27T17:05:11.258Z,1582823111.258 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0041.lzma.bak 2020-02-27T17:05:11.259Z,1582823111.259 [DataOverHttps](INFO): SBD MOMSN=12272586 2020-02-27T17:05:12.576Z,1582823112.576 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:05:12.576Z,1582823112.576 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:05:12.576Z,1582823112.576 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:05:30.334Z,1582823130.334 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:09:45.709Z,1582823385.709 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-27T17:10:13.164Z,1582823413.164 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:10:13.164Z,1582823413.164 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:10:13.165Z,1582823413.165 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:10:13.165Z,1582823413.165 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:10:13.576Z,1582823413.576 [Default:CheckIn:D] Stopped 2020-02-27T17:10:13.576Z,1582823413.576 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:10:13.987Z,1582823413.987 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.391414 min 2020-02-27T17:10:13.987Z,1582823413.987 [Default:CheckIn:E] Stopped 2020-02-27T17:10:13.987Z,1582823413.987 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:10:13.987Z,1582823413.987 [Default:CheckIn] Stopped 2020-02-27T17:10:13.987Z,1582823413.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:10:13.988Z,1582823413.988 [Default:CheckIn](INFO): Running loop #12 2020-02-27T17:10:13.988Z,1582823413.988 [Default:CheckIn] Running Loop=12 2020-02-27T17:10:13.988Z,1582823413.988 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:10:13.988Z,1582823413.988 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:10:15.972Z,1582823415.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171016.00,A,3648.16581,N,12147.21576,W,0.000,23.58,270220,,,D*44 2020-02-27T17:10:15.974Z,1582823415.974 [NAL9602](INFO): GPS fix at 20200227T171016: (36.802763, -121.786929) 2020-02-27T17:10:16.009Z,1582823416.009 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:10:16.009Z,1582823416.009 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:10:23.368Z,1582823423.368 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Courier0043.lzma 2020-02-27T17:10:25.374Z,1582823425.374 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0043.lzma.bak 2020-02-27T17:10:25.375Z,1582823425.375 [DataOverHttps](INFO): SBD MOMSN=12272597 2020-02-27T17:10:42.753Z,1582823442.753 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200227T154729/Express0044.lzma 2020-02-27T17:10:44.761Z,1582823444.761 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0044.lzma.bak 2020-02-27T17:10:44.762Z,1582823444.762 [DataOverHttps](INFO): SBD MOMSN=12272600 2020-02-27T17:10:45.932Z,1582823445.932 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:10:45.932Z,1582823445.932 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:10:45.933Z,1582823445.933 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:10:47.480Z,1582823447.480 [NAL9602](INFO): SBD MO Status=2, MOMSN=6619, MT Status=2, MTMSN=0 2020-02-27T17:10:47.480Z,1582823447.480 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T17:11:23.435Z,1582823483.435 [NAL9602](INFO): SBD MO Status=2, MOMSN=6619, MT Status=2, MTMSN=0 2020-02-27T17:11:23.436Z,1582823483.436 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T17:12:10.299Z,1582823530.299 [NAL9602](INFO): SBD MO Status=0, MOMSN=6619, MT Status=0, MTMSN=0 2020-02-27T17:12:10.300Z,1582823530.300 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:12:41.002Z,1582823561.002 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:15:46.461Z,1582823746.461 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:15:46.461Z,1582823746.461 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:15:46.461Z,1582823746.461 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:15:46.462Z,1582823746.462 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:15:46.888Z,1582823746.888 [Default:CheckIn:D] Stopped 2020-02-27T17:15:46.888Z,1582823746.888 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:15:47.285Z,1582823747.285 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.946615 min 2020-02-27T17:15:47.285Z,1582823747.285 [Default:CheckIn:E] Stopped 2020-02-27T17:15:47.285Z,1582823747.285 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:15:47.286Z,1582823747.286 [Default:CheckIn] Stopped 2020-02-27T17:15:47.286Z,1582823747.286 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:15:47.286Z,1582823747.286 [Default:CheckIn](INFO): Running loop #13 2020-02-27T17:15:47.286Z,1582823747.286 [Default:CheckIn] Running Loop=13 2020-02-27T17:15:47.286Z,1582823747.286 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:15:47.286Z,1582823747.286 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:15:49.281Z,1582823749.281 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171549.00,A,3648.16677,N,12147.21429,W,0.000,23.58,270220,,,D*4A 2020-02-27T17:15:49.283Z,1582823749.283 [NAL9602](INFO): GPS fix at 20200227T171549: (36.802779, -121.786905) 2020-02-27T17:15:49.328Z,1582823749.328 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:15:49.328Z,1582823749.328 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:15:56.944Z,1582823756.944 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20200227T154729/Courier0046.lzma 2020-02-27T17:15:58.950Z,1582823758.950 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0046.lzma.bak 2020-02-27T17:15:58.951Z,1582823758.951 [DataOverHttps](INFO): SBD MOMSN=12272622 2020-02-27T17:16:18.249Z,1582823778.249 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200227T154729/Express0047.lzma 2020-02-27T17:16:20.254Z,1582823780.254 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0047.lzma.bak 2020-02-27T17:16:20.255Z,1582823780.255 [DataOverHttps](INFO): SBD MOMSN=12272625 2020-02-27T17:16:21.187Z,1582823781.187 [NAL9602](INFO): SBD MO Status=0, MOMSN=6620, MT Status=0, MTMSN=0 2020-02-27T17:16:21.188Z,1582823781.188 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:16:21.628Z,1582823781.628 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:16:21.628Z,1582823781.628 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:16:21.628Z,1582823781.628 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:16:51.890Z,1582823811.890 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:21:22.191Z,1582824082.191 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:21:22.191Z,1582824082.191 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:21:22.191Z,1582824082.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:21:22.191Z,1582824082.191 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:21:22.596Z,1582824082.596 [Default:CheckIn:D] Stopped 2020-02-27T17:21:22.596Z,1582824082.596 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:21:23.011Z,1582824083.011 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.541740 min 2020-02-27T17:21:23.011Z,1582824083.011 [Default:CheckIn:E] Stopped 2020-02-27T17:21:23.011Z,1582824083.011 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:21:23.011Z,1582824083.011 [Default:CheckIn] Stopped 2020-02-27T17:21:23.012Z,1582824083.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:21:23.012Z,1582824083.012 [Default:CheckIn](INFO): Running loop #14 2020-02-27T17:21:23.012Z,1582824083.012 [Default:CheckIn] Running Loop=14 2020-02-27T17:21:23.012Z,1582824083.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:21:23.012Z,1582824083.012 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:21:24.992Z,1582824084.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172125.00,A,3648.16505,N,12147.21492,W,0.039,176.23,270220,,,D*76 2020-02-27T17:21:24.994Z,1582824084.994 [NAL9602](INFO): GPS fix at 20200227T172125: (36.802751, -121.786915) 2020-02-27T17:21:25.029Z,1582824085.029 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:21:25.029Z,1582824085.029 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:21:32.672Z,1582824092.672 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20200227T154729/Courier0049.lzma 2020-02-27T17:21:34.678Z,1582824094.678 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0049.lzma.bak 2020-02-27T17:21:34.679Z,1582824094.679 [DataOverHttps](INFO): SBD MOMSN=12272666 2020-02-27T17:21:41.555Z,1582824101.555 [NAL9602](INFO): SBD MO Status=2, MOMSN=6621, MT Status=2, MTMSN=0 2020-02-27T17:21:41.556Z,1582824101.556 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T17:21:52.025Z,1582824112.025 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200227T154729/Express0050.lzma 2020-02-27T17:21:54.030Z,1582824114.030 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0050.lzma.bak 2020-02-27T17:21:54.031Z,1582824114.031 [DataOverHttps](INFO): SBD MOMSN=12272669 2020-02-27T17:21:55.318Z,1582824115.318 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:21:55.318Z,1582824115.318 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:21:55.318Z,1582824115.318 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:22:11.871Z,1582824131.871 [NAL9602](INFO): SBD MO Status=2, MOMSN=6621, MT Status=2, MTMSN=0 2020-02-27T17:22:11.872Z,1582824131.872 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T17:22:47.819Z,1582824167.819 [NAL9602](INFO): SBD MO Status=0, MOMSN=6621, MT Status=0, MTMSN=0 2020-02-27T17:22:47.819Z,1582824167.819 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:23:18.518Z,1582824198.518 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:26:55.938Z,1582824415.938 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:26:55.938Z,1582824415.938 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:26:55.938Z,1582824415.938 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:26:55.938Z,1582824415.938 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:26:56.315Z,1582824416.315 [Default:CheckIn:D] Stopped 2020-02-27T17:26:56.315Z,1582824416.315 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:26:56.720Z,1582824416.720 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.103719 min 2020-02-27T17:26:56.720Z,1582824416.720 [Default:CheckIn:E] Stopped 2020-02-27T17:26:56.721Z,1582824416.721 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:26:56.721Z,1582824416.721 [Default:CheckIn] Stopped 2020-02-27T17:26:56.721Z,1582824416.721 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:26:56.721Z,1582824416.721 [Default:CheckIn](INFO): Running loop #15 2020-02-27T17:26:56.721Z,1582824416.721 [Default:CheckIn] Running Loop=15 2020-02-27T17:26:56.721Z,1582824416.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:26:56.722Z,1582824416.722 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:26:58.708Z,1582824418.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172658.00,A,3648.16460,N,12147.21480,W,0.039,176.23,270220,,,D*7A 2020-02-27T17:26:58.712Z,1582824418.712 [NAL9602](INFO): GPS fix at 20200227T172658: (36.802743, -121.786913) 2020-02-27T17:26:58.736Z,1582824418.736 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:26:58.736Z,1582824418.736 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:27:06.084Z,1582824426.084 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20200227T154729/Courier0052.lzma 2020-02-27T17:27:08.090Z,1582824428.090 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0052.lzma.bak 2020-02-27T17:27:08.091Z,1582824428.091 [DataOverHttps](INFO): SBD MOMSN=12272687 2020-02-27T17:27:25.380Z,1582824445.380 [NAL9602](INFO): SBD MO Status=2, MOMSN=6622, MT Status=2, MTMSN=0 2020-02-27T17:27:25.383Z,1582824445.383 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T17:27:28.333Z,1582824448.333 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20200227T154729/Express0053.lzma 2020-02-27T17:27:30.338Z,1582824450.338 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0053.lzma.bak 2020-02-27T17:27:30.339Z,1582824450.339 [DataOverHttps](INFO): SBD MOMSN=12272690 2020-02-27T17:27:31.460Z,1582824451.460 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:27:31.460Z,1582824451.460 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:27:31.460Z,1582824451.460 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:27:48.804Z,1582824468.804 [NAL9602](INFO): SBD MO Status=0, MOMSN=6622, MT Status=0, MTMSN=0 2020-02-27T17:27:48.804Z,1582824468.804 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:28:19.510Z,1582824499.510 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:32:32.047Z,1582824752.047 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:32:32.047Z,1582824752.047 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:32:32.047Z,1582824752.047 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:32:32.047Z,1582824752.047 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:32:32.468Z,1582824752.468 [Default:CheckIn:D] Stopped 2020-02-27T17:32:32.468Z,1582824752.468 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:32:32.856Z,1582824752.856 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.706266 min 2020-02-27T17:32:32.856Z,1582824752.856 [Default:CheckIn:E] Stopped 2020-02-27T17:32:32.856Z,1582824752.856 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:32:32.856Z,1582824752.856 [Default:CheckIn] Stopped 2020-02-27T17:32:32.857Z,1582824752.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:32:32.857Z,1582824752.857 [Default:CheckIn](INFO): Running loop #16 2020-02-27T17:32:32.857Z,1582824752.857 [Default:CheckIn] Running Loop=16 2020-02-27T17:32:32.857Z,1582824752.857 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:32:32.857Z,1582824752.857 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:32:34.848Z,1582824754.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173234.00,A,3648.16511,N,12147.21448,W,0.058,176.23,270220,,,D*71 2020-02-27T17:32:34.850Z,1582824754.850 [NAL9602](INFO): GPS fix at 20200227T173234: (36.802752, -121.786908) 2020-02-27T17:32:34.906Z,1582824754.906 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:32:34.906Z,1582824754.906 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:32:42.496Z,1582824762.496 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20200227T154729/Courier0055.lzma 2020-02-27T17:32:44.502Z,1582824764.502 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0055.lzma.bak 2020-02-27T17:32:44.503Z,1582824764.503 [DataOverHttps](INFO): SBD MOMSN=12272700 2020-02-27T17:32:53.072Z,1582824773.072 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-27T17:33:01.836Z,1582824781.836 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200227T154729/Express0056.lzma 2020-02-27T17:33:03.842Z,1582824783.842 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0056.lzma.bak 2020-02-27T17:33:03.843Z,1582824783.843 [DataOverHttps](INFO): SBD MOMSN=12272703 2020-02-27T17:33:05.180Z,1582824785.180 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:33:05.180Z,1582824785.180 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:33:05.180Z,1582824785.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:33:34.235Z,1582824814.235 [NAL9602](INFO): SBD MO Status=2, MOMSN=6623, MT Status=2, MTMSN=0 2020-02-27T17:33:34.236Z,1582824814.236 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T17:34:13.016Z,1582824853.016 [NAL9602](INFO): SBD MO Status=2, MOMSN=6623, MT Status=2, MTMSN=0 2020-02-27T17:34:13.016Z,1582824853.016 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-27T17:34:36.903Z,1582824876.903 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-02-27T17:35:19.688Z,1582824919.688 [NAL9602](INFO): SBD MO Status=0, MOMSN=6623, MT Status=0, MTMSN=0 2020-02-27T17:35:19.688Z,1582824919.688 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:35:50.387Z,1582824950.387 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:37:57.707Z,1582825077.707 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-27T17:38:05.792Z,1582825085.792 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:38:05.792Z,1582825085.792 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:38:05.792Z,1582825085.792 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:38:05.792Z,1582825085.792 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:38:06.200Z,1582825086.200 [Default:CheckIn:D] Stopped 2020-02-27T17:38:06.200Z,1582825086.200 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:38:06.659Z,1582825086.659 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.268473 min 2020-02-27T17:38:06.659Z,1582825086.659 [Default:CheckIn:E] Stopped 2020-02-27T17:38:06.660Z,1582825086.660 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:38:06.660Z,1582825086.660 [Default:CheckIn] Stopped 2020-02-27T17:38:06.660Z,1582825086.660 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:38:06.660Z,1582825086.660 [Default:CheckIn](INFO): Running loop #17 2020-02-27T17:38:06.660Z,1582825086.660 [Default:CheckIn] Running Loop=17 2020-02-27T17:38:06.660Z,1582825086.660 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:38:06.660Z,1582825086.660 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:38:08.576Z,1582825088.576 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173808.00,A,3648.16433,N,12147.21472,W,0.078,176.23,270220,,,D*7E 2020-02-27T17:38:08.578Z,1582825088.578 [NAL9602](INFO): GPS fix at 20200227T173808: (36.802739, -121.786912) 2020-02-27T17:38:08.632Z,1582825088.632 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:38:08.632Z,1582825088.632 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:38:09.526Z,1582825089.526 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:38:09.526Z,1582825089.526 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:38:09.526Z,1582825089.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:38:21.571Z,1582825101.571 [NAL9602](INFO): SBD MO Status=0, MOMSN=6624, MT Status=0, MTMSN=0 2020-02-27T17:38:21.572Z,1582825101.572 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:38:52.262Z,1582825132.262 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:40:13.090Z,1582825213.090 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-02-27T17:43:10.048Z,1582825390.048 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:43:10.049Z,1582825390.049 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:43:10.049Z,1582825390.049 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:43:10.049Z,1582825390.049 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:43:10.456Z,1582825390.456 [Default:CheckIn:D] Stopped 2020-02-27T17:43:10.456Z,1582825390.456 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:43:10.856Z,1582825390.856 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.339412 min 2020-02-27T17:43:10.857Z,1582825390.857 [Default:CheckIn:E] Stopped 2020-02-27T17:43:10.857Z,1582825390.857 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:43:10.857Z,1582825390.857 [Default:CheckIn] Stopped 2020-02-27T17:43:10.857Z,1582825390.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:43:10.857Z,1582825390.857 [Default:CheckIn](INFO): Running loop #18 2020-02-27T17:43:10.857Z,1582825390.857 [Default:CheckIn] Running Loop=18 2020-02-27T17:43:10.857Z,1582825390.857 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:43:10.858Z,1582825390.858 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:43:12.844Z,1582825392.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174312.00,A,3648.16842,N,12147.19525,W,0.350,114.83,270220,,,D*7C 2020-02-27T17:43:12.847Z,1582825392.847 [NAL9602](INFO): GPS fix at 20200227T174312: (36.802807, -121.786587) 2020-02-27T17:43:12.917Z,1582825392.917 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:43:12.917Z,1582825392.917 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:43:20.276Z,1582825400.276 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Courier0058.lzma 2020-02-27T17:43:22.282Z,1582825402.282 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0058.lzma.bak 2020-02-27T17:43:22.283Z,1582825402.283 [DataOverHttps](INFO): SBD MOMSN=12272730 2020-02-27T17:43:38.699Z,1582825418.699 [NAL9602](INFO): SBD MO Status=0, MOMSN=6625, MT Status=0, MTMSN=0 2020-02-27T17:43:38.700Z,1582825418.700 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:43:39.577Z,1582825419.577 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Courier0061.lzma 2020-02-27T17:43:41.582Z,1582825421.582 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0061.lzma.bak 2020-02-27T17:43:41.583Z,1582825421.583 [DataOverHttps](INFO): SBD MOMSN=12272733 2020-02-27T17:43:59.002Z,1582825439.002 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20200227T154729/Express0059.lzma 2020-02-27T17:44:01.007Z,1582825441.007 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0059.lzma.bak 2020-02-27T17:44:01.007Z,1582825441.007 [DataOverHttps](INFO): SBD MOMSN=12272736 2020-02-27T17:44:09.434Z,1582825449.434 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:44:22.360Z,1582825462.360 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200227T154729/Express0062.lzma 2020-02-27T17:44:24.366Z,1582825464.366 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0062.lzma.bak 2020-02-27T17:44:24.367Z,1582825464.367 [DataOverHttps](INFO): SBD MOMSN=12272739 2020-02-27T17:44:25.627Z,1582825465.627 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:44:25.628Z,1582825465.628 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:44:25.628Z,1582825465.628 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:49:26.206Z,1582825766.206 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:49:26.206Z,1582825766.206 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:49:26.206Z,1582825766.206 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:49:26.206Z,1582825766.206 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:49:26.613Z,1582825766.613 [Default:CheckIn:D] Stopped 2020-02-27T17:49:26.613Z,1582825766.613 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:49:27.008Z,1582825767.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.608700 min 2020-02-27T17:49:27.008Z,1582825767.008 [Default:CheckIn:E] Stopped 2020-02-27T17:49:27.008Z,1582825767.008 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:49:27.008Z,1582825767.008 [Default:CheckIn] Stopped 2020-02-27T17:49:27.008Z,1582825767.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:49:27.008Z,1582825767.008 [Default:CheckIn](INFO): Running loop #19 2020-02-27T17:49:27.008Z,1582825767.008 [Default:CheckIn] Running Loop=19 2020-02-27T17:49:27.009Z,1582825767.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:49:27.009Z,1582825767.009 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:49:28.000Z,1582825769.000 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174929.00,A,3648.45901,N,12147.31413,W,7.678,236.58,270220,,,D*7D 2020-02-27T17:49:29.002Z,1582825769.002 [NAL9602](INFO): GPS fix at 20200227T174929: (36.807650, -121.788569) 2020-02-27T17:49:29.049Z,1582825769.049 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:49:29.049Z,1582825769.049 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:49:36.436Z,1582825776.436 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20200227T154729/Courier0064.lzma 2020-02-27T17:49:38.442Z,1582825778.442 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0064.lzma.bak 2020-02-27T17:49:38.443Z,1582825778.443 [DataOverHttps](INFO): SBD MOMSN=12272764 2020-02-27T17:49:44.763Z,1582825784.763 [NAL9602](INFO): SBD MO Status=0, MOMSN=6626, MT Status=0, MTMSN=0 2020-02-27T17:49:44.764Z,1582825784.764 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:49:55.753Z,1582825795.753 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200227T154729/Express0065.lzma 2020-02-27T17:49:57.759Z,1582825797.759 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0065.lzma.bak 2020-02-27T17:49:57.759Z,1582825797.759 [DataOverHttps](INFO): SBD MOMSN=12272779 2020-02-27T17:49:58.928Z,1582825798.928 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:49:58.928Z,1582825798.928 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:49:58.928Z,1582825798.928 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:50:15.462Z,1582825815.462 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T17:54:59.501Z,1582826099.501 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T17:54:59.501Z,1582826099.501 [Default:CheckIn:C.Wait] Stopped 2020-02-27T17:54:59.501Z,1582826099.501 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T17:54:59.501Z,1582826099.501 [Default:CheckIn:D] Running Loop=1 2020-02-27T17:54:59.931Z,1582826099.931 [Default:CheckIn:D] Stopped 2020-02-27T17:54:59.931Z,1582826099.931 [Default:CheckIn:E] Running Loop=1 2020-02-27T17:55:00.321Z,1582826100.321 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.163997 min 2020-02-27T17:55:00.321Z,1582826100.321 [Default:CheckIn:E] Stopped 2020-02-27T17:55:00.322Z,1582826100.322 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T17:55:00.322Z,1582826100.322 [Default:CheckIn] Stopped 2020-02-27T17:55:00.322Z,1582826100.322 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T17:55:00.322Z,1582826100.322 [Default:CheckIn](INFO): Running loop #20 2020-02-27T17:55:00.322Z,1582826100.322 [Default:CheckIn] Running Loop=20 2020-02-27T17:55:00.322Z,1582826100.322 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T17:55:00.322Z,1582826100.322 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T17:55:02.308Z,1582826102.308 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175502.00,A,3648.27740,N,12149.60659,W,25.523,266.27,270220,,,D*40 2020-02-27T17:55:02.310Z,1582826102.310 [NAL9602](INFO): GPS fix at 20200227T175502: (36.804623, -121.826776) 2020-02-27T17:55:02.344Z,1582826102.344 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T17:55:02.345Z,1582826102.345 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T17:55:10.464Z,1582826110.464 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20200227T154729/Courier0067.lzma 2020-02-27T17:55:12.470Z,1582826112.470 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0067.lzma.bak 2020-02-27T17:55:12.471Z,1582826112.471 [DataOverHttps](INFO): SBD MOMSN=12272789 2020-02-27T17:55:13.629Z,1582826113.629 [NAL9602](INFO): SBD MO Status=0, MOMSN=6627, MT Status=0, MTMSN=0 2020-02-27T17:55:13.629Z,1582826113.629 [NAL9602](INFO): No messages in MT queue 2020-02-27T17:55:29.736Z,1582826129.736 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20200227T154729/Express0068.lzma 2020-02-27T17:55:31.742Z,1582826131.742 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0068.lzma.bak 2020-02-27T17:55:31.743Z,1582826131.743 [DataOverHttps](INFO): SBD MOMSN=12272793 2020-02-27T17:55:33.051Z,1582826133.051 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T17:55:33.051Z,1582826133.051 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T17:55:33.051Z,1582826133.051 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T17:55:44.320Z,1582826144.320 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T18:00:33.621Z,1582826433.621 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-27T18:00:33.621Z,1582826433.621 [Default:CheckIn:C.Wait] Stopped 2020-02-27T18:00:33.621Z,1582826433.621 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-27T18:00:33.621Z,1582826433.621 [Default:CheckIn:D] Running Loop=1 2020-02-27T18:00:34.040Z,1582826434.040 [Default:CheckIn:D] Stopped 2020-02-27T18:00:34.040Z,1582826434.040 [Default:CheckIn:E] Running Loop=1 2020-02-27T18:00:34.435Z,1582826434.435 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.732471 min 2020-02-27T18:00:34.435Z,1582826434.435 [Default:CheckIn:E] Stopped 2020-02-27T18:00:34.436Z,1582826434.436 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-27T18:00:34.436Z,1582826434.436 [Default:CheckIn] Stopped 2020-02-27T18:00:34.436Z,1582826434.436 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-27T18:00:34.436Z,1582826434.436 [Default:CheckIn](INFO): Running loop #21 2020-02-27T18:00:34.436Z,1582826434.436 [Default:CheckIn] Running Loop=21 2020-02-27T18:00:34.436Z,1582826434.436 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-27T18:00:34.436Z,1582826434.436 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-27T18:00:36.428Z,1582826436.428 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180036.00,A,3648.19487,N,12150.98892,W,0.272,284.91,270220,,,D*7E 2020-02-27T18:00:36.430Z,1582826436.430 [NAL9602](INFO): GPS fix at 20200227T180036: (36.803248, -121.849815) 2020-02-27T18:00:36.454Z,1582826436.454 [Default:CheckIn:Read_GPS] Stopped 2020-02-27T18:00:36.454Z,1582826436.454 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-27T18:00:52.984Z,1582826452.984 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200227T154729/Courier0070.lzma 2020-02-27T18:00:54.990Z,1582826454.990 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Courier0070.lzma.bak 2020-02-27T18:00:54.991Z,1582826454.991 [DataOverHttps](INFO): SBD MOMSN=12272811 2020-02-27T18:00:58.651Z,1582826458.651 [NAL9602](INFO): SBD MO Status=0, MOMSN=6628, MT Status=0, MTMSN=0 2020-02-27T18:00:58.651Z,1582826458.651 [NAL9602](INFO): No messages in MT queue 2020-02-27T18:01:21.216Z,1582826481.216 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20200227T154729/Express0071.lzma 2020-02-27T18:01:23.222Z,1582826483.222 [DataOverHttps](INFO): Moved sent file to Logs/20200227T154729/Express0071.lzma.bak 2020-02-27T18:01:23.223Z,1582826483.223 [DataOverHttps](INFO): SBD MOMSN=12272814 2020-02-27T18:01:24.536Z,1582826484.536 [Default:CheckIn:Read_Iridium] Stopped 2020-02-27T18:01:24.536Z,1582826484.536 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-27T18:01:24.536Z,1582826484.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-27T18:01:29.350Z,1582826489.350 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-27T18:03:20.398Z,1582826600.398 [DataOverHttps](IMPORTANT): SBD MTMSN=20200227T180319 2020-02-27T18:03:30.172Z,1582826610.172 [DataOverHttps](INFO): Received command:restart logs