2020-06-08T17:47:06.387Z,1591638426.387 [Supervisor](DEBUG): Initializing supervisor. 2020-06-08T17:47:06.390Z,1591638426.390 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-06-08T17:47:06.391Z,1591638426.391 [SyncHandler](INFO): Protected caller Thread ID is 2372 2020-06-08T17:47:06.392Z,1591638426.392 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-06-08T17:47:06.392Z,1591638426.392 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-06-08T17:47:06.393Z,1591638426.393 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2373 2020-06-08T17:47:06.396Z,1591638426.396 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-06-08T17:47:06.409Z,1591638426.409 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-06-08T17:47:06.410Z,1591638426.410 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-06-08T17:47:06.410Z,1591638426.410 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2374 2020-06-08T17:47:06.411Z,1591638426.411 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-06-08T17:47:06.412Z,1591638426.412 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-06-08T17:47:06.413Z,1591638426.413 [logger ThreadHandler](INFO): Protected caller Thread ID is 2375 2020-06-08T17:47:06.415Z,1591638426.415 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-06-08T17:47:06.415Z,1591638426.415 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-06-08T17:47:06.417Z,1591638426.417 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-06-08T17:47:06.618Z,1591638426.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-06-08T17:47:06.619Z,1591638426.619 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-06-08T17:47:06.696Z,1591638426.696 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-06-08T17:47:07.106Z,1591638427.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-06-08T17:47:07.107Z,1591638427.107 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-06-08T17:47:07.407Z,1591638427.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-06-08T17:47:07.408Z,1591638427.408 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-06-08T17:47:07.501Z,1591638427.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-06-08T17:47:07.501Z,1591638427.501 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-06-08T17:47:07.780Z,1591638427.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-06-08T17:47:07.781Z,1591638427.781 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-06-08T17:47:07.964Z,1591638427.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-06-08T17:47:07.965Z,1591638427.965 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-06-08T17:47:08.409Z,1591638428.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-06-08T17:47:08.409Z,1591638428.409 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-06-08T17:47:08.509Z,1591638428.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-06-08T17:47:08.509Z,1591638428.509 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-06-08T17:47:08.607Z,1591638428.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-06-08T17:47:08.608Z,1591638428.608 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-06-08T17:47:09.232Z,1591638429.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-06-08T17:47:09.232Z,1591638429.232 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-06-08T17:47:09.643Z,1591638429.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-06-08T17:47:09.643Z,1591638429.643 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-06-08T17:47:09.823Z,1591638429.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-06-08T17:47:09.824Z,1591638429.824 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-06-08T17:47:09.966Z,1591638429.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-06-08T17:47:09.967Z,1591638429.967 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-06-08T17:47:10.109Z,1591638430.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-06-08T17:47:10.109Z,1591638430.109 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-06-08T17:47:10.310Z,1591638430.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-06-08T17:47:10.312Z,1591638430.312 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2020-06-08T17:47:10.313Z,1591638430.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2020-06-08T17:47:10.400Z,1591638430.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2020-06-08T17:47:10.497Z,1591638430.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2020-06-08T17:47:10.641Z,1591638430.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2020-06-08T17:47:10.884Z,1591638430.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2020-06-08T17:47:11.038Z,1591638431.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2020-06-08T17:47:11.140Z,1591638431.140 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2020-06-08T17:47:11.393Z,1591638431.393 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2020-06-08T17:47:11.468Z,1591638431.468 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2020-06-08T17:47:11.626Z,1591638431.626 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2020-06-08T17:47:11.767Z,1591638431.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2020-06-08T17:47:11.880Z,1591638431.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2020-06-08T17:47:12.108Z,1591638432.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-06-08T17:47:12.110Z,1591638432.110 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2020-06-08T17:47:12.110Z,1591638432.110 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-06-08T17:47:12.115Z,1591638432.115 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-06-08T17:47:12.166Z,1591638432.166 [VerticalControl](DEBUG): Construct VerticalControl. 2020-06-08T17:47:12.279Z,1591638432.279 [VerticalControl] Loaded 2020-06-08T17:47:12.279Z,1591638432.279 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-06-08T17:47:12.280Z,1591638432.280 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-06-08T17:47:12.360Z,1591638432.360 [HorizontalControl] Loaded 2020-06-08T17:47:12.361Z,1591638432.361 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-06-08T17:47:12.361Z,1591638432.361 [SpeedControl](DEBUG): Construct SpeedControl. 2020-06-08T17:47:12.367Z,1591638432.367 [SpeedControl] Loaded 2020-06-08T17:47:12.367Z,1591638432.367 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-06-08T17:47:12.368Z,1591638432.368 [LoopControl](DEBUG): Construct LoopControl. 2020-06-08T17:47:12.368Z,1591638432.368 [LoopControl] Loaded 2020-06-08T17:47:12.369Z,1591638432.369 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-06-08T17:47:12.369Z,1591638432.369 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-06-08T17:47:12.369Z,1591638432.369 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-06-08T17:47:12.388Z,1591638432.388 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-06-08T17:47:12.389Z,1591638432.389 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-06-08T17:47:12.488Z,1591638432.488 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-06-08T17:47:12.489Z,1591638432.489 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-06-08T17:47:12.617Z,1591638432.617 [BuoyancyServo] Loaded 2020-06-08T17:47:12.617Z,1591638432.617 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-06-08T17:47:12.628Z,1591638432.628 [ElevatorServo] Loaded 2020-06-08T17:47:12.628Z,1591638432.628 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-06-08T17:47:12.639Z,1591638432.639 [MassServo] Loaded 2020-06-08T17:47:12.639Z,1591638432.639 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-06-08T17:47:12.650Z,1591638432.650 [RudderServo] Loaded 2020-06-08T17:47:12.650Z,1591638432.650 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-06-08T17:47:12.661Z,1591638432.661 [ThrusterServo] Loaded 2020-06-08T17:47:12.661Z,1591638432.661 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-06-08T17:47:12.662Z,1591638432.662 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-06-08T17:47:12.662Z,1591638432.662 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-06-08T17:47:12.768Z,1591638432.768 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-06-08T17:47:12.768Z,1591638432.768 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-06-08T17:47:12.789Z,1591638432.789 [NavChart] Loaded 2020-06-08T17:47:12.789Z,1591638432.789 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-06-08T17:47:12.793Z,1591638432.793 [UniversalFixResidualReporter] Loaded 2020-06-08T17:47:12.793Z,1591638432.793 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-06-08T17:47:12.794Z,1591638432.794 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-06-08T17:47:12.794Z,1591638432.794 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-06-08T17:47:12.876Z,1591638432.876 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-06-08T17:47:12.877Z,1591638432.877 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-06-08T17:47:13.162Z,1591638433.162 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-06-08T17:47:13.167Z,1591638433.167 [AHRS_M2](INFO): created writer for : platform_orientation 2020-06-08T17:47:13.168Z,1591638433.168 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-06-08T17:47:13.174Z,1591638433.174 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-06-08T17:47:13.174Z,1591638433.174 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-06-08T17:47:13.179Z,1591638433.179 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-06-08T17:47:13.180Z,1591638433.180 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-06-08T17:47:13.185Z,1591638433.185 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-06-08T17:47:13.258Z,1591638433.258 [AHRS_M2] Loaded 2020-06-08T17:47:13.259Z,1591638433.259 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-06-08T17:47:13.338Z,1591638433.338 [DataOverHttps] Loaded 2020-06-08T17:47:13.338Z,1591638433.338 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-06-08T17:47:13.339Z,1591638433.339 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408924E0 2020-06-08T17:47:13.339Z,1591638433.339 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2460 2020-06-08T17:47:13.353Z,1591638433.353 [Depth_Keller] Loaded 2020-06-08T17:47:13.353Z,1591638433.353 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-06-08T17:47:13.358Z,1591638433.358 [DropWeight] Loaded 2020-06-08T17:47:13.358Z,1591638433.358 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-06-08T17:47:13.457Z,1591638433.457 [NAL9602] Loaded 2020-06-08T17:47:13.457Z,1591638433.457 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-06-08T17:47:13.504Z,1591638433.504 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-06-08T17:47:13.505Z,1591638433.505 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-06-08T17:47:13.505Z,1591638433.505 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-06-08T17:47:13.506Z,1591638433.506 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-06-08T17:47:13.506Z,1591638433.506 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-06-08T17:47:13.507Z,1591638433.507 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-06-08T17:47:13.507Z,1591638433.507 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-06-08T17:47:13.508Z,1591638433.508 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-06-08T17:47:13.508Z,1591638433.508 [Onboard] Loaded 2020-06-08T17:47:13.508Z,1591638433.508 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-06-08T17:47:13.509Z,1591638433.509 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0 2020-06-08T17:47:13.510Z,1591638433.510 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2461 2020-06-08T17:47:13.513Z,1591638433.513 [Radio_Surface] Loaded 2020-06-08T17:47:13.513Z,1591638433.513 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-06-08T17:47:13.514Z,1591638433.514 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0 2020-06-08T17:47:13.515Z,1591638433.515 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2462 2020-06-08T17:47:13.650Z,1591638433.650 [DAT] Loaded 2020-06-08T17:47:13.650Z,1591638433.650 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-06-08T17:47:15.189Z,1591638435.189 [BPC1] Loaded 2020-06-08T17:47:15.189Z,1591638435.189 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-06-08T17:47:15.190Z,1591638435.190 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-06-08T17:47:15.190Z,1591638435.190 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-06-08T17:47:15.230Z,1591638435.230 [DepthRateCalculator] Loaded 2020-06-08T17:47:15.231Z,1591638435.231 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-06-08T17:47:15.236Z,1591638435.236 [PitchRateCalculator] Loaded 2020-06-08T17:47:15.236Z,1591638435.236 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-06-08T17:47:15.248Z,1591638435.248 [SpeedCalculator] Loaded 2020-06-08T17:47:15.248Z,1591638435.248 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-06-08T17:47:15.269Z,1591638435.269 [TempGradientCalculator] Loaded 2020-06-08T17:47:15.269Z,1591638435.269 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-06-08T17:47:15.274Z,1591638435.274 [YawRateCalculator] Loaded 2020-06-08T17:47:15.275Z,1591638435.275 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-06-08T17:47:15.305Z,1591638435.305 [ElevatorOffsetCalculator] Loaded 2020-06-08T17:47:15.305Z,1591638435.305 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-06-08T17:47:15.305Z,1591638435.305 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-06-08T17:47:15.306Z,1591638435.306 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-06-08T17:47:15.499Z,1591638435.499 [CANONSampler] Loaded 2020-06-08T17:47:15.499Z,1591638435.499 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2020-06-08T17:47:15.508Z,1591638435.508 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-06-08T17:47:15.513Z,1591638435.513 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-06-08T17:47:15.514Z,1591638435.514 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-06-08T17:47:15.519Z,1591638435.519 [CTD_Seabird](INFO): created writer for : depth 2020-06-08T17:47:15.519Z,1591638435.519 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-06-08T17:47:15.524Z,1591638435.524 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-06-08T17:47:15.525Z,1591638435.525 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-06-08T17:47:15.530Z,1591638435.530 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-06-08T17:47:15.531Z,1591638435.531 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-06-08T17:47:15.536Z,1591638435.536 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-06-08T17:47:15.536Z,1591638435.536 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-06-08T17:47:15.542Z,1591638435.542 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-06-08T17:47:15.542Z,1591638435.542 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-06-08T17:47:15.548Z,1591638435.548 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-06-08T17:47:15.548Z,1591638435.548 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water 2020-06-08T17:47:15.553Z,1591638435.553 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water 2020-06-08T17:47:15.554Z,1591638435.554 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq 2020-06-08T17:47:15.554Z,1591638435.554 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq 2020-06-08T17:47:15.587Z,1591638435.587 [CTD_Seabird] Loaded 2020-06-08T17:47:15.587Z,1591638435.587 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-06-08T17:47:15.588Z,1591638435.588 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409FE4E0 2020-06-08T17:47:15.589Z,1591638435.589 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2463 2020-06-08T17:47:15.604Z,1591638435.604 [PAR_Licor] Loaded 2020-06-08T17:47:15.604Z,1591638435.604 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-06-08T17:47:15.610Z,1591638435.610 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-06-08T17:47:15.610Z,1591638435.610 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-06-08T17:47:15.615Z,1591638435.615 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-06-08T17:47:15.615Z,1591638435.615 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-06-08T17:47:15.619Z,1591638435.619 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-06-08T17:47:15.619Z,1591638435.619 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-06-08T17:47:15.624Z,1591638435.624 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-06-08T17:47:15.624Z,1591638435.624 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-06-08T17:47:15.628Z,1591638435.628 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-06-08T17:47:15.628Z,1591638435.628 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-06-08T17:47:15.633Z,1591638435.633 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-06-08T17:47:15.633Z,1591638435.633 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-06-08T17:47:15.637Z,1591638435.637 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-06-08T17:47:15.638Z,1591638435.638 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-06-08T17:47:15.642Z,1591638435.642 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-06-08T17:47:15.647Z,1591638435.647 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-06-08T17:47:15.647Z,1591638435.647 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-06-08T17:47:15.647Z,1591638435.647 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-06-08T17:47:15.652Z,1591638435.652 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-06-08T17:47:15.652Z,1591638435.652 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-06-08T17:47:15.656Z,1591638435.656 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-06-08T17:47:15.656Z,1591638435.656 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-06-08T17:47:15.661Z,1591638435.661 [WetLabsBB2FL] Loaded 2020-06-08T17:47:15.661Z,1591638435.661 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-06-08T17:47:15.662Z,1591638435.662 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A2E4E0 2020-06-08T17:47:15.662Z,1591638435.662 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2464 2020-06-08T17:47:15.663Z,1591638435.663 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-06-08T17:47:15.664Z,1591638435.664 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-06-08T17:47:15.847Z,1591638435.847 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-06-08T17:47:15.847Z,1591638435.847 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-06-08T17:47:16.212Z,1591638436.212 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-06-08T17:47:16.212Z,1591638436.212 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-06-08T17:47:16.347Z,1591638436.347 [SBIT](DEBUG): Construct Startup Built In Test. 2020-06-08T17:47:16.358Z,1591638436.358 [SBIT] Loaded 2020-06-08T17:47:16.358Z,1591638436.358 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-06-08T17:47:16.359Z,1591638436.359 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-06-08T17:47:16.371Z,1591638436.371 [IBIT] Loaded 2020-06-08T17:47:16.371Z,1591638436.371 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-06-08T17:47:16.374Z,1591638436.374 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-06-08T17:47:16.514Z,1591638436.514 [CBIT] Loaded 2020-06-08T17:47:16.514Z,1591638436.514 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-06-08T17:47:16.514Z,1591638436.514 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-06-08T17:47:16.515Z,1591638436.515 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-06-08T17:47:16.591Z,1591638436.591 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-06-08T17:47:16.595Z,1591638436.595 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-06-08T17:47:16.596Z,1591638436.596 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-06-08T17:47:16.602Z,1591638436.602 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-06-08T17:47:16.603Z,1591638436.603 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5F4E0 2020-06-08T17:47:16.604Z,1591638436.604 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2465 2020-06-08T17:47:16.608Z,1591638436.608 [Supervisor](INFO): Main Thread ID is 2104 2020-06-08T17:47:16.608Z,1591638436.608 [Supervisor](DEBUG): Running supervisor. 2020-06-08T17:47:16.609Z,1591638436.609 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2466 2020-06-08T17:47:16.611Z,1591638436.611 [controlThread ThreadHandler](INFO): Handler Thread ID is 2467 2020-06-08T17:47:16.612Z,1591638436.612 [controlThread](DEBUG): Initializing ControlThread 2020-06-08T17:47:16.613Z,1591638436.613 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-06-08T17:47:16.614Z,1591638436.614 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-06-08T17:47:16.615Z,1591638436.615 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-06-08T17:47:16.615Z,1591638436.615 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-06-08T17:47:16.617Z,1591638436.617 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-06-08T17:47:16.618Z,1591638436.618 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-06-08T17:47:16.621Z,1591638436.621 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-06-08T17:47:16.621Z,1591638436.621 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-06-08T17:47:16.621Z,1591638436.621 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-06-08T17:47:16.622Z,1591638436.622 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-06-08T17:47:16.622Z,1591638436.622 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-06-08T17:47:16.622Z,1591638436.622 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-06-08T17:47:16.627Z,1591638436.627 [SBIT](INFO): Initialize SBIT Component. 2020-06-08T17:47:16.628Z,1591638436.628 [SBIT](IMPORTANT): git: 2020-06-05 2020-06-08T17:47:16.628Z,1591638436.628 [SBIT](INFO): git hash: c12bfff2bdf9b929af60ba43c4eb841aed31d072 2020-06-08T17:47:16.628Z,1591638436.628 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-06-08T17:47:16.629Z,1591638436.629 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 2020-06-08T17:47:16.630Z,1591638436.630 [SBIT](INFO): Beginning SBIT in 76.000000 seconds. 2020-06-08T17:47:16.631Z,1591638436.631 [IBIT](INFO): Initialize IBIT Component. 2020-06-08T17:47:16.632Z,1591638436.632 [CBIT](DEBUG): Initialize CBIT Component. 2020-06-08T17:47:16.633Z,1591638436.633 [logger ThreadHandler](INFO): Handler Thread ID is 2468 2020-06-08T17:47:16.643Z,1591638436.643 [CBIT](DEBUG): Initialized mux pins. 2020-06-08T17:47:16.643Z,1591638436.643 [CBIT](DEBUG): Initializing the watchdog timer. 2020-06-08T17:47:16.651Z,1591638436.651 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2469 2020-06-08T17:47:16.652Z,1591638436.652 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-06-08T17:47:16.663Z,1591638436.663 [Onboard ThreadHandler](INFO): Handler Thread ID is 2470 2020-06-08T17:47:16.667Z,1591638436.667 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-06-08T17:47:16.668Z,1591638436.668 [CBIT](DEBUG): Initializing heartbeat. 2020-06-08T17:47:16.680Z,1591638436.680 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2471 2020-06-08T17:47:16.691Z,1591638436.691 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2472 2020-06-08T17:47:16.692Z,1591638436.692 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-06-08T17:47:16.695Z,1591638436.695 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-06-08T17:47:16.696Z,1591638436.696 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2474 2020-06-08T17:47:16.697Z,1591638436.697 [WetLabsBB2FL](INFO): Powering down 2020-06-08T17:47:16.727Z,1591638436.727 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2475 2020-06-08T17:47:16.730Z,1591638436.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-06-08T17:47:16.731Z,1591638436.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-06-08T17:47:16.731Z,1591638436.731 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-06-08T17:47:16.731Z,1591638436.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-06-08T17:47:16.731Z,1591638436.731 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-06-08T17:47:16.731Z,1591638436.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-06-08T17:47:16.732Z,1591638436.732 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-06-08T17:47:16.732Z,1591638436.732 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-06-08T17:47:16.732Z,1591638436.732 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-06-08T17:47:16.732Z,1591638436.732 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-06-08T17:47:16.732Z,1591638436.732 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-06-08T17:47:16.733Z,1591638436.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-06-08T17:47:16.733Z,1591638436.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-06-08T17:47:16.733Z,1591638436.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-06-08T17:47:16.733Z,1591638436.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-06-08T17:47:16.733Z,1591638436.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-06-08T17:47:16.739Z,1591638436.739 [CBIT](DEBUG): Deactivating GF circuits. 2020-06-08T17:47:16.739Z,1591638436.739 [CBIT](DEBUG): Deactivating emergency mode. 2020-06-08T17:47:16.775Z,1591638436.775 [CBIT](DEBUG): Backplane powered. 2020-06-08T17:47:16.776Z,1591638436.776 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-06-08T17:47:16.786Z,1591638436.786 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-06-08T17:47:16.797Z,1591638436.797 [MissionManager](DEBUG): 2020-06-08T17:47:16.797Z,1591638436.797 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-06-08T17:47:16.881Z,1591638436.881 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-06-08T17:47:16.882Z,1591638436.882 [Default:A.Wait](DEBUG): Construct Wait. 2020-06-08T17:47:16.884Z,1591638436.884 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-06-08T17:47:16.923Z,1591638436.923 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-06-08T17:47:16.926Z,1591638436.926 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-06-08T17:47:16.947Z,1591638436.947 [Default:E.Execute](DEBUG): Construct Execute. 2020-06-08T17:47:16.964Z,1591638436.964 [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-06-08T17:47:16.968Z,1591638436.968 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,DAT,BPC1,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-06-08T17:47:16.983Z,1591638436.983 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-06-08T17:47:17.020Z,1591638437.020 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2020-06-08T17:47:17.023Z,1591638437.023 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-06-08T17:47:17.023Z,1591638437.023 [DAT](INFO): Powering up 2020-06-08T17:47:17.024Z,1591638437.024 [DAT](DEBUG): Initializing DAT. 2020-06-08T17:47:17.083Z,1591638437.083 [Radio_Surface](INFO): Powering up 2020-06-08T17:47:17.084Z,1591638437.084 [CANONSampler](INFO): Powering down 2020-06-08T17:47:17.111Z,1591638437.111 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-06-08T17:47:17.241Z,1591638437.241 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-06-08T17:47:17.264Z,1591638437.264 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-06-08T17:47:17.271Z,1591638437.271 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-06-08T17:47:17.272Z,1591638437.272 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-06-08T17:47:17.279Z,1591638437.279 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-06-08T17:47:17.280Z,1591638437.280 [MassServo](DEBUG): Initializing EZServoServo. 2020-06-08T17:47:17.287Z,1591638437.287 [MassServo](DEBUG): Initializing MassServo. 2020-06-08T17:47:17.288Z,1591638437.288 [RudderServo](DEBUG): Initializing EZServoServo. 2020-06-08T17:47:17.295Z,1591638437.295 [RudderServo](DEBUG): Initializing RudderServo. 2020-06-08T17:47:17.296Z,1591638437.296 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-06-08T17:47:17.303Z,1591638437.303 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-06-08T17:47:17.489Z,1591638437.489 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-06-08T17:47:17.489Z,1591638437.489 [DropWeight] Hardware Fault, FailCount= 1 2020-06-08T17:47:17.489Z,1591638437.489 [DropWeight](ERROR): Hardware Fault 2020-06-08T17:47:17.574Z,1591638437.574 [CommandLine](FAULT): Scheduling is paused 2020-06-08T17:47:17.574Z,1591638437.574 [CBIT](INFO): Critical error at 20200608T174717 2020-06-08T17:47:17.575Z,1591638437.575 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-06-08T17:47:17.577Z,1591638437.577 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-06-08T17:47:17.578Z,1591638437.578 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-06-08T17:47:18.115Z,1591638438.115 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-06-08T17:47:18.115Z,1591638438.115 [RudderServo](FAULT): Rudder failed to initialize 2020-06-08T17:47:18.115Z,1591638438.115 [RudderServo] Communications Fault, FailCount= 1 2020-06-08T17:47:18.115Z,1591638438.115 [RudderServo](ERROR): Communications Fault 2020-06-08T17:47:18.252Z,1591638438.252 [CBIT](INFO): Critical error at 20200608T174717 2020-06-08T17:47:18.254Z,1591638438.254 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-06-08T17:47:18.452Z,1591638438.452 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-06-08T17:47:18.452Z,1591638438.452 [RudderServo](INFO): Powering down 2020-06-08T17:47:19.090Z,1591638439.090 [RudderServo](DEBUG): Initializing EZServoServo. 2020-06-08T17:47:19.208Z,1591638439.208 [RudderServo](DEBUG): Initializing RudderServo. 2020-06-08T17:47:19.212Z,1591638439.212 [CBIT](INFO): Clearing failed state for component RudderServo 2020-06-08T17:47:19.212Z,1591638439.212 [RudderServo] No Fault, FailCount= 1 2020-06-08T17:47:27.584Z,1591638447.584 [DAT](DEBUG): 2020-06-08T17:47:27.585Z,1591638447.585 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-06-08T17:47:28.785Z,1591638448.785 [DAT](DEBUG): MF Frequency Band 2020-06-08T17:47:28.786Z,1591638448.786 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21 2020-06-08T17:47:28.786Z,1591638448.786 [DAT](DEBUG): Jan 3 2000 23:15:42 2020-06-08T17:47:29.593Z,1591638449.593 [DAT](DEBUG): Features enabled [Bearing] 2020-06-08T17:47:29.594Z,1591638449.594 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-06-08T17:47:29.594Z,1591638449.594 [DAT](INFO): commRate: 800 2020-06-08T17:47:29.996Z,1591638449.996 [DAT](INFO): entering command mode 2020-06-08T17:47:30.400Z,1591638450.400 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-08T17:47:30.401Z,1591638450.401 [DAT](INFO): command mode acknowledged 2020-06-08T17:47:30.401Z,1591638450.401 [DAT](INFO): setting verbose to 3 2020-06-08T17:47:30.804Z,1591638450.804 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-06-08T17:47:30.805Z,1591638450.805 [DAT](INFO): set verbose to 3 2020-06-08T17:47:31.216Z,1591638451.216 [DAT](INFO): setting transmit power to 8 2020-06-08T17:47:31.612Z,1591638451.612 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-06-08T17:47:32.016Z,1591638452.016 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-06-08T17:47:32.017Z,1591638452.017 [DAT](INFO): set transmit power to 8 2020-06-08T17:47:32.420Z,1591638452.420 [DAT](INFO): setting local address to 7 2020-06-08T17:47:32.825Z,1591638452.825 [DAT](DEBUG): checking for local address setting acknowledgment 2020-06-08T17:47:33.228Z,1591638453.228 [DAT](DEBUG): checking for local address setting acknowledgment 2020-06-08T17:47:33.229Z,1591638453.229 [DAT](INFO): set local address to 7 2020-06-08T17:47:43.732Z,1591638463.732 [NAL9602](INFO): Powering up NAL9602 2020-06-08T17:47:54.640Z,1591638474.640 [NAL9602](INFO): NAL9602 initialized 2020-06-08T17:48:31.060Z,1591638511.060 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T17:48:33.034Z,1591638513.034 [SBIT](IMPORTANT): Beginning Startup BIT 2020-06-08T17:48:33.038Z,1591638513.038 [CBIT](IMPORTANT): Beginning ground fault scan 2020-06-08T17:48:44.468Z,1591638524.468 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.003330 CHAN A1 (24V): 0.000378 CHAN A2 (12V): -0.001988 CHAN A3 (5V): -0.001484 CHAN B0 (3.3V): 4.766929 CHAN B1 (3.15aV): -0.000120 CHAN B2 (3.15bV): -0.000055 CHAN B3 (GND): 0.000053 OPEN: -0.000612 Full Scale Calc: 4.765 mA, -1.589 mA 2020-06-08T17:49:26.662Z,1591638566.662 [SBIT](IMPORTANT): SBIT PASSED 2020-06-08T17:49:26.663Z,1591638566.663 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-06-08T17:49:26.664Z,1591638566.664 [SBIT](IMPORTANT): CBIT.gf24Offset=154 microampere; 2020-06-08T17:49:26.664Z,1591638566.664 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-06-08T17:49:26.664Z,1591638566.664 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=264.728883 cubic_centimeter; 2020-06-08T17:49:26.664Z,1591638566.664 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.226688 millimeter; 2020-06-08T17:49:27.053Z,1591638567.053 [MissionManager](IMPORTANT): Started mission Startup 2020-06-08T17:49:27.053Z,1591638567.053 [Startup] Running Loop=1 2020-06-08T17:49:27.053Z,1591638567.053 [Startup](DEBUG): Aggregate::initialize Startup 2020-06-08T17:49:27.053Z,1591638567.053 [Startup:A.GoToSurface] Running Loop=1 2020-06-08T17:49:27.053Z,1591638567.053 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-06-08T17:49:27.054Z,1591638567.054 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-06-08T17:49:27.054Z,1591638567.054 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-06-08T17:49:27.055Z,1591638567.055 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-06-08T17:49:27.055Z,1591638567.055 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-06-08T17:49:27.056Z,1591638567.056 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-06-08T17:49:27.057Z,1591638567.057 [Startup:StartupSatComms] Running Loop=1 2020-06-08T17:49:27.057Z,1591638567.057 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-06-08T17:49:27.057Z,1591638567.057 [Startup:StartupSatComms:A] Running Loop=1 2020-06-08T17:49:27.449Z,1591638567.449 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-06-08T17:49:37.998Z,1591638577.998 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T17:49:59.474Z,1591638599.474 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005077 2020-06-08T17:50:16.728Z,1591638616.728 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-06-08T17:50:16.728Z,1591638616.728 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T17:50:16.761Z,1591638616.761 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T17:50:17.180Z,1591638617.180 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T17:50:17.195Z,1591638617.195 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T17:50:17.195Z,1591638617.195 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-06-08T17:50:27.246Z,1591638627.246 [Startup:StartupSatComms:A](INFO): Timed out from 2020-06-08T17:49:27.1Z 2020-06-08T17:50:27.246Z,1591638627.246 [Startup:StartupSatComms:A] Stopped 2020-06-08T17:50:27.246Z,1591638627.246 [Startup:StartupSatComms:B] Running Loop=1 2020-06-08T17:50:27.664Z,1591638627.664 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-06-08T17:50:27.979Z,1591638627.979 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20200608T173803/Courier0004.lzma 2020-06-08T17:50:28.837Z,1591638628.837 [DataOverHttps](INFO): Moved sent file to Logs/20200608T173803/Courier0004.lzma.bak 2020-06-08T17:50:28.837Z,1591638628.837 [DataOverHttps](INFO): SBD MOMSN=12372384 2020-06-08T17:50:44.299Z,1591638644.299 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20200608T174706/Courier0000.lzma 2020-06-08T17:50:45.300Z,1591638645.300 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0000.lzma.bak 2020-06-08T17:50:45.301Z,1591638645.301 [DataOverHttps](INFO): SBD MOMSN=12372386 2020-06-08T17:51:00.720Z,1591638660.720 [DataOverHttps](INFO): Sending 571 bytes from file Logs/20200608T173803/Express0005.lzma 2020-06-08T17:51:01.724Z,1591638661.724 [DataOverHttps](INFO): Moved sent file to Logs/20200608T173803/Express0005.lzma.bak 2020-06-08T17:51:01.724Z,1591638661.724 [DataOverHttps](INFO): SBD MOMSN=12372391 2020-06-08T17:51:17.272Z,1591638677.272 [DataOverHttps](INFO): Sending 752 bytes from file Logs/20200608T174706/Express0001.lzma 2020-06-08T17:51:18.272Z,1591638678.272 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0001.lzma.bak 2020-06-08T17:51:18.273Z,1591638678.273 [DataOverHttps](INFO): SBD MOMSN=12372414 2020-06-08T17:51:19.809Z,1591638679.809 [Startup:StartupSatComms:B] Stopped 2020-06-08T17:51:19.809Z,1591638679.809 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-06-08T17:51:19.809Z,1591638679.809 [Startup:StartupSatComms] Stopped 2020-06-08T17:51:19.809Z,1591638679.809 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-06-08T17:51:19.810Z,1591638679.810 [Startup](INFO): Completed Startup 2020-06-08T17:51:19.810Z,1591638679.810 [MissionManager](INFO): Startup is completed. 2020-06-08T17:51:19.810Z,1591638679.810 [MissionManager](INFO): Uninitializing Mission Startup 2020-06-08T17:51:19.810Z,1591638679.810 [Startup] Stopped 2020-06-08T17:51:19.810Z,1591638679.810 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-06-08T17:51:19.810Z,1591638679.810 [Startup:A.GoToSurface] Stopped 2020-06-08T17:51:19.810Z,1591638679.810 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-06-08T17:51:20.196Z,1591638680.196 [MissionManager](IMPORTANT): Started mission Default 2020-06-08T17:51:20.197Z,1591638680.197 [Default] Running Loop=1 2020-06-08T17:51:20.197Z,1591638680.197 [Default](DEBUG): Aggregate::initialize Default 2020-06-08T17:51:20.197Z,1591638680.197 [Default:B.GoToSurface] Running Loop=1 2020-06-08T17:51:20.197Z,1591638680.197 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-06-08T17:51:20.197Z,1591638680.197 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-06-08T17:51:20.197Z,1591638680.197 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-06-08T17:51:20.198Z,1591638680.198 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-06-08T17:51:20.198Z,1591638680.198 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-06-08T17:51:20.198Z,1591638680.198 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-06-08T17:51:20.199Z,1591638680.199 [Default:A.Wait] Running Loop=1 2020-06-08T17:51:20.199Z,1591638680.199 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-06-08T17:51:26.733Z,1591638686.733 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T17:51:33.545Z,1591638693.545 [Default:A.Wait](INFO): Done Waiting. 2020-06-08T17:51:33.545Z,1591638693.545 [Default:A.Wait] Stopped 2020-06-08T17:51:33.545Z,1591638693.545 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T17:51:33.952Z,1591638693.952 [Default:CheckIn] Running Loop=1 2020-06-08T17:51:33.952Z,1591638693.952 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T17:51:33.953Z,1591638693.953 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T17:51:34.329Z,1591638694.329 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-06-08T17:51:41.196Z,1591638701.196 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2020-06-08T17:52:54.820Z,1591638774.820 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T17:52:57.945Z,1591638777.945 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-06-08T17:53:06.096Z,1591638786.096 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T17:53:17.772Z,1591638797.772 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-06-08T17:53:17.772Z,1591638797.772 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T17:53:17.781Z,1591638797.781 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T17:53:18.179Z,1591638798.179 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T17:53:18.179Z,1591638798.179 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-06-08T17:54:20.835Z,1591638860.835 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T17:54:36.190Z,1591638876.190 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T17:54:50.346Z,1591638890.346 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T17:55:20.594Z,1591638920.594 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-06-08T17:55:20.597Z,1591638920.597 [BPC1](INFO): Received data from all battery sticks. 2020-06-08T17:56:18.768Z,1591638978.768 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-06-08T17:56:18.768Z,1591638978.768 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T17:56:18.778Z,1591638978.778 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T17:56:19.183Z,1591638979.183 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T17:56:19.184Z,1591638979.184 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-06-08T17:56:34.122Z,1591638994.122 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T17:51:33.0Z 2020-06-08T17:56:34.122Z,1591638994.122 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T17:56:34.122Z,1591638994.122 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T17:56:34.521Z,1591638994.521 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-06-08T17:56:41.287Z,1591639001.287 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20200608T174706/Courier0004.lzma 2020-06-08T17:56:42.288Z,1591639002.288 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0004.lzma.bak 2020-06-08T17:56:42.289Z,1591639002.289 [DataOverHttps](INFO): SBD MOMSN=12372445 2020-06-08T17:56:57.619Z,1591639017.619 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20200608T174706/Express0005.lzma 2020-06-08T17:56:58.621Z,1591639018.621 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0005.lzma.bak 2020-06-08T17:56:58.621Z,1591639018.621 [DataOverHttps](INFO): SBD MOMSN=12372447 2020-06-08T17:56:59.968Z,1591639019.968 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T17:56:59.968Z,1591639019.968 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T17:56:59.968Z,1591639019.968 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T17:57:55.696Z,1591639075.696 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T17:57:55.696Z,1591639075.696 [NAL9602] Data Fault, FailCount= 1 2020-06-08T17:57:55.696Z,1591639075.696 [NAL9602](ERROR): Data Fault 2020-06-08T17:57:55.714Z,1591639075.714 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T17:57:56.100Z,1591639076.100 [NAL9602](INFO): Powering down 2020-06-08T17:57:56.942Z,1591639076.942 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T17:57:56.942Z,1591639076.942 [NAL9602] No Fault, FailCount= 1 2020-06-08T17:58:26.400Z,1591639106.400 [NAL9602](INFO): Powering up NAL9602 2020-06-08T17:58:37.315Z,1591639117.315 [NAL9602](INFO): NAL9602 initialized 2020-06-08T17:59:19.735Z,1591639159.735 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-06-08T17:59:19.735Z,1591639159.735 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T17:59:19.769Z,1591639159.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T17:59:20.148Z,1591639160.148 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T17:59:20.148Z,1591639160.148 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-06-08T18:02:00.552Z,1591639320.552 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T18:02:00.552Z,1591639320.552 [Default:CheckIn:C.Wait] Stopped 2020-06-08T18:02:00.552Z,1591639320.552 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T18:02:00.552Z,1591639320.552 [Default:CheckIn:D] Running Loop=1 2020-06-08T18:02:00.965Z,1591639320.965 [Default:CheckIn:D] Stopped 2020-06-08T18:02:00.965Z,1591639320.965 [Default:CheckIn:E] Running Loop=1 2020-06-08T18:02:01.357Z,1591639321.357 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.679468 min 2020-06-08T18:02:01.358Z,1591639321.358 [Default:CheckIn:E] Stopped 2020-06-08T18:02:01.358Z,1591639321.358 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T18:02:01.358Z,1591639321.358 [Default:CheckIn] Stopped 2020-06-08T18:02:01.358Z,1591639321.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T18:02:01.358Z,1591639321.358 [Default:CheckIn](INFO): Running loop #2 2020-06-08T18:02:01.358Z,1591639321.358 [Default:CheckIn] Running Loop=2 2020-06-08T18:02:01.358Z,1591639321.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T18:02:01.358Z,1591639321.358 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T18:02:17.537Z,1591639337.537 [CBIT](INFO): Clearing failed state for component DropWeight 2020-06-08T18:02:17.537Z,1591639337.537 [DropWeight] No Fault, FailCount= 1 2020-06-08T18:02:20.769Z,1591639340.769 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2020-06-08T18:02:20.769Z,1591639340.769 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:02:20.779Z,1591639340.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:02:21.185Z,1591639341.185 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:02:21.185Z,1591639341.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2020-06-08T18:05:21.757Z,1591639521.757 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2020-06-08T18:05:21.757Z,1591639521.757 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:05:21.766Z,1591639521.766 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:05:22.173Z,1591639522.173 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:05:22.173Z,1591639522.173 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2020-06-08T18:07:01.549Z,1591639621.549 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T18:02:01.4Z 2020-06-08T18:07:01.549Z,1591639621.549 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T18:07:01.549Z,1591639621.549 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T18:07:08.426Z,1591639628.426 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20200608T174706/Express0008.lzma 2020-06-08T18:07:09.428Z,1591639629.428 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0008.lzma.bak 2020-06-08T18:07:09.429Z,1591639629.429 [DataOverHttps](INFO): SBD MOMSN=12372477 2020-06-08T18:07:10.844Z,1591639630.844 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T18:07:10.844Z,1591639630.844 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T18:07:10.844Z,1591639630.844 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T18:08:22.764Z,1591639702.764 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2020-06-08T18:08:22.764Z,1591639702.764 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:08:22.774Z,1591639702.774 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:08:23.176Z,1591639703.176 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:08:23.176Z,1591639703.176 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2020-06-08T18:08:39.304Z,1591639719.304 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T18:08:39.304Z,1591639719.304 [NAL9602] Data Fault, FailCount= 2 2020-06-08T18:08:39.304Z,1591639719.304 [NAL9602](ERROR): Data Fault 2020-06-08T18:08:39.351Z,1591639719.351 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T18:08:39.704Z,1591639719.704 [NAL9602](INFO): Powering down 2020-06-08T18:08:40.536Z,1591639720.536 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T18:08:40.536Z,1591639720.536 [NAL9602] No Fault, FailCount= 2 2020-06-08T18:09:10.004Z,1591639750.004 [NAL9602](INFO): Powering up NAL9602 2020-06-08T18:09:20.917Z,1591639760.917 [NAL9602](INFO): NAL9602 initialized 2020-06-08T18:11:23.740Z,1591639883.740 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2020-06-08T18:11:23.740Z,1591639883.740 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:11:23.750Z,1591639883.750 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:11:24.156Z,1591639884.156 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:11:24.156Z,1591639884.156 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2020-06-08T18:12:11.437Z,1591639931.437 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T18:12:11.437Z,1591639931.437 [Default:CheckIn:C.Wait] Stopped 2020-06-08T18:12:11.437Z,1591639931.437 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T18:12:11.438Z,1591639931.438 [Default:CheckIn:D] Running Loop=1 2020-06-08T18:12:11.824Z,1591639931.824 [Default:CheckIn:D] Stopped 2020-06-08T18:12:11.824Z,1591639931.824 [Default:CheckIn:E] Running Loop=1 2020-06-08T18:12:12.216Z,1591639932.216 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.860457 min 2020-06-08T18:12:12.217Z,1591639932.217 [Default:CheckIn:E] Stopped 2020-06-08T18:12:12.217Z,1591639932.217 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T18:12:12.217Z,1591639932.217 [Default:CheckIn] Stopped 2020-06-08T18:12:12.217Z,1591639932.217 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T18:12:12.217Z,1591639932.217 [Default:CheckIn](INFO): Running loop #3 2020-06-08T18:12:12.217Z,1591639932.217 [Default:CheckIn] Running Loop=3 2020-06-08T18:12:12.217Z,1591639932.217 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T18:12:12.217Z,1591639932.217 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T18:14:24.742Z,1591640064.742 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2020-06-08T18:14:24.742Z,1591640064.742 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:14:24.752Z,1591640064.752 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:14:25.171Z,1591640065.171 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:14:25.172Z,1591640065.172 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2020-06-08T18:17:12.394Z,1591640232.394 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T18:12:12.2Z 2020-06-08T18:17:12.394Z,1591640232.394 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T18:17:12.394Z,1591640232.394 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T18:17:20.547Z,1591640240.547 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200608T174706/Express0011.lzma 2020-06-08T18:17:21.549Z,1591640241.549 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0011.lzma.bak 2020-06-08T18:17:21.549Z,1591640241.549 [DataOverHttps](INFO): SBD MOMSN=12372595 2020-06-08T18:17:22.918Z,1591640242.918 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T18:17:22.918Z,1591640242.918 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T18:17:22.918Z,1591640242.918 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T18:17:25.751Z,1591640245.751 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2020-06-08T18:17:25.751Z,1591640245.751 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:17:25.769Z,1591640245.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:17:26.167Z,1591640246.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:17:26.167Z,1591640246.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2020-06-08T18:19:22.064Z,1591640362.064 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T18:19:22.064Z,1591640362.064 [NAL9602] Data Fault, FailCount= 3 2020-06-08T18:19:22.064Z,1591640362.064 [NAL9602](ERROR): Data Fault 2020-06-08T18:19:22.081Z,1591640362.081 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T18:19:22.472Z,1591640362.472 [NAL9602](INFO): Powering down 2020-06-08T18:19:23.321Z,1591640363.321 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T18:19:23.321Z,1591640363.321 [NAL9602] No Fault, FailCount= 3 2020-06-08T18:19:52.768Z,1591640392.768 [NAL9602](INFO): Powering up NAL9602 2020-06-08T18:20:03.672Z,1591640403.672 [NAL9602](INFO): NAL9602 initialized 2020-06-08T18:20:26.737Z,1591640426.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2020-06-08T18:20:26.737Z,1591640426.737 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:20:26.751Z,1591640426.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:20:27.163Z,1591640427.163 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:20:27.163Z,1591640427.163 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2020-06-08T18:22:23.481Z,1591640543.481 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T18:22:23.481Z,1591640543.481 [Default:CheckIn:C.Wait] Stopped 2020-06-08T18:22:23.481Z,1591640543.481 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T18:22:23.481Z,1591640543.481 [Default:CheckIn:D] Running Loop=1 2020-06-08T18:22:23.877Z,1591640543.877 [Default:CheckIn:D] Stopped 2020-06-08T18:22:23.877Z,1591640543.877 [Default:CheckIn:E] Running Loop=1 2020-06-08T18:22:24.294Z,1591640544.294 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.061344 min 2020-06-08T18:22:24.294Z,1591640544.294 [Default:CheckIn:E] Stopped 2020-06-08T18:22:24.294Z,1591640544.294 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T18:22:24.294Z,1591640544.294 [Default:CheckIn] Stopped 2020-06-08T18:22:24.295Z,1591640544.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T18:22:24.295Z,1591640544.295 [Default:CheckIn](INFO): Running loop #4 2020-06-08T18:22:24.295Z,1591640544.295 [Default:CheckIn] Running Loop=4 2020-06-08T18:22:24.295Z,1591640544.295 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T18:22:24.295Z,1591640544.295 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T18:23:27.726Z,1591640607.726 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2020-06-08T18:23:27.726Z,1591640607.726 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:23:27.735Z,1591640607.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:23:28.156Z,1591640608.156 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:23:28.156Z,1591640608.156 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2020-06-08T18:26:28.722Z,1591640788.722 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2020-06-08T18:26:28.722Z,1591640788.722 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:26:28.733Z,1591640788.733 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:26:29.133Z,1591640789.133 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:26:29.133Z,1591640789.133 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2020-06-08T18:27:24.449Z,1591640844.449 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T18:22:24.3Z 2020-06-08T18:27:24.449Z,1591640844.449 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T18:27:24.449Z,1591640844.449 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T18:27:31.371Z,1591640851.371 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200608T174706/Express0014.lzma 2020-06-08T18:27:32.373Z,1591640852.373 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0014.lzma.bak 2020-06-08T18:27:32.373Z,1591640852.373 [DataOverHttps](INFO): SBD MOMSN=12372711 2020-06-08T18:27:33.766Z,1591640853.766 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T18:27:33.766Z,1591640853.766 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T18:27:33.766Z,1591640853.766 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T18:29:29.702Z,1591640969.702 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2020-06-08T18:29:29.702Z,1591640969.702 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:29:29.712Z,1591640969.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:29:30.125Z,1591640970.125 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:29:30.125Z,1591640970.125 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2020-06-08T18:30:04.824Z,1591641004.824 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T18:30:04.824Z,1591641004.824 [NAL9602] Data Fault, FailCount= 4 2020-06-08T18:30:04.824Z,1591641004.824 [NAL9602](ERROR): Data Fault 2020-06-08T18:30:04.861Z,1591641004.861 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T18:30:05.232Z,1591641005.232 [NAL9602](INFO): Powering down 2020-06-08T18:30:06.065Z,1591641006.065 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T18:30:06.065Z,1591641006.065 [NAL9602] No Fault, FailCount= 4 2020-06-08T18:30:35.533Z,1591641035.533 [NAL9602](INFO): Powering up NAL9602 2020-06-08T18:30:46.444Z,1591641046.444 [NAL9602](INFO): NAL9602 initialized 2020-06-08T18:32:30.684Z,1591641150.684 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2020-06-08T18:32:30.684Z,1591641150.684 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:32:30.694Z,1591641150.694 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:32:31.132Z,1591641151.132 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:32:31.132Z,1591641151.132 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2020-06-08T18:32:34.333Z,1591641154.333 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T18:32:34.333Z,1591641154.333 [Default:CheckIn:C.Wait] Stopped 2020-06-08T18:32:34.333Z,1591641154.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T18:32:34.333Z,1591641154.333 [Default:CheckIn:D] Running Loop=1 2020-06-08T18:32:34.745Z,1591641154.745 [Default:CheckIn:D] Stopped 2020-06-08T18:32:34.745Z,1591641154.745 [Default:CheckIn:E] Running Loop=1 2020-06-08T18:32:35.145Z,1591641155.145 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.242476 min 2020-06-08T18:32:35.145Z,1591641155.145 [Default:CheckIn:E] Stopped 2020-06-08T18:32:35.145Z,1591641155.145 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T18:32:35.145Z,1591641155.145 [Default:CheckIn] Stopped 2020-06-08T18:32:35.145Z,1591641155.145 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T18:32:35.146Z,1591641155.146 [Default:CheckIn](INFO): Running loop #5 2020-06-08T18:32:35.146Z,1591641155.146 [Default:CheckIn] Running Loop=5 2020-06-08T18:32:35.146Z,1591641155.146 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T18:32:35.146Z,1591641155.146 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T18:35:31.684Z,1591641331.684 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2020-06-08T18:35:31.684Z,1591641331.684 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:35:31.694Z,1591641331.694 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:35:32.104Z,1591641332.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:35:32.104Z,1591641332.104 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2020-06-08T18:37:35.304Z,1591641455.304 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T18:32:35.1Z 2020-06-08T18:37:35.304Z,1591641455.304 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T18:37:35.304Z,1591641455.304 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T18:37:42.410Z,1591641462.410 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200608T174706/Express0017.lzma 2020-06-08T18:37:43.413Z,1591641463.413 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0017.lzma.bak 2020-06-08T18:37:43.413Z,1591641463.413 [DataOverHttps](INFO): SBD MOMSN=12372747 2020-06-08T18:37:44.589Z,1591641464.589 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T18:37:44.589Z,1591641464.589 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T18:37:44.589Z,1591641464.589 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T18:38:32.663Z,1591641512.663 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2020-06-08T18:38:32.663Z,1591641512.663 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:38:32.703Z,1591641512.703 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:38:33.106Z,1591641513.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:38:33.106Z,1591641513.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2020-06-08T18:40:47.597Z,1591641647.597 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T18:40:47.597Z,1591641647.597 [NAL9602] Data Fault, FailCount= 5 2020-06-08T18:40:47.597Z,1591641647.597 [NAL9602](ERROR): Data Fault 2020-06-08T18:40:47.614Z,1591641647.614 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T18:40:47.614Z,1591641647.614 [CBIT](CRITICAL): Data Fault in component: NAL9602 2020-06-08T18:40:47.992Z,1591641647.992 [NAL9602](INFO): Powering down 2020-06-08T18:40:48.039Z,1591641648.039 [CBIT](INFO): Critical error at 20200608T184047 2020-06-08T18:41:33.663Z,1591641693.663 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2020-06-08T18:41:33.663Z,1591641693.663 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:41:33.697Z,1591641693.697 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:41:34.072Z,1591641694.072 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:41:34.072Z,1591641694.072 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2020-06-08T18:42:45.171Z,1591641765.171 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T18:42:45.172Z,1591641765.172 [Default:CheckIn:C.Wait] Stopped 2020-06-08T18:42:45.172Z,1591641765.172 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T18:42:45.172Z,1591641765.172 [Default:CheckIn:D] Running Loop=1 2020-06-08T18:42:45.581Z,1591641765.581 [Default:CheckIn:D] Stopped 2020-06-08T18:42:45.581Z,1591641765.581 [Default:CheckIn:E] Running Loop=1 2020-06-08T18:42:45.973Z,1591641765.973 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.423067 min 2020-06-08T18:42:45.973Z,1591641765.973 [Default:CheckIn:E] Stopped 2020-06-08T18:42:45.973Z,1591641765.973 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T18:42:45.973Z,1591641765.973 [Default:CheckIn] Stopped 2020-06-08T18:42:45.973Z,1591641765.973 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T18:42:45.973Z,1591641765.973 [Default:CheckIn](INFO): Running loop #6 2020-06-08T18:42:45.973Z,1591641765.973 [Default:CheckIn] Running Loop=6 2020-06-08T18:42:45.974Z,1591641765.974 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T18:42:45.974Z,1591641765.974 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T18:42:48.001Z,1591641768.001 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T18:42:48.001Z,1591641768.001 [NAL9602] No Fault, FailCount= 5 2020-06-08T18:42:48.388Z,1591641768.388 [NAL9602](INFO): Powering up NAL9602 2020-06-08T18:42:59.296Z,1591641779.296 [NAL9602](INFO): NAL9602 initialized 2020-06-08T18:44:34.657Z,1591641874.657 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2020-06-08T18:44:34.657Z,1591641874.657 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:44:34.666Z,1591641874.666 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:44:35.084Z,1591641875.084 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T18:44:35.084Z,1591641875.084 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2020-06-08T18:47:35.668Z,1591642055.668 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2020-06-08T18:47:35.668Z,1591642055.668 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T18:47:35.678Z,1591642055.678 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:47:35.678Z,1591642055.678 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T18:47:46.144Z,1591642066.144 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T18:42:45.0Z 2020-06-08T18:47:46.144Z,1591642066.144 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T18:47:46.144Z,1591642066.144 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T18:47:53.686Z,1591642073.686 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20200608T174706/Courier0019.lzma 2020-06-08T18:47:54.689Z,1591642074.689 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0019.lzma.bak 2020-06-08T18:47:54.689Z,1591642074.689 [DataOverHttps](INFO): SBD MOMSN=12372796 2020-06-08T18:48:09.999Z,1591642089.999 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20200608T174706/Express0020.lzma 2020-06-08T18:48:11.001Z,1591642091.001 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0020.lzma.bak 2020-06-08T18:48:11.001Z,1591642091.001 [DataOverHttps](INFO): SBD MOMSN=12372803 2020-06-08T18:48:12.424Z,1591642092.424 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T18:48:12.424Z,1591642092.424 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T18:48:12.424Z,1591642092.424 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T18:53:00.448Z,1591642380.448 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T18:53:00.448Z,1591642380.448 [NAL9602] Data Fault, FailCount= 1 2020-06-08T18:53:00.448Z,1591642380.448 [NAL9602](ERROR): Data Fault 2020-06-08T18:53:00.464Z,1591642380.464 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T18:53:00.858Z,1591642380.858 [NAL9602](INFO): Powering down 2020-06-08T18:53:01.676Z,1591642381.676 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T18:53:01.676Z,1591642381.676 [NAL9602] No Fault, FailCount= 1 2020-06-08T18:53:12.979Z,1591642392.979 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T18:53:12.979Z,1591642392.979 [Default:CheckIn:C.Wait] Stopped 2020-06-08T18:53:12.979Z,1591642392.979 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T18:53:12.979Z,1591642392.979 [Default:CheckIn:D] Running Loop=1 2020-06-08T18:53:13.384Z,1591642393.384 [Default:CheckIn:D] Stopped 2020-06-08T18:53:13.384Z,1591642393.384 [Default:CheckIn:E] Running Loop=1 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.886454 min 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn:E] Stopped 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn] Stopped 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn](INFO): Running loop #7 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn] Running Loop=7 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T18:53:13.792Z,1591642393.792 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T18:53:31.156Z,1591642411.156 [NAL9602](INFO): Powering up NAL9602 2020-06-08T18:53:42.060Z,1591642422.060 [NAL9602](INFO): NAL9602 initialized 2020-06-08T18:56:43.073Z,1591642603.073 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2020-06-08T18:56:54.018Z,1591642614.018 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T18:57:06.948Z,1591642626.948 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T18:57:32.756Z,1591642652.756 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2020-06-08T18:57:32.757Z,1591642652.757 [BPC1](ERROR): BPC1B failed to parse battery stick message. 2020-06-08T18:57:47.758Z,1591642667.758 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T18:58:13.965Z,1591642693.965 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T18:53:13.8Z 2020-06-08T18:58:13.965Z,1591642693.965 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T18:58:13.965Z,1591642693.965 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T18:58:21.562Z,1591642701.562 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200608T174706/Express0023.lzma 2020-06-08T18:58:22.565Z,1591642702.565 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0023.lzma.bak 2020-06-08T18:58:22.565Z,1591642702.565 [DataOverHttps](INFO): SBD MOMSN=12372823 2020-06-08T18:58:24.076Z,1591642704.076 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T18:58:24.076Z,1591642704.076 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T18:58:24.076Z,1591642704.076 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T19:00:03.908Z,1591642803.908 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T19:00:48.349Z,1591642848.349 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T19:02:05.519Z,1591642925.519 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T19:02:20.487Z,1591642940.487 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T19:02:35.360Z,1591642955.360 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-06-08T19:02:35.362Z,1591642955.362 [BPC1](INFO): Received data from all battery sticks. 2020-06-08T19:02:35.781Z,1591642955.781 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:02:35.781Z,1591642955.781 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2020-06-08T19:03:24.653Z,1591643004.653 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T19:03:24.653Z,1591643004.653 [Default:CheckIn:C.Wait] Stopped 2020-06-08T19:03:24.653Z,1591643004.653 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T19:03:24.653Z,1591643004.653 [Default:CheckIn:D] Running Loop=1 2020-06-08T19:03:25.046Z,1591643005.046 [Default:CheckIn:D] Stopped 2020-06-08T19:03:25.046Z,1591643005.046 [Default:CheckIn:E] Running Loop=1 2020-06-08T19:03:25.445Z,1591643005.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.080827 min 2020-06-08T19:03:25.445Z,1591643005.445 [Default:CheckIn:E] Stopped 2020-06-08T19:03:25.445Z,1591643005.445 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T19:03:25.445Z,1591643005.445 [Default:CheckIn] Stopped 2020-06-08T19:03:25.445Z,1591643005.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T19:03:25.446Z,1591643005.446 [Default:CheckIn](INFO): Running loop #8 2020-06-08T19:03:25.446Z,1591643005.446 [Default:CheckIn] Running Loop=8 2020-06-08T19:03:25.446Z,1591643005.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T19:03:25.446Z,1591643005.446 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T19:03:43.216Z,1591643023.216 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T19:03:43.216Z,1591643023.216 [NAL9602] Data Fault, FailCount= 2 2020-06-08T19:03:43.216Z,1591643023.216 [NAL9602](ERROR): Data Fault 2020-06-08T19:03:43.233Z,1591643023.233 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T19:03:43.622Z,1591643023.622 [NAL9602](INFO): Powering down 2020-06-08T19:03:44.440Z,1591643024.440 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T19:03:44.440Z,1591643024.440 [NAL9602] No Fault, FailCount= 2 2020-06-08T19:04:13.912Z,1591643053.912 [NAL9602](INFO): Powering up NAL9602 2020-06-08T19:04:24.824Z,1591643064.824 [NAL9602](INFO): NAL9602 initialized 2020-06-08T19:05:36.356Z,1591643136.356 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-06-08T19:05:36.356Z,1591643136.356 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:05:36.366Z,1591643136.366 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:05:36.782Z,1591643136.782 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:05:36.782Z,1591643136.782 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-06-08T19:08:25.631Z,1591643305.631 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T19:03:25.4Z 2020-06-08T19:08:25.631Z,1591643305.631 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T19:08:25.631Z,1591643305.631 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T19:08:32.778Z,1591643312.778 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20200608T174706/Courier0025.lzma 2020-06-08T19:08:33.781Z,1591643313.781 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0025.lzma.bak 2020-06-08T19:08:33.781Z,1591643313.781 [DataOverHttps](INFO): SBD MOMSN=12372840 2020-06-08T19:08:37.355Z,1591643317.355 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-06-08T19:08:37.355Z,1591643317.355 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:08:37.373Z,1591643317.373 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:08:37.773Z,1591643317.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:08:37.773Z,1591643317.773 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-06-08T19:08:49.127Z,1591643329.127 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20200608T174706/Express0026.lzma 2020-06-08T19:08:50.128Z,1591643330.128 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0026.lzma.bak 2020-06-08T19:08:50.129Z,1591643330.129 [DataOverHttps](INFO): SBD MOMSN=12372842 2020-06-08T19:08:51.482Z,1591643331.482 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T19:08:51.482Z,1591643331.482 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T19:08:51.482Z,1591643331.482 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T19:11:38.338Z,1591643498.338 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-06-08T19:11:38.338Z,1591643498.338 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:11:38.347Z,1591643498.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:11:38.736Z,1591643498.736 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:11:38.736Z,1591643498.736 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-06-08T19:13:52.061Z,1591643632.061 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T19:13:52.061Z,1591643632.061 [Default:CheckIn:C.Wait] Stopped 2020-06-08T19:13:52.061Z,1591643632.061 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T19:13:52.061Z,1591643632.061 [Default:CheckIn:D] Running Loop=1 2020-06-08T19:13:52.476Z,1591643632.476 [Default:CheckIn:D] Stopped 2020-06-08T19:13:52.476Z,1591643632.476 [Default:CheckIn:E] Running Loop=1 2020-06-08T19:13:52.871Z,1591643632.871 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.537988 min 2020-06-08T19:13:52.871Z,1591643632.871 [Default:CheckIn:E] Stopped 2020-06-08T19:13:52.871Z,1591643632.871 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T19:13:52.871Z,1591643632.871 [Default:CheckIn] Stopped 2020-06-08T19:13:52.871Z,1591643632.871 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T19:13:52.872Z,1591643632.872 [Default:CheckIn](INFO): Running loop #9 2020-06-08T19:13:52.872Z,1591643632.872 [Default:CheckIn] Running Loop=9 2020-06-08T19:13:52.872Z,1591643632.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T19:13:52.872Z,1591643632.872 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T19:14:25.980Z,1591643665.980 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T19:14:25.980Z,1591643665.980 [NAL9602] Data Fault, FailCount= 3 2020-06-08T19:14:25.980Z,1591643665.980 [NAL9602](ERROR): Data Fault 2020-06-08T19:14:26.046Z,1591643666.046 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T19:14:26.376Z,1591643666.376 [NAL9602](INFO): Powering down 2020-06-08T19:14:27.269Z,1591643667.269 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T19:14:27.269Z,1591643667.269 [NAL9602] No Fault, FailCount= 3 2020-06-08T19:14:39.327Z,1591643679.327 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-06-08T19:14:39.327Z,1591643679.327 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:14:39.337Z,1591643679.337 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:14:39.749Z,1591643679.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:14:39.749Z,1591643679.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-06-08T19:14:56.680Z,1591643696.680 [NAL9602](INFO): Powering up NAL9602 2020-06-08T19:15:07.588Z,1591643707.588 [NAL9602](INFO): NAL9602 initialized 2020-06-08T19:17:40.307Z,1591643860.307 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2020-06-08T19:17:40.307Z,1591643860.307 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:17:40.316Z,1591643860.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:17:40.727Z,1591643860.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:17:40.727Z,1591643860.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2020-06-08T19:18:53.052Z,1591643933.052 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T19:13:52.9Z 2020-06-08T19:18:53.052Z,1591643933.052 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T19:18:53.053Z,1591643933.053 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T19:18:59.615Z,1591643939.615 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20200608T174706/Express0029.lzma 2020-06-08T19:19:00.617Z,1591643940.617 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0029.lzma.bak 2020-06-08T19:19:00.617Z,1591643940.617 [DataOverHttps](INFO): SBD MOMSN=12372857 2020-06-08T19:19:01.973Z,1591643941.973 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T19:19:01.973Z,1591643941.973 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T19:19:01.973Z,1591643941.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T19:20:41.346Z,1591644041.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2020-06-08T19:20:41.346Z,1591644041.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:20:41.384Z,1591644041.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:20:41.741Z,1591644041.741 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:20:41.741Z,1591644041.741 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2020-06-08T19:23:42.325Z,1591644222.325 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2020-06-08T19:23:42.325Z,1591644222.325 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:23:42.335Z,1591644222.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:23:42.748Z,1591644222.748 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:23:42.748Z,1591644222.748 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2020-06-08T19:24:02.536Z,1591644242.536 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T19:24:02.536Z,1591644242.536 [Default:CheckIn:C.Wait] Stopped 2020-06-08T19:24:02.536Z,1591644242.536 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T19:24:02.536Z,1591644242.536 [Default:CheckIn:D] Running Loop=1 2020-06-08T19:24:02.949Z,1591644242.949 [Default:CheckIn:D] Stopped 2020-06-08T19:24:02.949Z,1591644242.949 [Default:CheckIn:E] Running Loop=1 2020-06-08T19:24:03.335Z,1591644243.335 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.712533 min 2020-06-08T19:24:03.335Z,1591644243.335 [Default:CheckIn:E] Stopped 2020-06-08T19:24:03.335Z,1591644243.335 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T19:24:03.335Z,1591644243.335 [Default:CheckIn] Stopped 2020-06-08T19:24:03.335Z,1591644243.335 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T19:24:03.335Z,1591644243.335 [Default:CheckIn](INFO): Running loop #10 2020-06-08T19:24:03.336Z,1591644243.336 [Default:CheckIn] Running Loop=10 2020-06-08T19:24:03.336Z,1591644243.336 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T19:24:03.336Z,1591644243.336 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T19:25:09.569Z,1591644309.569 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T19:25:09.569Z,1591644309.569 [NAL9602] Data Fault, FailCount= 4 2020-06-08T19:25:09.569Z,1591644309.569 [NAL9602](ERROR): Data Fault 2020-06-08T19:25:09.630Z,1591644309.630 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T19:25:09.972Z,1591644309.972 [NAL9602](INFO): Powering down 2020-06-08T19:25:10.813Z,1591644310.813 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T19:25:10.813Z,1591644310.813 [NAL9602] No Fault, FailCount= 4 2020-06-08T19:25:40.276Z,1591644340.276 [NAL9602](INFO): Powering up NAL9602 2020-06-08T19:25:51.192Z,1591644351.192 [NAL9602](INFO): NAL9602 initialized 2020-06-08T19:26:43.316Z,1591644403.316 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2020-06-08T19:26:43.316Z,1591644403.316 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:26:43.326Z,1591644403.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:26:43.732Z,1591644403.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:26:43.732Z,1591644403.732 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2020-06-08T19:29:03.509Z,1591644543.509 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T19:24:03.3Z 2020-06-08T19:29:03.509Z,1591644543.509 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T19:29:03.509Z,1591644543.509 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T19:29:10.423Z,1591644550.423 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200608T174706/Express0032.lzma 2020-06-08T19:29:11.425Z,1591644551.425 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0032.lzma.bak 2020-06-08T19:29:11.425Z,1591644551.425 [DataOverHttps](INFO): SBD MOMSN=12372892 2020-06-08T19:29:12.821Z,1591644552.821 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T19:29:12.821Z,1591644552.821 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T19:29:12.821Z,1591644552.821 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T19:29:44.323Z,1591644584.323 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2020-06-08T19:29:44.323Z,1591644584.323 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:29:44.333Z,1591644584.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:29:44.756Z,1591644584.756 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:29:44.756Z,1591644584.756 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2020-06-08T19:32:45.304Z,1591644765.304 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2020-06-08T19:32:45.304Z,1591644765.304 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:32:45.314Z,1591644765.314 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:32:45.721Z,1591644765.721 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:32:45.721Z,1591644765.721 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2020-06-08T19:34:13.372Z,1591644853.372 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T19:34:13.373Z,1591644853.373 [Default:CheckIn:C.Wait] Stopped 2020-06-08T19:34:13.373Z,1591644853.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T19:34:13.373Z,1591644853.373 [Default:CheckIn:D] Running Loop=1 2020-06-08T19:34:13.797Z,1591644853.797 [Default:CheckIn:D] Stopped 2020-06-08T19:34:13.797Z,1591644853.797 [Default:CheckIn:E] Running Loop=1 2020-06-08T19:34:14.201Z,1591644854.201 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.893335 min 2020-06-08T19:34:14.201Z,1591644854.201 [Default:CheckIn:E] Stopped 2020-06-08T19:34:14.201Z,1591644854.201 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T19:34:14.201Z,1591644854.201 [Default:CheckIn] Stopped 2020-06-08T19:34:14.202Z,1591644854.202 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T19:34:14.202Z,1591644854.202 [Default:CheckIn](INFO): Running loop #11 2020-06-08T19:34:14.202Z,1591644854.202 [Default:CheckIn] Running Loop=11 2020-06-08T19:34:14.202Z,1591644854.202 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T19:34:14.202Z,1591644854.202 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T19:35:46.312Z,1591644946.312 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2020-06-08T19:35:46.312Z,1591644946.312 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:35:46.322Z,1591644946.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:35:46.728Z,1591644946.728 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:35:46.729Z,1591644946.729 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2020-06-08T19:35:52.348Z,1591644952.348 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T19:35:52.348Z,1591644952.348 [NAL9602] Data Fault, FailCount= 5 2020-06-08T19:35:52.348Z,1591644952.348 [NAL9602](ERROR): Data Fault 2020-06-08T19:35:52.365Z,1591644952.365 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T19:35:52.366Z,1591644952.366 [CBIT](CRITICAL): Data Fault in component: NAL9602 2020-06-08T19:35:52.748Z,1591644952.748 [NAL9602](INFO): Powering down 2020-06-08T19:35:52.784Z,1591644952.784 [CBIT](INFO): Critical error at 20200608T193552 2020-06-08T19:37:52.752Z,1591645072.752 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T19:37:52.752Z,1591645072.752 [NAL9602] No Fault, FailCount= 5 2020-06-08T19:37:53.140Z,1591645073.140 [NAL9602](INFO): Powering up NAL9602 2020-06-08T19:38:04.048Z,1591645084.048 [NAL9602](INFO): NAL9602 initialized 2020-06-08T19:38:47.301Z,1591645127.301 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2020-06-08T19:38:47.301Z,1591645127.301 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:38:47.311Z,1591645127.311 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:38:47.726Z,1591645127.726 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:38:47.726Z,1591645127.726 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2020-06-08T19:39:14.357Z,1591645154.357 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T19:34:14.2Z 2020-06-08T19:39:14.357Z,1591645154.357 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T19:39:14.357Z,1591645154.357 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T19:39:21.378Z,1591645161.378 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20200608T174706/Courier0034.lzma 2020-06-08T19:39:22.381Z,1591645162.381 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0034.lzma.bak 2020-06-08T19:39:22.381Z,1591645162.381 [DataOverHttps](INFO): SBD MOMSN=12372909 2020-06-08T19:39:37.711Z,1591645177.711 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200608T174706/Express0035.lzma 2020-06-08T19:39:38.713Z,1591645178.713 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0035.lzma.bak 2020-06-08T19:39:38.713Z,1591645178.713 [DataOverHttps](INFO): SBD MOMSN=12372912 2020-06-08T19:39:40.245Z,1591645180.245 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T19:39:40.245Z,1591645180.245 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T19:39:40.245Z,1591645180.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T19:41:48.295Z,1591645308.295 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2020-06-08T19:41:48.295Z,1591645308.295 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:41:48.305Z,1591645308.305 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:41:48.719Z,1591645308.719 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:41:48.719Z,1591645308.719 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2020-06-08T19:44:40.793Z,1591645480.793 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T19:44:40.793Z,1591645480.793 [Default:CheckIn:C.Wait] Stopped 2020-06-08T19:44:40.793Z,1591645480.793 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T19:44:40.793Z,1591645480.793 [Default:CheckIn:D] Running Loop=1 2020-06-08T19:44:41.200Z,1591645481.200 [Default:CheckIn:D] Stopped 2020-06-08T19:44:41.200Z,1591645481.200 [Default:CheckIn:E] Running Loop=1 2020-06-08T19:44:41.593Z,1591645481.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.350065 min 2020-06-08T19:44:41.593Z,1591645481.593 [Default:CheckIn:E] Stopped 2020-06-08T19:44:41.593Z,1591645481.593 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T19:44:41.593Z,1591645481.593 [Default:CheckIn] Stopped 2020-06-08T19:44:41.593Z,1591645481.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T19:44:41.594Z,1591645481.594 [Default:CheckIn](INFO): Running loop #12 2020-06-08T19:44:41.594Z,1591645481.594 [Default:CheckIn] Running Loop=12 2020-06-08T19:44:41.594Z,1591645481.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T19:44:41.594Z,1591645481.594 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T19:44:49.280Z,1591645489.280 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2020-06-08T19:44:49.280Z,1591645489.280 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:44:49.290Z,1591645489.290 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:44:49.704Z,1591645489.704 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:44:49.704Z,1591645489.704 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2020-06-08T19:47:50.277Z,1591645670.277 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2020-06-08T19:47:50.278Z,1591645670.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:47:50.287Z,1591645670.287 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:47:50.688Z,1591645670.688 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:47:50.688Z,1591645670.688 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2020-06-08T19:48:05.201Z,1591645685.201 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T19:48:05.201Z,1591645685.201 [NAL9602] Data Fault, FailCount= 1 2020-06-08T19:48:05.201Z,1591645685.201 [NAL9602](ERROR): Data Fault 2020-06-08T19:48:05.253Z,1591645685.253 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T19:48:05.600Z,1591645685.600 [NAL9602](INFO): Powering down 2020-06-08T19:48:06.428Z,1591645686.428 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T19:48:06.428Z,1591645686.428 [NAL9602] No Fault, FailCount= 1 2020-06-08T19:48:35.905Z,1591645715.905 [NAL9602](INFO): Powering up NAL9602 2020-06-08T19:48:46.812Z,1591645726.812 [NAL9602](INFO): NAL9602 initialized 2020-06-08T19:49:41.777Z,1591645781.777 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T19:44:41.6Z 2020-06-08T19:49:41.777Z,1591645781.777 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T19:49:41.777Z,1591645781.777 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T19:49:49.071Z,1591645789.071 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20200608T174706/Express0038.lzma 2020-06-08T19:49:50.073Z,1591645790.073 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0038.lzma.bak 2020-06-08T19:49:50.073Z,1591645790.073 [DataOverHttps](INFO): SBD MOMSN=12372935 2020-06-08T19:49:51.469Z,1591645791.469 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T19:49:51.469Z,1591645791.469 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T19:49:51.469Z,1591645791.469 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T19:50:51.264Z,1591645851.264 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2020-06-08T19:50:51.264Z,1591645851.264 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:50:51.274Z,1591645851.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:50:51.664Z,1591645851.664 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:50:51.664Z,1591645851.664 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2020-06-08T19:53:52.269Z,1591646032.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2020-06-08T19:53:52.269Z,1591646032.269 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:53:52.278Z,1591646032.278 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:53:52.661Z,1591646032.661 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:53:52.661Z,1591646032.661 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2020-06-08T19:54:52.055Z,1591646092.055 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T19:54:52.055Z,1591646092.055 [Default:CheckIn:C.Wait] Stopped 2020-06-08T19:54:52.055Z,1591646092.055 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T19:54:52.055Z,1591646092.055 [Default:CheckIn:D] Running Loop=1 2020-06-08T19:54:52.464Z,1591646092.464 [Default:CheckIn:D] Stopped 2020-06-08T19:54:52.464Z,1591646092.464 [Default:CheckIn:E] Running Loop=1 2020-06-08T19:54:52.848Z,1591646092.848 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.537793 min 2020-06-08T19:54:52.848Z,1591646092.848 [Default:CheckIn:E] Stopped 2020-06-08T19:54:52.848Z,1591646092.848 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T19:54:52.848Z,1591646092.848 [Default:CheckIn] Stopped 2020-06-08T19:54:52.849Z,1591646092.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T19:54:52.849Z,1591646092.849 [Default:CheckIn](INFO): Running loop #13 2020-06-08T19:54:52.849Z,1591646092.849 [Default:CheckIn] Running Loop=13 2020-06-08T19:54:52.849Z,1591646092.849 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T19:54:52.849Z,1591646092.849 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T19:56:53.256Z,1591646213.256 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2020-06-08T19:56:53.256Z,1591646213.256 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:56:53.293Z,1591646213.293 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:56:53.655Z,1591646213.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:56:53.655Z,1591646213.655 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2020-06-08T19:58:47.964Z,1591646327.964 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T19:58:47.965Z,1591646327.965 [NAL9602] Data Fault, FailCount= 2 2020-06-08T19:58:47.965Z,1591646327.965 [NAL9602](ERROR): Data Fault 2020-06-08T19:58:48.013Z,1591646328.013 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T19:58:48.368Z,1591646328.368 [NAL9602](INFO): Powering down 2020-06-08T19:58:49.197Z,1591646329.197 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T19:58:49.197Z,1591646329.197 [NAL9602] No Fault, FailCount= 2 2020-06-08T19:59:18.674Z,1591646358.674 [NAL9602](INFO): Powering up NAL9602 2020-06-08T19:59:29.576Z,1591646369.576 [NAL9602](INFO): NAL9602 initialized 2020-06-08T19:59:53.037Z,1591646393.037 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T19:54:52.8Z 2020-06-08T19:59:53.037Z,1591646393.037 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T19:59:53.037Z,1591646393.037 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T19:59:54.248Z,1591646394.248 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2020-06-08T19:59:54.248Z,1591646394.248 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T19:59:54.287Z,1591646394.287 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T19:59:54.658Z,1591646394.658 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T19:59:54.658Z,1591646394.658 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2020-06-08T20:00:00.139Z,1591646400.139 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20200608T174706/Express0041.lzma 2020-06-08T20:00:01.141Z,1591646401.141 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0041.lzma.bak 2020-06-08T20:00:01.141Z,1591646401.141 [DataOverHttps](INFO): SBD MOMSN=12372946 2020-06-08T20:00:02.734Z,1591646402.734 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T20:00:02.734Z,1591646402.734 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T20:00:02.734Z,1591646402.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T20:02:55.239Z,1591646575.239 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2020-06-08T20:02:55.239Z,1591646575.239 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:02:55.249Z,1591646575.249 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:02:55.249Z,1591646575.249 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:05:03.316Z,1591646703.316 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T20:05:03.316Z,1591646703.316 [Default:CheckIn:C.Wait] Stopped 2020-06-08T20:05:03.316Z,1591646703.316 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T20:05:03.316Z,1591646703.316 [Default:CheckIn:D] Running Loop=1 2020-06-08T20:05:03.705Z,1591646703.705 [Default:CheckIn:D] Stopped 2020-06-08T20:05:03.705Z,1591646703.705 [Default:CheckIn:E] Running Loop=1 2020-06-08T20:05:04.113Z,1591646704.113 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.725138 min 2020-06-08T20:05:04.113Z,1591646704.113 [Default:CheckIn:E] Stopped 2020-06-08T20:05:04.113Z,1591646704.113 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T20:05:04.113Z,1591646704.113 [Default:CheckIn] Stopped 2020-06-08T20:05:04.113Z,1591646704.113 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T20:05:04.113Z,1591646704.113 [Default:CheckIn](INFO): Running loop #14 2020-06-08T20:05:04.113Z,1591646704.113 [Default:CheckIn] Running Loop=14 2020-06-08T20:05:04.114Z,1591646704.114 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T20:05:04.114Z,1591646704.114 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T20:07:42.944Z,1591646862.944 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T20:09:08.589Z,1591646948.589 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T20:09:30.748Z,1591646970.748 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T20:09:30.748Z,1591646970.748 [NAL9602] Data Fault, FailCount= 3 2020-06-08T20:09:30.748Z,1591646970.748 [NAL9602](ERROR): Data Fault 2020-06-08T20:09:30.802Z,1591646970.802 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T20:09:31.148Z,1591646971.148 [NAL9602](INFO): Powering down 2020-06-08T20:09:31.976Z,1591646971.976 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T20:09:31.976Z,1591646971.976 [NAL9602] No Fault, FailCount= 3 2020-06-08T20:09:44.085Z,1591646984.085 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-06-08T20:09:44.099Z,1591646984.099 [BPC1](INFO): Received data from all battery sticks. 2020-06-08T20:10:01.452Z,1591647001.452 [NAL9602](INFO): Powering up NAL9602 2020-06-08T20:10:04.319Z,1591647004.319 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T20:05:04.1Z 2020-06-08T20:10:04.319Z,1591647004.319 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T20:10:04.319Z,1591647004.319 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T20:10:11.654Z,1591647011.654 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20200608T174706/Courier0043.lzma 2020-06-08T20:10:12.540Z,1591647012.540 [NAL9602](INFO): NAL9602 initialized 2020-06-08T20:10:12.657Z,1591647012.657 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0043.lzma.bak 2020-06-08T20:10:12.657Z,1591647012.657 [DataOverHttps](INFO): SBD MOMSN=12372959 2020-06-08T20:10:27.951Z,1591647027.951 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20200608T174706/Express0044.lzma 2020-06-08T20:10:28.953Z,1591647028.953 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0044.lzma.bak 2020-06-08T20:10:28.953Z,1591647028.953 [DataOverHttps](INFO): SBD MOMSN=12372964 2020-06-08T20:10:30.341Z,1591647030.341 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T20:10:30.341Z,1591647030.341 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T20:10:30.341Z,1591647030.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T20:15:30.911Z,1591647330.911 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T20:15:30.912Z,1591647330.912 [Default:CheckIn:C.Wait] Stopped 2020-06-08T20:15:30.912Z,1591647330.912 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T20:15:30.912Z,1591647330.912 [Default:CheckIn:D] Running Loop=1 2020-06-08T20:15:31.328Z,1591647331.328 [Default:CheckIn:D] Stopped 2020-06-08T20:15:31.328Z,1591647331.328 [Default:CheckIn:E] Running Loop=1 2020-06-08T20:15:31.724Z,1591647331.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.185531 min 2020-06-08T20:15:31.724Z,1591647331.724 [Default:CheckIn:E] Stopped 2020-06-08T20:15:31.724Z,1591647331.724 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T20:15:31.725Z,1591647331.725 [Default:CheckIn] Stopped 2020-06-08T20:15:31.725Z,1591647331.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T20:15:31.725Z,1591647331.725 [Default:CheckIn](INFO): Running loop #15 2020-06-08T20:15:31.725Z,1591647331.725 [Default:CheckIn] Running Loop=15 2020-06-08T20:15:31.725Z,1591647331.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T20:15:31.725Z,1591647331.725 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T20:17:55.564Z,1591647475.564 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:17:55.564Z,1591647475.564 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2020-06-08T20:20:13.696Z,1591647613.696 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T20:20:13.697Z,1591647613.697 [NAL9602] Data Fault, FailCount= 4 2020-06-08T20:20:13.697Z,1591647613.697 [NAL9602](ERROR): Data Fault 2020-06-08T20:20:13.734Z,1591647613.734 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T20:20:14.092Z,1591647614.092 [NAL9602](INFO): Powering down 2020-06-08T20:20:14.916Z,1591647614.916 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T20:20:14.916Z,1591647614.916 [NAL9602] No Fault, FailCount= 4 2020-06-08T20:20:31.876Z,1591647631.876 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T20:15:31.7Z 2020-06-08T20:20:31.876Z,1591647631.876 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T20:20:31.876Z,1591647631.876 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T20:20:38.559Z,1591647638.559 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200608T174706/Express0047.lzma 2020-06-08T20:20:39.560Z,1591647639.560 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0047.lzma.bak 2020-06-08T20:20:39.561Z,1591647639.561 [DataOverHttps](INFO): SBD MOMSN=12372981 2020-06-08T20:20:40.765Z,1591647640.765 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T20:20:40.765Z,1591647640.765 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T20:20:40.765Z,1591647640.765 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T20:20:44.396Z,1591647644.396 [NAL9602](INFO): Powering up NAL9602 2020-06-08T20:20:55.304Z,1591647655.304 [NAL9602](INFO): NAL9602 initialized 2020-06-08T20:20:56.148Z,1591647656.148 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-06-08T20:20:56.148Z,1591647656.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:20:56.158Z,1591647656.158 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:20:56.549Z,1591647656.549 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:20:56.549Z,1591647656.549 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-06-08T20:23:57.128Z,1591647837.128 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-06-08T20:23:57.128Z,1591647837.128 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:23:57.162Z,1591647837.162 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:23:57.546Z,1591647837.546 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:23:57.546Z,1591647837.546 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-06-08T20:25:41.373Z,1591647941.373 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T20:25:41.373Z,1591647941.373 [Default:CheckIn:C.Wait] Stopped 2020-06-08T20:25:41.373Z,1591647941.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T20:25:41.373Z,1591647941.373 [Default:CheckIn:D] Running Loop=1 2020-06-08T20:25:41.781Z,1591647941.781 [Default:CheckIn:D] Stopped 2020-06-08T20:25:41.781Z,1591647941.781 [Default:CheckIn:E] Running Loop=1 2020-06-08T20:25:42.157Z,1591647942.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.359733 min 2020-06-08T20:25:42.157Z,1591647942.157 [Default:CheckIn:E] Stopped 2020-06-08T20:25:42.157Z,1591647942.157 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T20:25:42.157Z,1591647942.157 [Default:CheckIn] Stopped 2020-06-08T20:25:42.157Z,1591647942.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T20:25:42.157Z,1591647942.157 [Default:CheckIn](INFO): Running loop #16 2020-06-08T20:25:42.157Z,1591647942.157 [Default:CheckIn] Running Loop=16 2020-06-08T20:25:42.158Z,1591647942.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T20:25:42.158Z,1591647942.158 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T20:26:58.126Z,1591648018.126 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-06-08T20:26:58.126Z,1591648018.126 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:26:58.136Z,1591648018.136 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:26:58.520Z,1591648018.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:26:58.520Z,1591648018.520 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-06-08T20:29:59.118Z,1591648199.118 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-06-08T20:29:59.118Z,1591648199.118 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:29:59.128Z,1591648199.128 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:29:59.512Z,1591648199.512 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:29:59.512Z,1591648199.512 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-06-08T20:30:42.329Z,1591648242.329 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T20:25:42.2Z 2020-06-08T20:30:42.329Z,1591648242.329 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T20:30:42.329Z,1591648242.329 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T20:30:49.750Z,1591648249.750 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20200608T174706/Express0050.lzma 2020-06-08T20:30:50.753Z,1591648250.753 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0050.lzma.bak 2020-06-08T20:30:50.753Z,1591648250.753 [DataOverHttps](INFO): SBD MOMSN=12372999 2020-06-08T20:30:52.040Z,1591648252.040 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T20:30:52.040Z,1591648252.040 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T20:30:52.040Z,1591648252.040 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T20:30:56.456Z,1591648256.456 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T20:30:56.456Z,1591648256.456 [NAL9602] Data Fault, FailCount= 5 2020-06-08T20:30:56.456Z,1591648256.456 [NAL9602](ERROR): Data Fault 2020-06-08T20:30:56.489Z,1591648256.489 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T20:30:56.490Z,1591648256.490 [CBIT](CRITICAL): Data Fault in component: NAL9602 2020-06-08T20:30:56.872Z,1591648256.872 [NAL9602](INFO): Powering down 2020-06-08T20:30:56.906Z,1591648256.906 [CBIT](INFO): Critical error at 20200608T203056 2020-06-08T20:32:56.508Z,1591648376.508 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T20:32:56.508Z,1591648376.508 [NAL9602] No Fault, FailCount= 5 2020-06-08T20:32:56.856Z,1591648376.856 [NAL9602](INFO): Powering up NAL9602 2020-06-08T20:33:00.096Z,1591648380.096 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2020-06-08T20:33:00.096Z,1591648380.096 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:33:00.106Z,1591648380.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:33:00.520Z,1591648380.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:33:00.520Z,1591648380.520 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2020-06-08T20:33:07.768Z,1591648387.768 [NAL9602](INFO): NAL9602 initialized 2020-06-08T20:35:52.622Z,1591648552.622 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T20:35:52.622Z,1591648552.622 [Default:CheckIn:C.Wait] Stopped 2020-06-08T20:35:52.622Z,1591648552.622 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T20:35:52.622Z,1591648552.622 [Default:CheckIn:D] Running Loop=1 2020-06-08T20:35:53.029Z,1591648553.029 [Default:CheckIn:D] Stopped 2020-06-08T20:35:53.029Z,1591648553.029 [Default:CheckIn:E] Running Loop=1 2020-06-08T20:35:53.437Z,1591648553.437 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.547201 min 2020-06-08T20:35:53.437Z,1591648553.437 [Default:CheckIn:E] Stopped 2020-06-08T20:35:53.437Z,1591648553.437 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T20:35:53.437Z,1591648553.437 [Default:CheckIn] Stopped 2020-06-08T20:35:53.438Z,1591648553.438 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T20:35:53.438Z,1591648553.438 [Default:CheckIn](INFO): Running loop #17 2020-06-08T20:35:53.438Z,1591648553.438 [Default:CheckIn] Running Loop=17 2020-06-08T20:35:53.438Z,1591648553.438 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T20:35:53.438Z,1591648553.438 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T20:36:01.096Z,1591648561.096 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2020-06-08T20:36:01.096Z,1591648561.096 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:36:01.105Z,1591648561.105 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:36:01.524Z,1591648561.524 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:36:01.524Z,1591648561.524 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2020-06-08T20:39:02.076Z,1591648742.076 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2020-06-08T20:39:02.076Z,1591648742.076 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:39:02.085Z,1591648742.085 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:39:02.496Z,1591648742.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:39:02.496Z,1591648742.496 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2020-06-08T20:40:53.585Z,1591648853.585 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T20:35:53.4Z 2020-06-08T20:40:53.585Z,1591648853.585 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T20:40:53.585Z,1591648853.585 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T20:41:00.734Z,1591648860.734 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20200608T174706/Courier0052.lzma 2020-06-08T20:41:01.737Z,1591648861.737 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0052.lzma.bak 2020-06-08T20:41:01.737Z,1591648861.737 [DataOverHttps](INFO): SBD MOMSN=12373015 2020-06-08T20:41:17.063Z,1591648877.063 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200608T174706/Express0053.lzma 2020-06-08T20:41:18.065Z,1591648878.065 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0053.lzma.bak 2020-06-08T20:41:18.065Z,1591648878.065 [DataOverHttps](INFO): SBD MOMSN=12373018 2020-06-08T20:41:19.453Z,1591648879.453 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T20:41:19.453Z,1591648879.453 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T20:41:19.453Z,1591648879.453 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T20:42:03.079Z,1591648923.079 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2020-06-08T20:42:03.079Z,1591648923.079 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:42:03.089Z,1591648923.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:42:03.496Z,1591648923.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:42:03.496Z,1591648923.496 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2020-06-08T20:43:10.532Z,1591648990.532 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T20:43:10.533Z,1591648990.533 [NAL9602] Data Fault, FailCount= 1 2020-06-08T20:43:10.533Z,1591648990.533 [NAL9602](ERROR): Data Fault 2020-06-08T20:43:10.549Z,1591648990.549 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T20:43:10.932Z,1591648990.932 [NAL9602](INFO): Powering down 2020-06-08T20:43:11.756Z,1591648991.756 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T20:43:11.756Z,1591648991.756 [NAL9602] No Fault, FailCount= 1 2020-06-08T20:43:41.232Z,1591649021.232 [NAL9602](INFO): Powering up NAL9602 2020-06-08T20:43:52.152Z,1591649032.152 [NAL9602](INFO): NAL9602 initialized 2020-06-08T20:45:04.096Z,1591649104.096 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2020-06-08T20:45:04.096Z,1591649104.096 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:45:04.114Z,1591649104.114 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:45:04.512Z,1591649104.512 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:45:04.512Z,1591649104.512 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2020-06-08T20:46:20.041Z,1591649180.041 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T20:46:20.041Z,1591649180.041 [Default:CheckIn:C.Wait] Stopped 2020-06-08T20:46:20.041Z,1591649180.041 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T20:46:20.041Z,1591649180.041 [Default:CheckIn:D] Running Loop=1 2020-06-08T20:46:20.445Z,1591649180.445 [Default:CheckIn:D] Stopped 2020-06-08T20:46:20.445Z,1591649180.445 [Default:CheckIn:E] Running Loop=1 2020-06-08T20:46:20.837Z,1591649180.837 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.004134 min 2020-06-08T20:46:20.837Z,1591649180.837 [Default:CheckIn:E] Stopped 2020-06-08T20:46:20.837Z,1591649180.837 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T20:46:20.837Z,1591649180.837 [Default:CheckIn] Stopped 2020-06-08T20:46:20.838Z,1591649180.838 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T20:46:20.838Z,1591649180.838 [Default:CheckIn](INFO): Running loop #18 2020-06-08T20:46:20.838Z,1591649180.838 [Default:CheckIn] Running Loop=18 2020-06-08T20:46:20.838Z,1591649180.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T20:46:20.838Z,1591649180.838 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T20:48:05.080Z,1591649285.080 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2020-06-08T20:48:05.080Z,1591649285.080 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:48:05.116Z,1591649285.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:48:05.486Z,1591649285.486 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:48:05.487Z,1591649285.487 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2020-06-08T20:51:06.059Z,1591649466.059 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2020-06-08T20:51:06.060Z,1591649466.060 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:51:06.106Z,1591649466.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:51:06.513Z,1591649466.513 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:51:06.513Z,1591649466.513 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2020-06-08T20:51:21.001Z,1591649481.001 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T20:46:20.8Z 2020-06-08T20:51:21.001Z,1591649481.001 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T20:51:21.001Z,1591649481.001 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T20:51:28.026Z,1591649488.026 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200608T174706/Express0056.lzma 2020-06-08T20:51:29.029Z,1591649489.029 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0056.lzma.bak 2020-06-08T20:51:29.029Z,1591649489.029 [DataOverHttps](INFO): SBD MOMSN=12373079 2020-06-08T20:51:30.296Z,1591649490.296 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T20:51:30.296Z,1591649490.296 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T20:51:30.296Z,1591649490.296 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T20:53:54.509Z,1591649634.509 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T20:53:54.510Z,1591649634.510 [NAL9602] Data Fault, FailCount= 2 2020-06-08T20:53:54.510Z,1591649634.510 [NAL9602](ERROR): Data Fault 2020-06-08T20:53:54.537Z,1591649634.537 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T20:53:54.912Z,1591649634.912 [NAL9602](INFO): Powering down 2020-06-08T20:53:55.741Z,1591649635.741 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T20:53:55.741Z,1591649635.741 [NAL9602] No Fault, FailCount= 2 2020-06-08T20:54:07.038Z,1591649647.038 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2020-06-08T20:54:07.039Z,1591649647.039 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:54:07.048Z,1591649647.048 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:54:07.448Z,1591649647.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:54:07.448Z,1591649647.448 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2020-06-08T20:54:25.212Z,1591649665.212 [NAL9602](INFO): Powering up NAL9602 2020-06-08T20:54:36.120Z,1591649676.120 [NAL9602](INFO): NAL9602 initialized 2020-06-08T20:56:30.911Z,1591649790.911 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T20:56:30.911Z,1591649790.911 [Default:CheckIn:C.Wait] Stopped 2020-06-08T20:56:30.911Z,1591649790.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T20:56:30.912Z,1591649790.912 [Default:CheckIn:D] Running Loop=1 2020-06-08T20:56:31.328Z,1591649791.328 [Default:CheckIn:D] Stopped 2020-06-08T20:56:31.328Z,1591649791.328 [Default:CheckIn:E] Running Loop=1 2020-06-08T20:56:31.717Z,1591649791.717 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 185.185531 min 2020-06-08T20:56:31.717Z,1591649791.717 [Default:CheckIn:E] Stopped 2020-06-08T20:56:31.717Z,1591649791.717 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T20:56:31.717Z,1591649791.717 [Default:CheckIn] Stopped 2020-06-08T20:56:31.718Z,1591649791.718 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T20:56:31.718Z,1591649791.718 [Default:CheckIn](INFO): Running loop #19 2020-06-08T20:56:31.718Z,1591649791.718 [Default:CheckIn] Running Loop=19 2020-06-08T20:56:31.718Z,1591649791.718 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T20:56:31.718Z,1591649791.718 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T20:57:08.079Z,1591649828.079 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2020-06-08T20:57:08.079Z,1591649828.079 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T20:57:08.097Z,1591649828.097 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T20:57:08.492Z,1591649828.492 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T20:57:08.492Z,1591649828.492 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2020-06-08T21:00:09.067Z,1591650009.067 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2020-06-08T21:00:09.067Z,1591650009.067 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:00:09.085Z,1591650009.085 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:00:09.495Z,1591650009.495 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:00:09.495Z,1591650009.495 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2020-06-08T21:01:31.889Z,1591650091.889 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T20:56:31.7Z 2020-06-08T21:01:31.889Z,1591650091.889 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T21:01:31.889Z,1591650091.889 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T21:01:38.452Z,1591650098.452 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20200608T174706/Express0059.lzma 2020-06-08T21:01:39.453Z,1591650099.453 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0059.lzma.bak 2020-06-08T21:01:39.453Z,1591650099.453 [DataOverHttps](INFO): SBD MOMSN=12373096 2020-06-08T21:01:40.777Z,1591650100.777 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T21:01:40.777Z,1591650100.777 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T21:01:40.777Z,1591650100.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T21:03:10.064Z,1591650190.064 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2020-06-08T21:03:10.064Z,1591650190.064 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:03:10.074Z,1591650190.074 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:03:10.472Z,1591650190.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:03:10.472Z,1591650190.472 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2020-06-08T21:04:37.700Z,1591650277.700 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T21:04:37.701Z,1591650277.701 [NAL9602] Data Fault, FailCount= 3 2020-06-08T21:04:37.701Z,1591650277.701 [NAL9602](ERROR): Data Fault 2020-06-08T21:04:37.718Z,1591650277.718 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T21:04:38.108Z,1591650278.108 [NAL9602](INFO): Powering down 2020-06-08T21:04:38.928Z,1591650278.928 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T21:04:38.928Z,1591650278.928 [NAL9602] No Fault, FailCount= 3 2020-06-08T21:05:08.404Z,1591650308.404 [NAL9602](INFO): Powering up NAL9602 2020-06-08T21:05:19.312Z,1591650319.312 [NAL9602](INFO): NAL9602 initialized 2020-06-08T21:06:11.036Z,1591650371.036 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2020-06-08T21:06:11.036Z,1591650371.036 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:06:11.058Z,1591650371.058 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:06:11.448Z,1591650371.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:06:11.448Z,1591650371.448 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2020-06-08T21:06:41.341Z,1591650401.341 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T21:06:41.341Z,1591650401.341 [Default:CheckIn:C.Wait] Stopped 2020-06-08T21:06:41.341Z,1591650401.341 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T21:06:41.341Z,1591650401.341 [Default:CheckIn:D] Running Loop=1 2020-06-08T21:06:41.753Z,1591650401.753 [Default:CheckIn:D] Stopped 2020-06-08T21:06:41.753Z,1591650401.753 [Default:CheckIn:E] Running Loop=1 2020-06-08T21:06:42.161Z,1591650402.161 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.359261 min 2020-06-08T21:06:42.161Z,1591650402.161 [Default:CheckIn:E] Stopped 2020-06-08T21:06:42.161Z,1591650402.161 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T21:06:42.161Z,1591650402.161 [Default:CheckIn] Stopped 2020-06-08T21:06:42.162Z,1591650402.162 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T21:06:42.162Z,1591650402.162 [Default:CheckIn](INFO): Running loop #20 2020-06-08T21:06:42.162Z,1591650402.162 [Default:CheckIn] Running Loop=20 2020-06-08T21:06:42.162Z,1591650402.162 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T21:06:42.162Z,1591650402.162 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T21:09:12.031Z,1591650552.031 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2020-06-08T21:09:12.031Z,1591650552.031 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:09:12.060Z,1591650552.060 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:09:12.436Z,1591650552.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:09:12.436Z,1591650552.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2020-06-08T21:11:42.320Z,1591650702.320 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T21:06:42.2Z 2020-06-08T21:11:42.321Z,1591650702.321 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T21:11:42.321Z,1591650702.321 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T21:11:49.587Z,1591650709.587 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20200608T174706/Express0062.lzma 2020-06-08T21:11:50.589Z,1591650710.589 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0062.lzma.bak 2020-06-08T21:11:50.589Z,1591650710.589 [DataOverHttps](INFO): SBD MOMSN=12373122 2020-06-08T21:11:52.032Z,1591650712.032 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T21:11:52.032Z,1591650712.032 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T21:11:52.032Z,1591650712.032 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T21:12:13.021Z,1591650733.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2020-06-08T21:12:13.021Z,1591650733.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:12:13.031Z,1591650733.031 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:12:13.441Z,1591650733.441 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:12:13.441Z,1591650733.441 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2020-06-08T21:13:10.061Z,1591650790.061 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T21:13:53.278Z,1591650833.278 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T21:14:22.358Z,1591650862.358 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T21:14:49.844Z,1591650889.844 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T21:15:03.975Z,1591650903.975 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T21:15:14.012Z,1591650914.012 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2020-06-08T21:15:14.012Z,1591650914.012 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:15:14.022Z,1591650914.022 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:15:14.430Z,1591650914.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:15:14.430Z,1591650914.430 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2020-06-08T21:15:21.682Z,1591650921.682 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T21:15:21.682Z,1591650921.682 [NAL9602] Data Fault, FailCount= 4 2020-06-08T21:15:21.682Z,1591650921.682 [NAL9602](ERROR): Data Fault 2020-06-08T21:15:21.705Z,1591650921.705 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T21:15:22.084Z,1591650922.084 [NAL9602](INFO): Powering down 2020-06-08T21:15:22.909Z,1591650922.909 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T21:15:22.909Z,1591650922.909 [NAL9602] No Fault, FailCount= 4 2020-06-08T21:15:52.392Z,1591650952.392 [NAL9602](INFO): Powering up NAL9602 2020-06-08T21:15:53.670Z,1591650953.670 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-06-08T21:16:03.296Z,1591650963.296 [NAL9602](INFO): NAL9602 initialized 2020-06-08T21:16:17.901Z,1591650977.901 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-06-08T21:16:52.591Z,1591651012.591 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T21:16:52.592Z,1591651012.592 [Default:CheckIn:C.Wait] Stopped 2020-06-08T21:16:52.592Z,1591651012.592 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T21:16:52.592Z,1591651012.592 [Default:CheckIn:D] Running Loop=1 2020-06-08T21:16:53.016Z,1591651013.016 [Default:CheckIn:D] Stopped 2020-06-08T21:16:53.016Z,1591651013.016 [Default:CheckIn:E] Running Loop=1 2020-06-08T21:16:53.421Z,1591651013.421 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 205.546989 min 2020-06-08T21:16:53.421Z,1591651013.421 [Default:CheckIn:E] Stopped 2020-06-08T21:16:53.421Z,1591651013.421 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T21:16:53.421Z,1591651013.421 [Default:CheckIn] Stopped 2020-06-08T21:16:53.422Z,1591651013.422 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T21:16:53.422Z,1591651013.422 [Default:CheckIn](INFO): Running loop #21 2020-06-08T21:16:53.422Z,1591651013.422 [Default:CheckIn] Running Loop=21 2020-06-08T21:16:53.422Z,1591651013.422 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T21:16:53.422Z,1591651013.422 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T21:17:24.512Z,1591651044.512 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-06-08T21:17:24.514Z,1591651044.514 [BPC1](INFO): Received data from all battery sticks. 2020-06-08T21:18:15.028Z,1591651095.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2020-06-08T21:18:15.028Z,1591651095.028 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:18:15.038Z,1591651095.038 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:18:15.038Z,1591651095.038 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:21:53.580Z,1591651313.580 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T21:16:53.4Z 2020-06-08T21:21:53.580Z,1591651313.580 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T21:21:53.580Z,1591651313.580 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T21:22:00.146Z,1591651320.146 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20200608T174706/Courier0064.lzma 2020-06-08T21:22:01.149Z,1591651321.149 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0064.lzma.bak 2020-06-08T21:22:01.149Z,1591651321.149 [DataOverHttps](INFO): SBD MOMSN=12373149 2020-06-08T21:22:16.431Z,1591651336.431 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20200608T174706/Express0065.lzma 2020-06-08T21:22:17.433Z,1591651337.433 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0065.lzma.bak 2020-06-08T21:22:17.433Z,1591651337.433 [DataOverHttps](INFO): SBD MOMSN=12373151 2020-06-08T21:22:18.616Z,1591651338.616 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T21:22:18.616Z,1591651338.616 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T21:22:18.616Z,1591651338.616 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T21:26:04.453Z,1591651564.453 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T21:26:04.453Z,1591651564.453 [NAL9602] Data Fault, FailCount= 5 2020-06-08T21:26:04.453Z,1591651564.453 [NAL9602](ERROR): Data Fault 2020-06-08T21:26:04.468Z,1591651564.468 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T21:26:04.468Z,1591651564.468 [CBIT](CRITICAL): Data Fault in component: NAL9602 2020-06-08T21:26:04.852Z,1591651564.852 [NAL9602](INFO): Powering down 2020-06-08T21:26:04.874Z,1591651564.874 [CBIT](INFO): Critical error at 20200608T212604 2020-06-08T21:27:19.211Z,1591651639.211 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T21:27:19.212Z,1591651639.212 [Default:CheckIn:C.Wait] Stopped 2020-06-08T21:27:19.212Z,1591651639.212 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T21:27:19.212Z,1591651639.212 [Default:CheckIn:D] Running Loop=1 2020-06-08T21:27:19.603Z,1591651639.603 [Default:CheckIn:D] Stopped 2020-06-08T21:27:19.603Z,1591651639.603 [Default:CheckIn:E] Running Loop=1 2020-06-08T21:27:20.007Z,1591651640.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 215.990104 min 2020-06-08T21:27:20.007Z,1591651640.007 [Default:CheckIn:E] Stopped 2020-06-08T21:27:20.007Z,1591651640.007 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T21:27:20.008Z,1591651640.008 [Default:CheckIn] Stopped 2020-06-08T21:27:20.008Z,1591651640.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T21:27:20.008Z,1591651640.008 [Default:CheckIn](INFO): Running loop #22 2020-06-08T21:27:20.008Z,1591651640.008 [Default:CheckIn] Running Loop=22 2020-06-08T21:27:20.008Z,1591651640.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T21:27:20.008Z,1591651640.008 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T21:28:04.461Z,1591651684.461 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T21:28:04.461Z,1591651684.461 [NAL9602] No Fault, FailCount= 5 2020-06-08T21:28:04.844Z,1591651684.844 [NAL9602](INFO): Powering up NAL9602 2020-06-08T21:28:15.758Z,1591651695.758 [NAL9602](INFO): NAL9602 initialized 2020-06-08T21:32:20.198Z,1591651940.198 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-06-08T21:27:20.0Z 2020-06-08T21:32:20.198Z,1591651940.198 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T21:32:20.198Z,1591651940.198 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-06-08T21:32:27.586Z,1591651947.586 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20200608T174706/Courier0067.lzma 2020-06-08T21:32:28.589Z,1591651948.589 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Courier0067.lzma.bak 2020-06-08T21:32:28.589Z,1591651948.589 [DataOverHttps](INFO): SBD MOMSN=12373434 2020-06-08T21:32:42.801Z,1591651962.801 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20200608T174706/Express0068.lzma 2020-06-08T21:32:43.785Z,1591651963.785 [DataOverHttps](INFO): Moved sent file to Logs/20200608T174706/Express0068.lzma.bak 2020-06-08T21:32:43.785Z,1591651963.785 [DataOverHttps](INFO): SBD MOMSN=12373437 2020-06-08T21:32:45.238Z,1591651965.238 [Default:CheckIn:Read_Iridium] Stopped 2020-06-08T21:32:45.238Z,1591651965.238 [Default:CheckIn:C.Wait] Running Loop=1 2020-06-08T21:32:45.238Z,1591651965.238 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-06-08T21:33:15.137Z,1591651995.137 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:33:15.137Z,1591651995.137 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2020-06-08T21:36:15.728Z,1591652175.728 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-06-08T21:36:15.728Z,1591652175.728 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:36:15.738Z,1591652175.738 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:36:16.144Z,1591652176.144 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:36:16.144Z,1591652176.144 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-06-08T21:37:45.818Z,1591652265.818 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-06-08T21:37:45.818Z,1591652265.818 [Default:CheckIn:C.Wait] Stopped 2020-06-08T21:37:45.835Z,1591652265.835 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-06-08T21:37:45.836Z,1591652265.836 [Default:CheckIn:D] Running Loop=1 2020-06-08T21:37:46.229Z,1591652266.229 [Default:CheckIn:D] Stopped 2020-06-08T21:37:46.229Z,1591652266.229 [Default:CheckIn:E] Running Loop=1 2020-06-08T21:37:46.617Z,1591652266.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 226.433870 min 2020-06-08T21:37:46.617Z,1591652266.617 [Default:CheckIn:E] Stopped 2020-06-08T21:37:46.617Z,1591652266.617 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-06-08T21:37:46.617Z,1591652266.617 [Default:CheckIn] Stopped 2020-06-08T21:37:46.617Z,1591652266.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T21:37:46.618Z,1591652266.618 [Default:CheckIn](INFO): Running loop #23 2020-06-08T21:37:46.618Z,1591652266.618 [Default:CheckIn] Running Loop=23 2020-06-08T21:37:46.618Z,1591652266.618 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-06-08T21:37:46.618Z,1591652266.618 [Default:CheckIn:Read_GPS] Running Loop=1 2020-06-08T21:38:17.304Z,1591652297.304 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-06-08T21:38:17.304Z,1591652297.304 [NAL9602] Data Fault, FailCount= 1 2020-06-08T21:38:17.304Z,1591652297.304 [NAL9602](ERROR): Data Fault 2020-06-08T21:38:17.328Z,1591652297.328 [CBIT](ERROR): Data Fault in component: NAL9602 2020-06-08T21:38:17.718Z,1591652297.718 [NAL9602](INFO): Powering down 2020-06-08T21:38:18.536Z,1591652298.536 [CBIT](INFO): Clearing failed state for component NAL9602 2020-06-08T21:38:18.536Z,1591652298.536 [NAL9602] No Fault, FailCount= 1 2020-06-08T21:38:48.020Z,1591652328.020 [NAL9602](INFO): Powering up NAL9602 2020-06-08T21:38:58.051Z,1591652338.051 [CommandLine](IMPORTANT): got command failComponent 2020-06-08T21:38:58.052Z,1591652338.052 [CommandLine](IMPORTANT): Failed components: 2020-06-08T21:38:58.052Z,1591652338.052 [CommandLine](IMPORTANT): No failed Components. 2020-06-08T21:38:58.921Z,1591652338.921 [NAL9602](INFO): NAL9602 initialized 2020-06-08T21:39:00.204Z,1591652340.204 [CommandLine](IMPORTANT): got command show stack 2020-06-08T21:39:00.204Z,1591652340.204 [CommandLine](IMPORTANT): Behavior Stack: 2020-06-08T21:39:00.204Z,1591652340.204 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2020-06-08T21:39:00.204Z,1591652340.204 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS 2020-06-08T21:39:16.722Z,1591652356.722 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-06-08T21:39:16.722Z,1591652356.722 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:39:16.732Z,1591652356.732 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:39:17.136Z,1591652357.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:39:17.136Z,1591652357.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-06-08T21:39:51.684Z,1591652391.684 [CommandLine](IMPORTANT): got command show variable address 2020-06-08T21:39:51.686Z,1591652391.686 [CommandLine](IMPORTANT): acoustic_contact_address (unknown) 2020-06-08T21:39:51.798Z,1591652391.798 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.localAddress (count) 2020-06-08T21:39:51.807Z,1591652391.807 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum) 2020-06-08T21:39:51.807Z,1591652391.807 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.transponderAddress (enum) 2020-06-08T21:39:51.810Z,1591652391.810 [CommandLine](IMPORTANT): DAT.localAddress (count) 2020-06-08T21:39:51.810Z,1591652391.810 [CommandLine](IMPORTANT): DAT.sbdAddress (enum) 2020-06-08T21:39:51.815Z,1591652391.815 [CommandLine](IMPORTANT): DAT.transponderAddress (enum) 2020-06-08T21:39:51.818Z,1591652391.818 [CommandLine](IMPORTANT): Micromodem.localAddress (enum) 2020-06-08T21:39:51.818Z,1591652391.818 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum) 2020-06-08T21:39:52.030Z,1591652392.030 [CommandLine](IMPORTANT): DAT.remoteAddress (enum) 2020-06-08T21:39:52.035Z,1591652392.035 [CommandLine](IMPORTANT): DAT.localAddressReading (enum) 2020-06-08T21:39:56.705Z,1591652396.705 [CommandLine](IMPORTANT): got command get DAT.localAddress 2020-06-08T21:39:56.705Z,1591652396.705 [CommandLine](IMPORTANT): DAT.localAddress 7 count 2020-06-08T21:41:42.101Z,1591652502.101 [CommandLine](IMPORTANT): got command failComponent 2020-06-08T21:41:42.101Z,1591652502.101 [CommandLine](IMPORTANT): Failed components: 2020-06-08T21:41:42.101Z,1591652502.101 [CommandLine](IMPORTANT): No failed Components. 2020-06-08T21:41:44.508Z,1591652504.508 [CommandLine](IMPORTANT): got command failComponent hardware DAT 2020-06-08T21:41:44.508Z,1591652504.508 [DAT] Hardware Fault, FailCount= 1 2020-06-08T21:41:44.508Z,1591652504.508 [DAT](ERROR): Hardware Fault 2020-06-08T21:41:44.508Z,1591652504.508 [CommandLine](IMPORTANT): DAT failureMode is Hardware Fault 2020-06-08T21:41:44.587Z,1591652504.587 [DAT](INFO): Powering down 2020-06-08T21:41:44.732Z,1591652504.732 [CBIT](ERROR): Hardware Fault in component: DAT 2020-06-08T21:41:45.409Z,1591652505.409 [CBIT](INFO): Clearing failed state for component DAT 2020-06-08T21:41:45.409Z,1591652505.409 [DAT] No Fault, FailCount= 1 2020-06-08T21:41:47.809Z,1591652507.809 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-06-08T21:41:47.809Z,1591652507.809 [DAT](INFO): Powering up 2020-06-08T21:41:47.809Z,1591652507.809 [DAT](DEBUG): Initializing DAT. 2020-06-08T21:41:47.907Z,1591652507.907 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-06-08T21:41:49.661Z,1591652509.661 [CommandLine](IMPORTANT): got command failComponent 2020-06-08T21:41:49.661Z,1591652509.661 [CommandLine](IMPORTANT): Failed components: 2020-06-08T21:41:49.661Z,1591652509.661 [CommandLine](IMPORTANT): No failed Components. 2020-06-08T21:41:57.901Z,1591652517.901 [DAT](DEBUG): 2020-06-08T21:41:58.312Z,1591652518.312 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-06-08T21:41:59.114Z,1591652519.114 [DAT](DEBUG): MF Frequency Band 2020-06-08T21:41:59.528Z,1591652519.528 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21 2020-06-08T21:41:59.529Z,1591652519.529 [DAT](DEBUG): Jan 4 2000 03:10:12 2020-06-08T21:42:00.339Z,1591652520.339 [DAT](DEBUG): Features enabled [Bearing] 2020-06-08T21:42:00.340Z,1591652520.340 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-06-08T21:42:00.340Z,1591652520.340 [DAT](INFO): commRate: 800 2020-06-08T21:42:00.726Z,1591652520.726 [DAT](INFO): entering command mode 2020-06-08T21:42:01.125Z,1591652521.125 [DAT](DEBUG): checking for command mode acknowledgment 2020-06-08T21:42:01.125Z,1591652521.125 [DAT](INFO): command mode acknowledged 2020-06-08T21:42:01.125Z,1591652521.125 [DAT](INFO): setting verbose to 3 2020-06-08T21:42:01.547Z,1591652521.547 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-06-08T21:42:01.548Z,1591652521.548 [DAT](INFO): set verbose to 3 2020-06-08T21:42:01.955Z,1591652521.955 [DAT](INFO): setting transmit power to 8 2020-06-08T21:42:02.347Z,1591652522.347 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-06-08T21:42:02.742Z,1591652522.742 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-06-08T21:42:02.742Z,1591652522.742 [DAT](INFO): set transmit power to 8 2020-06-08T21:42:03.153Z,1591652523.153 [DAT](INFO): setting local address to 7 2020-06-08T21:42:03.559Z,1591652523.559 [DAT](DEBUG): checking for local address setting acknowledgment 2020-06-08T21:42:03.967Z,1591652523.967 [DAT](DEBUG): checking for local address setting acknowledgment 2020-06-08T21:42:03.968Z,1591652523.968 [DAT](INFO): set local address to 7 2020-06-08T21:42:17.724Z,1591652537.724 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-06-08T21:42:17.724Z,1591652537.724 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-06-08T21:42:17.734Z,1591652537.734 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-06-08T21:42:18.136Z,1591652538.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-06-08T21:42:18.136Z,1591652538.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-06-08T21:42:33.827Z,1591652553.827 [CommandLine](IMPORTANT): got command 2020-06-08T21:42:33.828Z,1591652553.828 [CommandLine](FAULT): Incomplete syntax. Try: help 2020-06-08T21:42:37.043Z,1591652557.043 [CommandLine](IMPORTANT): got command failComponent 2020-06-08T21:42:37.044Z,1591652557.044 [CommandLine](IMPORTANT): Failed components: 2020-06-08T21:42:37.044Z,1591652557.044 [CommandLine](IMPORTANT): No failed Components. 2020-06-08T21:42:38.196Z,1591652558.196 [CommandLine](IMPORTANT): got command quit 2020-06-08T21:42:39.203Z,1591652559.203 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:39.203Z,1591652559.203 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:39.359Z,1591652559.359 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-06-08T21:42:39.359Z,1591652559.359 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:39.360Z,1591652559.360 [CommandLine](INFO): Join timeout helper Thread ID is 2721 2020-06-08T21:42:39.360Z,1591652559.360 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-06-08T21:42:39.360Z,1591652559.360 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:39.361Z,1591652559.361 [NavChartDb](INFO): Join timeout helper Thread ID is 2722 2020-06-08T21:42:39.627Z,1591652559.627 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:39.627Z,1591652559.627 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:39.647Z,1591652559.647 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-06-08T21:42:39.647Z,1591652559.647 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:39.647Z,1591652559.647 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2723 2020-06-08T21:42:39.739Z,1591652559.739 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:39.739Z,1591652559.739 [WetLabsBB2FL](INFO): Powering down 2020-06-08T21:42:39.740Z,1591652559.740 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:39.751Z,1591652559.751 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-06-08T21:42:39.751Z,1591652559.751 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:39.751Z,1591652559.751 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2724 2020-06-08T21:42:40.035Z,1591652560.035 [CTD_Seabird](INFO): Powering down 2020-06-08T21:42:40.047Z,1591652560.047 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:40.047Z,1591652560.047 [CTD_Seabird](INFO): Powering down 2020-06-08T21:42:40.059Z,1591652560.059 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:40.079Z,1591652560.079 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-06-08T21:42:40.079Z,1591652560.079 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:40.079Z,1591652560.079 [Radio_Surface](INFO): Join timeout helper Thread ID is 2725 2020-06-08T21:42:40.419Z,1591652560.419 [Radio_Surface](INFO): Powering down 2020-06-08T21:42:40.420Z,1591652560.420 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:40.420Z,1591652560.420 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:40.424Z,1591652560.424 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-06-08T21:42:40.424Z,1591652560.424 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:40.424Z,1591652560.424 [Onboard](INFO): Join timeout helper Thread ID is 2726 2020-06-08T21:42:41.099Z,1591652561.099 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-06-08T21:42:43.479Z,1591652563.479 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:43.479Z,1591652563.479 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:43.499Z,1591652563.499 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-06-08T21:42:43.500Z,1591652563.500 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:43.500Z,1591652563.500 [DataOverHttps](INFO): Join timeout helper Thread ID is 2727 2020-06-08T21:42:43.584Z,1591652563.584 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2305760 %. Average:283984.625000 2020-06-08T21:42:44.111Z,1591652564.111 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:44.111Z,1591652564.111 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.135Z,1591652564.135 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-06-08T21:42:44.135Z,1591652564.135 [logger ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.135Z,1591652564.135 [logger](INFO): Join timeout helper Thread ID is 2728 2020-06-08T21:42:44.135Z,1591652564.135 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:44.136Z,1591652564.136 [logger ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.137Z,1591652564.137 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-06-08T21:42:44.137Z,1591652564.137 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.137Z,1591652564.137 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-06-08T21:42:44.137Z,1591652564.137 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.137Z,1591652564.137 [controlThread](INFO): Join timeout helper Thread ID is 2729 2020-06-08T21:42:44.343Z,1591652564.343 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-08T21:42:44.343Z,1591652564.343 [controlThread](DEBUG): Uninitializing ControlThread 2020-06-08T21:42:44.343Z,1591652564.343 [AHRS_M2](INFO): Powering down 2020-06-08T21:42:44.415Z,1591652564.415 [NAL9602](INFO): Powering down 2020-06-08T21:42:44.416Z,1591652564.416 [DAT](INFO): Powering down 2020-06-08T21:42:44.537Z,1591652564.537 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-06-08T21:42:44.537Z,1591652564.537 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-06-08T21:42:44.538Z,1591652564.538 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-06-08T21:42:44.538Z,1591652564.538 [MissionManager](INFO): Uninitializing Mission Default 2020-06-08T21:42:44.538Z,1591652564.538 [Default] Stopped 2020-06-08T21:42:44.539Z,1591652564.539 [Default](DEBUG): Aggregate::uninitialize Default 2020-06-08T21:42:44.539Z,1591652564.539 [Default:B.GoToSurface] Stopped 2020-06-08T21:42:44.539Z,1591652564.539 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-06-08T21:42:44.539Z,1591652564.539 [Default:CheckIn] Stopped 2020-06-08T21:42:44.539Z,1591652564.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-06-08T21:42:44.539Z,1591652564.539 [Default:CheckIn:Read_GPS] Stopped 2020-06-08T21:42:44.542Z,1591652564.542 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-06-08T21:42:44.542Z,1591652564.542 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-06-08T21:42:44.542Z,1591652564.542 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-06-08T21:42:44.542Z,1591652564.542 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-06-08T21:42:44.543Z,1591652564.543 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-08T21:42:44.543Z,1591652564.543 [BuoyancyServo](INFO): Powering down 2020-06-08T21:42:44.555Z,1591652564.555 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-06-08T21:42:44.555Z,1591652564.555 [ElevatorServo](INFO): Powering down 2020-06-08T21:42:44.556Z,1591652564.556 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-06-08T21:42:44.556Z,1591652564.556 [MassServo](INFO): Powering down 2020-06-08T21:42:44.557Z,1591652564.557 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-06-08T21:42:44.557Z,1591652564.557 [RudderServo](INFO): Powering down 2020-06-08T21:42:44.557Z,1591652564.557 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-06-08T21:42:44.558Z,1591652564.558 [ThrusterServo](INFO): Powering down 2020-06-08T21:42:44.558Z,1591652564.558 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-06-08T21:42:44.559Z,1591652564.559 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-06-08T21:42:44.559Z,1591652564.559 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-06-08T21:42:44.559Z,1591652564.559 [CBIT](DEBUG): Powering off loads. 2020-06-08T21:42:44.571Z,1591652564.571 [CBIT](DEBUG): Disabling WDT. 2020-06-08T21:42:44.583Z,1591652564.583 [CBIT](DEBUG): Opening all GF detection circuits. 2020-06-08T21:42:44.583Z,1591652564.583 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.674Z,1591652564.674 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.676Z,1591652564.676 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.681Z,1591652564.681 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.713Z,1591652564.713 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.717Z,1591652564.717 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.760Z,1591652564.760 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-08T21:42:44.822Z,1591652564.822 [logger ThreadHandler](INFO): Thread cancelled.