2021-07-27T18:40:56.875Z,1627411256.875 [Supervisor](DEBUG): Initializing supervisor. 2021-07-27T18:40:56.880Z,1627411256.880 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-07-27T18:40:56.880Z,1627411256.880 [SyncHandler](INFO): Protected caller Thread ID is 1232 2021-07-27T18:40:56.881Z,1627411256.881 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-07-27T18:40:56.882Z,1627411256.882 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-07-27T18:40:56.882Z,1627411256.882 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1233 2021-07-27T18:40:56.886Z,1627411256.886 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-07-27T18:40:56.908Z,1627411256.908 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-07-27T18:40:56.909Z,1627411256.909 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-07-27T18:40:56.909Z,1627411256.909 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1234 2021-07-27T18:40:56.911Z,1627411256.911 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-07-27T18:40:56.912Z,1627411256.912 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-07-27T18:40:56.913Z,1627411256.913 [logger ThreadHandler](INFO): Protected caller Thread ID is 1235 2021-07-27T18:40:56.917Z,1627411256.917 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-07-27T18:40:56.917Z,1627411256.917 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-07-27T18:40:56.919Z,1627411256.919 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-07-27T18:40:57.339Z,1627411257.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-07-27T18:40:57.339Z,1627411257.339 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-07-27T18:40:57.487Z,1627411257.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-07-27T18:40:57.487Z,1627411257.487 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-07-27T18:40:57.568Z,1627411257.568 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-07-27T18:40:57.788Z,1627411257.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-07-27T18:40:57.789Z,1627411257.789 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-07-27T18:40:57.867Z,1627411257.867 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-07-27T18:40:57.962Z,1627411257.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-07-27T18:40:57.963Z,1627411257.963 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-07-27T18:40:58.309Z,1627411258.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-07-27T18:40:58.309Z,1627411258.309 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-07-27T18:40:58.433Z,1627411258.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-07-27T18:40:58.434Z,1627411258.434 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-07-27T18:40:58.969Z,1627411258.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-07-27T18:40:58.970Z,1627411258.970 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-07-27T18:40:59.164Z,1627411259.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-07-27T18:40:59.164Z,1627411259.164 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-07-27T18:40:59.549Z,1627411259.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-07-27T18:40:59.549Z,1627411259.549 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-07-27T18:40:59.792Z,1627411259.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-07-27T18:40:59.792Z,1627411259.792 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-07-27T18:40:59.904Z,1627411259.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-07-27T18:40:59.905Z,1627411259.905 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-07-27T18:41:00.570Z,1627411260.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-07-27T18:41:00.570Z,1627411260.570 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-07-27T18:41:00.984Z,1627411260.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-07-27T18:41:00.985Z,1627411260.985 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-07-27T18:41:01.613Z,1627411261.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-07-27T18:41:01.615Z,1627411261.615 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2021-07-27T18:41:01.616Z,1627411261.616 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2021-07-27T18:41:02.033Z,1627411262.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2021-07-27T18:41:02.154Z,1627411262.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2021-07-27T18:41:02.294Z,1627411262.294 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2021-07-27T18:41:02.380Z,1627411262.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2021-07-27T18:41:02.646Z,1627411262.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-07-27T18:41:02.646Z,1627411262.646 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2021-07-27T18:41:02.750Z,1627411262.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2021-07-27T18:41:02.863Z,1627411262.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2021-07-27T18:41:02.957Z,1627411262.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2021-07-27T18:41:03.103Z,1627411263.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2021-07-27T18:41:03.201Z,1627411263.201 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2021-07-27T18:41:03.294Z,1627411263.294 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2021-07-27T18:41:03.305Z,1627411263.305 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-07-27T18:41:03.317Z,1627411263.317 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-07-27T18:41:03.318Z,1627411263.318 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-07-27T18:41:03.420Z,1627411263.420 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-07-27T18:41:03.421Z,1627411263.421 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-07-27T18:41:03.479Z,1627411263.479 [VerticalControl](DEBUG): Construct VerticalControl. 2021-07-27T18:41:03.543Z,1627411263.543 [VerticalControl] Loaded 2021-07-27T18:41:03.543Z,1627411263.543 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-07-27T18:41:03.546Z,1627411263.546 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-07-27T18:41:03.585Z,1627411263.585 [HorizontalControl] Loaded 2021-07-27T18:41:03.585Z,1627411263.585 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-07-27T18:41:03.588Z,1627411263.588 [SpeedControl](DEBUG): Construct SpeedControl. 2021-07-27T18:41:03.591Z,1627411263.591 [SpeedControl] Loaded 2021-07-27T18:41:03.591Z,1627411263.591 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-07-27T18:41:03.594Z,1627411263.594 [LoopControl](DEBUG): Construct LoopControl. 2021-07-27T18:41:03.594Z,1627411263.594 [LoopControl] Loaded 2021-07-27T18:41:03.595Z,1627411263.595 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-07-27T18:41:03.595Z,1627411263.595 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-07-27T18:41:03.596Z,1627411263.596 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-07-27T18:41:03.756Z,1627411263.756 [BuoyancyServo] Loaded 2021-07-27T18:41:03.756Z,1627411263.756 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-07-27T18:41:03.775Z,1627411263.775 [ElevatorServo] Loaded 2021-07-27T18:41:03.776Z,1627411263.776 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-07-27T18:41:03.795Z,1627411263.795 [MassServo] Loaded 2021-07-27T18:41:03.795Z,1627411263.795 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-07-27T18:41:03.813Z,1627411263.813 [RudderServo] Loaded 2021-07-27T18:41:03.814Z,1627411263.814 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-07-27T18:41:03.829Z,1627411263.829 [ThrusterHE] Loaded 2021-07-27T18:41:03.829Z,1627411263.829 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2021-07-27T18:41:03.830Z,1627411263.830 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-07-27T18:41:03.830Z,1627411263.830 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-07-27T18:41:03.875Z,1627411263.875 [DepthRateCalculator] Loaded 2021-07-27T18:41:03.876Z,1627411263.876 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-07-27T18:41:03.880Z,1627411263.880 [PitchRateCalculator] Loaded 2021-07-27T18:41:03.881Z,1627411263.881 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-07-27T18:41:03.891Z,1627411263.891 [SpeedCalculator] Loaded 2021-07-27T18:41:03.891Z,1627411263.891 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-07-27T18:41:03.905Z,1627411263.905 [TempGradientCalculator] Loaded 2021-07-27T18:41:03.905Z,1627411263.905 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-07-27T18:41:03.910Z,1627411263.910 [YawRateCalculator] Loaded 2021-07-27T18:41:03.910Z,1627411263.910 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-07-27T18:41:03.928Z,1627411263.928 [ElevatorOffsetCalculator] Loaded 2021-07-27T18:41:03.928Z,1627411263.928 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-07-27T18:41:03.929Z,1627411263.929 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-07-27T18:41:03.929Z,1627411263.929 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-07-27T18:41:04.014Z,1627411264.014 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-07-27T18:41:04.014Z,1627411264.014 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-07-27T18:41:04.028Z,1627411264.028 [NavChart] Loaded 2021-07-27T18:41:04.029Z,1627411264.029 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-07-27T18:41:04.034Z,1627411264.034 [UniversalFixResidualReporter] Loaded 2021-07-27T18:41:04.035Z,1627411264.035 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-07-27T18:41:04.035Z,1627411264.035 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-07-27T18:41:04.036Z,1627411264.036 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-07-27T18:41:04.430Z,1627411264.430 [AHRS_M2] Loaded 2021-07-27T18:41:04.430Z,1627411264.430 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-07-27T18:41:04.461Z,1627411264.461 [BackseatComponent] Loaded 2021-07-27T18:41:04.461Z,1627411264.461 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2021-07-27T18:41:04.462Z,1627411264.462 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4086C4E0 2021-07-27T18:41:04.463Z,1627411264.463 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1317 2021-07-27T18:41:04.466Z,1627411264.466 [LcmUniversalReporter] Loaded 2021-07-27T18:41:04.466Z,1627411264.466 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2021-07-27T18:41:05.282Z,1627411265.282 [BPC1] Loaded 2021-07-27T18:41:05.283Z,1627411265.283 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-07-27T18:41:05.357Z,1627411265.357 [DataOverHttps] Loaded 2021-07-27T18:41:05.357Z,1627411265.357 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-07-27T18:41:05.358Z,1627411265.358 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4089C4E0 2021-07-27T18:41:05.358Z,1627411265.358 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1318 2021-07-27T18:41:05.379Z,1627411265.379 [Depth_Keller] Loaded 2021-07-27T18:41:05.379Z,1627411265.379 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-07-27T18:41:05.384Z,1627411265.384 [DropWeight] Loaded 2021-07-27T18:41:05.384Z,1627411265.384 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-07-27T18:41:05.444Z,1627411265.444 [NAL9602] Loaded 2021-07-27T18:41:05.445Z,1627411265.445 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-07-27T18:41:05.476Z,1627411265.476 [Onboard] Loaded 2021-07-27T18:41:05.476Z,1627411265.476 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-07-27T18:41:05.477Z,1627411265.477 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408CC4E0 2021-07-27T18:41:05.477Z,1627411265.477 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1319 2021-07-27T18:41:05.490Z,1627411265.490 [Power24vConverter] Loaded 2021-07-27T18:41:05.490Z,1627411265.490 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-07-27T18:41:05.504Z,1627411265.504 [Radio_Surface] Loaded 2021-07-27T18:41:05.504Z,1627411265.504 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-07-27T18:41:05.505Z,1627411265.505 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408FC4E0 2021-07-27T18:41:05.505Z,1627411265.505 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1320 2021-07-27T18:41:05.506Z,1627411265.506 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-07-27T18:41:05.507Z,1627411265.507 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-07-27T18:41:05.569Z,1627411265.569 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-07-27T18:41:05.569Z,1627411265.569 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-07-27T18:41:05.683Z,1627411265.683 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-07-27T18:41:05.684Z,1627411265.684 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-07-27T18:41:05.714Z,1627411265.714 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-07-27T18:41:05.714Z,1627411265.714 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-07-27T18:41:05.900Z,1627411265.900 [CTD_Seabird] Loaded 2021-07-27T18:41:05.900Z,1627411265.900 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-07-27T18:41:05.901Z,1627411265.901 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A1B4E0 2021-07-27T18:41:05.902Z,1627411265.902 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1321 2021-07-27T18:41:05.922Z,1627411265.922 [PAR_Licor] Loaded 2021-07-27T18:41:05.922Z,1627411265.922 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-07-27T18:41:05.955Z,1627411265.955 [WetLabsBB2FL] Loaded 2021-07-27T18:41:05.955Z,1627411265.955 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-07-27T18:41:05.956Z,1627411265.956 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A4B4E0 2021-07-27T18:41:05.956Z,1627411265.956 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1322 2021-07-27T18:41:05.957Z,1627411265.957 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-07-27T18:41:05.958Z,1627411265.958 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-07-27T18:41:06.587Z,1627411266.587 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-07-27T18:41:06.588Z,1627411266.588 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-07-27T18:41:06.898Z,1627411266.898 [SBIT](DEBUG): Construct Startup Built In Test. 2021-07-27T18:41:06.907Z,1627411266.907 [SBIT] Loaded 2021-07-27T18:41:06.908Z,1627411266.908 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-07-27T18:41:06.911Z,1627411266.911 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-07-27T18:41:06.924Z,1627411266.924 [IBIT] Loaded 2021-07-27T18:41:06.925Z,1627411266.925 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-07-27T18:41:06.930Z,1627411266.930 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-07-27T18:41:07.034Z,1627411267.034 [CBIT] Loaded 2021-07-27T18:41:07.035Z,1627411267.035 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-07-27T18:41:07.035Z,1627411267.035 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-07-27T18:41:07.041Z,1627411267.041 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-07-27T18:41:07.044Z,1627411267.044 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-07-27T18:41:07.057Z,1627411267.057 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-07-27T18:41:07.058Z,1627411267.058 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B314E0 2021-07-27T18:41:07.058Z,1627411267.058 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1323 2021-07-27T18:41:07.063Z,1627411267.063 [Supervisor](INFO): Main Thread ID is 831 2021-07-27T18:41:07.063Z,1627411267.063 [Supervisor](DEBUG): Running supervisor. 2021-07-27T18:41:07.064Z,1627411267.064 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1324 2021-07-27T18:41:07.066Z,1627411267.066 [controlThread ThreadHandler](INFO): Handler Thread ID is 1325 2021-07-27T18:41:07.067Z,1627411267.067 [controlThread](DEBUG): Initializing ControlThread 2021-07-27T18:41:07.067Z,1627411267.067 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-07-27T18:41:07.069Z,1627411267.069 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-07-27T18:41:07.070Z,1627411267.070 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-07-27T18:41:07.071Z,1627411267.071 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-07-27T18:41:07.072Z,1627411267.072 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-07-27T18:41:07.073Z,1627411267.073 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-07-27T18:41:07.073Z,1627411267.073 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-07-27T18:41:07.073Z,1627411267.073 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-07-27T18:41:07.074Z,1627411267.074 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-07-27T18:41:07.074Z,1627411267.074 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-07-27T18:41:07.076Z,1627411267.076 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-07-27T18:41:07.076Z,1627411267.076 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-07-27T18:41:07.084Z,1627411267.084 [SBIT](INFO): Initialize SBIT Component. 2021-07-27T18:41:07.085Z,1627411267.085 [SBIT](IMPORTANT): git: 2021-07-27 2021-07-27T18:41:07.085Z,1627411267.085 [SBIT](INFO): git hash: 5707822c30af8335208c08218c2782520cd217e2 2021-07-27T18:41:07.085Z,1627411267.085 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-07-27T18:41:07.087Z,1627411267.087 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2021-07-27T18:41:07.088Z,1627411267.088 [SBIT](INFO): Beginning SBIT in 67.000000 seconds. 2021-07-27T18:41:07.089Z,1627411267.089 [IBIT](INFO): Initialize IBIT Component. 2021-07-27T18:41:07.089Z,1627411267.089 [CBIT](DEBUG): Initialize CBIT Component. 2021-07-27T18:41:07.090Z,1627411267.090 [logger ThreadHandler](INFO): Handler Thread ID is 1326 2021-07-27T18:41:07.104Z,1627411267.104 [CBIT](DEBUG): Initialized mux pins. 2021-07-27T18:41:07.104Z,1627411267.104 [CBIT](DEBUG): Initializing the watchdog timer. 2021-07-27T18:41:07.108Z,1627411267.108 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1327 2021-07-27T18:41:07.117Z,1627411267.117 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1328 2021-07-27T18:41:07.117Z,1627411267.117 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-07-27T18:41:07.124Z,1627411267.124 [Onboard ThreadHandler](INFO): Handler Thread ID is 1329 2021-07-27T18:41:07.129Z,1627411267.129 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-07-27T18:41:07.129Z,1627411267.129 [CBIT](DEBUG): Initializing heartbeat. 2021-07-27T18:41:07.145Z,1627411267.145 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1330 2021-07-27T18:41:07.160Z,1627411267.160 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1331 2021-07-27T18:41:07.161Z,1627411267.161 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-07-27T18:41:07.166Z,1627411267.166 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1333 2021-07-27T18:41:07.168Z,1627411267.168 [WetLabsBB2FL](INFO): Powering up 2021-07-27T18:41:07.170Z,1627411267.170 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1335 2021-07-27T18:41:07.173Z,1627411267.173 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-07-27T18:41:07.174Z,1627411267.174 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-07-27T18:41:07.174Z,1627411267.174 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-07-27T18:41:07.174Z,1627411267.174 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-07-27T18:41:07.174Z,1627411267.174 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-07-27T18:41:07.175Z,1627411267.175 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-07-27T18:41:07.175Z,1627411267.175 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-07-27T18:41:07.175Z,1627411267.175 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-07-27T18:41:07.175Z,1627411267.175 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-07-27T18:41:07.175Z,1627411267.175 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-07-27T18:41:07.176Z,1627411267.176 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-07-27T18:41:07.176Z,1627411267.176 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-07-27T18:41:07.177Z,1627411267.177 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-07-27T18:41:07.177Z,1627411267.177 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-07-27T18:41:07.177Z,1627411267.177 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-07-27T18:41:07.177Z,1627411267.177 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-07-27T18:41:07.200Z,1627411267.200 [CBIT](DEBUG): Deactivating GF circuits. 2021-07-27T18:41:07.200Z,1627411267.200 [CBIT](DEBUG): Deactivating emergency mode. 2021-07-27T18:41:07.236Z,1627411267.236 [CBIT](DEBUG): Backplane powered. 2021-07-27T18:41:07.237Z,1627411267.237 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-07-27T18:41:07.251Z,1627411267.251 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-07-27T18:41:07.283Z,1627411267.283 [MissionManager](DEBUG): 2021-07-27T18:41:07.283Z,1627411267.283 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-07-27T18:41:07.354Z,1627411267.354 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-07-27T18:41:07.355Z,1627411267.355 [Default:A.Wait](DEBUG): Construct Wait. 2021-07-27T18:41:07.374Z,1627411267.374 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-07-27T18:41:07.421Z,1627411267.421 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-07-27T18:41:07.424Z,1627411267.424 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-07-27T18:41:07.437Z,1627411267.437 [Default:E.Execute](DEBUG): Construct Execute. 2021-07-27T18:41:07.461Z,1627411267.461 [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 2021-07-27T18:41:07.467Z,1627411267.467 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2021-07-27T18:41:07.490Z,1627411267.490 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-07-27T18:41:07.548Z,1627411267.548 [Radio_Surface](INFO): Powering up 2021-07-27T18:41:07.561Z,1627411267.561 [Depth_Keller](ERROR): Pressure reading out of range: 962.881653 decibar 2021-07-27T18:41:07.562Z,1627411267.562 [Power24vConverter](INFO): Powering up. 2021-07-27T18:41:07.582Z,1627411267.582 [DepthRateCalculator](ERROR): Depth measurement is not active 2021-07-27T18:41:07.622Z,1627411267.622 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-07-27T18:41:07.628Z,1627411267.628 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-07-27T18:41:07.629Z,1627411267.629 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-07-27T18:41:07.636Z,1627411267.636 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-07-27T18:41:07.637Z,1627411267.637 [MassServo](DEBUG): Initializing EZServoServo. 2021-07-27T18:41:07.644Z,1627411267.644 [MassServo](DEBUG): Initializing MassServo. 2021-07-27T18:41:07.645Z,1627411267.645 [RudderServo](DEBUG): Initializing EZServoServo. 2021-07-27T18:41:07.652Z,1627411267.652 [RudderServo](DEBUG): Initializing RudderServo. 2021-07-27T18:41:07.653Z,1627411267.653 [ThrusterHE](DEBUG): Initializing EZServoServo. 2021-07-27T18:41:07.660Z,1627411267.660 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2021-07-27T18:41:09.276Z,1627411269.276 [WetLabsBB2FL](INFO): Powering down 2021-07-27T18:41:14.192Z,1627411274.192 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-07-27T18:41:35.762Z,1627411295.762 [NAL9602](INFO): Powering up NAL9602 2021-07-27T18:41:46.666Z,1627411306.666 [NAL9602](INFO): NAL9602 initialized 2021-07-27T18:42:14.596Z,1627411334.596 [SBIT](IMPORTANT): Beginning Startup BIT 2021-07-27T18:42:14.608Z,1627411334.608 [CBIT](IMPORTANT): Beginning ground fault scan 2021-07-27T18:42:25.577Z,1627411345.577 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.251379 CHAN A1 (24V): -0.012743 CHAN A2 (12V): 0.158557 CHAN A3 (5V): 0.034634 CHAN B0 (3.3V): 0.020616 CHAN B1 (3.15aV): 0.020169 CHAN B2 (3.15bV): 0.019688 CHAN B3 (GND): -0.013429 OPEN: 0.010041 Full Scale Calc: 4.765 mA, -1.589 mA 2021-07-27T18:43:08.314Z,1627411388.314 [SBIT](IMPORTANT): SBIT PASSED 2021-07-27T18:43:08.314Z,1627411388.314 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2021-07-27T18:43:08.315Z,1627411388.315 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count; 2021-07-27T18:43:08.315Z,1627411388.315 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 minute; 2021-07-27T18:43:08.315Z,1627411388.315 [SBIT](IMPORTANT): BPC1.batteryStickCommsTimeout=10 second; 2021-07-27T18:43:08.315Z,1627411388.315 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2021-07-27T18:43:08.315Z,1627411388.315 [SBIT](IMPORTANT): CBIT.gf24Offset=-27 microampere; 2021-07-27T18:43:08.315Z,1627411388.315 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2021-07-27T18:43:08.316Z,1627411388.316 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2021-07-27T18:43:08.320Z,1627411388.320 [SBIT](IMPORTANT): Express linearApproximation platform_pitch_angle 5.000000 degree; 2021-07-27T18:43:08.320Z,1627411388.320 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree; 2021-07-27T18:43:08.321Z,1627411388.321 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=148.198110 cubic_centimeter; 2021-07-27T18:43:08.321Z,1627411388.321 [SBIT](IMPORTANT): VerticalControl.massDefault=11.996887 millimeter; 2021-07-27T18:43:08.710Z,1627411388.710 [MissionManager](IMPORTANT): Started mission Startup 2021-07-27T18:43:08.710Z,1627411388.710 [Startup] Running Loop=1 2021-07-27T18:43:08.710Z,1627411388.710 [Startup](DEBUG): Aggregate::initialize Startup 2021-07-27T18:43:08.710Z,1627411388.710 [Startup:A.GoToSurface] Running Loop=1 2021-07-27T18:43:08.710Z,1627411388.710 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-07-27T18:43:08.711Z,1627411388.711 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-07-27T18:43:08.711Z,1627411388.711 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-07-27T18:43:08.712Z,1627411388.712 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-07-27T18:43:08.713Z,1627411388.713 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-07-27T18:43:08.713Z,1627411388.713 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-07-27T18:43:08.715Z,1627411388.715 [Startup:StartupSatComms] Running Loop=1 2021-07-27T18:43:08.715Z,1627411388.715 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-07-27T18:43:08.715Z,1627411388.715 [Startup:StartupSatComms:A] Running Loop=1 2021-07-27T18:43:09.106Z,1627411389.106 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-07-27T18:43:34.910Z,1627411414.910 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004933 2021-07-27T18:43:35.345Z,1627411415.345 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:43:49.075Z,1627411429.075 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:44:02.413Z,1627411442.413 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:44:07.313Z,1627411447.313 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-07-27T18:44:07.313Z,1627411447.313 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-07-27T18:44:07.330Z,1627411447.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-07-27T18:44:07.702Z,1627411447.702 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-07-27T18:44:07.702Z,1627411447.702 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-07-27T18:44:08.890Z,1627411448.890 [Startup:StartupSatComms:A](INFO): Timed out from 2021-07-27T18:43:08.7Z 2021-07-27T18:44:08.890Z,1627411448.890 [Startup:StartupSatComms:A] Stopped 2021-07-27T18:44:08.890Z,1627411448.890 [Startup:StartupSatComms:B] Running Loop=1 2021-07-27T18:44:09.293Z,1627411449.293 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-07-27T18:44:15.739Z,1627411455.739 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:44:17.612Z,1627411457.612 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210727T153650/Courier0058.lzma 2021-07-27T18:44:18.614Z,1627411458.614 [DataOverHttps](INFO): Moved sent file to Logs/20210727T153650/Courier0058.lzma.bak 2021-07-27T18:44:18.614Z,1627411458.614 [DataOverHttps](INFO): SBD MOMSN=15764279 2021-07-27T18:44:29.071Z,1627411469.071 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:44:34.051Z,1627411474.051 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210727T184056/Courier0000.lzma 2021-07-27T18:44:35.054Z,1627411475.054 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Courier0000.lzma.bak 2021-07-27T18:44:35.054Z,1627411475.054 [DataOverHttps](INFO): SBD MOMSN=15764281 2021-07-27T18:44:43.211Z,1627411483.211 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:44:50.560Z,1627411490.560 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20210727T153650/Express0059.lzma 2021-07-27T18:44:51.562Z,1627411491.562 [DataOverHttps](INFO): Moved sent file to Logs/20210727T153650/Express0059.lzma.bak 2021-07-27T18:44:51.562Z,1627411491.562 [DataOverHttps](INFO): SBD MOMSN=15764285 2021-07-27T18:44:56.947Z,1627411496.947 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:45:07.056Z,1627411507.056 [DataOverHttps](INFO): Sending 1004 bytes from file Logs/20210727T184056/Express0001.lzma 2021-07-27T18:45:08.054Z,1627411508.054 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Express0001.lzma.bak 2021-07-27T18:45:08.054Z,1627411508.054 [DataOverHttps](INFO): SBD MOMSN=15764290 2021-07-27T18:45:09.108Z,1627411509.108 [Startup:StartupSatComms:B](INFO): Timed out from 2021-07-27T18:44:08.9Z 2021-07-27T18:45:09.108Z,1627411509.108 [Startup:StartupSatComms:B] Stopped 2021-07-27T18:45:09.109Z,1627411509.109 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-07-27T18:45:09.109Z,1627411509.109 [Startup:StartupSatComms] Stopped 2021-07-27T18:45:09.109Z,1627411509.109 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-07-27T18:45:09.110Z,1627411509.110 [Startup](INFO): Completed Startup 2021-07-27T18:45:09.110Z,1627411509.110 [MissionManager](INFO): Startup is completed. 2021-07-27T18:45:09.110Z,1627411509.110 [MissionManager](INFO): Uninitializing Mission Startup 2021-07-27T18:45:09.110Z,1627411509.110 [Startup] Stopped 2021-07-27T18:45:09.110Z,1627411509.110 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-07-27T18:45:09.110Z,1627411509.110 [Startup:A.GoToSurface] Stopped 2021-07-27T18:45:09.110Z,1627411509.110 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-07-27T18:45:09.505Z,1627411509.505 [MissionManager](IMPORTANT): Started mission Default 2021-07-27T18:45:09.506Z,1627411509.506 [Default] Running Loop=1 2021-07-27T18:45:09.506Z,1627411509.506 [Default](DEBUG): Aggregate::initialize Default 2021-07-27T18:45:09.506Z,1627411509.506 [Default:B.GoToSurface] Running Loop=1 2021-07-27T18:45:09.506Z,1627411509.506 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-07-27T18:45:09.506Z,1627411509.506 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-07-27T18:45:09.507Z,1627411509.507 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-07-27T18:45:09.507Z,1627411509.507 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-07-27T18:45:09.507Z,1627411509.507 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-07-27T18:45:09.532Z,1627411509.532 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-07-27T18:45:09.532Z,1627411509.532 [Default:A.Wait] Running Loop=1 2021-07-27T18:45:09.533Z,1627411509.533 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-07-27T18:45:10.699Z,1627411510.699 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:45:22.866Z,1627411522.866 [Default:A.Wait](INFO): Done Waiting. 2021-07-27T18:45:22.866Z,1627411522.866 [Default:A.Wait] Stopped 2021-07-27T18:45:22.867Z,1627411522.867 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-07-27T18:45:23.259Z,1627411523.259 [Default:CheckIn] Running Loop=1 2021-07-27T18:45:23.259Z,1627411523.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-07-27T18:45:23.260Z,1627411523.260 [Default:CheckIn:Read_GPS] Running Loop=1 2021-07-27T18:45:23.722Z,1627411523.722 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-07-27T18:45:24.031Z,1627411524.031 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:45:37.378Z,1627411537.378 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:45:51.108Z,1627411551.108 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T18:45:52.773Z,1627411552.773 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute 2021-07-27T18:45:52.776Z,1627411552.776 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2021-07-27T18:45:53.157Z,1627411553.157 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2021-07-27T18:46:04.607Z,1627411564.607 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 600.000000 revolution_per_minute 2021-07-27T18:46:06.980Z,1627411566.980 [ThrusterHE](FAULT): getSpeed error serial timeout 2021-07-27T18:46:06.980Z,1627411566.980 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2021-07-27T18:46:06.980Z,1627411566.980 [ThrusterHE] Communications Fault, FailCount= 1 2021-07-27T18:46:06.980Z,1627411566.980 [ThrusterHE](ERROR): Communications Fault 2021-07-27T18:46:06.985Z,1627411566.985 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2021-07-27T18:46:07.305Z,1627411567.305 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2021-07-27T18:46:07.306Z,1627411567.306 [ThrusterHE](INFO): Powering down 2021-07-27T18:46:08.105Z,1627411568.105 [ThrusterHE](DEBUG): Initializing EZServoServo. 2021-07-27T18:46:08.221Z,1627411568.221 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2021-07-27T18:46:08.225Z,1627411568.225 [CBIT](INFO): Clearing failed state for component ThrusterHE 2021-07-27T18:46:08.225Z,1627411568.225 [ThrusterHE] No Fault, FailCount= 1 2021-07-27T18:46:09.468Z,1627411569.468 [CommandLine](IMPORTANT): got command maintain clear 2021-07-27T18:46:09.723Z,1627411569.723 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2021-07-27T18:46:11.181Z,1627411571.181 [CommandLine](IMPORTANT): got command failComponent 2021-07-27T18:46:11.181Z,1627411571.181 [CommandLine](IMPORTANT): Failed components: 2021-07-27T18:46:11.181Z,1627411571.181 [CommandLine](IMPORTANT): No failed Components. 2021-07-27T18:46:25.515Z,1627411585.515 [CommandLine](IMPORTANT): got command strobe off 2021-07-27T18:46:25.515Z,1627411585.515 [CommandLine](IMPORTANT): Deactivating strobe 2021-07-27T18:46:49.694Z,1627411609.694 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-07-27T18:47:08.350Z,1627411628.350 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-07-27T18:47:08.350Z,1627411628.350 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-07-27T18:47:08.381Z,1627411628.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-07-27T18:47:08.703Z,1627411628.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-07-27T18:47:08.703Z,1627411628.703 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-07-27T18:48:31.501Z,1627411711.501 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5. 2021-07-27T18:48:31.505Z,1627411711.505 [BPC1](INFO): Received data from all battery sticks. 2021-07-27T18:50:09.305Z,1627411809.305 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-07-27T18:50:09.305Z,1627411809.305 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-07-27T18:50:09.316Z,1627411809.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-07-27T18:50:09.724Z,1627411809.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-07-27T18:50:09.724Z,1627411809.724 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-07-27T18:50:23.427Z,1627411823.427 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-07-27T18:45:23.3Z 2021-07-27T18:50:23.428Z,1627411823.428 [Default:CheckIn:Read_GPS] Stopped 2021-07-27T18:50:23.428Z,1627411823.428 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-07-27T18:50:23.818Z,1627411823.818 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-07-27T18:50:32.704Z,1627411832.704 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210727T184056/Courier0004.lzma 2021-07-27T18:50:33.706Z,1627411833.706 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Courier0004.lzma.bak 2021-07-27T18:50:33.706Z,1627411833.706 [DataOverHttps](INFO): SBD MOMSN=15764312 2021-07-27T18:50:51.085Z,1627411851.085 [DataOverHttps](INFO): Sending 432 bytes from file Logs/20210727T184056/Express0005.lzma 2021-07-27T18:50:52.088Z,1627411852.088 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Express0005.lzma.bak 2021-07-27T18:50:52.088Z,1627411852.088 [DataOverHttps](INFO): SBD MOMSN=15764314 2021-07-27T18:50:53.403Z,1627411853.403 [Default:CheckIn:Read_Iridium] Stopped 2021-07-27T18:50:53.403Z,1627411853.403 [Default:CheckIn:C.Wait] Running Loop=1 2021-07-27T18:50:53.403Z,1627411853.403 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-07-27T18:51:08.324Z,1627411868.324 [Power24vConverter](INFO): Powering down. 2021-07-27T18:51:48.730Z,1627411908.730 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-07-27T18:51:48.730Z,1627411908.730 [NAL9602] Data Fault, FailCount= 1 2021-07-27T18:51:48.730Z,1627411908.730 [NAL9602](ERROR): Data Fault 2021-07-27T18:51:48.769Z,1627411908.769 [CBIT](ERROR): Data Fault in component: NAL9602 2021-07-27T18:51:49.133Z,1627411909.133 [NAL9602](INFO): Powering down 2021-07-27T18:51:49.537Z,1627411909.537 [NAL9602](FAULT): LCB fault: Software Overcurrent. 2021-07-27T18:51:49.537Z,1627411909.537 [NAL9602] Hardware Fault, FailCount= 1 2021-07-27T18:51:49.538Z,1627411909.538 [NAL9602](ERROR): Hardware Fault 2021-07-27T18:51:49.961Z,1627411909.961 [CBIT](INFO): Clearing failed state for component NAL9602 2021-07-27T18:51:49.962Z,1627411909.962 [NAL9602] No Fault, FailCount= 1 2021-07-27T18:52:19.430Z,1627411939.430 [NAL9602](INFO): Powering up NAL9602 2021-07-27T18:52:30.338Z,1627411950.338 [NAL9602](INFO): NAL9602 initialized 2021-07-27T18:53:10.366Z,1627411990.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2021-07-27T18:53:10.366Z,1627411990.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-07-27T18:53:10.398Z,1627411990.398 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-07-27T18:53:10.766Z,1627411990.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-07-27T18:53:10.766Z,1627411990.766 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2021-07-27T18:55:01.079Z,1627412101.079 [CommandLine](IMPORTANT): got command failComponent 2021-07-27T18:55:01.079Z,1627412101.079 [CommandLine](IMPORTANT): Failed components: 2021-07-27T18:55:01.079Z,1627412101.079 [CommandLine](IMPORTANT): No failed Components. 2021-07-27T18:55:54.022Z,1627412154.022 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-07-27T18:55:54.022Z,1627412154.022 [Default:CheckIn:C.Wait] Stopped 2021-07-27T18:55:54.022Z,1627412154.022 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-07-27T18:55:54.022Z,1627412154.022 [Default:CheckIn:D] Running Loop=1 2021-07-27T18:55:54.439Z,1627412154.439 [Default:CheckIn:D] Stopped 2021-07-27T18:55:54.439Z,1627412154.439 [Default:CheckIn:E] Running Loop=1 2021-07-27T18:55:54.855Z,1627412154.855 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.748887 min 2021-07-27T18:55:54.855Z,1627412154.855 [Default:CheckIn:E] Stopped 2021-07-27T18:55:54.855Z,1627412154.855 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-07-27T18:55:54.855Z,1627412154.855 [Default:CheckIn] Stopped 2021-07-27T18:55:54.855Z,1627412154.855 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-07-27T18:55:54.856Z,1627412154.856 [Default:CheckIn](INFO): Running loop #2 2021-07-27T18:55:54.856Z,1627412154.856 [Default:CheckIn] Running Loop=2 2021-07-27T18:55:54.856Z,1627412154.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-07-27T18:55:54.856Z,1627412154.856 [Default:CheckIn:Read_GPS] Running Loop=1 2021-07-27T18:56:11.402Z,1627412171.402 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2021-07-27T18:56:11.402Z,1627412171.402 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-07-27T18:56:11.413Z,1627412171.413 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-07-27T18:56:11.835Z,1627412171.835 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-07-27T18:56:11.835Z,1627412171.835 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2021-07-27T18:59:12.402Z,1627412352.402 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2021-07-27T18:59:12.402Z,1627412352.402 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-07-27T18:59:12.445Z,1627412352.445 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-07-27T18:59:12.824Z,1627412352.824 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-07-27T18:59:12.848Z,1627412352.848 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2021-07-27T19:00:55.030Z,1627412455.030 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-07-27T18:55:54.9Z 2021-07-27T19:00:55.030Z,1627412455.030 [Default:CheckIn:Read_GPS] Stopped 2021-07-27T19:00:55.030Z,1627412455.030 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-07-27T19:00:59.175Z,1627412459.175 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:00:59.936Z,1627412459.936 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210727T184056/Courier0007.lzma 2021-07-27T19:01:00.938Z,1627412460.938 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Courier0007.lzma.bak 2021-07-27T19:01:00.938Z,1627412460.938 [DataOverHttps](INFO): SBD MOMSN=15764328 2021-07-27T19:01:12.562Z,1627412472.562 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:01:16.470Z,1627412476.470 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20210727T184056/Express0008.lzma 2021-07-27T19:01:17.466Z,1627412477.466 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Express0008.lzma.bak 2021-07-27T19:01:17.466Z,1627412477.466 [DataOverHttps](INFO): SBD MOMSN=15764330 2021-07-27T19:01:18.535Z,1627412478.535 [Default:CheckIn:Read_Iridium] Stopped 2021-07-27T19:01:18.535Z,1627412478.535 [Default:CheckIn:C.Wait] Running Loop=1 2021-07-27T19:01:18.535Z,1627412478.535 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-07-27T19:01:25.834Z,1627412485.834 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:01:39.167Z,1627412499.167 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:01:52.497Z,1627412512.497 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:02:05.836Z,1627412525.836 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:02:13.458Z,1627412533.458 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2021-07-27T19:02:13.458Z,1627412533.458 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-07-27T19:02:13.470Z,1627412533.470 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-07-27T19:02:13.870Z,1627412533.870 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-07-27T19:02:13.870Z,1627412533.870 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2021-07-27T19:02:18.696Z,1627412538.696 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:02:31.631Z,1627412551.631 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:02:31.665Z,1627412551.665 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-07-27T19:02:31.665Z,1627412551.665 [NAL9602] Data Fault, FailCount= 2 2021-07-27T19:02:31.665Z,1627412551.665 [NAL9602](ERROR): Data Fault 2021-07-27T19:02:31.754Z,1627412551.754 [CBIT](ERROR): Data Fault in component: NAL9602 2021-07-27T19:02:32.233Z,1627412552.233 [NAL9602](INFO): Powering down 2021-07-27T19:02:32.943Z,1627412552.943 [CBIT](INFO): Clearing failed state for component NAL9602 2021-07-27T19:02:32.944Z,1627412552.944 [NAL9602] No Fault, FailCount= 2 2021-07-27T19:02:45.485Z,1627412565.485 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:02:58.818Z,1627412578.818 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:03:02.388Z,1627412582.388 [NAL9602](INFO): Powering up NAL9602 2021-07-27T19:03:12.148Z,1627412592.148 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:03:13.322Z,1627412593.322 [NAL9602](INFO): NAL9602 initialized 2021-07-27T19:03:25.487Z,1627412605.487 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:05:14.508Z,1627412714.508 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2021-07-27T19:05:14.508Z,1627412714.508 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-07-27T19:05:14.552Z,1627412714.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-07-27T19:05:14.923Z,1627412714.923 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-07-27T19:05:14.923Z,1627412714.923 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2021-07-27T19:05:52.905Z,1627412752.905 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2021-07-27T19:05:52.923Z,1627412752.923 [BPC1](INFO): Received data from all battery sticks. 2021-07-27T19:06:19.149Z,1627412779.149 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-07-27T19:06:19.149Z,1627412779.149 [Default:CheckIn:C.Wait] Stopped 2021-07-27T19:06:19.150Z,1627412779.150 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-07-27T19:06:19.150Z,1627412779.150 [Default:CheckIn:D] Running Loop=1 2021-07-27T19:06:19.571Z,1627412779.571 [Default:CheckIn:D] Stopped 2021-07-27T19:06:19.571Z,1627412779.571 [Default:CheckIn:E] Running Loop=1 2021-07-27T19:06:19.979Z,1627412779.979 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.167751 min 2021-07-27T19:06:19.979Z,1627412779.979 [Default:CheckIn:E] Stopped 2021-07-27T19:06:19.979Z,1627412779.979 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-07-27T19:06:19.979Z,1627412779.979 [Default:CheckIn] Stopped 2021-07-27T19:06:19.980Z,1627412779.980 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-07-27T19:06:19.980Z,1627412779.980 [Default:CheckIn](INFO): Running loop #3 2021-07-27T19:06:19.980Z,1627412779.980 [Default:CheckIn] Running Loop=3 2021-07-27T19:06:19.980Z,1627412779.980 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-07-27T19:06:19.980Z,1627412779.980 [Default:CheckIn:Read_GPS] Running Loop=1 2021-07-27T19:07:46.811Z,1627412866.811 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190745.00,A,3648.14167,N,12147.27377,W,3.654,151.75,270721,,,A*7B 2021-07-27T19:07:46.815Z,1627412866.815 [NAL9602](INFO): GPS fix at 20210727T190745: (36.802361, -121.787896) 2021-07-27T19:07:46.867Z,1627412866.867 [Default:CheckIn:Read_GPS] Stopped 2021-07-27T19:07:46.867Z,1627412866.867 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-07-27T19:07:56.968Z,1627412876.968 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20210727T184056/Courier0010.lzma 2021-07-27T19:07:57.970Z,1627412877.970 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Courier0010.lzma.bak 2021-07-27T19:07:57.970Z,1627412877.970 [DataOverHttps](INFO): SBD MOMSN=15764338 2021-07-27T19:08:14.828Z,1627412894.828 [DataOverHttps](INFO): Sending 310 bytes from file Logs/20210727T184056/Express0011.lzma 2021-07-27T19:08:15.830Z,1627412895.830 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Express0011.lzma.bak 2021-07-27T19:08:15.830Z,1627412895.830 [DataOverHttps](INFO): SBD MOMSN=15764341 2021-07-27T19:08:17.147Z,1627412897.147 [Default:CheckIn:Read_Iridium] Stopped 2021-07-27T19:08:17.147Z,1627412897.147 [Default:CheckIn:C.Wait] Running Loop=1 2021-07-27T19:08:17.147Z,1627412897.147 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-07-27T19:08:19.535Z,1627412899.535 [NAL9602](INFO): Not Powering down - fast GPS 2021-07-27T19:13:17.781Z,1627413197.781 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-07-27T19:13:17.781Z,1627413197.781 [Default:CheckIn:C.Wait] Stopped 2021-07-27T19:13:17.781Z,1627413197.781 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-07-27T19:13:17.781Z,1627413197.781 [Default:CheckIn:D] Running Loop=1 2021-07-27T19:13:18.178Z,1627413198.178 [Default:CheckIn:D] Stopped 2021-07-27T19:13:18.178Z,1627413198.178 [Default:CheckIn:E] Running Loop=1 2021-07-27T19:13:18.618Z,1627413198.618 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.144539 min 2021-07-27T19:13:18.618Z,1627413198.618 [Default:CheckIn:E] Stopped 2021-07-27T19:13:18.618Z,1627413198.618 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-07-27T19:13:18.619Z,1627413198.619 [Default:CheckIn] Stopped 2021-07-27T19:13:18.619Z,1627413198.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-07-27T19:13:18.619Z,1627413198.619 [Default:CheckIn](INFO): Running loop #4 2021-07-27T19:13:18.619Z,1627413198.619 [Default:CheckIn] Running Loop=4 2021-07-27T19:13:18.619Z,1627413198.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-07-27T19:13:18.619Z,1627413198.619 [Default:CheckIn:Read_GPS] Running Loop=1 2021-07-27T19:13:20.597Z,1627413200.597 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191319.00,A,3648.13917,N,12147.20793,W,0.058,110.13,270721,,,A*7A 2021-07-27T19:13:20.599Z,1627413200.599 [NAL9602](INFO): GPS fix at 20210727T191319: (36.802320, -121.786799) 2021-07-27T19:13:20.610Z,1627413200.610 [Default:CheckIn:Read_GPS] Stopped 2021-07-27T19:13:20.610Z,1627413200.610 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-07-27T19:13:27.443Z,1627413207.443 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210727T184056/Courier0013.lzma 2021-07-27T19:13:28.446Z,1627413208.446 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Courier0013.lzma.bak 2021-07-27T19:13:28.446Z,1627413208.446 [DataOverHttps](INFO): SBD MOMSN=15764347 2021-07-27T19:13:42.819Z,1627413222.819 [NAL9602](INFO): SBD MO Status=0, MOMSN=5213, MT Status=0, MTMSN=0 2021-07-27T19:13:42.819Z,1627413222.819 [NAL9602](INFO): No messages in MT queue 2021-07-27T19:13:44.320Z,1627413224.320 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20210727T184056/Express0014.lzma 2021-07-27T19:13:45.322Z,1627413225.322 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Express0014.lzma.bak 2021-07-27T19:13:45.322Z,1627413225.322 [DataOverHttps](INFO): SBD MOMSN=15764352 2021-07-27T19:13:46.473Z,1627413226.473 [Default:CheckIn:Read_Iridium] Stopped 2021-07-27T19:13:46.473Z,1627413226.473 [Default:CheckIn:C.Wait] Running Loop=1 2021-07-27T19:13:46.473Z,1627413226.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-07-27T19:14:13.521Z,1627413253.521 [NAL9602](INFO): Not Powering down - fast GPS 2021-07-27T19:18:21.326Z,1627413501.326 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:18:34.739Z,1627413514.739 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:18:47.153Z,1627413527.153 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-07-27T19:18:47.154Z,1627413527.154 [Default:CheckIn:C.Wait] Stopped 2021-07-27T19:18:47.154Z,1627413527.154 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-07-27T19:18:47.154Z,1627413527.154 [Default:CheckIn:D] Running Loop=1 2021-07-27T19:18:47.613Z,1627413527.613 [Default:CheckIn:D] Stopped 2021-07-27T19:18:47.613Z,1627413527.613 [Default:CheckIn:E] Running Loop=1 2021-07-27T19:18:47.934Z,1627413527.934 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:18:47.946Z,1627413527.946 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.635111 min 2021-07-27T19:18:47.946Z,1627413527.946 [Default:CheckIn:E] Stopped 2021-07-27T19:18:47.947Z,1627413527.947 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-07-27T19:18:47.947Z,1627413527.947 [Default:CheckIn] Stopped 2021-07-27T19:18:47.947Z,1627413527.947 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-07-27T19:18:47.947Z,1627413527.947 [Default:CheckIn](INFO): Running loop #5 2021-07-27T19:18:47.947Z,1627413527.947 [Default:CheckIn] Running Loop=5 2021-07-27T19:18:47.947Z,1627413527.947 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-07-27T19:18:47.947Z,1627413527.947 [Default:CheckIn:Read_GPS] Running Loop=1 2021-07-27T19:18:49.965Z,1627413529.965 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191849.00,A,3648.16780,N,12147.17296,W,5.851,28.38,270721,,,D*47 2021-07-27T19:18:49.967Z,1627413529.967 [NAL9602](INFO): GPS fix at 20210727T191849: (36.802797, -121.786216) 2021-07-27T19:18:49.978Z,1627413529.978 [Default:CheckIn:Read_GPS] Stopped 2021-07-27T19:18:49.978Z,1627413529.978 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-07-27T19:18:56.871Z,1627413536.871 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210727T184056/Courier0016.lzma 2021-07-27T19:18:57.874Z,1627413537.874 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Courier0016.lzma.bak 2021-07-27T19:18:57.874Z,1627413537.874 [DataOverHttps](INFO): SBD MOMSN=15764397 2021-07-27T19:19:01.265Z,1627413541.265 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:19:13.852Z,1627413553.852 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20210727T184056/Express0017.lzma 2021-07-27T19:19:14.598Z,1627413554.598 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:19:14.854Z,1627413554.854 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Express0017.lzma.bak 2021-07-27T19:19:14.854Z,1627413554.854 [DataOverHttps](INFO): SBD MOMSN=15764400 2021-07-27T19:19:16.260Z,1627413556.260 [Default:CheckIn:Read_Iridium] Stopped 2021-07-27T19:19:16.260Z,1627413556.260 [Default:CheckIn:C.Wait] Running Loop=1 2021-07-27T19:19:16.260Z,1627413556.260 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-07-27T19:19:27.930Z,1627413567.930 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:19:41.262Z,1627413581.262 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:19:50.557Z,1627413590.557 [NAL9602](INFO): SBD MO Status=2, MOMSN=5214, MT Status=2, MTMSN=0 2021-07-27T19:19:50.557Z,1627413590.557 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-07-27T19:19:54.593Z,1627413594.593 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:19:59.122Z,1627413599.122 [DataOverHttps](IMPORTANT): SBD MTMSN=20210727T191958 2021-07-27T19:20:06.484Z,1627413606.484 [DataOverHttps](INFO): Received command:ibit 2021-07-27T19:20:06.570Z,1627413606.570 [CommandLine](IMPORTANT): got command ibit 2021-07-27T19:20:06.763Z,1627413606.763 [IBIT](IMPORTANT): Beginning Initiated BIT 2021-07-27T19:20:06.763Z,1627413606.763 [IBIT](IMPORTANT): Beginning control surface checks. 2021-07-27T19:20:06.767Z,1627413606.767 [CBIT](IMPORTANT): Beginning ground fault scan 2021-07-27T19:20:07.925Z,1627413607.925 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:20:17.652Z,1627413617.652 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.261217 CHAN A1 (24V): -0.013139 CHAN A2 (12V): 0.163171 CHAN A3 (5V): 0.032533 CHAN B0 (3.3V): 0.018395 CHAN B1 (3.15aV): 0.017944 CHAN B2 (3.15bV): 0.018262 CHAN B3 (GND): -0.014253 OPEN: 0.009890 Full Scale Calc: 4.765 mA, -1.589 mA 2021-07-27T19:20:21.258Z,1627413621.258 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:20:34.592Z,1627413634.592 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:20:53.219Z,1627413653.219 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 1 Latitude: 36.802795 Longitude: -121.786217 2021-07-27T19:20:53.623Z,1627413653.623 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 286.889160 Voltage: 15.739000 2021-07-27T19:20:53.624Z,1627413653.624 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2021-07-27T19:20:53.624Z,1627413653.624 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2021-07-27T19:20:54.007Z,1627413654.007 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2021-07-27T19:20:54.007Z,1627413654.007 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2021-07-27T19:20:54.008Z,1627413654.008 [IBIT](IMPORTANT): Pressure:9.831405 PSI 2021-07-27T19:20:54.008Z,1627413654.008 [IBIT](IMPORTANT): Humidity:13.447685 % 2021-07-27T19:20:54.411Z,1627413654.411 [IBIT](IMPORTANT): Vehicle Pitch:-0.128721 degrees 2021-07-27T19:20:54.412Z,1627413654.412 [IBIT](IMPORTANT): Vehicle Roll:10.218781 degrees 2021-07-27T19:20:54.412Z,1627413654.412 [IBIT](IMPORTANT): Vehicle Heading:359.745667 degrees 2021-07-27T19:20:54.834Z,1627413654.834 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2021-07-27T19:20:54.835Z,1627413654.835 [IBIT](IMPORTANT): buoyancyNeutral: 148.198105 cc 2021-07-27T19:20:54.835Z,1627413654.835 [IBIT](IMPORTANT): massDefault: 1.199689 cm 2021-07-27T19:20:54.835Z,1627413654.835 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2021-07-27T19:20:54.836Z,1627413654.836 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2021-07-27T19:20:54.836Z,1627413654.836 [IBIT](IMPORTANT): IBIT PASSED 2021-07-27T19:21:37.627Z,1627413697.627 [NAL9602](INFO): SBD MO Status=2, MOMSN=5214, MT Status=2, MTMSN=0 2021-07-27T19:21:37.627Z,1627413697.627 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-07-27T19:22:31.807Z,1627413751.807 [NAL9602](INFO): SBD MO Status=0, MOMSN=5214, MT Status=0, MTMSN=0 2021-07-27T19:22:31.807Z,1627413751.807 [NAL9602](INFO): No messages in MT queue 2021-07-27T19:23:02.525Z,1627413782.525 [NAL9602](INFO): Not Powering down - fast GPS 2021-07-27T19:23:16.264Z,1627413796.264 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5. 2021-07-27T19:23:16.267Z,1627413796.267 [BPC1](INFO): Received data from all battery sticks. 2021-07-27T19:24:16.855Z,1627413856.855 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-07-27T19:24:16.855Z,1627413856.855 [Default:CheckIn:C.Wait] Stopped 2021-07-27T19:24:16.855Z,1627413856.855 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-07-27T19:24:16.855Z,1627413856.855 [Default:CheckIn:D] Running Loop=1 2021-07-27T19:24:17.269Z,1627413857.269 [Default:CheckIn:D] Stopped 2021-07-27T19:24:17.269Z,1627413857.269 [Default:CheckIn:E] Running Loop=1 2021-07-27T19:24:17.681Z,1627413857.681 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.129386 min 2021-07-27T19:24:17.682Z,1627413857.682 [Default:CheckIn:E] Stopped 2021-07-27T19:24:17.682Z,1627413857.682 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-07-27T19:24:17.682Z,1627413857.682 [Default:CheckIn] Stopped 2021-07-27T19:24:17.682Z,1627413857.682 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-07-27T19:24:17.682Z,1627413857.682 [Default:CheckIn](INFO): Running loop #6 2021-07-27T19:24:17.682Z,1627413857.682 [Default:CheckIn] Running Loop=6 2021-07-27T19:24:17.682Z,1627413857.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-07-27T19:24:17.683Z,1627413857.683 [Default:CheckIn:Read_GPS] Running Loop=1 2021-07-27T19:24:19.675Z,1627413859.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192418.00,A,3648.46469,N,12147.30652,W,14.384,233.02,270721,,,D*46 2021-07-27T19:24:19.678Z,1627413859.678 [NAL9602](INFO): GPS fix at 20210727T192418: (36.807745, -121.788442) 2021-07-27T19:24:19.693Z,1627413859.693 [Default:CheckIn:Read_GPS] Stopped 2021-07-27T19:24:19.693Z,1627413859.693 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-07-27T19:24:30.112Z,1627413870.112 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20210727T184056/Courier0019.lzma 2021-07-27T19:24:31.114Z,1627413871.114 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Courier0019.lzma.bak 2021-07-27T19:24:31.114Z,1627413871.114 [DataOverHttps](INFO): SBD MOMSN=15764405 2021-07-27T19:24:32.563Z,1627413872.563 [NAL9602](INFO): SBD MO Status=0, MOMSN=5215, MT Status=0, MTMSN=0 2021-07-27T19:24:32.564Z,1627413872.564 [NAL9602](INFO): No messages in MT queue 2021-07-27T19:24:52.913Z,1627413892.913 [DataOverHttps](INFO): Sending 872 bytes from file Logs/20210727T184056/Express0020.lzma 2021-07-27T19:24:53.914Z,1627413893.914 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Express0020.lzma.bak 2021-07-27T19:24:53.914Z,1627413893.914 [DataOverHttps](INFO): SBD MOMSN=15764410 2021-07-27T19:25:03.309Z,1627413903.309 [NAL9602](INFO): Not Powering down - fast GPS 2021-07-27T19:25:09.539Z,1627413909.539 [DataOverHttps](INFO): Sending 57 bytes from file Logs/20210727T184056/Express0023.lzma 2021-07-27T19:25:10.538Z,1627413910.538 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Express0023.lzma.bak 2021-07-27T19:25:10.538Z,1627413910.538 [DataOverHttps](INFO): SBD MOMSN=15764442 2021-07-27T19:25:11.812Z,1627413911.812 [Default:CheckIn:Read_Iridium] Stopped 2021-07-27T19:25:11.812Z,1627413911.812 [Default:CheckIn:C.Wait] Running Loop=1 2021-07-27T19:25:11.812Z,1627413911.812 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-07-27T19:29:19.813Z,1627414159.813 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.695740 2021-07-27T19:30:02.252Z,1627414202.252 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002837 2021-07-27T19:30:12.451Z,1627414212.451 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-07-27T19:30:12.451Z,1627414212.451 [Default:CheckIn:C.Wait] Stopped 2021-07-27T19:30:12.451Z,1627414212.451 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-07-27T19:30:12.451Z,1627414212.451 [Default:CheckIn:D] Running Loop=1 2021-07-27T19:30:12.861Z,1627414212.861 [Default:CheckIn:D] Stopped 2021-07-27T19:30:12.861Z,1627414212.861 [Default:CheckIn:E] Running Loop=1 2021-07-27T19:30:13.262Z,1627414213.262 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.055920 min 2021-07-27T19:30:13.262Z,1627414213.262 [Default:CheckIn:E] Stopped 2021-07-27T19:30:13.262Z,1627414213.262 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-07-27T19:30:13.262Z,1627414213.262 [Default:CheckIn] Stopped 2021-07-27T19:30:13.262Z,1627414213.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-07-27T19:30:13.263Z,1627414213.263 [Default:CheckIn](INFO): Running loop #7 2021-07-27T19:30:13.263Z,1627414213.263 [Default:CheckIn] Running Loop=7 2021-07-27T19:30:13.263Z,1627414213.263 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-07-27T19:30:13.263Z,1627414213.263 [Default:CheckIn:Read_GPS] Running Loop=1 2021-07-27T19:30:15.275Z,1627414215.275 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193014.00,A,3648.18422,N,12149.17418,W,7.445,290.96,270721,,,D*70 2021-07-27T19:30:15.287Z,1627414215.287 [NAL9602](INFO): GPS fix at 20210727T193014: (36.803070, -121.819570) 2021-07-27T19:30:15.297Z,1627414215.297 [Default:CheckIn:Read_GPS] Stopped 2021-07-27T19:30:15.297Z,1627414215.297 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-07-27T19:30:24.284Z,1627414224.284 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210727T184056/Courier0025.lzma 2021-07-27T19:30:26.290Z,1627414226.290 [DataOverHttps](INFO): Moved sent file to Logs/20210727T184056/Courier0025.lzma.bak 2021-07-27T19:30:26.290Z,1627414226.290 [DataOverHttps](INFO): SBD MOMSN=15764449 2021-07-27T19:30:32.654Z,1627414232.654 [NAL9602](INFO): SBD MO Status=0, MOMSN=5216, MT Status=0, MTMSN=0 2021-07-27T19:30:32.654Z,1627414232.654 [NAL9602](INFO): No messages in MT queue 2021-07-27T19:30:57.428Z,1627414257.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-07-27T19:31:03.365Z,1627414263.365 [NAL9602](INFO): Not Powering down - fast GPS 2021-07-27T19:31:26.585Z,1627414286.585 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.295082 2021-07-27T19:31:40.605Z,1627414300.605 [NAL9602](INFO): SBD MO Status=1, MOMSN=5217, MT Status=0, MTMSN=0 2021-07-27T19:31:40.668Z,1627414300.668 [NAL9602](INFO): Sent 332 bytes from file Logs/20210727T184056/Express0026.lzma 2021-07-27T19:31:40.669Z,1627414300.669 [NAL9602](INFO): Packets left to send: 1 2021-07-27T19:31:51.947Z,1627414311.947 [NAL9602](INFO): SBD MO Status=1, MOMSN=5218, MT Status=0, MTMSN=0 2021-07-27T19:31:52.000Z,1627414312.000 [NAL9602](INFO): Sent 14 bytes from file Logs/20210727T184056/Express0026.lzma 2021-07-27T19:31:52.000Z,1627414312.000 [NAL9602](INFO): Packets left to send: 0 2021-07-27T19:31:57.708Z,1627414317.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-07-27T19:31:59.215Z,1627414319.215 [NAL9602](INFO): SBD MO Status=0, MOMSN=5219, MT Status=0, MTMSN=0 2021-07-27T19:31:59.293Z,1627414319.293 [Default:CheckIn:Read_Iridium] Stopped 2021-07-27T19:31:59.293Z,1627414319.293 [Default:CheckIn:C.Wait] Running Loop=1 2021-07-27T19:31:59.293Z,1627414319.293 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-07-27T19:32:29.913Z,1627414349.913 [NAL9602](INFO): Not Powering down - fast GPS 2021-07-27T19:32:32.856Z,1627414352.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-07-27T19:33:08.008Z,1627414388.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-07-27T19:33:43.160Z,1627414423.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-07-27T19:34:18.300Z,1627414458.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-07-27T19:34:53.460Z,1627414493.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-07-27T19:35:28.608Z,1627414528.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-07-27T19:35:44.125Z,1627414544.125 [DataOverHttps](IMPORTANT): SBD MTMSN=20210727T193543 2021-07-27T19:35:44.128Z,1627414544.128 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003211 2021-07-27T19:35:45.866Z,1627414545.866 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2021-07-27T19:35:51.436Z,1627414551.436 [DataOverHttps](INFO): Received command:restart logs