2020-07-10T17:51:41.878Z,1594403501.878 [Supervisor](DEBUG): Initializing supervisor. 2020-07-10T17:51:41.881Z,1594403501.881 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-07-10T17:51:41.882Z,1594403501.882 [SyncHandler](INFO): Protected caller Thread ID is 2388 2020-07-10T17:51:41.882Z,1594403501.882 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-07-10T17:51:41.883Z,1594403501.883 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-07-10T17:51:41.883Z,1594403501.883 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2389 2020-07-10T17:51:41.886Z,1594403501.886 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-07-10T17:51:41.899Z,1594403501.899 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-07-10T17:51:41.900Z,1594403501.900 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-07-10T17:51:41.900Z,1594403501.900 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2390 2020-07-10T17:51:41.901Z,1594403501.901 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-07-10T17:51:41.902Z,1594403501.902 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-07-10T17:51:41.902Z,1594403501.902 [logger ThreadHandler](INFO): Protected caller Thread ID is 2391 2020-07-10T17:51:41.904Z,1594403501.904 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-07-10T17:51:41.905Z,1594403501.905 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-07-10T17:51:41.911Z,1594403501.911 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-07-10T17:51:42.274Z,1594403502.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-07-10T17:51:42.275Z,1594403502.275 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-07-10T17:51:42.369Z,1594403502.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-07-10T17:51:42.371Z,1594403502.371 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-07-10T17:51:42.686Z,1594403502.686 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-07-10T17:51:42.688Z,1594403502.688 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-07-10T17:51:43.129Z,1594403503.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-07-10T17:51:43.131Z,1594403503.131 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-07-10T17:51:43.275Z,1594403503.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-07-10T17:51:43.277Z,1594403503.277 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-07-10T17:51:43.914Z,1594403503.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-07-10T17:51:43.915Z,1594403503.915 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-07-10T17:51:44.236Z,1594403504.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-07-10T17:51:44.237Z,1594403504.237 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-07-10T17:51:44.320Z,1594403504.320 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-07-10T17:51:44.505Z,1594403504.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-07-10T17:51:44.506Z,1594403504.506 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-07-10T17:51:45.044Z,1594403505.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-07-10T17:51:45.044Z,1594403505.044 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-07-10T17:51:45.246Z,1594403505.246 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-07-10T17:51:45.247Z,1594403505.247 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-07-10T17:51:45.347Z,1594403505.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-07-10T17:51:45.348Z,1594403505.348 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-07-10T17:51:45.549Z,1594403505.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-07-10T17:51:45.550Z,1594403505.550 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-07-10T17:51:45.974Z,1594403505.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-07-10T17:51:45.975Z,1594403505.975 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-07-10T17:51:46.080Z,1594403506.080 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-07-10T17:51:46.080Z,1594403506.080 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-07-10T17:51:46.227Z,1594403506.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-07-10T17:51:46.229Z,1594403506.229 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-tethys/ 2020-07-10T17:51:46.233Z,1594403506.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Battery.cfg 2020-07-10T17:51:46.463Z,1594403506.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-07-10T17:51:46.463Z,1594403506.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Servo.cfg 2020-07-10T17:51:46.559Z,1594403506.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/secure.cfg 2020-07-10T17:51:46.642Z,1594403506.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Control.cfg 2020-07-10T17:51:46.734Z,1594403506.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/vehicle.cfg 2020-07-10T17:51:46.941Z,1594403506.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Sensor.cfg 2020-07-10T17:51:47.086Z,1594403507.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Navigation.cfg 2020-07-10T17:51:47.176Z,1594403507.176 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/logger.cfg 2020-07-10T17:51:47.256Z,1594403507.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Simulator.cfg 2020-07-10T17:51:47.341Z,1594403507.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/BIT.cfg 2020-07-10T17:51:47.435Z,1594403507.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Science.cfg 2020-07-10T17:51:47.611Z,1594403507.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/workSite.cfg 2020-07-10T17:51:47.700Z,1594403507.700 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-07-10T17:51:47.714Z,1594403507.714 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-07-10T17:51:47.850Z,1594403507.850 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-07-10T17:51:47.850Z,1594403507.850 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-07-10T17:51:47.871Z,1594403507.871 [NavChart] Loaded 2020-07-10T17:51:47.871Z,1594403507.871 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-07-10T17:51:47.875Z,1594403507.875 [UniversalFixResidualReporter] Loaded 2020-07-10T17:51:47.875Z,1594403507.875 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-07-10T17:51:47.876Z,1594403507.876 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-07-10T17:51:47.876Z,1594403507.876 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-07-10T17:51:48.168Z,1594403508.168 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-07-10T17:51:48.173Z,1594403508.173 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-07-10T17:51:48.175Z,1594403508.175 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-07-10T17:51:48.180Z,1594403508.180 [CTD_Seabird](INFO): created writer for : depth 2020-07-10T17:51:48.180Z,1594403508.180 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-07-10T17:51:48.185Z,1594403508.185 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-07-10T17:51:48.186Z,1594403508.186 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-07-10T17:51:48.191Z,1594403508.191 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-07-10T17:51:48.191Z,1594403508.191 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-07-10T17:51:48.196Z,1594403508.196 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-07-10T17:51:48.197Z,1594403508.197 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-07-10T17:51:48.202Z,1594403508.202 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-07-10T17:51:48.203Z,1594403508.203 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-07-10T17:51:48.208Z,1594403508.208 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-07-10T17:51:48.208Z,1594403508.208 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water 2020-07-10T17:51:48.214Z,1594403508.214 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water 2020-07-10T17:51:48.214Z,1594403508.214 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq 2020-07-10T17:51:48.215Z,1594403508.215 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq 2020-07-10T17:51:48.246Z,1594403508.246 [CTD_Seabird] Loaded 2020-07-10T17:51:48.246Z,1594403508.246 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-07-10T17:51:48.247Z,1594403508.247 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 405F44E0 2020-07-10T17:51:48.247Z,1594403508.247 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2476 2020-07-10T17:51:48.295Z,1594403508.295 [ESPComponent] Loaded 2020-07-10T17:51:48.296Z,1594403508.296 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-07-10T17:51:48.326Z,1594403508.326 [PAR_Licor] Loaded 2020-07-10T17:51:48.326Z,1594403508.326 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-07-10T17:51:48.327Z,1594403508.327 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-07-10T17:51:48.328Z,1594403508.328 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-07-10T17:51:48.355Z,1594403508.355 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-07-10T17:51:48.356Z,1594403508.356 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-07-10T17:51:48.768Z,1594403508.768 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-07-10T17:51:48.773Z,1594403508.773 [AHRS_M2](INFO): created writer for : platform_orientation 2020-07-10T17:51:48.774Z,1594403508.774 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-07-10T17:51:48.779Z,1594403508.779 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-07-10T17:51:48.779Z,1594403508.779 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-07-10T17:51:48.784Z,1594403508.784 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-07-10T17:51:48.785Z,1594403508.785 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-07-10T17:51:48.790Z,1594403508.790 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-07-10T17:51:48.860Z,1594403508.860 [AHRS_M2] Loaded 2020-07-10T17:51:48.860Z,1594403508.860 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-07-10T17:51:48.936Z,1594403508.936 [DataOverHttps] Loaded 2020-07-10T17:51:48.936Z,1594403508.936 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-07-10T17:51:48.937Z,1594403508.937 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408A04E0 2020-07-10T17:51:48.937Z,1594403508.937 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2477 2020-07-10T17:51:48.967Z,1594403508.967 [Depth_Keller] Loaded 2020-07-10T17:51:48.967Z,1594403508.967 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-07-10T17:51:48.972Z,1594403508.972 [DropWeight] Loaded 2020-07-10T17:51:48.972Z,1594403508.972 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-07-10T17:51:49.083Z,1594403509.083 [NAL9602] Loaded 2020-07-10T17:51:49.083Z,1594403509.083 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-07-10T17:51:49.130Z,1594403509.130 [Onboard] Loaded 2020-07-10T17:51:49.130Z,1594403509.130 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-07-10T17:51:49.131Z,1594403509.131 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0 2020-07-10T17:51:49.132Z,1594403509.132 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2478 2020-07-10T17:51:49.138Z,1594403509.138 [PowerOnly](INFO): Adding load control power supply at /dev/loadC4 2020-07-10T17:51:49.159Z,1594403509.159 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2020-07-10T17:51:49.179Z,1594403509.179 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1 2020-07-10T17:51:49.199Z,1594403509.199 [PowerOnly] Loaded 2020-07-10T17:51:49.200Z,1594403509.200 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2020-07-10T17:51:49.222Z,1594403509.222 [Radio_Surface] Loaded 2020-07-10T17:51:49.222Z,1594403509.222 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-07-10T17:51:49.223Z,1594403509.223 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409004E0 2020-07-10T17:51:49.223Z,1594403509.223 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2479 2020-07-10T17:51:49.281Z,1594403509.281 [RDI_Pathfinder] Loaded 2020-07-10T17:51:49.281Z,1594403509.281 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-07-10T17:51:49.440Z,1594403509.440 [DAT] Loaded 2020-07-10T17:51:49.440Z,1594403509.440 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-07-10T17:51:50.961Z,1594403510.961 [BPC1] Loaded 2020-07-10T17:51:50.961Z,1594403510.961 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-07-10T17:51:50.962Z,1594403510.962 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-07-10T17:51:50.962Z,1594403510.962 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-07-10T17:51:51.085Z,1594403511.085 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-07-10T17:51:51.086Z,1594403511.086 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-07-10T17:51:51.145Z,1594403511.145 [VerticalControl](DEBUG): Construct VerticalControl. 2020-07-10T17:51:51.252Z,1594403511.252 [VerticalControl] Loaded 2020-07-10T17:51:51.252Z,1594403511.252 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-07-10T17:51:51.252Z,1594403511.252 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-07-10T17:51:51.333Z,1594403511.333 [HorizontalControl] Loaded 2020-07-10T17:51:51.333Z,1594403511.333 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-07-10T17:51:51.334Z,1594403511.334 [SpeedControl](DEBUG): Construct SpeedControl. 2020-07-10T17:51:51.339Z,1594403511.339 [SpeedControl] Loaded 2020-07-10T17:51:51.339Z,1594403511.339 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-07-10T17:51:51.340Z,1594403511.340 [LoopControl](DEBUG): Construct LoopControl. 2020-07-10T17:51:51.341Z,1594403511.341 [LoopControl] Loaded 2020-07-10T17:51:51.341Z,1594403511.341 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-07-10T17:51:51.341Z,1594403511.341 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-07-10T17:51:51.342Z,1594403511.342 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-07-10T17:51:51.380Z,1594403511.380 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-07-10T17:51:51.381Z,1594403511.381 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-07-10T17:51:51.700Z,1594403511.700 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-07-10T17:51:51.700Z,1594403511.700 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-07-10T17:51:51.751Z,1594403511.751 [DepthRateCalculator] Loaded 2020-07-10T17:51:51.752Z,1594403511.752 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-07-10T17:51:51.757Z,1594403511.757 [PitchRateCalculator] Loaded 2020-07-10T17:51:51.757Z,1594403511.757 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-07-10T17:51:51.769Z,1594403511.769 [SpeedCalculator] Loaded 2020-07-10T17:51:51.769Z,1594403511.769 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-07-10T17:51:51.790Z,1594403511.790 [TempGradientCalculator] Loaded 2020-07-10T17:51:51.790Z,1594403511.790 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-07-10T17:51:51.795Z,1594403511.795 [YawRateCalculator] Loaded 2020-07-10T17:51:51.795Z,1594403511.795 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-07-10T17:51:51.824Z,1594403511.824 [ElevatorOffsetCalculator] Loaded 2020-07-10T17:51:51.825Z,1594403511.825 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-07-10T17:51:51.825Z,1594403511.825 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-07-10T17:51:51.825Z,1594403511.825 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-07-10T17:51:51.968Z,1594403511.968 [BuoyancyServo] Loaded 2020-07-10T17:51:51.968Z,1594403511.968 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-07-10T17:51:51.997Z,1594403511.997 [ElevatorServo] Loaded 2020-07-10T17:51:51.997Z,1594403511.997 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-07-10T17:51:52.025Z,1594403512.025 [MassServo] Loaded 2020-07-10T17:51:52.025Z,1594403512.025 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-07-10T17:51:52.052Z,1594403512.052 [RudderServo] Loaded 2020-07-10T17:51:52.053Z,1594403512.053 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-07-10T17:51:52.079Z,1594403512.079 [ThrusterServo] Loaded 2020-07-10T17:51:52.080Z,1594403512.080 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-07-10T17:51:52.080Z,1594403512.080 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-07-10T17:51:52.081Z,1594403512.081 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-07-10T17:51:52.236Z,1594403512.236 [SBIT](DEBUG): Construct Startup Built In Test. 2020-07-10T17:51:52.247Z,1594403512.247 [SBIT] Loaded 2020-07-10T17:51:52.247Z,1594403512.247 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-07-10T17:51:52.248Z,1594403512.248 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-07-10T17:51:52.261Z,1594403512.261 [IBIT] Loaded 2020-07-10T17:51:52.261Z,1594403512.261 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-07-10T17:51:52.264Z,1594403512.264 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-07-10T17:51:52.404Z,1594403512.404 [CBIT] Loaded 2020-07-10T17:51:52.405Z,1594403512.405 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-07-10T17:51:52.405Z,1594403512.405 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-07-10T17:51:52.406Z,1594403512.406 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-07-10T17:51:52.498Z,1594403512.498 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-07-10T17:51:52.498Z,1594403512.498 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-07-10T17:51:52.615Z,1594403512.615 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-07-10T17:51:52.618Z,1594403512.618 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-07-10T17:51:52.619Z,1594403512.619 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-07-10T17:51:52.626Z,1594403512.626 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-07-10T17:51:52.627Z,1594403512.627 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B2D4E0 2020-07-10T17:51:52.627Z,1594403512.627 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2480 2020-07-10T17:51:52.631Z,1594403512.631 [Supervisor](INFO): Main Thread ID is 816 2020-07-10T17:51:52.632Z,1594403512.632 [Supervisor](DEBUG): Running supervisor. 2020-07-10T17:51:52.632Z,1594403512.632 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2481 2020-07-10T17:51:52.634Z,1594403512.634 [controlThread ThreadHandler](INFO): Handler Thread ID is 2482 2020-07-10T17:51:52.635Z,1594403512.635 [controlThread](DEBUG): Initializing ControlThread 2020-07-10T17:51:52.636Z,1594403512.636 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-07-10T17:51:52.637Z,1594403512.637 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-07-10T17:51:52.647Z,1594403512.647 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-07-10T17:51:52.649Z,1594403512.649 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-07-10T17:51:52.650Z,1594403512.650 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-07-10T17:51:52.650Z,1594403512.650 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-07-10T17:51:52.650Z,1594403512.650 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-07-10T17:51:52.651Z,1594403512.651 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-07-10T17:51:52.651Z,1594403512.651 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-07-10T17:51:52.651Z,1594403512.651 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-07-10T17:51:52.652Z,1594403512.652 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-07-10T17:51:52.652Z,1594403512.652 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-07-10T17:51:52.654Z,1594403512.654 [SBIT](INFO): Initialize SBIT Component. 2020-07-10T17:51:52.655Z,1594403512.655 [SBIT](IMPORTANT): git: 2020-07-06 2020-07-10T17:51:52.655Z,1594403512.655 [SBIT](INFO): git hash: 27d6d613a5802cbc38609e1e9bc8b2bacbda1d86 2020-07-10T17:51:52.655Z,1594403512.655 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-07-10T17:51:52.656Z,1594403512.656 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019 2020-07-10T17:51:52.658Z,1594403512.658 [SBIT](INFO): Beginning SBIT in 58.000000 seconds. 2020-07-10T17:51:52.658Z,1594403512.658 [IBIT](INFO): Initialize IBIT Component. 2020-07-10T17:51:52.659Z,1594403512.659 [CBIT](DEBUG): Initialize CBIT Component. 2020-07-10T17:51:52.660Z,1594403512.660 [logger ThreadHandler](INFO): Handler Thread ID is 2483 2020-07-10T17:51:52.673Z,1594403512.673 [CBIT](DEBUG): Initialized mux pins. 2020-07-10T17:51:52.673Z,1594403512.673 [CBIT](DEBUG): Initializing the watchdog timer. 2020-07-10T17:51:52.677Z,1594403512.677 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2484 2020-07-10T17:51:52.678Z,1594403512.678 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-07-10T17:51:52.682Z,1594403512.682 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2486 2020-07-10T17:51:52.683Z,1594403512.683 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-07-10T17:51:52.694Z,1594403512.694 [Onboard ThreadHandler](INFO): Handler Thread ID is 2487 2020-07-10T17:51:52.698Z,1594403512.698 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-07-10T17:51:52.698Z,1594403512.698 [CBIT](DEBUG): Initializing heartbeat. 2020-07-10T17:51:52.714Z,1594403512.714 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2488 2020-07-10T17:51:52.729Z,1594403512.729 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2489 2020-07-10T17:51:52.735Z,1594403512.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-07-10T17:51:52.735Z,1594403512.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-07-10T17:51:52.736Z,1594403512.736 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-07-10T17:51:52.736Z,1594403512.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-07-10T17:51:52.736Z,1594403512.736 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-07-10T17:51:52.736Z,1594403512.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-07-10T17:51:52.736Z,1594403512.736 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-07-10T17:51:52.736Z,1594403512.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-07-10T17:51:52.737Z,1594403512.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-07-10T17:51:52.737Z,1594403512.737 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-07-10T17:51:52.737Z,1594403512.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-07-10T17:51:52.737Z,1594403512.737 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-07-10T17:51:52.737Z,1594403512.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-07-10T17:51:52.738Z,1594403512.738 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-07-10T17:51:52.738Z,1594403512.738 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-07-10T17:51:52.738Z,1594403512.738 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-07-10T17:51:52.769Z,1594403512.769 [CBIT](DEBUG): Deactivating GF circuits. 2020-07-10T17:51:52.769Z,1594403512.769 [CBIT](DEBUG): Deactivating emergency mode. 2020-07-10T17:51:52.805Z,1594403512.805 [CBIT](DEBUG): Backplane powered. 2020-07-10T17:51:52.806Z,1594403512.806 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-07-10T17:51:52.818Z,1594403512.818 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-07-10T17:51:52.866Z,1594403512.866 [MissionManager](DEBUG): 2020-07-10T17:51:52.867Z,1594403512.867 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-07-10T17:51:52.924Z,1594403512.924 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-07-10T17:51:52.941Z,1594403512.941 [Default:A.Wait](DEBUG): Construct Wait. 2020-07-10T17:51:52.943Z,1594403512.943 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-07-10T17:51:52.969Z,1594403512.969 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-07-10T17:51:52.971Z,1594403512.971 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-07-10T17:51:53.000Z,1594403513.000 [Default:E.Execute](DEBUG): Construct Execute. 2020-07-10T17:51:53.016Z,1594403513.016 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2020-07-10T17:51:53.026Z,1594403513.026 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,NAL9602,PowerOnly,RDI_Pathfinder,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, 2020-07-10T17:51:53.049Z,1594403513.049 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-07-10T17:51:53.080Z,1594403513.080 [Depth_Keller](ERROR): Pressure reading out of range: 2102.507080 decibar 2020-07-10T17:51:53.092Z,1594403513.092 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-07-10T17:51:53.092Z,1594403513.092 [DAT](INFO): Powering up 2020-07-10T17:51:53.092Z,1594403513.092 [DAT](DEBUG): Initializing DAT. 2020-07-10T17:51:53.117Z,1594403513.117 [Radio_Surface](INFO): Powering up 2020-07-10T17:51:53.137Z,1594403513.137 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-07-10T17:51:53.170Z,1594403513.170 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-07-10T17:51:53.204Z,1594403513.204 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-07-10T17:51:53.213Z,1594403513.213 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-07-10T17:51:53.214Z,1594403513.214 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-07-10T17:51:53.225Z,1594403513.225 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-07-10T17:51:53.226Z,1594403513.226 [MassServo](DEBUG): Initializing EZServoServo. 2020-07-10T17:51:53.237Z,1594403513.237 [MassServo](DEBUG): Initializing MassServo. 2020-07-10T17:51:53.238Z,1594403513.238 [RudderServo](DEBUG): Initializing EZServoServo. 2020-07-10T17:51:53.249Z,1594403513.249 [RudderServo](DEBUG): Initializing RudderServo. 2020-07-10T17:51:53.250Z,1594403513.250 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-07-10T17:51:53.261Z,1594403513.261 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-07-10T17:51:54.173Z,1594403514.173 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-07-10T17:51:54.173Z,1594403514.173 [RudderServo](FAULT): Rudder failed to initialize 2020-07-10T17:51:54.173Z,1594403514.173 [RudderServo] Communications Fault, FailCount= 1 2020-07-10T17:51:54.173Z,1594403514.173 [RudderServo](ERROR): Communications Fault 2020-07-10T17:51:54.284Z,1594403514.284 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-07-10T17:51:54.490Z,1594403514.490 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-07-10T17:51:54.490Z,1594403514.490 [RudderServo](INFO): Powering down 2020-07-10T17:51:55.121Z,1594403515.121 [RudderServo](DEBUG): Initializing EZServoServo. 2020-07-10T17:51:55.242Z,1594403515.242 [RudderServo](DEBUG): Initializing RudderServo. 2020-07-10T17:51:55.246Z,1594403515.246 [CBIT](INFO): Clearing failed state for component RudderServo 2020-07-10T17:51:55.246Z,1594403515.246 [RudderServo] No Fault, FailCount= 1 2020-07-10T17:52:07.237Z,1594403527.237 [DAT](INFO): commRate: 800 2020-07-10T17:52:07.237Z,1594403527.237 [DAT](INFO): commRate: 800 2020-07-10T17:52:07.646Z,1594403527.646 [DAT](INFO): entering command mode 2020-07-10T17:52:08.039Z,1594403528.039 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:08.443Z,1594403528.443 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:08.858Z,1594403528.858 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:09.251Z,1594403529.251 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:09.665Z,1594403529.665 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:10.059Z,1594403530.059 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:10.463Z,1594403530.463 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:10.878Z,1594403530.878 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:11.271Z,1594403531.271 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:11.686Z,1594403531.686 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:12.079Z,1594403532.079 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:12.499Z,1594403532.499 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:12.957Z,1594403532.957 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:13.291Z,1594403533.291 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:13.703Z,1594403533.703 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:14.122Z,1594403534.122 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:14.503Z,1594403534.503 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:14.927Z,1594403534.927 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:15.334Z,1594403535.334 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:15.742Z,1594403535.742 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:16.143Z,1594403536.143 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:16.574Z,1594403536.574 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:16.927Z,1594403536.927 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:17.339Z,1594403537.339 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:17.754Z,1594403537.754 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:18.139Z,1594403538.139 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:18.570Z,1594403538.570 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:18.951Z,1594403538.951 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:19.350Z,1594403539.350 [NAL9602](INFO): Powering up NAL9602 2020-07-10T17:52:19.352Z,1594403539.352 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:19.790Z,1594403539.790 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:20.159Z,1594403540.159 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:20.568Z,1594403540.568 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:21.010Z,1594403541.010 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:21.371Z,1594403541.371 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:21.797Z,1594403541.797 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:22.179Z,1594403542.179 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:22.593Z,1594403542.593 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:22.987Z,1594403542.987 [DAT](DEBUG): checking for command mode acknowledgment 2020-07-10T17:52:22.987Z,1594403542.987 [DAT](FAULT): failed to enter command mode 2020-07-10T17:52:23.433Z,1594403543.433 [DAT](INFO): entering command mode 2020-07-10T17:52:23.796Z,1594403543.796 [DAT](INFO): setting verbose to 3 2020-07-10T17:52:24.214Z,1594403544.214 [DAT](INFO): set verbose to 3 2020-07-10T17:52:24.215Z,1594403544.215 [DAT](INFO): setting DatVerbose to 27440 2020-07-10T17:52:24.642Z,1594403544.642 [DAT](INFO): set DatVerbose to 27440 2020-07-10T17:52:24.642Z,1594403544.642 [DAT](INFO): setting transmit power to 8 2020-07-10T17:52:25.004Z,1594403545.004 [DAT](INFO): set transmit power to 8 2020-07-10T17:52:25.005Z,1594403545.005 [DAT](INFO): setting local address to 6 2020-07-10T17:52:25.412Z,1594403545.412 [DAT](INFO): set local address to 6 2020-07-10T17:52:30.259Z,1594403550.259 [NAL9602](INFO): NAL9602 initialized 2020-07-10T17:52:51.289Z,1594403571.289 [SBIT](IMPORTANT): Beginning Startup BIT 2020-07-10T17:52:51.293Z,1594403571.293 [CBIT](IMPORTANT): Beginning ground fault scan 2020-07-10T17:52:55.309Z,1594403575.309 [NAL9602](INFO): SBD MO Status=2, MOMSN=63492, MT Status=2, MTMSN=0 2020-07-10T17:52:55.309Z,1594403575.309 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T17:53:02.612Z,1594403582.612 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.101257 CHAN A1 (24V): 0.171474 CHAN A2 (12V): 0.076834 CHAN A3 (5V): 0.016371 CHAN B0 (3.3V): 0.005999 CHAN B1 (3.15aV): 0.005357 CHAN B2 (3.15bV): 0.006082 CHAN B3 (GND): -0.004588 OPEN: 0.007911 Full Scale Calc: 4.765 mA, -1.589 mA 2020-07-10T17:53:12.690Z,1594403592.690 [NAL9602](INFO): SBD MO Status=2, MOMSN=63492, MT Status=2, MTMSN=0 2020-07-10T17:53:12.690Z,1594403592.690 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T17:53:45.502Z,1594403625.502 [SBIT](IMPORTANT): SBIT PASSED 2020-07-10T17:53:45.502Z,1594403625.502 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-07-10T17:53:45.503Z,1594403625.503 [SBIT](IMPORTANT): DAT.sbdAddress=0 enum; 2020-07-10T17:53:45.503Z,1594403625.503 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2020-07-10T17:53:45.503Z,1594403625.503 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute; 2020-07-10T17:53:45.504Z,1594403625.504 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2020-07-10T17:53:45.504Z,1594403625.504 [SBIT](IMPORTANT): Express linearApproximation DAT.range 5.000000 meter; 2020-07-10T17:53:45.504Z,1594403625.504 [SBIT](IMPORTANT): Express none acoustic_contact_range; 2020-07-10T17:53:45.504Z,1594403625.504 [SBIT](IMPORTANT): Express none height_above_sea_floor; 2020-07-10T17:53:45.504Z,1594403625.504 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=3 minute; 2020-07-10T17:53:45.504Z,1594403625.504 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=304.676894 cubic_centimeter; 2020-07-10T17:53:45.504Z,1594403625.504 [SBIT](IMPORTANT): VerticalControl.massDefault=-9.661535 millimeter; 2020-07-10T17:53:45.504Z,1594403625.504 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool; 2020-07-10T17:53:45.833Z,1594403625.833 [MissionManager](IMPORTANT): Started mission Startup 2020-07-10T17:53:45.834Z,1594403625.834 [Startup] Running Loop=1 2020-07-10T17:53:45.834Z,1594403625.834 [Startup](DEBUG): Aggregate::initialize Startup 2020-07-10T17:53:45.834Z,1594403625.834 [Startup:A.GoToSurface] Running Loop=1 2020-07-10T17:53:45.834Z,1594403625.834 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-07-10T17:53:45.835Z,1594403625.835 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-07-10T17:53:45.835Z,1594403625.835 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-07-10T17:53:45.835Z,1594403625.835 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-07-10T17:53:45.836Z,1594403625.836 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-07-10T17:53:45.836Z,1594403625.836 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-07-10T17:53:45.838Z,1594403625.838 [Startup:StartupSatComms] Running Loop=1 2020-07-10T17:53:45.838Z,1594403625.838 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-07-10T17:53:45.838Z,1594403625.838 [Startup:StartupSatComms:A] Running Loop=1 2020-07-10T17:53:46.235Z,1594403626.235 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-07-10T17:53:53.484Z,1594403633.484 [NAL9602](INFO): SBD MO Status=2, MOMSN=63492, MT Status=2, MTMSN=0 2020-07-10T17:53:53.485Z,1594403633.485 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T17:54:29.054Z,1594403669.054 [NAL9602](INFO): SBD MO Status=2, MOMSN=63492, MT Status=2, MTMSN=0 2020-07-10T17:54:29.055Z,1594403669.055 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T17:54:46.008Z,1594403686.008 [NAL9602](INFO): SBD MO Status=2, MOMSN=63492, MT Status=2, MTMSN=0 2020-07-10T17:54:46.008Z,1594403686.008 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T17:54:46.047Z,1594403686.047 [Startup:StartupSatComms:A](INFO): Timed out from 2020-07-10T17:53:45.8Z 2020-07-10T17:54:46.047Z,1594403686.047 [Startup:StartupSatComms:A] Stopped 2020-07-10T17:54:46.047Z,1594403686.047 [Startup:StartupSatComms:B] Running Loop=1 2020-07-10T17:54:46.435Z,1594403686.435 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-07-10T17:54:52.919Z,1594403692.919 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-07-10T17:54:52.919Z,1594403692.919 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-07-10T17:54:53.003Z,1594403693.003 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-07-10T17:54:53.373Z,1594403693.373 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-07-10T17:54:53.373Z,1594403693.373 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-07-10T17:55:42.984Z,1594403742.984 [NAL9602](INFO): SBD MO Status=2, MOMSN=63492, MT Status=2, MTMSN=0 2020-07-10T17:55:42.984Z,1594403742.984 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T17:55:43.370Z,1594403743.370 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-07-10T17:55:46.243Z,1594403746.243 [Startup:StartupSatComms:B](INFO): Timed out from 2020-07-10T17:54:46.0Z 2020-07-10T17:55:46.243Z,1594403746.243 [Startup:StartupSatComms:B] Stopped 2020-07-10T17:55:46.243Z,1594403746.243 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-07-10T17:55:46.243Z,1594403746.243 [Startup:StartupSatComms] Stopped 2020-07-10T17:55:46.243Z,1594403746.243 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-07-10T17:55:46.244Z,1594403746.244 [Startup](INFO): Completed Startup 2020-07-10T17:55:46.244Z,1594403746.244 [MissionManager](INFO): Startup is completed. 2020-07-10T17:55:46.244Z,1594403746.244 [MissionManager](INFO): Uninitializing Mission Startup 2020-07-10T17:55:46.244Z,1594403746.244 [Startup] Stopped 2020-07-10T17:55:46.244Z,1594403746.244 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-07-10T17:55:46.244Z,1594403746.244 [Startup:A.GoToSurface] Stopped 2020-07-10T17:55:46.244Z,1594403746.244 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-07-10T17:55:46.621Z,1594403746.621 [MissionManager](IMPORTANT): Started mission Default 2020-07-10T17:55:46.621Z,1594403746.621 [Default] Running Loop=1 2020-07-10T17:55:46.621Z,1594403746.621 [Default](DEBUG): Aggregate::initialize Default 2020-07-10T17:55:46.621Z,1594403746.621 [Default:B.GoToSurface] Running Loop=1 2020-07-10T17:55:46.622Z,1594403746.622 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-07-10T17:55:46.622Z,1594403746.622 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-07-10T17:55:46.622Z,1594403746.622 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-07-10T17:55:46.622Z,1594403746.622 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-07-10T17:55:46.623Z,1594403746.623 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-07-10T17:55:46.623Z,1594403746.623 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-07-10T17:55:46.623Z,1594403746.623 [Default:A.Wait] Running Loop=1 2020-07-10T17:55:46.623Z,1594403746.623 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-07-10T17:55:47.315Z,1594403747.315 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004914 2020-07-10T17:55:59.940Z,1594403759.940 [Default:A.Wait](INFO): Done Waiting. 2020-07-10T17:55:59.940Z,1594403759.940 [Default:A.Wait] Stopped 2020-07-10T17:55:59.941Z,1594403759.941 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-07-10T17:56:00.364Z,1594403760.364 [Default:CheckIn] Running Loop=1 2020-07-10T17:56:00.364Z,1594403760.364 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-07-10T17:56:00.364Z,1594403760.364 [Default:CheckIn:Read_GPS] Running Loop=1 2020-07-10T17:56:00.775Z,1594403760.775 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-07-10T17:57:02.774Z,1594403822.774 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2020-07-10T17:57:33.897Z,1594403853.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T17:57:53.882Z,1594403873.882 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-07-10T17:57:53.882Z,1594403873.882 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-07-10T17:57:53.892Z,1594403873.892 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-07-10T17:57:54.289Z,1594403874.289 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-07-10T17:57:54.290Z,1594403874.290 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-07-10T17:58:05.030Z,1594403885.030 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T17:58:36.185Z,1594403916.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T17:59:07.309Z,1594403947.309 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T17:59:09.317Z,1594403949.317 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.455444 2020-07-10T17:59:22.362Z,1594403962.362 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-07-10T17:59:22.365Z,1594403962.365 [BPC1](INFO): Received data from all battery sticks. 2020-07-10T17:59:25.172Z,1594403965.172 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175906.00,A,3646.27075,N,12152.99345,W,1.030,0.00,100720,,,A*71 2020-07-10T17:59:25.175Z,1594403965.175 [NAL9602](INFO): GPS fix at 20200710T175906: (36.771179, -121.883224) 2020-07-10T17:59:25.186Z,1594403965.186 [Default:CheckIn:Read_GPS] Stopped 2020-07-10T17:59:25.186Z,1594403965.186 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-07-10T17:59:25.612Z,1594403965.612 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-07-10T17:59:26.787Z,1594403966.787 [DAT](INFO): unknown deviceResponse_: il PSNR:02.8 CCERR:002 Mod:MFSK 2020-07-10T17:59:48.306Z,1594403988.306 [NAL9602](INFO): SBD MO Status=1, MOMSN=63492, MT Status=0, MTMSN=0 2020-07-10T17:59:48.357Z,1594403988.357 [NAL9602](INFO): Sent 50 bytes from file Logs/20200710T040013/Courier0075.lzma 2020-07-10T17:59:48.357Z,1594403988.357 [NAL9602](INFO): Packets left to send: 0 2020-07-10T18:00:08.553Z,1594404008.553 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:00:11.003Z,1594404011.003 [NAL9602](INFO): SBD MO Status=1, MOMSN=63493, MT Status=0, MTMSN=0 2020-07-10T18:00:11.065Z,1594404011.065 [NAL9602](INFO): Sent 151 bytes from file Logs/20200710T175141/Courier0000.lzma 2020-07-10T18:00:11.065Z,1594404011.065 [NAL9602](INFO): Packets left to send: 0 2020-07-10T18:00:29.284Z,1594404029.284 [NAL9602](INFO): SBD MO Status=2, MOMSN=63494, MT Status=2, MTMSN=0 2020-07-10T18:00:29.284Z,1594404029.284 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:00:37.426Z,1594404037.426 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003447 2020-07-10T18:00:45.009Z,1594404045.009 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20200710T175141/Courier0004.lzma 2020-07-10T18:00:46.011Z,1594404046.011 [DataOverHttps](INFO): Moved sent file to Logs/20200710T175141/Courier0004.lzma.bak 2020-07-10T18:00:46.011Z,1594404046.011 [DataOverHttps](INFO): SBD MOMSN=12433341 2020-07-10T18:00:54.790Z,1594404054.790 [NAL9602](INFO): SBD MO Status=2, MOMSN=63494, MT Status=2, MTMSN=0 2020-07-10T18:00:54.791Z,1594404054.791 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:01:25.500Z,1594404085.500 [NAL9602](INFO): Not Powering down - fast GPS 2020-07-10T18:01:49.265Z,1594404109.265 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:02:20.389Z,1594404140.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:02:51.513Z,1594404171.513 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:03:22.637Z,1594404202.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:03:46.734Z,1594404226.734 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.723053 2020-07-10T18:04:04.463Z,1594404244.463 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003508 2020-07-10T18:04:12.037Z,1594404252.037 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20200710T040013/Express0076.lzma 2020-07-10T18:04:13.039Z,1594404253.039 [DataOverHttps](INFO): Moved sent file to Logs/20200710T040013/Express0076.lzma.bak 2020-07-10T18:04:13.039Z,1594404253.039 [DataOverHttps](INFO): SBD MOMSN=12433382 2020-07-10T18:04:16.083Z,1594404256.083 [NAL9602](INFO): SBD MO Status=1, MOMSN=63494, MT Status=0, MTMSN=0 2020-07-10T18:04:16.135Z,1594404256.135 [NAL9602](INFO): Sent 272 bytes from file Logs/20200710T040013/Express0076.lzma 2020-07-10T18:04:16.135Z,1594404256.135 [NAL9602](INFO): Packets left to send: 0 2020-07-10T18:04:16.197Z,1594404256.197 [Default:CheckIn:Read_Iridium] Stopped 2020-07-10T18:04:16.197Z,1594404256.197 [Default:CheckIn:C.Wait] Running Loop=1 2020-07-10T18:04:16.197Z,1594404256.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-07-10T18:04:30.289Z,1594404270.289 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20200710T040013/Express0079.lzma 2020-07-10T18:04:31.291Z,1594404271.291 [DataOverHttps](INFO): Moved sent file to Logs/20200710T040013/Express0079.lzma.bak 2020-07-10T18:04:31.291Z,1594404271.291 [DataOverHttps](INFO): SBD MOMSN=12433409 2020-07-10T18:04:46.766Z,1594404286.766 [NAL9602](INFO): Not Powering down - fast GPS 2020-07-10T18:05:55.145Z,1594404355.145 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:06:26.269Z,1594404386.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:06:57.393Z,1594404417.393 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:07:28.518Z,1594404448.518 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:07:59.641Z,1594404479.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:08:02.654Z,1594404482.654 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.731567 2020-07-10T18:09:00.902Z,1594404540.902 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:09:16.667Z,1594404556.667 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-07-10T18:09:16.667Z,1594404556.667 [Default:CheckIn:C.Wait] Stopped 2020-07-10T18:09:16.667Z,1594404556.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-07-10T18:09:16.667Z,1594404556.667 [Default:CheckIn:D] Running Loop=1 2020-07-10T18:09:17.055Z,1594404557.055 [Default:CheckIn:D] Stopped 2020-07-10T18:09:17.055Z,1594404557.055 [Default:CheckIn:E] Running Loop=1 2020-07-10T18:09:17.458Z,1594404557.458 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.507221 min 2020-07-10T18:09:17.459Z,1594404557.459 [Default:CheckIn:E] Stopped 2020-07-10T18:09:17.459Z,1594404557.459 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-07-10T18:09:17.459Z,1594404557.459 [Default:CheckIn] Stopped 2020-07-10T18:09:17.459Z,1594404557.459 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-07-10T18:09:17.459Z,1594404557.459 [Default:CheckIn](INFO): Running loop #2 2020-07-10T18:09:17.459Z,1594404557.459 [Default:CheckIn] Running Loop=2 2020-07-10T18:09:17.459Z,1594404557.459 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-07-10T18:09:17.459Z,1594404557.459 [Default:CheckIn:Read_GPS] Running Loop=1 2020-07-10T18:09:19.473Z,1594404559.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180901.00,A,3646.23765,N,12152.90780,W,1.244,285.74,100720,,,D*72 2020-07-10T18:09:19.475Z,1594404559.475 [NAL9602](INFO): GPS fix at 20200710T180901: (36.770628, -121.881797) 2020-07-10T18:09:19.486Z,1594404559.486 [Default:CheckIn:Read_GPS] Stopped 2020-07-10T18:09:19.486Z,1594404559.486 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-07-10T18:09:32.041Z,1594404572.041 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:09:36.502Z,1594404576.502 [NAL9602](INFO): SBD MO Status=1, MOMSN=63495, MT Status=0, MTMSN=0 2020-07-10T18:09:36.551Z,1594404576.551 [NAL9602](INFO): Sent 73 bytes from file Logs/20200710T175141/Courier0007.lzma 2020-07-10T18:09:36.551Z,1594404576.551 [NAL9602](INFO): Packets left to send: 0 2020-07-10T18:09:54.763Z,1594404594.763 [NAL9602](INFO): SBD MO Status=2, MOMSN=63496, MT Status=2, MTMSN=0 2020-07-10T18:09:54.764Z,1594404594.764 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:10:03.177Z,1594404603.177 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:10:34.301Z,1594404634.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:11:05.425Z,1594404665.425 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:11:36.550Z,1594404696.550 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:12:07.673Z,1594404727.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:12:38.797Z,1594404758.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:13:09.921Z,1594404789.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:13:41.053Z,1594404821.053 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:13:47.547Z,1594404827.547 [NAL9602](INFO): SBD MO Status=2, MOMSN=63496, MT Status=2, MTMSN=0 2020-07-10T18:13:47.547Z,1594404827.547 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:14:12.177Z,1594404852.177 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:14:36.924Z,1594404876.924 [NAL9602](INFO): SBD MO Status=2, MOMSN=63496, MT Status=2, MTMSN=0 2020-07-10T18:14:36.924Z,1594404876.924 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:14:43.301Z,1594404883.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:15:07.294Z,1594404907.294 [NAL9602](INFO): SBD MO Status=2, MOMSN=63496, MT Status=2, MTMSN=0 2020-07-10T18:15:07.295Z,1594404907.295 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:15:14.437Z,1594404914.437 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:15:33.206Z,1594404933.206 [NAL9602](INFO): SBD MO Status=2, MOMSN=63496, MT Status=2, MTMSN=0 2020-07-10T18:15:33.206Z,1594404933.206 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:15:45.561Z,1594404945.561 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:16:16.685Z,1594404976.685 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:16:30.650Z,1594404990.650 [NAL9602](INFO): SBD MO Status=2, MOMSN=63496, MT Status=2, MTMSN=0 2020-07-10T18:16:30.651Z,1594404990.651 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:16:47.809Z,1594405007.809 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:16:59.002Z,1594405019.002 [NAL9602](INFO): SBD MO Status=1, MOMSN=63496, MT Status=0, MTMSN=0 2020-07-10T18:16:59.053Z,1594405019.053 [NAL9602](INFO): Sent 332 bytes from file Logs/20200710T175141/Express0001.lzma 2020-07-10T18:16:59.053Z,1594405019.053 [NAL9602](INFO): Packets left to send: 2 2020-07-10T18:17:16.858Z,1594405036.858 [NAL9602](INFO): SBD MO Status=2, MOMSN=63497, MT Status=2, MTMSN=0 2020-07-10T18:17:16.858Z,1594405036.858 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:17:18.933Z,1594405038.933 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:17:42.394Z,1594405062.394 [NAL9602](INFO): SBD MO Status=2, MOMSN=63497, MT Status=2, MTMSN=0 2020-07-10T18:17:42.394Z,1594405062.394 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:17:50.073Z,1594405070.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:18:07.914Z,1594405087.914 [NAL9602](INFO): SBD MO Status=1, MOMSN=63497, MT Status=0, MTMSN=0 2020-07-10T18:18:07.965Z,1594405087.965 [NAL9602](INFO): Sent 332 bytes from file Logs/20200710T175141/Express0001.lzma 2020-07-10T18:18:07.965Z,1594405087.965 [NAL9602](INFO): Packets left to send: 1 2020-07-10T18:18:19.317Z,1594405099.317 [NAL9602](INFO): SBD MO Status=1, MOMSN=63498, MT Status=0, MTMSN=0 2020-07-10T18:18:19.366Z,1594405099.366 [NAL9602](INFO): Sent 299 bytes from file Logs/20200710T175141/Express0001.lzma 2020-07-10T18:18:19.367Z,1594405099.367 [NAL9602](INFO): Packets left to send: 0 2020-07-10T18:18:21.197Z,1594405101.197 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:18:32.713Z,1594405112.713 [NAL9602](INFO): SBD MO Status=2, MOMSN=63499, MT Status=2, MTMSN=0 2020-07-10T18:18:32.713Z,1594405112.713 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:18:52.321Z,1594405132.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:19:23.465Z,1594405163.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:19:23.679Z,1594405163.679 [NAL9602](INFO): SBD MO Status=2, MOMSN=63499, MT Status=2, MTMSN=0 2020-07-10T18:19:23.679Z,1594405163.679 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:19:54.589Z,1594405194.589 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:19:59.719Z,1594405199.719 [NAL9602](INFO): SBD MO Status=2, MOMSN=63499, MT Status=2, MTMSN=0 2020-07-10T18:19:59.719Z,1594405199.719 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-07-10T18:20:16.789Z,1594405216.789 [NAL9602](INFO): SBD MO Status=1, MOMSN=63499, MT Status=0, MTMSN=0 2020-07-10T18:20:16.837Z,1594405216.837 [NAL9602](INFO): Sent 321 bytes from file Logs/20200710T175141/Express0005.lzma 2020-07-10T18:20:16.838Z,1594405216.838 [NAL9602](INFO): Packets left to send: 0 2020-07-10T18:20:25.713Z,1594405225.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-07-10T18:20:26.135Z,1594405226.135 [NAL9602](INFO): SBD MO Status=1, MOMSN=63500, MT Status=0, MTMSN=0 2020-07-10T18:20:26.181Z,1594405226.181 [NAL9602](INFO): Sent 175 bytes from file Logs/20200710T175141/Express0008.lzma 2020-07-10T18:20:26.181Z,1594405226.181 [NAL9602](INFO): Packets left to send: 0 2020-07-10T18:20:36.634Z,1594405236.634 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=63501, MT Status=1, MTMSN=2258 2020-07-10T18:20:37.129Z,1594405237.129 [NAL9602](INFO): Received command:restart logs