2019-05-30T23:36:51.897Z,1559259411.897 [Supervisor](DEBUG): Initializing supervisor. 2019-05-30T23:36:51.899Z,1559259411.899 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-05-30T23:36:51.900Z,1559259411.900 [SyncHandler](INFO): Protected caller Thread ID is 2616 2019-05-30T23:36:51.900Z,1559259411.900 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-05-30T23:36:51.901Z,1559259411.901 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-05-30T23:36:51.901Z,1559259411.901 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2617 2019-05-30T23:36:51.904Z,1559259411.904 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-05-30T23:36:51.915Z,1559259411.915 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-05-30T23:36:51.916Z,1559259411.916 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-05-30T23:36:51.917Z,1559259411.917 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2618 2019-05-30T23:36:51.918Z,1559259411.918 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-05-30T23:36:51.919Z,1559259411.919 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-05-30T23:36:51.919Z,1559259411.919 [logger ThreadHandler](INFO): Protected caller Thread ID is 2619 2019-05-30T23:36:51.921Z,1559259411.921 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-05-30T23:36:51.921Z,1559259411.921 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-05-30T23:36:51.924Z,1559259411.924 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-05-30T23:36:52.087Z,1559259412.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-05-30T23:36:52.089Z,1559259412.089 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-05-30T23:36:52.291Z,1559259412.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-05-30T23:36:52.293Z,1559259412.293 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-05-30T23:36:52.432Z,1559259412.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-05-30T23:36:52.433Z,1559259412.433 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-05-30T23:36:52.967Z,1559259412.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-05-30T23:36:52.968Z,1559259412.968 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-05-30T23:36:53.418Z,1559259413.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-05-30T23:36:53.419Z,1559259413.419 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-05-30T23:36:53.892Z,1559259413.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-05-30T23:36:53.895Z,1559259413.895 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-05-30T23:36:54.199Z,1559259414.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-05-30T23:36:54.200Z,1559259414.200 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-05-30T23:36:54.534Z,1559259414.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-05-30T23:36:54.536Z,1559259414.536 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-05-30T23:36:54.929Z,1559259414.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-05-30T23:36:54.930Z,1559259414.930 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-05-30T23:36:55.083Z,1559259415.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-05-30T23:36:55.084Z,1559259415.084 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-05-30T23:36:55.191Z,1559259415.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-05-30T23:36:55.192Z,1559259415.192 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-05-30T23:36:55.276Z,1559259415.276 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-05-30T23:36:55.381Z,1559259415.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-05-30T23:36:55.381Z,1559259415.381 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-05-30T23:36:55.578Z,1559259415.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-05-30T23:36:55.578Z,1559259415.578 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-05-30T23:36:55.786Z,1559259415.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-05-30T23:36:55.788Z,1559259415.788 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-05-30T23:36:55.791Z,1559259415.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-05-30T23:36:55.878Z,1559259415.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-05-30T23:36:56.105Z,1559259416.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-05-30T23:36:56.107Z,1559259416.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-05-30T23:36:56.206Z,1559259416.206 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-05-30T23:36:56.373Z,1559259416.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-05-30T23:36:56.579Z,1559259416.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-05-30T23:36:56.668Z,1559259416.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-05-30T23:36:56.769Z,1559259416.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-05-30T23:36:56.866Z,1559259416.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-05-30T23:36:57.168Z,1559259417.168 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-05-30T23:36:58.059Z,1559259418.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-05-30T23:36:58.161Z,1559259418.161 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-05-30T23:36:58.183Z,1559259418.183 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-05-30T23:36:58.303Z,1559259418.303 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-05-30T23:36:58.304Z,1559259418.304 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-05-30T23:36:58.430Z,1559259418.430 [BuoyancyServo] Loaded 2019-05-30T23:36:58.430Z,1559259418.430 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-05-30T23:36:58.444Z,1559259418.444 [ElevatorServo] Loaded 2019-05-30T23:36:58.445Z,1559259418.445 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-05-30T23:36:58.458Z,1559259418.458 [MassServo] Loaded 2019-05-30T23:36:58.459Z,1559259418.459 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-05-30T23:36:58.473Z,1559259418.473 [RudderServo] Loaded 2019-05-30T23:36:58.473Z,1559259418.473 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-05-30T23:36:58.487Z,1559259418.487 [ThrusterServo] Loaded 2019-05-30T23:36:58.487Z,1559259418.487 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-05-30T23:36:58.488Z,1559259418.488 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-05-30T23:36:58.488Z,1559259418.488 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-05-30T23:36:58.640Z,1559259418.640 [SBIT](DEBUG): Construct Startup Built In Test. 2019-05-30T23:36:58.669Z,1559259418.669 [SBIT] Loaded 2019-05-30T23:36:58.669Z,1559259418.669 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-05-30T23:36:58.670Z,1559259418.670 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-05-30T23:36:58.696Z,1559259418.696 [IBIT] Loaded 2019-05-30T23:36:58.696Z,1559259418.696 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-05-30T23:36:58.699Z,1559259418.699 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-05-30T23:36:59.073Z,1559259419.073 [CBIT] Loaded 2019-05-30T23:36:59.073Z,1559259419.073 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-05-30T23:36:59.073Z,1559259419.073 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-05-30T23:36:59.074Z,1559259419.074 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-05-30T23:36:59.323Z,1559259419.323 [Aanderaa_O2] Loaded 2019-05-30T23:36:59.323Z,1559259419.323 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-05-30T23:36:59.332Z,1559259419.332 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-05-30T23:36:59.337Z,1559259419.337 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-05-30T23:36:59.339Z,1559259419.339 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-05-30T23:36:59.343Z,1559259419.343 [CTD_Seabird](INFO): created writer for : depth 2019-05-30T23:36:59.344Z,1559259419.344 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-05-30T23:36:59.349Z,1559259419.349 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-05-30T23:36:59.349Z,1559259419.349 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-05-30T23:36:59.354Z,1559259419.354 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-05-30T23:36:59.355Z,1559259419.355 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-05-30T23:36:59.360Z,1559259419.360 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-05-30T23:36:59.361Z,1559259419.361 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-05-30T23:36:59.366Z,1559259419.366 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-05-30T23:36:59.366Z,1559259419.366 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-05-30T23:36:59.371Z,1559259419.371 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-05-30T23:36:59.396Z,1559259419.396 [CTD_Seabird] Loaded 2019-05-30T23:36:59.397Z,1559259419.397 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-05-30T23:36:59.398Z,1559259419.398 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066A4E0 2019-05-30T23:36:59.398Z,1559259419.398 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2698 2019-05-30T23:36:59.427Z,1559259419.427 [ESPComponent] Loaded 2019-05-30T23:36:59.427Z,1559259419.427 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-05-30T23:36:59.440Z,1559259419.440 [PAR_Licor] Loaded 2019-05-30T23:36:59.441Z,1559259419.441 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-05-30T23:36:59.487Z,1559259419.487 [WetLabsBB2FL] Loaded 2019-05-30T23:36:59.487Z,1559259419.487 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-05-30T23:36:59.488Z,1559259419.488 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069A4E0 2019-05-30T23:36:59.488Z,1559259419.488 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2699 2019-05-30T23:36:59.489Z,1559259419.489 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-05-30T23:36:59.490Z,1559259419.490 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-05-30T23:36:59.543Z,1559259419.543 [DepthRateCalculator] Loaded 2019-05-30T23:36:59.543Z,1559259419.543 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-05-30T23:36:59.548Z,1559259419.548 [PitchRateCalculator] Loaded 2019-05-30T23:36:59.549Z,1559259419.549 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-05-30T23:36:59.564Z,1559259419.564 [SpeedCalculator] Loaded 2019-05-30T23:36:59.564Z,1559259419.564 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-05-30T23:36:59.584Z,1559259419.584 [TempGradientCalculator] Loaded 2019-05-30T23:36:59.585Z,1559259419.585 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-05-30T23:36:59.590Z,1559259419.590 [YawRateCalculator] Loaded 2019-05-30T23:36:59.591Z,1559259419.591 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-05-30T23:36:59.622Z,1559259419.622 [ElevatorOffsetCalculator] Loaded 2019-05-30T23:36:59.622Z,1559259419.622 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-05-30T23:36:59.623Z,1559259419.623 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-05-30T23:36:59.623Z,1559259419.623 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-05-30T23:36:59.670Z,1559259419.670 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-05-30T23:36:59.670Z,1559259419.670 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-05-30T23:36:59.786Z,1559259419.786 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-05-30T23:36:59.786Z,1559259419.786 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-05-30T23:36:59.806Z,1559259419.806 [NavChart] Loaded 2019-05-30T23:36:59.807Z,1559259419.807 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-05-30T23:36:59.814Z,1559259419.814 [UniversalFixResidualReporter] Loaded 2019-05-30T23:36:59.815Z,1559259419.815 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-05-30T23:36:59.815Z,1559259419.815 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-05-30T23:36:59.816Z,1559259419.816 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-05-30T23:37:00.513Z,1559259420.513 [AHRS_M2] Loaded 2019-05-30T23:37:00.513Z,1559259420.513 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-05-30T23:37:00.610Z,1559259420.610 [DataOverHttps] Loaded 2019-05-30T23:37:00.610Z,1559259420.610 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-05-30T23:37:00.611Z,1559259420.611 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409734E0 2019-05-30T23:37:00.612Z,1559259420.612 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2700 2019-05-30T23:37:00.624Z,1559259420.624 [Depth_Keller] Loaded 2019-05-30T23:37:00.624Z,1559259420.624 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-05-30T23:37:00.629Z,1559259420.629 [DropWeight] Loaded 2019-05-30T23:37:00.629Z,1559259420.629 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-05-30T23:37:00.722Z,1559259420.722 [NAL9602] Loaded 2019-05-30T23:37:00.722Z,1559259420.722 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-05-30T23:37:00.727Z,1559259420.727 [Onboard] Loaded 2019-05-30T23:37:00.727Z,1559259420.727 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-05-30T23:37:00.730Z,1559259420.730 [Radio_Surface] Loaded 2019-05-30T23:37:00.731Z,1559259420.731 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-05-30T23:37:00.732Z,1559259420.732 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A34E0 2019-05-30T23:37:00.732Z,1559259420.732 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2701 2019-05-30T23:37:00.861Z,1559259420.861 [DAT] Loaded 2019-05-30T23:37:00.861Z,1559259420.861 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-05-30T23:37:02.094Z,1559259422.094 [BPC1] Loaded 2019-05-30T23:37:02.094Z,1559259422.094 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-05-30T23:37:02.095Z,1559259422.095 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-05-30T23:37:02.095Z,1559259422.095 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-05-30T23:37:02.244Z,1559259422.244 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-05-30T23:37:02.245Z,1559259422.245 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-05-30T23:37:02.333Z,1559259422.333 [VerticalControl](DEBUG): Construct VerticalControl. 2019-05-30T23:37:02.419Z,1559259422.419 [VerticalControl] Loaded 2019-05-30T23:37:02.419Z,1559259422.419 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-05-30T23:37:02.420Z,1559259422.420 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-05-30T23:37:02.475Z,1559259422.475 [HorizontalControl] Loaded 2019-05-30T23:37:02.476Z,1559259422.476 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-05-30T23:37:02.476Z,1559259422.476 [SpeedControl](DEBUG): Construct SpeedControl. 2019-05-30T23:37:02.478Z,1559259422.478 [SpeedControl] Loaded 2019-05-30T23:37:02.478Z,1559259422.478 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-05-30T23:37:02.479Z,1559259422.479 [LoopControl](DEBUG): Construct LoopControl. 2019-05-30T23:37:02.479Z,1559259422.479 [LoopControl] Loaded 2019-05-30T23:37:02.480Z,1559259422.480 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-05-30T23:37:02.480Z,1559259422.480 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-05-30T23:37:02.481Z,1559259422.481 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-05-30T23:37:02.505Z,1559259422.505 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-05-30T23:37:02.505Z,1559259422.505 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-05-30T23:37:02.842Z,1559259422.842 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-05-30T23:37:02.846Z,1559259422.846 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-05-30T23:37:02.847Z,1559259422.847 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-05-30T23:37:02.854Z,1559259422.854 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-05-30T23:37:02.858Z,1559259422.858 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0 2019-05-30T23:37:02.859Z,1559259422.859 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2702 2019-05-30T23:37:02.863Z,1559259422.863 [Supervisor](INFO): Main Thread ID is 2615 2019-05-30T23:37:02.864Z,1559259422.864 [Supervisor](DEBUG): Running supervisor. 2019-05-30T23:37:02.864Z,1559259422.864 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2703 2019-05-30T23:37:02.867Z,1559259422.867 [controlThread ThreadHandler](INFO): Handler Thread ID is 2704 2019-05-30T23:37:02.868Z,1559259422.868 [controlThread](DEBUG): Initializing ControlThread 2019-05-30T23:37:02.870Z,1559259422.870 [SBIT](INFO): Initialize SBIT Component. 2019-05-30T23:37:02.870Z,1559259422.870 [SBIT](IMPORTANT): git: 2019-05-21 2019-05-30T23:37:02.870Z,1559259422.870 [SBIT](INFO): git hash: d33b11e57afc4926c4e35bc3081773f5ba5b446b 2019-05-30T23:37:02.871Z,1559259422.871 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-05-30T23:37:02.872Z,1559259422.872 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-05-30T23:37:02.873Z,1559259422.873 [SBIT](INFO): Beginning SBIT in 56.000000 seconds. 2019-05-30T23:37:02.874Z,1559259422.874 [IBIT](INFO): Initialize IBIT Component. 2019-05-30T23:37:02.874Z,1559259422.874 [CBIT](DEBUG): Initialize CBIT Component. 2019-05-30T23:37:02.875Z,1559259422.875 [logger ThreadHandler](INFO): Handler Thread ID is 2705 2019-05-30T23:37:02.889Z,1559259422.889 [CBIT](DEBUG): Initialized mux pins. 2019-05-30T23:37:02.889Z,1559259422.889 [CBIT](DEBUG): Initializing the watchdog timer. 2019-05-30T23:37:02.897Z,1559259422.897 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2706 2019-05-30T23:37:02.898Z,1559259422.898 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-05-30T23:37:02.905Z,1559259422.905 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-05-30T23:37:02.907Z,1559259422.907 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2708 2019-05-30T23:37:02.908Z,1559259422.908 [WetLabsBB2FL](INFO): Powering down 2019-05-30T23:37:02.913Z,1559259422.913 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-05-30T23:37:02.913Z,1559259422.913 [CBIT](DEBUG): Initializing heartbeat. 2019-05-30T23:37:02.938Z,1559259422.938 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2709 2019-05-30T23:37:02.939Z,1559259422.939 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-05-30T23:37:02.961Z,1559259422.961 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2710 2019-05-30T23:37:02.977Z,1559259422.977 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2711 2019-05-30T23:37:02.984Z,1559259422.984 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-05-30T23:37:02.985Z,1559259422.985 [CBIT](DEBUG): Deactivating GF circuits. 2019-05-30T23:37:02.985Z,1559259422.985 [CBIT](DEBUG): Deactivating emergency mode. 2019-05-30T23:37:02.986Z,1559259422.986 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-05-30T23:37:02.986Z,1559259422.986 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-05-30T23:37:02.986Z,1559259422.986 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-05-30T23:37:02.986Z,1559259422.986 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-05-30T23:37:02.986Z,1559259422.986 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-05-30T23:37:02.987Z,1559259422.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-05-30T23:37:02.987Z,1559259422.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-05-30T23:37:02.987Z,1559259422.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-05-30T23:37:02.987Z,1559259422.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-05-30T23:37:02.987Z,1559259422.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-05-30T23:37:02.987Z,1559259422.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-05-30T23:37:02.988Z,1559259422.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-05-30T23:37:02.988Z,1559259422.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-05-30T23:37:02.988Z,1559259422.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-05-30T23:37:02.988Z,1559259422.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-05-30T23:37:03.021Z,1559259423.021 [CBIT](DEBUG): Backplane powered. 2019-05-30T23:37:03.025Z,1559259423.025 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-05-30T23:37:03.025Z,1559259423.025 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-05-30T23:37:03.026Z,1559259423.026 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-05-30T23:37:03.026Z,1559259423.026 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-05-30T23:37:03.027Z,1559259423.027 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-05-30T23:37:03.027Z,1559259423.027 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-05-30T23:37:03.028Z,1559259423.028 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-05-30T23:37:03.029Z,1559259423.029 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-05-30T23:37:03.043Z,1559259423.043 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-05-30T23:37:03.054Z,1559259423.054 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-05-30T23:37:03.054Z,1559259423.054 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-05-30T23:37:03.055Z,1559259423.055 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-05-30T23:37:03.056Z,1559259423.056 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-05-30T23:37:03.094Z,1559259423.094 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-30T23:37:03.126Z,1559259423.126 [MissionManager](DEBUG): 2019-05-30T23:37:03.126Z,1559259423.126 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-05-30T23:37:03.205Z,1559259423.205 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-05-30T23:37:03.207Z,1559259423.207 [Default:A.Wait](DEBUG): Construct Wait. 2019-05-30T23:37:03.208Z,1559259423.208 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-30T23:37:03.255Z,1559259423.255 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-05-30T23:37:03.257Z,1559259423.257 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-05-30T23:37:03.279Z,1559259423.279 [Default:E.Execute](DEBUG): Construct Execute. 2019-05-30T23:37:03.282Z,1559259423.282 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-05-30T23:37:03.293Z,1559259423.293 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-05-30T23:37:03.331Z,1559259423.331 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-05-30T23:37:03.365Z,1559259423.365 [Radio_Surface](INFO): Powering up 2019-05-30T23:37:03.480Z,1559259423.480 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar 2019-05-30T23:37:03.511Z,1559259423.511 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-05-30T23:37:03.511Z,1559259423.511 [DAT](INFO): Powering up 2019-05-30T23:37:03.512Z,1559259423.512 [DAT](DEBUG): Initializing DAT. 2019-05-30T23:37:03.565Z,1559259423.565 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-05-30T23:37:03.601Z,1559259423.601 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-05-30T23:37:03.675Z,1559259423.675 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-05-30T23:37:03.686Z,1559259423.686 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-05-30T23:37:03.687Z,1559259423.687 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-05-30T23:37:03.693Z,1559259423.693 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-05-30T23:37:03.694Z,1559259423.694 [MassServo](DEBUG): Initializing EZServoServo. 2019-05-30T23:37:03.701Z,1559259423.701 [MassServo](DEBUG): Initializing MassServo. 2019-05-30T23:37:03.702Z,1559259423.702 [RudderServo](DEBUG): Initializing EZServoServo. 2019-05-30T23:37:03.709Z,1559259423.709 [RudderServo](DEBUG): Initializing RudderServo. 2019-05-30T23:37:03.710Z,1559259423.710 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-05-30T23:37:03.717Z,1559259423.717 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-05-30T23:37:03.923Z,1559259423.923 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-05-30T23:37:03.923Z,1559259423.923 [DropWeight] Hardware Fault, FailCount= 1 2019-05-30T23:37:03.923Z,1559259423.923 [DropWeight](ERROR): Hardware Fault 2019-05-30T23:37:03.984Z,1559259423.984 [CommandLine](FAULT): Scheduling is paused 2019-05-30T23:37:03.993Z,1559259423.993 [CBIT](INFO): Critical error at 20190530T233703 2019-05-30T23:37:03.993Z,1559259423.993 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-05-30T23:37:03.996Z,1559259423.996 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-05-30T23:37:03.996Z,1559259423.996 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-05-30T23:37:04.194Z,1559259424.194 [CBIT](INFO): Critical error at 20190530T233703 2019-05-30T23:37:06.677Z,1559259426.677 [Aanderaa_O2](INFO): Powering down 2019-05-30T23:37:15.000Z,1559259436.000 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005627 2019-05-30T23:37:19.161Z,1559259439.161 [DAT](INFO): setting local address to 3 2019-05-30T23:37:19.565Z,1559259439.565 [DAT](INFO): set local address to 3 2019-05-30T23:37:30.866Z,1559259450.866 [NAL9602](INFO): Powering up NAL9602 2019-05-30T23:37:41.774Z,1559259461.774 [NAL9602](INFO): NAL9602 initialized 2019-05-30T23:37:42.589Z,1559259462.589 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:37:59.585Z,1559259479.585 [SBIT](IMPORTANT): Beginning Startup BIT 2019-05-30T23:37:59.590Z,1559259479.590 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-30T23:38:10.665Z,1559259490.665 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.017365 CHAN A1 (24V): 0.012906 CHAN A2 (12V): 0.002587 CHAN A3 (5V): -0.004623 CHAN B0 (3.3V): -0.004631 CHAN B1 (3.15aV): -0.004153 CHAN B2 (3.15bV): -0.003480 CHAN B3 (GND): -0.007104 OPEN: 0.007407 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-30T23:38:20.050Z,1559259500.050 [CommandLine](IMPORTANT): got command burn on 2019-05-30T23:38:20.050Z,1559259500.050 [CommandLine](IMPORTANT): Activating dropweight wire 2019-05-30T23:38:23.135Z,1559259503.135 [CommandLine](IMPORTANT): got command burn off 2019-05-30T23:38:23.135Z,1559259503.135 [CommandLine](IMPORTANT): Deactivating dropweight wire 2019-05-30T23:38:53.178Z,1559259533.178 [SBIT](IMPORTANT): SBIT PASSED 2019-05-30T23:38:53.197Z,1559259533.197 [CommandLine](IMPORTANT): got command configSet list 2019-05-30T23:38:53.197Z,1559259533.197 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-05-30T23:38:53.198Z,1559259533.198 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=1 bool; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.loadAtStartup=0 bool; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=6 hour; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): BR_Ping1D.verbosity=1 count; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): CBIT.abortDepth=300 meter; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): CBIT.stopDepth=295 meter; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): DAT.ignoreElevationAngle=1 bool; 2019-05-30T23:38:53.199Z,1559259533.199 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=1 bool; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): Express linearApproximation DAT.range 20.000000 meter; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): PAR_Licor.adcCal=8.765e-06 microampere_per_count; 2019-05-30T23:38:53.200Z,1559259533.200 [CommandLine](IMPORTANT): PAR_Licor.darkCount=0 count; 2019-05-30T23:38:53.201Z,1559259533.201 [CommandLine](IMPORTANT): PAR_Licor.multiplier=190.3 micromole_per_second_per_square_meter_per_microampere; 2019-05-30T23:38:53.201Z,1559259533.201 [CommandLine](IMPORTANT): PNI_TCM.loadAtStartup=0 bool; 2019-05-30T23:38:53.201Z,1559259533.201 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool; 2019-05-30T23:38:53.201Z,1559259533.201 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=188 cubic_centimeter; 2019-05-30T23:38:53.201Z,1559259533.201 [CommandLine](IMPORTANT): VerticalControl.massDefault=-9.13 millimeter; 2019-05-30T23:38:53.583Z,1559259533.583 [MissionManager](IMPORTANT): Started mission Startup 2019-05-30T23:38:53.583Z,1559259533.583 [Startup] Running Loop=1 2019-05-30T23:38:53.583Z,1559259533.583 [Startup](DEBUG): Aggregate::initialize Startup 2019-05-30T23:38:53.583Z,1559259533.583 [Startup:A.GoToSurface] Running Loop=1 2019-05-30T23:38:53.583Z,1559259533.583 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-05-30T23:38:53.584Z,1559259533.584 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-05-30T23:38:53.584Z,1559259533.584 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-05-30T23:38:53.589Z,1559259533.589 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-05-30T23:38:53.589Z,1559259533.589 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-05-30T23:38:53.590Z,1559259533.590 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-05-30T23:38:53.591Z,1559259533.591 [Startup:StartupSatComms] Running Loop=1 2019-05-30T23:38:53.591Z,1559259533.591 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-05-30T23:38:53.591Z,1559259533.591 [Startup:StartupSatComms:A] Running Loop=1 2019-05-30T23:38:54.007Z,1559259534.007 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-05-30T23:39:53.782Z,1559259593.782 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-30T23:38:53.6Z 2019-05-30T23:39:53.782Z,1559259593.782 [Startup:StartupSatComms:A] Stopped 2019-05-30T23:39:53.782Z,1559259593.782 [Startup:StartupSatComms:B] Running Loop=1 2019-05-30T23:39:54.174Z,1559259594.174 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-05-30T23:40:01.900Z,1559259601.900 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190530T194514/Courier0004.lzma 2019-05-30T23:40:02.706Z,1559259602.706 [DataOverHttps](INFO): Moved sent file to Logs/20190530T194514/Courier0004.lzma.bak 2019-05-30T23:40:02.707Z,1559259602.707 [DataOverHttps](INFO): SBD MOMSN=11226020 2019-05-30T23:40:03.094Z,1559259603.094 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-05-30T23:40:03.094Z,1559259603.094 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-30T23:40:03.104Z,1559259603.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-30T23:40:03.535Z,1559259603.535 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-30T23:40:03.535Z,1559259603.535 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-05-30T23:40:17.452Z,1559259617.452 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20190530T233651/Courier0000.lzma 2019-05-30T23:40:18.258Z,1559259618.258 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0000.lzma.bak 2019-05-30T23:40:18.258Z,1559259618.258 [DataOverHttps](INFO): SBD MOMSN=11226022 2019-05-30T23:40:37.193Z,1559259637.193 [DataOverHttps](INFO): Sending 400 bytes from file Logs/20190530T194514/Express0005.lzma 2019-05-30T23:40:37.999Z,1559259637.999 [DataOverHttps](INFO): Moved sent file to Logs/20190530T194514/Express0005.lzma.bak 2019-05-30T23:40:37.999Z,1559259637.999 [DataOverHttps](INFO): SBD MOMSN=11226037 2019-05-30T23:40:54.042Z,1559259654.042 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-30T23:39:53.8Z 2019-05-30T23:40:54.042Z,1559259654.042 [Startup:StartupSatComms:B] Stopped 2019-05-30T23:40:54.042Z,1559259654.042 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-05-30T23:40:54.043Z,1559259654.043 [Startup:StartupSatComms] Stopped 2019-05-30T23:40:54.043Z,1559259654.043 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-05-30T23:40:54.043Z,1559259654.043 [Startup](INFO): Completed Startup 2019-05-30T23:40:54.044Z,1559259654.044 [MissionManager](INFO): Startup is completed. 2019-05-30T23:40:54.044Z,1559259654.044 [MissionManager](INFO): Uninitializing Mission Startup 2019-05-30T23:40:54.044Z,1559259654.044 [Startup] Stopped 2019-05-30T23:40:54.044Z,1559259654.044 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-05-30T23:40:54.044Z,1559259654.044 [Startup:A.GoToSurface] Stopped 2019-05-30T23:40:54.044Z,1559259654.044 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-05-30T23:40:54.426Z,1559259654.426 [MissionManager](IMPORTANT): Started mission Default 2019-05-30T23:40:54.426Z,1559259654.426 [Default] Running Loop=1 2019-05-30T23:40:54.426Z,1559259654.426 [Default](DEBUG): Aggregate::initialize Default 2019-05-30T23:40:54.426Z,1559259654.426 [Default:B.GoToSurface] Running Loop=1 2019-05-30T23:40:54.426Z,1559259654.426 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-05-30T23:40:54.426Z,1559259654.426 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-05-30T23:40:54.427Z,1559259654.427 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-05-30T23:40:54.427Z,1559259654.427 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-05-30T23:40:54.427Z,1559259654.427 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-05-30T23:40:54.428Z,1559259654.428 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-05-30T23:40:54.428Z,1559259654.428 [Default:A.Wait] Running Loop=1 2019-05-30T23:40:54.428Z,1559259654.428 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-05-30T23:40:54.992Z,1559259654.992 [DataOverHttps](INFO): Sending 1193 bytes from file Logs/20190530T233651/Express0001.lzma 2019-05-30T23:40:55.795Z,1559259655.795 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0001.lzma.bak 2019-05-30T23:40:55.795Z,1559259655.795 [DataOverHttps](INFO): SBD MOMSN=11226046 2019-05-30T23:41:07.516Z,1559259667.516 [Default:A.Wait](INFO): Done Waiting. 2019-05-30T23:41:07.516Z,1559259667.516 [Default:A.Wait] Stopped 2019-05-30T23:41:07.516Z,1559259667.516 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-05-30T23:41:07.884Z,1559259667.884 [Default:CheckIn] Running Loop=1 2019-05-30T23:41:07.884Z,1559259667.884 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-30T23:41:07.884Z,1559259667.884 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-30T23:41:08.252Z,1559259668.252 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-05-30T23:42:44.778Z,1559259764.778 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-05-30T23:42:45.597Z,1559259765.597 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:42:48.429Z,1559259768.429 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:42:50.457Z,1559259770.457 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:42:53.681Z,1559259773.681 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:42:56.505Z,1559259776.505 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:42:59.737Z,1559259779.737 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:02.569Z,1559259782.569 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:04.222Z,1559259784.222 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-05-30T23:43:04.222Z,1559259784.222 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-30T23:43:04.232Z,1559259784.232 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-30T23:43:04.622Z,1559259784.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-30T23:43:04.622Z,1559259784.622 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-05-30T23:43:05.797Z,1559259785.797 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:08.625Z,1559259788.625 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:11.453Z,1559259791.453 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:13.477Z,1559259793.477 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:16.705Z,1559259796.705 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:19.533Z,1559259799.533 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:22.765Z,1559259802.765 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:25.593Z,1559259805.593 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:28.825Z,1559259808.825 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:31.649Z,1559259811.649 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:34.481Z,1559259814.481 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:36.505Z,1559259816.505 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:38.521Z,1559259818.521 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:41.753Z,1559259821.753 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:44.597Z,1559259824.597 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:47.825Z,1559259827.825 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:50.641Z,1559259830.641 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:53.877Z,1559259833.877 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:56.697Z,1559259836.697 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:43:59.525Z,1559259839.525 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:01.549Z,1559259841.549 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:04.781Z,1559259844.781 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:07.621Z,1559259847.621 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:10.845Z,1559259850.845 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:13.665Z,1559259853.665 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:16.905Z,1559259856.905 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:19.729Z,1559259859.729 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:22.561Z,1559259862.561 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:24.589Z,1559259864.589 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:26.601Z,1559259866.601 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:29.825Z,1559259869.825 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:32.657Z,1559259872.657 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:35.889Z,1559259875.889 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:38.717Z,1559259878.717 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:41.953Z,1559259881.953 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:43.982Z,1559259883.982 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-05-30T23:44:43.983Z,1559259883.983 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-05-30T23:44:43.983Z,1559259883.983 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2019-05-30T23:44:43.987Z,1559259883.987 [BPC1](INFO): Received data from all battery sticks. 2019-05-30T23:44:44.785Z,1559259884.785 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:47.605Z,1559259887.605 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:49.625Z,1559259889.625 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:52.857Z,1559259892.857 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:55.689Z,1559259895.689 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:44:58.917Z,1559259898.917 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:01.749Z,1559259901.749 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:04.977Z,1559259904.977 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:07.809Z,1559259907.809 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:10.633Z,1559259910.633 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:12.653Z,1559259912.653 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:14.677Z,1559259914.677 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:17.905Z,1559259917.905 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:20.733Z,1559259920.733 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:23.965Z,1559259923.965 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:26.793Z,1559259926.793 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:30.025Z,1559259930.025 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:32.877Z,1559259932.877 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:35.693Z,1559259935.693 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:37.701Z,1559259937.701 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:40.933Z,1559259940.933 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:43.761Z,1559259943.761 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:46.993Z,1559259946.993 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:49.821Z,1559259949.821 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:53.053Z,1559259953.053 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:55.881Z,1559259955.881 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:45:58.713Z,1559259958.713 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:00.725Z,1559259960.725 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:02.749Z,1559259962.749 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:05.183Z,1559259965.183 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-05-30T23:46:05.183Z,1559259965.183 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-30T23:46:05.193Z,1559259965.193 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-30T23:46:05.591Z,1559259965.591 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-30T23:46:05.591Z,1559259965.591 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-05-30T23:46:05.981Z,1559259965.981 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:08.012Z,1559259968.012 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-30T23:41:07.9Z 2019-05-30T23:46:08.012Z,1559259968.012 [Default:CheckIn:Read_GPS] Stopped 2019-05-30T23:46:08.012Z,1559259968.012 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-30T23:46:08.412Z,1559259968.412 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-05-30T23:46:08.813Z,1559259968.813 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:12.041Z,1559259972.041 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:14.877Z,1559259974.877 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190530T233651/Courier0004.lzma 2019-05-30T23:46:14.976Z,1559259974.976 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:15.646Z,1559259975.646 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0004.lzma.bak 2019-05-30T23:46:15.646Z,1559259975.646 [DataOverHttps](INFO): SBD MOMSN=11226092 2019-05-30T23:46:18.101Z,1559259978.101 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:20.929Z,1559259980.929 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:23.753Z,1559259983.753 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:25.777Z,1559259985.777 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:29.009Z,1559259989.009 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:29.685Z,1559259989.685 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190530T233651/Express0005.lzma 2019-05-30T23:46:30.490Z,1559259990.490 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0005.lzma.bak 2019-05-30T23:46:30.490Z,1559259990.490 [DataOverHttps](INFO): SBD MOMSN=11226094 2019-05-30T23:46:31.841Z,1559259991.841 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:34.682Z,1559259994.682 [Default:CheckIn:Read_Iridium] Stopped 2019-05-30T23:46:34.682Z,1559259994.682 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-30T23:46:34.683Z,1559259994.683 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-30T23:46:35.081Z,1559259995.081 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:37.897Z,1559259997.897 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:41.129Z,1559260001.129 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:43.961Z,1559260003.961 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:46.781Z,1559260006.781 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:48.801Z,1559260008.801 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:52.037Z,1559260012.037 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:54.877Z,1559260014.877 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:46:58.100Z,1559260018.100 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:00.925Z,1559260020.925 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:04.161Z,1559260024.161 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:06.985Z,1559260026.985 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:09.813Z,1559260029.813 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:11.833Z,1559260031.833 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:13.857Z,1559260033.857 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:17.081Z,1559260037.081 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:19.909Z,1559260039.909 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:23.145Z,1559260043.145 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:25.973Z,1559260045.973 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:29.209Z,1559260049.209 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:32.033Z,1559260052.033 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:34.865Z,1559260054.865 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:36.885Z,1559260056.885 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:40.113Z,1559260060.113 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:47:42.930Z,1559260062.930 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-05-30T23:47:42.930Z,1559260062.930 [NAL9602] Data Fault, FailCount= 1 2019-05-30T23:47:42.930Z,1559260062.930 [NAL9602](ERROR): Data Fault 2019-05-30T23:47:42.959Z,1559260062.959 [CBIT](ERROR): Data Fault in component: NAL9602 2019-05-30T23:47:43.334Z,1559260063.334 [NAL9602](INFO): Powering down 2019-05-30T23:47:44.171Z,1559260064.171 [CBIT](INFO): Clearing failed state for component NAL9602 2019-05-30T23:47:44.171Z,1559260064.171 [NAL9602] No Fault, FailCount= 1 2019-05-30T23:48:13.634Z,1559260093.634 [NAL9602](INFO): Powering up NAL9602 2019-05-30T23:48:24.543Z,1559260104.543 [NAL9602](INFO): NAL9602 initialized 2019-05-30T23:48:25.365Z,1559260105.365 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:27.785Z,1559260107.785 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:31.017Z,1559260111.017 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:33.849Z,1559260113.849 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:36.673Z,1559260116.673 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:38.693Z,1559260118.693 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:40.716Z,1559260120.716 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:43.945Z,1559260123.945 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:46.789Z,1559260126.789 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:50.009Z,1559260130.009 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:52.833Z,1559260132.833 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:56.065Z,1559260136.065 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:48:58.893Z,1559260138.893 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:01.721Z,1559260141.721 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:03.741Z,1559260143.741 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:05.761Z,1559260145.761 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:06.193Z,1559260146.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-05-30T23:49:06.193Z,1559260146.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-30T23:49:06.203Z,1559260146.203 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-30T23:49:06.601Z,1559260146.601 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-30T23:49:06.601Z,1559260146.601 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-05-30T23:49:08.997Z,1559260148.997 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:11.817Z,1559260151.817 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:15.057Z,1559260155.057 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:17.885Z,1559260157.885 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:21.121Z,1559260161.121 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:23.945Z,1559260163.945 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:26.765Z,1559260166.765 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:28.789Z,1559260168.789 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:32.025Z,1559260172.025 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:34.849Z,1559260174.849 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:38.081Z,1559260178.081 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:40.909Z,1559260180.909 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:44.145Z,1559260184.145 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:46.973Z,1559260186.973 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:49.797Z,1559260189.797 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:52.225Z,1559260192.225 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:55.049Z,1559260195.049 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:49:57.877Z,1559260197.877 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:01.113Z,1559260201.113 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:03.937Z,1559260203.937 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:06.769Z,1559260206.769 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:09.997Z,1559260209.997 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:12.849Z,1559260212.849 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:16.061Z,1559260216.061 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:18.881Z,1559260218.881 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:22.117Z,1559260222.117 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:24.945Z,1559260224.945 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:28.177Z,1559260228.177 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:31.001Z,1559260231.001 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:34.237Z,1559260234.237 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:37.065Z,1559260237.065 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:40.297Z,1559260240.297 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:43.136Z,1559260243.136 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:45.957Z,1559260245.957 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:49.181Z,1559260249.181 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:52.017Z,1559260252.017 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:54.841Z,1559260254.841 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:50:58.073Z,1559260258.073 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:00.901Z,1559260260.901 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:04.137Z,1559260264.137 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:06.961Z,1559260266.961 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:10.197Z,1559260270.197 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:13.021Z,1559260273.021 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:16.257Z,1559260276.257 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:19.085Z,1559260279.085 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:22.317Z,1559260282.317 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:25.141Z,1559260285.141 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:28.373Z,1559260288.373 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:31.201Z,1559260291.201 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:34.437Z,1559260294.437 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:35.274Z,1559260295.274 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-30T23:51:35.274Z,1559260295.274 [Default:CheckIn:C.Wait] Stopped 2019-05-30T23:51:35.274Z,1559260295.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-30T23:51:35.274Z,1559260295.274 [Default:CheckIn:D] Running Loop=1 2019-05-30T23:51:35.658Z,1559260295.658 [Default:CheckIn:D] Stopped 2019-05-30T23:51:35.658Z,1559260295.658 [Default:CheckIn:E] Running Loop=1 2019-05-30T23:51:36.064Z,1559260296.064 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.687208 min 2019-05-30T23:51:36.064Z,1559260296.064 [Default:CheckIn:E] Stopped 2019-05-30T23:51:36.065Z,1559260296.065 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-30T23:51:36.065Z,1559260296.065 [Default:CheckIn] Stopped 2019-05-30T23:51:36.065Z,1559260296.065 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-30T23:51:36.065Z,1559260296.065 [Default:CheckIn](INFO): Running loop #2 2019-05-30T23:51:36.065Z,1559260296.065 [Default:CheckIn] Running Loop=2 2019-05-30T23:51:36.065Z,1559260296.065 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-30T23:51:36.066Z,1559260296.066 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-30T23:51:37.261Z,1559260297.261 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:40.093Z,1559260300.093 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:42.921Z,1559260302.921 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:46.145Z,1559260306.145 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:48.977Z,1559260308.977 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:52.213Z,1559260312.213 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:55.049Z,1559260315.049 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:51:58.277Z,1559260318.277 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:01.097Z,1559260321.097 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:03.949Z,1559260323.949 [CBIT](INFO): Clearing failed state for component DropWeight 2019-05-30T23:52:03.949Z,1559260323.949 [DropWeight] No Fault, FailCount= 1 2019-05-30T23:52:04.329Z,1559260324.329 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:07.173Z,1559260327.173 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:07.192Z,1559260327.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-05-30T23:52:07.192Z,1559260327.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-30T23:52:07.226Z,1559260327.226 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-30T23:52:07.599Z,1559260327.599 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-30T23:52:07.599Z,1559260327.599 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-05-30T23:52:10.389Z,1559260330.389 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:13.221Z,1559260333.221 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:16.449Z,1559260336.449 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:19.289Z,1559260339.289 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:22.513Z,1559260342.513 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:25.341Z,1559260345.341 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:28.165Z,1559260348.165 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:30.993Z,1559260350.993 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:34.225Z,1559260354.225 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:37.053Z,1559260357.053 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:40.289Z,1559260360.289 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:43.117Z,1559260363.117 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:46.345Z,1559260366.345 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:49.169Z,1559260369.169 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:52.409Z,1559260372.409 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:55.233Z,1559260375.233 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:52:58.465Z,1559260378.465 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:01.293Z,1559260381.293 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:04.529Z,1559260384.529 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:07.361Z,1559260387.361 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:10.185Z,1559260390.185 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:13.413Z,1559260393.413 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:16.245Z,1559260396.245 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:19.469Z,1559260399.469 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:19.868Z,1559260399.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235233.00,A,3648.16307,N,12147.24670,W,2.449,148.63,300519,,,A*7B 2019-05-30T23:53:19.901Z,1559260399.901 [NAL9602](INFO): GPS fix at 20190530T235233: (36.802718, -121.787445) 2019-05-30T23:53:19.966Z,1559260399.966 [Default:CheckIn:Read_GPS] Stopped 2019-05-30T23:53:19.966Z,1559260399.966 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-30T23:53:24.984Z,1559260404.984 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190530T233651/Courier0007.lzma 2019-05-30T23:53:25.798Z,1559260405.798 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0007.lzma.bak 2019-05-30T23:53:25.799Z,1559260405.799 [DataOverHttps](IMPORTANT): SBD MOMSN=11226121, MTMSN=20190530T235240 2019-05-30T23:53:37.541Z,1559260417.541 [DataOverHttps](INFO): Received command:ibit 2019-05-30T23:53:37.633Z,1559260417.633 [CommandLine](IMPORTANT): got command ibit 2019-05-30T23:53:37.776Z,1559260417.776 [IBIT](IMPORTANT): Beginning Initiated BIT 2019-05-30T23:53:37.776Z,1559260417.776 [IBIT](IMPORTANT): Beginning control surface checks. 2019-05-30T23:53:37.779Z,1559260417.779 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-30T23:53:38.973Z,1559260418.973 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:39.368Z,1559260419.368 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235252.00,A,3648.15028,N,12147.25164,W,2.683,194.76,300519,,,A*73 2019-05-30T23:53:39.370Z,1559260419.370 [NAL9602](INFO): GPS fix at 20190530T235252: (36.802505, -121.787527) 2019-05-30T23:53:41.397Z,1559260421.397 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:41.804Z,1559260421.804 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235255.00,A,3648.14863,N,12147.25234,W,2.080,184.89,300519,,,A*70 2019-05-30T23:53:41.806Z,1559260421.806 [NAL9602](INFO): GPS fix at 20190530T235255: (36.802477, -121.787539) 2019-05-30T23:53:44.233Z,1559260424.233 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:44.628Z,1559260424.628 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235258.00,A,3648.14665,N,12147.25195,W,1.866,176.54,300519,,,A*73 2019-05-30T23:53:44.642Z,1559260424.642 [NAL9602](INFO): GPS fix at 20190530T235258: (36.802444, -121.787532) 2019-05-30T23:53:47.365Z,1559260427.365 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20190530T233651/Express0008.lzma 2019-05-30T23:53:47.465Z,1559260427.465 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:47.854Z,1559260427.854 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235301.00,A,3648.14471,N,12147.25056,W,2.197,148.31,300519,,,A*7D 2019-05-30T23:53:47.856Z,1559260427.856 [NAL9602](INFO): GPS fix at 20190530T235301: (36.802412, -121.787509) 2019-05-30T23:53:48.170Z,1559260428.170 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0008.lzma.bak 2019-05-30T23:53:48.170Z,1559260428.170 [DataOverHttps](INFO): SBD MOMSN=11226125 2019-05-30T23:53:48.712Z,1559260428.712 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.169417 CHAN A1 (24V): 0.022716 CHAN A2 (12V): -0.004127 CHAN A3 (5V): -0.015446 CHAN B0 (3.3V): -0.012869 CHAN B1 (3.15aV): -0.011107 CHAN B2 (3.15bV): -0.009209 CHAN B3 (GND): -0.013310 OPEN: 0.007489 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-30T23:53:50.292Z,1559260430.292 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:50.696Z,1559260430.696 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235304.00,A,3648.14374,N,12147.24906,W,1.516,144.17,300519,,,A*71 2019-05-30T23:53:50.698Z,1559260430.698 [NAL9602](INFO): GPS fix at 20190530T235304: (36.802396, -121.787484) 2019-05-30T23:53:53.537Z,1559260433.537 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:53.918Z,1559260433.918 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235307.00,A,3648.14323,N,12147.24743,W,1.944,144.17,300519,,,A*74 2019-05-30T23:53:53.920Z,1559260433.920 [NAL9602](INFO): GPS fix at 20190530T235307: (36.802387, -121.787457) 2019-05-30T23:53:56.349Z,1559260436.349 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:56.746Z,1559260436.746 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235310.00,A,3648.14246,N,12147.24540,W,2.216,119.32,300519,,,A*71 2019-05-30T23:53:56.748Z,1559260436.748 [NAL9602](INFO): GPS fix at 20190530T235310: (36.802374, -121.787423) 2019-05-30T23:53:59.585Z,1559260439.585 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:53:59.976Z,1559260439.976 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235313.00,A,3648.14206,N,12147.24335,W,1.788,107.88,300519,,,A*7D 2019-05-30T23:53:59.978Z,1559260439.978 [NAL9602](INFO): GPS fix at 20190530T235313: (36.802368, -121.787389) 2019-05-30T23:54:02.240Z,1559260442.240 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190530T233651/Courier0010.lzma 2019-05-30T23:54:02.409Z,1559260442.409 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:02.810Z,1559260442.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235316.00,A,3648.14162,N,12147.24193,W,1.477,105.24,300519,,,A*70 2019-05-30T23:54:02.812Z,1559260442.812 [NAL9602](INFO): GPS fix at 20190530T235316: (36.802360, -121.787366) 2019-05-30T23:54:03.046Z,1559260443.046 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0010.lzma.bak 2019-05-30T23:54:03.046Z,1559260443.046 [DataOverHttps](INFO): SBD MOMSN=11226135 2019-05-30T23:54:05.641Z,1559260445.641 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:06.038Z,1559260446.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235319.00,A,3648.14143,N,12147.24040,W,1.380,104.63,300519,,,A*7E 2019-05-30T23:54:06.040Z,1559260446.040 [NAL9602](INFO): GPS fix at 20190530T235319: (36.802357, -121.787340) 2019-05-30T23:54:08.469Z,1559260448.469 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:08.864Z,1559260448.864 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235322.00,A,3648.14157,N,12147.23924,W,1.069,99.97,300519,,,A*45 2019-05-30T23:54:08.866Z,1559260448.866 [NAL9602](INFO): GPS fix at 20190530T235322: (36.802360, -121.787321) 2019-05-30T23:54:11.297Z,1559260451.297 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:11.696Z,1559260451.696 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235325.00,A,3648.14183,N,12147.23887,W,0.058,99.97,300519,,,A*40 2019-05-30T23:54:11.698Z,1559260451.698 [NAL9602](INFO): GPS fix at 20190530T235325: (36.802364, -121.787314) 2019-05-30T23:54:14.529Z,1559260454.529 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:14.924Z,1559260454.924 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235328.00,A,3648.14175,N,12147.23893,W,0.097,99.97,300519,,,A*42 2019-05-30T23:54:14.926Z,1559260454.926 [NAL9602](INFO): GPS fix at 20190530T235328: (36.802363, -121.787316) 2019-05-30T23:54:17.369Z,1559260457.369 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:17.764Z,1559260457.764 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235331.00,A,3648.14177,N,12147.23904,W,0.039,99.97,300519,,,A*43 2019-05-30T23:54:17.766Z,1559260457.766 [NAL9602](INFO): GPS fix at 20190530T235331: (36.802363, -121.787317) 2019-05-30T23:54:18.737Z,1559260458.737 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20190530T233651/Express0011.lzma 2019-05-30T23:54:19.542Z,1559260459.542 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0011.lzma.bak 2019-05-30T23:54:19.543Z,1559260459.543 [DataOverHttps](INFO): SBD MOMSN=11226139 2019-05-30T23:54:20.589Z,1559260460.589 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:20.984Z,1559260460.984 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235334.00,A,3648.14173,N,12147.23938,W,0.058,99.97,300519,,,A*4A 2019-05-30T23:54:20.986Z,1559260460.986 [NAL9602](INFO): GPS fix at 20190530T235334: (36.802362, -121.787323) 2019-05-30T23:54:23.433Z,1559260463.433 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:23.812Z,1559260463.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235337.00,A,3648.14174,N,12147.23959,W,0.097,99.97,300519,,,A*4A 2019-05-30T23:54:23.814Z,1559260463.814 [NAL9602](INFO): GPS fix at 20190530T235337: (36.802362, -121.787327) 2019-05-30T23:54:26.653Z,1559260466.653 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:27.056Z,1559260467.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235340.00,A,3648.14175,N,12147.23973,W,0.058,99.97,300519,,,A*40 2019-05-30T23:54:27.058Z,1559260467.058 [NAL9602](INFO): GPS fix at 20190530T235340: (36.802363, -121.787329) 2019-05-30T23:54:29.489Z,1559260469.489 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:29.884Z,1559260469.884 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235343.00,A,3648.14161,N,12147.23963,W,0.739,99.97,300519,,,A*47 2019-05-30T23:54:29.886Z,1559260469.886 [NAL9602](INFO): GPS fix at 20190530T235343: (36.802360, -121.787327) 2019-05-30T23:54:32.713Z,1559260472.713 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:33.127Z,1559260473.127 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235346.00,A,3648.14099,N,12147.23868,W,1.594,109.97,300519,,,A*72 2019-05-30T23:54:33.141Z,1559260473.141 [NAL9602](INFO): GPS fix at 20190530T235346: (36.802350, -121.787311) 2019-05-30T23:54:35.557Z,1559260475.557 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:35.944Z,1559260475.944 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235349.00,A,3648.13972,N,12147.23693,W,2.935,124.07,300519,,,A*7E 2019-05-30T23:54:35.946Z,1559260475.946 [NAL9602](INFO): GPS fix at 20190530T235349: (36.802329, -121.787282) 2019-05-30T23:54:38.381Z,1559260478.381 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:38.762Z,1559260478.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235352.00,A,3648.13864,N,12147.23574,W,1.458,124.07,300519,,,A*7D 2019-05-30T23:54:38.764Z,1559260478.764 [NAL9602](INFO): GPS fix at 20190530T235352: (36.802311, -121.787262) 2019-05-30T23:54:41.601Z,1559260481.601 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:41.992Z,1559260481.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235355.00,A,3648.13806,N,12147.23390,W,2.702,114.54,300519,,,A*78 2019-05-30T23:54:41.999Z,1559260481.999 [NAL9602](INFO): GPS fix at 20190530T235355: (36.802301, -121.787232) 2019-05-30T23:54:44.425Z,1559260484.425 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:44.822Z,1559260484.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235358.00,A,3648.13724,N,12147.23153,W,2.683,110.14,300519,,,A*7F 2019-05-30T23:54:44.824Z,1559260484.824 [NAL9602](INFO): GPS fix at 20190530T235358: (36.802287, -121.787192) 2019-05-30T23:54:47.657Z,1559260487.657 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:48.064Z,1559260488.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235401.00,A,3648.13691,N,12147.22892,W,2.702,116.54,300519,,,A*74 2019-05-30T23:54:48.066Z,1559260488.066 [NAL9602](INFO): GPS fix at 20190530T235401: (36.802282, -121.787149) 2019-05-30T23:54:50.501Z,1559260490.501 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:50.882Z,1559260490.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235404.00,A,3648.13633,N,12147.22667,W,2.158,112.68,300519,,,A*7F 2019-05-30T23:54:50.884Z,1559260490.884 [NAL9602](INFO): GPS fix at 20190530T235404: (36.802272, -121.787111) 2019-05-30T23:54:53.737Z,1559260493.737 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:54.124Z,1559260494.124 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235407.00,A,3648.13601,N,12147.22433,W,2.216,103.45,300519,,,A*78 2019-05-30T23:54:54.126Z,1559260494.126 [NAL9602](INFO): GPS fix at 20190530T235407: (36.802267, -121.787072) 2019-05-30T23:54:56.569Z,1559260496.569 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:56.946Z,1559260496.946 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235410.00,A,3648.13573,N,12147.22176,W,2.566,104.58,300519,,,A*77 2019-05-30T23:54:56.948Z,1559260496.948 [NAL9602](INFO): GPS fix at 20190530T235410: (36.802262, -121.787029) 2019-05-30T23:54:59.373Z,1559260499.373 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:54:59.788Z,1559260499.788 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235413.00,A,3648.13574,N,12147.21918,W,2.449,95.59,300519,,,A*44 2019-05-30T23:54:59.790Z,1559260499.790 [NAL9602](INFO): GPS fix at 20190530T235413: (36.802262, -121.786986) 2019-05-30T23:55:02.609Z,1559260502.609 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:02.000Z,1559260503.000 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235416.00,A,3648.13594,N,12147.21691,W,1.886,93.29,300519,,,A*4C 2019-05-30T23:55:03.002Z,1559260503.002 [NAL9602](INFO): GPS fix at 20190530T235416: (36.802266, -121.786948) 2019-05-30T23:55:05.433Z,1559260505.433 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:05.828Z,1559260505.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235419.00,A,3648.13640,N,12147.21483,W,2.138,71.76,300519,,,A*41 2019-05-30T23:55:05.830Z,1559260505.830 [NAL9602](INFO): GPS fix at 20190530T235419: (36.802273, -121.786914) 2019-05-30T23:55:08.669Z,1559260508.669 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:09.062Z,1559260509.062 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235422.00,A,3648.13676,N,12147.21279,W,2.002,78.95,300519,,,A*43 2019-05-30T23:55:09.064Z,1559260509.064 [NAL9602](INFO): GPS fix at 20190530T235422: (36.802279, -121.786880) 2019-05-30T23:55:11.493Z,1559260511.493 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:11.888Z,1559260511.888 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235425.00,A,3648.13698,N,12147.21128,W,1.322,79.16,300519,,,A*4B 2019-05-30T23:55:11.890Z,1559260511.890 [NAL9602](INFO): GPS fix at 20190530T235425: (36.802283, -121.786855) 2019-05-30T23:55:14.737Z,1559260514.737 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:15.127Z,1559260515.127 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235428.00,A,3648.13682,N,12147.21083,W,0.369,79.16,300519,,,A*43 2019-05-30T23:55:15.132Z,1559260515.132 [NAL9602](INFO): GPS fix at 20190530T235428: (36.802280, -121.786847) 2019-05-30T23:55:17.553Z,1559260517.553 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:17.960Z,1559260517.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235431.00,A,3648.13685,N,12147.21018,W,1.108,79.16,300519,,,A*4A 2019-05-30T23:55:17.962Z,1559260517.962 [NAL9602](INFO): GPS fix at 20190530T235431: (36.802281, -121.786836) 2019-05-30T23:55:20.797Z,1559260520.797 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:21.182Z,1559260521.182 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235434.00,A,3648.13652,N,12147.20889,W,0.972,93.93,300519,,,A*49 2019-05-30T23:55:21.184Z,1559260521.184 [NAL9602](INFO): GPS fix at 20190530T235434: (36.802275, -121.786815) 2019-05-30T23:55:23.613Z,1559260523.613 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:24.008Z,1559260524.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235437.00,A,3648.13656,N,12147.20907,W,0.039,93.93,300519,,,A*4F 2019-05-30T23:55:24.010Z,1559260524.010 [NAL9602](INFO): GPS fix at 20190530T235437: (36.802276, -121.786818) 2019-05-30T23:55:26.441Z,1559260526.441 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:26.836Z,1559260526.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235440.00,A,3648.13642,N,12147.20917,W,0.039,93.93,300519,,,A*4B 2019-05-30T23:55:26.838Z,1559260526.838 [NAL9602](INFO): GPS fix at 20190530T235440: (36.802274, -121.786820) 2019-05-30T23:55:28.982Z,1559260528.982 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2019-05-30T23:55:29.448Z,1559260529.448 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 0.000000 2019-05-30T23:55:29.448Z,1559260529.448 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2019-05-30T23:55:29.448Z,1559260529.448 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2019-05-30T23:55:29.705Z,1559260529.705 [NAL9602](DEBUG): Fix Requested 2019-05-30T23:55:29.778Z,1559260529.778 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2019-05-30T23:55:29.779Z,1559260529.779 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2019-05-30T23:55:29.779Z,1559260529.779 [IBIT](IMPORTANT): Pressure:9.680013 PSI 2019-05-30T23:55:29.779Z,1559260529.779 [IBIT](IMPORTANT): Humidity:18.233427 % 2019-05-30T23:55:30.100Z,1559260530.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235443.00,A,3648.13632,N,12147.20937,W,0.058,93.93,300519,,,A*4A 2019-05-30T23:55:30.102Z,1559260530.102 [NAL9602](INFO): GPS fix at 20190530T235443: (36.802272, -121.786823) 2019-05-30T23:55:30.243Z,1559260530.243 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2019-05-30T23:55:30.243Z,1559260530.243 [IBIT](IMPORTANT): buoyancyNeutral: 188.000000 cc 2019-05-30T23:55:30.243Z,1559260530.243 [IBIT](IMPORTANT): massDefault: -0.913000 cm 2019-05-30T23:55:30.244Z,1559260530.244 [IBIT](IMPORTANT): stopDepth: 295.000000 m 2019-05-30T23:55:30.244Z,1559260530.244 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2019-05-30T23:55:30.244Z,1559260530.244 [IBIT](IMPORTANT): IBIT FAILED 2019-05-30T23:55:30.556Z,1559260530.556 [Default:CheckIn:Read_Iridium] Stopped 2019-05-30T23:55:30.556Z,1559260530.556 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-30T23:55:30.556Z,1559260530.556 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-30T23:56:02.404Z,1559260562.404 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-31T00:00:31.117Z,1559260831.117 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-31T00:00:31.117Z,1559260831.117 [Default:CheckIn:C.Wait] Stopped 2019-05-31T00:00:31.117Z,1559260831.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-31T00:00:31.117Z,1559260831.117 [Default:CheckIn:D] Running Loop=1 2019-05-31T00:00:31.529Z,1559260831.529 [Default:CheckIn:D] Stopped 2019-05-31T00:00:31.529Z,1559260831.529 [Default:CheckIn:E] Running Loop=1 2019-05-31T00:00:31.917Z,1559260831.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.618386 min 2019-05-31T00:00:31.917Z,1559260831.917 [Default:CheckIn:E] Stopped 2019-05-31T00:00:31.917Z,1559260831.917 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-31T00:00:31.917Z,1559260831.917 [Default:CheckIn] Stopped 2019-05-31T00:00:31.917Z,1559260831.917 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-31T00:00:31.917Z,1559260831.917 [Default:CheckIn](INFO): Running loop #3 2019-05-31T00:00:31.918Z,1559260831.918 [Default:CheckIn] Running Loop=3 2019-05-31T00:00:31.918Z,1559260831.918 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-31T00:00:31.918Z,1559260831.918 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-31T00:00:33.521Z,1559260833.521 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:00:33.913Z,1559260833.913 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235947.00,A,3648.13736,N,12147.20390,W,0.136,76.13,300519,,,D*4E 2019-05-31T00:00:33.915Z,1559260833.915 [NAL9602](INFO): GPS fix at 20190530T235947: (36.802289, -121.786732) 2019-05-31T00:00:33.938Z,1559260833.938 [Default:CheckIn:Read_GPS] Stopped 2019-05-31T00:00:33.938Z,1559260833.938 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-31T00:00:41.672Z,1559260841.672 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190530T233651/Courier0013.lzma 2019-05-31T00:00:42.478Z,1559260842.478 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0013.lzma.bak 2019-05-31T00:00:42.478Z,1559260842.478 [DataOverHttps](INFO): SBD MOMSN=11226321 2019-05-31T00:00:57.177Z,1559260857.177 [DataOverHttps](INFO): Sending 588 bytes from file Logs/20190530T233651/Express0014.lzma 2019-05-31T00:00:57.982Z,1559260857.982 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0014.lzma.bak 2019-05-31T00:00:57.982Z,1559260857.982 [DataOverHttps](INFO): SBD MOMSN=11226325 2019-05-31T00:01:02.260Z,1559260862.260 [Default:CheckIn:Read_Iridium] Stopped 2019-05-31T00:01:02.260Z,1559260862.260 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-31T00:01:02.261Z,1559260862.261 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-31T00:01:42.223Z,1559260902.223 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=26801, MT Status=1, MTMSN=1652 2019-05-31T00:01:42.224Z,1559260902.224 [NAL9602](INFO): Data available in MT queue 2019-05-31T00:01:42.745Z,1559260902.745 [NAL9602](INFO): Received command:! chmod 755 /mnt/mmc/LRAUV/Tools/*esp* 2019-05-31T00:01:42.765Z,1559260902.765 [CommandLine](IMPORTANT): got command ! chmod 755 /mnt/mmc/LRAUV/Tools/*esp* 2019-05-31T00:02:13.340Z,1559260933.340 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-31T00:06:02.826Z,1559261162.826 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-31T00:06:02.826Z,1559261162.826 [Default:CheckIn:C.Wait] Stopped 2019-05-31T00:06:02.826Z,1559261162.826 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-31T00:06:02.826Z,1559261162.826 [Default:CheckIn:D] Running Loop=1 2019-05-31T00:06:03.232Z,1559261163.232 [Default:CheckIn:D] Stopped 2019-05-31T00:06:03.232Z,1559261163.232 [Default:CheckIn:E] Running Loop=1 2019-05-31T00:06:03.628Z,1559261163.628 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.146771 min 2019-05-31T00:06:03.628Z,1559261163.628 [Default:CheckIn:E] Stopped 2019-05-31T00:06:03.628Z,1559261163.628 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-31T00:06:03.628Z,1559261163.628 [Default:CheckIn] Stopped 2019-05-31T00:06:03.628Z,1559261163.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-31T00:06:03.628Z,1559261163.628 [Default:CheckIn](INFO): Running loop #4 2019-05-31T00:06:03.628Z,1559261163.628 [Default:CheckIn] Running Loop=4 2019-05-31T00:06:03.629Z,1559261163.629 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-31T00:06:03.629Z,1559261163.629 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-31T00:06:05.237Z,1559261165.237 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:06:05.630Z,1559261165.630 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000518.00,A,3648.13576,N,12147.20562,W,0.019,76.13,310519,,,D*4C 2019-05-31T00:06:05.632Z,1559261165.632 [NAL9602](INFO): GPS fix at 20190531T000518: (36.802263, -121.786760) 2019-05-31T00:06:05.655Z,1559261165.655 [Default:CheckIn:Read_GPS] Stopped 2019-05-31T00:06:05.655Z,1559261165.655 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-31T00:06:14.752Z,1559261174.752 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190530T233651/Courier0016.lzma 2019-05-31T00:06:15.558Z,1559261175.558 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0016.lzma.bak 2019-05-31T00:06:15.558Z,1559261175.558 [DataOverHttps](INFO): SBD MOMSN=11226391 2019-05-31T00:06:21.806Z,1559261181.806 [NAL9602](INFO): SBD MO Status=0, MOMSN=26802, MT Status=0, MTMSN=0 2019-05-31T00:06:21.806Z,1559261181.806 [NAL9602](INFO): No messages in MT queue 2019-05-31T00:06:31.629Z,1559261191.629 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20190530T233651/Express0017.lzma 2019-05-31T00:06:32.438Z,1559261192.438 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0017.lzma.bak 2019-05-31T00:06:32.438Z,1559261192.438 [DataOverHttps](INFO): SBD MOMSN=11226395 2019-05-31T00:06:37.000Z,1559261198.000 [Default:CheckIn:Read_Iridium] Stopped 2019-05-31T00:06:37.000Z,1559261198.000 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-31T00:06:37.000Z,1559261198.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-31T00:06:46.894Z,1559261206.894 [DataOverHttps](IMPORTANT): SBD MTMSN=20190531T000559 2019-05-31T00:06:51.872Z,1559261211.872 [DataOverHttps](INFO): Received command:ibit 2019-05-31T00:06:51.898Z,1559261211.898 [CommandLine](IMPORTANT): got command ibit 2019-05-31T00:06:52.114Z,1559261212.114 [IBIT](IMPORTANT): Beginning Initiated BIT 2019-05-31T00:06:52.114Z,1559261212.114 [IBIT](IMPORTANT): Beginning control surface checks. 2019-05-31T00:06:52.117Z,1559261212.117 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-31T00:06:53.313Z,1559261213.313 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:06:53.707Z,1559261213.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000606.00,A,3648.13642,N,12147.20547,W,0.039,76.13,310519,,,D*41 2019-05-31T00:06:53.709Z,1559261213.709 [NAL9602](INFO): GPS fix at 20190531T000606: (36.802274, -121.786758) 2019-05-31T00:06:55.741Z,1559261215.741 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:06:56.134Z,1559261216.134 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000609.00,A,3648.13636,N,12147.20554,W,0.039,76.13,310519,,,D*4F 2019-05-31T00:06:56.136Z,1559261216.136 [NAL9602](INFO): GPS fix at 20190531T000609: (36.802273, -121.786759) 2019-05-31T00:06:58.565Z,1559261218.565 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:06:58.964Z,1559261218.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000612.00,A,3648.13630,N,12147.20558,W,0.019,76.13,310519,,,D*4D 2019-05-31T00:06:58.966Z,1559261218.966 [NAL9602](INFO): GPS fix at 20190531T000612: (36.802272, -121.786760) 2019-05-31T00:07:01.793Z,1559261221.793 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:02.190Z,1559261222.190 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000615.00,A,3648.13625,N,12147.20561,W,0.039,76.13,310519,,,D*46 2019-05-31T00:07:02.192Z,1559261222.192 [NAL9602](INFO): GPS fix at 20190531T000615: (36.802271, -121.786760) 2019-05-31T00:07:03.028Z,1559261223.028 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.148785 CHAN A1 (24V): 0.010343 CHAN A2 (12V): 0.002459 CHAN A3 (5V): -0.005976 CHAN B0 (3.3V): -0.005265 CHAN B1 (3.15aV): -0.005199 CHAN B2 (3.15bV): -0.004465 CHAN B3 (GND): -0.008613 OPEN: 0.007263 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-31T00:07:04.633Z,1559261224.633 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:05.024Z,1559261225.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000618.00,A,3648.13621,N,12147.20548,W,0.058,76.13,310519,,,D*43 2019-05-31T00:07:05.026Z,1559261225.026 [NAL9602](INFO): GPS fix at 20190531T000618: (36.802270, -121.786758) 2019-05-31T00:07:07.857Z,1559261227.857 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:08.250Z,1559261228.250 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000621.00,A,3648.13615,N,12147.20555,W,0.039,76.13,310519,,,D*45 2019-05-31T00:07:08.252Z,1559261228.252 [NAL9602](INFO): GPS fix at 20190531T000621: (36.802269, -121.786759) 2019-05-31T00:07:10.689Z,1559261230.689 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:11.084Z,1559261231.084 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000624.00,A,3648.13610,N,12147.20566,W,0.039,76.13,310519,,,D*45 2019-05-31T00:07:11.086Z,1559261231.086 [NAL9602](INFO): GPS fix at 20190531T000624: (36.802268, -121.786761) 2019-05-31T00:07:13.973Z,1559261233.973 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:14.316Z,1559261234.316 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000627.00,A,3648.13600,N,12147.20586,W,0.214,76.13,310519,,,D*44 2019-05-31T00:07:14.318Z,1559261234.318 [NAL9602](INFO): GPS fix at 20190531T000627: (36.802267, -121.786764) 2019-05-31T00:07:16.745Z,1559261236.745 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:17.144Z,1559261237.144 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000630.00,A,3648.13598,N,12147.20595,W,0.039,76.13,310519,,,D*4F 2019-05-31T00:07:17.146Z,1559261237.146 [NAL9602](INFO): GPS fix at 20190531T000630: (36.802266, -121.786766) 2019-05-31T00:07:19.585Z,1559261239.585 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:19.970Z,1559261239.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000633.00,A,3648.13595,N,12147.20596,W,0.117,76.13,310519,,,D*4F 2019-05-31T00:07:19.972Z,1559261239.972 [NAL9602](INFO): GPS fix at 20190531T000633: (36.802266, -121.786766) 2019-05-31T00:07:22.805Z,1559261242.805 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:23.198Z,1559261243.198 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000636.00,A,3648.13598,N,12147.20580,W,0.156,76.13,310519,,,D*45 2019-05-31T00:07:23.200Z,1559261243.200 [NAL9602](INFO): GPS fix at 20190531T000636: (36.802266, -121.786763) 2019-05-31T00:07:25.641Z,1559261245.641 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:26.026Z,1559261246.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000639.00,A,3648.13594,N,12147.20577,W,0.058,76.13,310519,,,D*41 2019-05-31T00:07:26.028Z,1559261246.028 [NAL9602](INFO): GPS fix at 20190531T000639: (36.802266, -121.786763) 2019-05-31T00:07:28.872Z,1559261248.872 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:29.272Z,1559261249.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000642.00,A,3648.13578,N,12147.20576,W,0.058,76.13,310519,,,D*4E 2019-05-31T00:07:29.274Z,1559261249.274 [NAL9602](INFO): GPS fix at 20190531T000642: (36.802263, -121.786763) 2019-05-31T00:07:31.697Z,1559261251.697 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:32.104Z,1559261252.104 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000645.00,A,3648.13571,N,12147.20570,W,0.058,76.13,310519,,,D*46 2019-05-31T00:07:32.106Z,1559261252.106 [NAL9602](INFO): GPS fix at 20190531T000645: (36.802262, -121.786762) 2019-05-31T00:07:34.937Z,1559261254.937 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:35.333Z,1559261255.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000648.00,A,3648.13561,N,12147.20562,W,0.097,76.13,310519,,,D*4A 2019-05-31T00:07:35.335Z,1559261255.335 [NAL9602](INFO): GPS fix at 20190531T000648: (36.802260, -121.786760) 2019-05-31T00:07:37.757Z,1559261257.757 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:07:38.154Z,1559261258.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000651.00,A,3648.13556,N,12147.20560,W,0.078,76.13,310519,,,D*45 2019-05-31T00:07:38.156Z,1559261258.156 [NAL9602](INFO): GPS fix at 20190531T000651: (36.802259, -121.786760) 2019-05-31T00:07:38.606Z,1559261258.606 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802258 Longitude: -121.786758 2019-05-31T00:07:38.994Z,1559261258.994 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 0.000000 2019-05-31T00:07:38.994Z,1559261258.994 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2019-05-31T00:07:38.995Z,1559261258.995 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2019-05-31T00:07:39.402Z,1559261259.402 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2019-05-31T00:07:39.402Z,1559261259.402 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2019-05-31T00:07:39.403Z,1559261259.403 [IBIT](IMPORTANT): Pressure:9.617024 PSI 2019-05-31T00:07:39.403Z,1559261259.403 [IBIT](IMPORTANT): Humidity:18.080820 % 2019-05-31T00:07:39.795Z,1559261259.795 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2019-05-31T00:07:39.795Z,1559261259.795 [IBIT](IMPORTANT): buoyancyNeutral: 188.000000 cc 2019-05-31T00:07:39.795Z,1559261259.795 [IBIT](IMPORTANT): massDefault: -0.913000 cm 2019-05-31T00:07:39.796Z,1559261259.796 [IBIT](IMPORTANT): stopDepth: 295.000000 m 2019-05-31T00:07:39.796Z,1559261259.796 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2019-05-31T00:07:39.796Z,1559261259.796 [IBIT](IMPORTANT): IBIT PASSED 2019-05-31T00:08:10.470Z,1559261290.470 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-31T00:11:38.552Z,1559261498.552 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-31T00:11:38.552Z,1559261498.552 [Default:CheckIn:C.Wait] Stopped 2019-05-31T00:11:38.552Z,1559261498.552 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-31T00:11:38.552Z,1559261498.552 [Default:CheckIn:D] Running Loop=1 2019-05-31T00:11:38.956Z,1559261498.956 [Default:CheckIn:D] Stopped 2019-05-31T00:11:38.956Z,1559261498.956 [Default:CheckIn:E] Running Loop=1 2019-05-31T00:11:39.360Z,1559261499.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.742163 min 2019-05-31T00:11:39.361Z,1559261499.361 [Default:CheckIn:E] Stopped 2019-05-31T00:11:39.361Z,1559261499.361 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-31T00:11:39.361Z,1559261499.361 [Default:CheckIn] Stopped 2019-05-31T00:11:39.361Z,1559261499.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-31T00:11:39.361Z,1559261499.361 [Default:CheckIn](INFO): Running loop #5 2019-05-31T00:11:39.361Z,1559261499.361 [Default:CheckIn] Running Loop=5 2019-05-31T00:11:39.361Z,1559261499.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-31T00:11:39.362Z,1559261499.362 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-31T00:11:40.967Z,1559261500.967 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:11:41.359Z,1559261501.359 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001054.00,A,3648.13723,N,12147.20501,W,0.039,76.13,310519,,,D*45 2019-05-31T00:11:41.362Z,1559261501.362 [NAL9602](INFO): GPS fix at 20190531T001054: (36.802287, -121.786750) 2019-05-31T00:11:41.403Z,1559261501.403 [Default:CheckIn:Read_GPS] Stopped 2019-05-31T00:11:41.403Z,1559261501.403 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-31T00:11:49.596Z,1559261509.596 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190530T233651/Courier0019.lzma 2019-05-31T00:11:50.402Z,1559261510.402 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0019.lzma.bak 2019-05-31T00:11:50.402Z,1559261510.402 [DataOverHttps](INFO): SBD MOMSN=11226416 2019-05-31T00:11:50.645Z,1559261510.645 [NAL9602](INFO): SBD MO Status=0, MOMSN=26803, MT Status=0, MTMSN=0 2019-05-31T00:11:50.645Z,1559261510.645 [NAL9602](INFO): No messages in MT queue 2019-05-31T00:12:03.348Z,1559261523.348 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190530T233651/Courier0022.lzma 2019-05-31T00:12:04.154Z,1559261524.154 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0022.lzma.bak 2019-05-31T00:12:04.154Z,1559261524.154 [DataOverHttps](INFO): SBD MOMSN=11226420 2019-05-31T00:12:19.065Z,1559261539.065 [DataOverHttps](INFO): Sending 705 bytes from file Logs/20190530T233651/Express0020.lzma 2019-05-31T00:12:19.870Z,1559261539.870 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0020.lzma.bak 2019-05-31T00:12:19.870Z,1559261539.870 [DataOverHttps](INFO): SBD MOMSN=11226422 2019-05-31T00:12:21.350Z,1559261541.350 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-31T00:12:35.648Z,1559261555.648 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190530T233651/Express0023.lzma 2019-05-31T00:12:36.454Z,1559261556.454 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0023.lzma.bak 2019-05-31T00:12:36.454Z,1559261556.454 [DataOverHttps](INFO): SBD MOMSN=11226447 2019-05-31T00:12:40.807Z,1559261560.807 [Default:CheckIn:Read_Iridium] Stopped 2019-05-31T00:12:40.807Z,1559261560.807 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-31T00:12:40.807Z,1559261560.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-31T00:17:41.353Z,1559261861.353 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-31T00:17:41.353Z,1559261861.353 [Default:CheckIn:C.Wait] Stopped 2019-05-31T00:17:41.353Z,1559261861.353 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-31T00:17:41.353Z,1559261861.353 [Default:CheckIn:D] Running Loop=1 2019-05-31T00:17:41.742Z,1559261861.742 [Default:CheckIn:D] Stopped 2019-05-31T00:17:41.742Z,1559261861.742 [Default:CheckIn:E] Running Loop=1 2019-05-31T00:17:42.152Z,1559261862.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.788607 min 2019-05-31T00:17:42.153Z,1559261862.153 [Default:CheckIn:E] Stopped 2019-05-31T00:17:42.153Z,1559261862.153 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-31T00:17:42.153Z,1559261862.153 [Default:CheckIn] Stopped 2019-05-31T00:17:42.153Z,1559261862.153 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-31T00:17:42.153Z,1559261862.153 [Default:CheckIn](INFO): Running loop #6 2019-05-31T00:17:42.153Z,1559261862.153 [Default:CheckIn] Running Loop=6 2019-05-31T00:17:42.153Z,1559261862.153 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-31T00:17:42.154Z,1559261862.154 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-31T00:17:43.757Z,1559261863.757 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:17:44.154Z,1559261864.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001656.00,A,3648.21173,N,12147.15635,W,4.490,20.12,310519,,,D*40 2019-05-31T00:17:44.156Z,1559261864.156 [NAL9602](INFO): GPS fix at 20190531T001656: (36.803529, -121.785939) 2019-05-31T00:17:44.179Z,1559261864.179 [Default:CheckIn:Read_GPS] Stopped 2019-05-31T00:17:44.179Z,1559261864.179 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-31T00:17:55.308Z,1559261875.308 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190530T233651/Courier0025.lzma 2019-05-31T00:17:56.114Z,1559261876.114 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0025.lzma.bak 2019-05-31T00:17:56.114Z,1559261876.114 [DataOverHttps](INFO): SBD MOMSN=11226451 2019-05-31T00:18:02.333Z,1559261882.333 [NAL9602](INFO): SBD MO Status=2, MOMSN=26804, MT Status=2, MTMSN=0 2019-05-31T00:18:02.334Z,1559261882.334 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-31T00:18:11.332Z,1559261891.332 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190530T233651/Express0026.lzma 2019-05-31T00:18:12.139Z,1559261892.139 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0026.lzma.bak 2019-05-31T00:18:12.139Z,1559261892.139 [DataOverHttps](INFO): SBD MOMSN=11226456 2019-05-31T00:18:16.491Z,1559261896.491 [Default:CheckIn:Read_Iridium] Stopped 2019-05-31T00:18:16.491Z,1559261896.491 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-31T00:18:16.491Z,1559261896.491 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-31T00:18:25.757Z,1559261905.757 [NAL9602](INFO): SBD MO Status=0, MOMSN=26804, MT Status=0, MTMSN=0 2019-05-31T00:18:25.758Z,1559261905.758 [NAL9602](INFO): No messages in MT queue 2019-05-31T00:18:56.466Z,1559261936.466 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-31T00:23:17.076Z,1559262197.076 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-31T00:23:17.076Z,1559262197.076 [Default:CheckIn:C.Wait] Stopped 2019-05-31T00:23:17.076Z,1559262197.076 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-31T00:23:17.076Z,1559262197.076 [Default:CheckIn:D] Running Loop=1 2019-05-31T00:23:17.474Z,1559262197.474 [Default:CheckIn:D] Stopped 2019-05-31T00:23:17.475Z,1559262197.475 [Default:CheckIn:E] Running Loop=1 2019-05-31T00:23:17.885Z,1559262197.885 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.384143 min 2019-05-31T00:23:17.885Z,1559262197.885 [Default:CheckIn:E] Stopped 2019-05-31T00:23:17.886Z,1559262197.886 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-31T00:23:17.886Z,1559262197.886 [Default:CheckIn] Stopped 2019-05-31T00:23:17.886Z,1559262197.886 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-31T00:23:17.886Z,1559262197.886 [Default:CheckIn](INFO): Running loop #7 2019-05-31T00:23:17.886Z,1559262197.886 [Default:CheckIn] Running Loop=7 2019-05-31T00:23:17.886Z,1559262197.886 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-31T00:23:17.886Z,1559262197.886 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-31T00:23:19.485Z,1559262199.485 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:23:19.878Z,1559262199.878 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002231.00,A,3648.39992,N,12147.43013,W,7.989,241.67,310519,,,D*78 2019-05-31T00:23:19.880Z,1559262199.880 [NAL9602](INFO): GPS fix at 20190531T002231: (36.806665, -121.790502) 2019-05-31T00:23:19.908Z,1559262199.908 [Default:CheckIn:Read_GPS] Stopped 2019-05-31T00:23:19.908Z,1559262199.908 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-31T00:23:27.444Z,1559262207.444 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190530T233651/Courier0028.lzma 2019-05-31T00:23:28.250Z,1559262208.250 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0028.lzma.bak 2019-05-31T00:23:28.250Z,1559262208.250 [DataOverHttps](INFO): SBD MOMSN=11226486 2019-05-31T00:23:43.747Z,1559262223.747 [NAL9602](INFO): SBD MO Status=0, MOMSN=26805, MT Status=0, MTMSN=0 2019-05-31T00:23:43.748Z,1559262223.748 [NAL9602](INFO): No messages in MT queue 2019-05-31T00:23:47.124Z,1559262227.124 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20190530T233651/Express0029.lzma 2019-05-31T00:23:47.930Z,1559262227.930 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0029.lzma.bak 2019-05-31T00:23:47.931Z,1559262227.931 [DataOverHttps](INFO): SBD MOMSN=11226529 2019-05-31T00:23:52.306Z,1559262232.306 [Default:CheckIn:Read_Iridium] Stopped 2019-05-31T00:23:52.306Z,1559262232.306 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-31T00:23:52.306Z,1559262232.306 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-31T00:24:14.448Z,1559262254.448 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-31T00:28:52.832Z,1559262532.832 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-31T00:28:52.832Z,1559262532.832 [Default:CheckIn:C.Wait] Stopped 2019-05-31T00:28:52.832Z,1559262532.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-31T00:28:52.832Z,1559262532.832 [Default:CheckIn:D] Running Loop=1 2019-05-31T00:28:53.230Z,1559262533.230 [Default:CheckIn:D] Stopped 2019-05-31T00:28:53.230Z,1559262533.230 [Default:CheckIn:E] Running Loop=1 2019-05-31T00:28:53.644Z,1559262533.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.980074 min 2019-05-31T00:28:53.644Z,1559262533.644 [Default:CheckIn:E] Stopped 2019-05-31T00:28:53.645Z,1559262533.645 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-31T00:28:53.645Z,1559262533.645 [Default:CheckIn] Stopped 2019-05-31T00:28:53.645Z,1559262533.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-31T00:28:53.645Z,1559262533.645 [Default:CheckIn](INFO): Running loop #8 2019-05-31T00:28:53.645Z,1559262533.645 [Default:CheckIn] Running Loop=8 2019-05-31T00:28:53.645Z,1559262533.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-31T00:28:53.645Z,1559262533.645 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-31T00:28:55.245Z,1559262535.245 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:28:55.634Z,1559262535.634 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002806.00,A,3648.67049,N,12148.29677,W,9.914,301.09,310519,,,D*72 2019-05-31T00:28:55.636Z,1559262535.636 [NAL9602](INFO): GPS fix at 20190531T002806: (36.811175, -121.804946) 2019-05-31T00:28:55.659Z,1559262535.659 [Default:CheckIn:Read_GPS] Stopped 2019-05-31T00:28:55.659Z,1559262535.659 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-31T00:29:03.496Z,1559262543.496 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190530T233651/Courier0031.lzma 2019-05-31T00:29:04.302Z,1559262544.302 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0031.lzma.bak 2019-05-31T00:29:04.302Z,1559262544.302 [DataOverHttps](INFO): SBD MOMSN=11226655 2019-05-31T00:29:09.394Z,1559262549.394 [NAL9602](INFO): SBD MO Status=0, MOMSN=26806, MT Status=0, MTMSN=0 2019-05-31T00:29:09.394Z,1559262549.394 [NAL9602](INFO): No messages in MT queue 2019-05-31T00:29:19.176Z,1559262559.176 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190530T233651/Express0032.lzma 2019-05-31T00:29:19.982Z,1559262559.982 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0032.lzma.bak 2019-05-31T00:29:19.983Z,1559262559.983 [DataOverHttps](INFO): SBD MOMSN=11226658 2019-05-31T00:29:25.176Z,1559262565.176 [Default:CheckIn:Read_Iridium] Stopped 2019-05-31T00:29:25.176Z,1559262565.176 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-31T00:29:25.176Z,1559262565.176 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-31T00:29:40.100Z,1559262580.100 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-31T00:34:25.748Z,1559262865.748 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-31T00:34:25.748Z,1559262865.748 [Default:CheckIn:C.Wait] Stopped 2019-05-31T00:34:25.748Z,1559262865.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-31T00:34:25.748Z,1559262865.748 [Default:CheckIn:D] Running Loop=1 2019-05-31T00:34:26.144Z,1559262866.144 [Default:CheckIn:D] Stopped 2019-05-31T00:34:26.144Z,1559262866.144 [Default:CheckIn:E] Running Loop=1 2019-05-31T00:34:26.544Z,1559262866.544 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.528630 min 2019-05-31T00:34:26.545Z,1559262866.545 [Default:CheckIn:E] Stopped 2019-05-31T00:34:26.545Z,1559262866.545 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-31T00:34:26.545Z,1559262866.545 [Default:CheckIn] Stopped 2019-05-31T00:34:26.545Z,1559262866.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-31T00:34:26.545Z,1559262866.545 [Default:CheckIn](INFO): Running loop #9 2019-05-31T00:34:26.545Z,1559262866.545 [Default:CheckIn] Running Loop=9 2019-05-31T00:34:26.545Z,1559262866.545 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-31T00:34:26.546Z,1559262866.546 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-31T00:34:28.153Z,1559262868.153 [NAL9602](DEBUG): Fix Requested 2019-05-31T00:34:28.554Z,1559262868.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003339.00,A,3648.88713,N,12149.00117,W,5.365,283.89,310519,,,D*75 2019-05-31T00:34:28.556Z,1559262868.556 [NAL9602](INFO): GPS fix at 20190531T003339: (36.814785, -121.816686) 2019-05-31T00:34:28.579Z,1559262868.579 [Default:CheckIn:Read_GPS] Stopped 2019-05-31T00:34:28.579Z,1559262868.579 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-31T00:34:36.404Z,1559262876.404 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190530T233651/Courier0034.lzma 2019-05-31T00:34:37.210Z,1559262877.210 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Courier0034.lzma.bak 2019-05-31T00:34:37.210Z,1559262877.210 [DataOverHttps](INFO): SBD MOMSN=11226662 2019-05-31T00:34:54.652Z,1559262894.652 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190530T233651/Express0035.lzma 2019-05-31T00:34:55.458Z,1559262895.458 [DataOverHttps](INFO): Moved sent file to Logs/20190530T233651/Express0035.lzma.bak 2019-05-31T00:34:55.458Z,1559262895.458 [DataOverHttps](INFO): SBD MOMSN=11226665 2019-05-31T00:34:56.015Z,1559262896.015 [NAL9602](INFO): SBD MO Status=2, MOMSN=26807, MT Status=2, MTMSN=0 2019-05-31T00:34:56.015Z,1559262896.015 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-05-31T00:34:59.709Z,1559262899.709 [Default:CheckIn:Read_Iridium] Stopped 2019-05-31T00:34:59.709Z,1559262899.709 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-31T00:34:59.709Z,1559262899.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-31T00:35:14.195Z,1559262914.195 [NAL9602](INFO): SBD MO Status=0, MOMSN=26807, MT Status=0, MTMSN=0 2019-05-31T00:35:14.195Z,1559262914.195 [NAL9602](INFO): No messages in MT queue 2019-05-31T00:35:44.896Z,1559262944.896 [NAL9602](INFO): Not Powering down - fast GPS 2019-05-31T00:38:15.510Z,1559263095.510 [DataOverHttps](IMPORTANT): SBD MTMSN=20190531T003725 2019-05-31T00:38:20.552Z,1559263100.552 [DataOverHttps](INFO): Received command:restart logs