2024-06-03T18:20:02.613Z,1717438802.613 [Supervisor](DEBUG): Initializing supervisor. 2024-06-03T18:20:02.617Z,1717438802.617 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-06-03T18:20:02.617Z,1717438802.617 [SyncHandler](INFO): Protected caller Thread ID is 838 2024-06-03T18:20:02.618Z,1717438802.618 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-06-03T18:20:02.619Z,1717438802.619 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-06-03T18:20:02.619Z,1717438802.619 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839 2024-06-03T18:20:02.623Z,1717438802.623 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-06-03T18:20:02.640Z,1717438802.640 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-06-03T18:20:02.641Z,1717438802.641 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-06-03T18:20:02.642Z,1717438802.642 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840 2024-06-03T18:20:02.646Z,1717438802.646 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-06-03T18:20:02.647Z,1717438802.647 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-06-03T18:20:02.647Z,1717438802.647 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841 2024-06-03T18:20:02.649Z,1717438802.649 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-06-03T18:20:02.650Z,1717438802.650 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-06-03T18:20:02.651Z,1717438802.651 [logger ThreadHandler](INFO): Protected caller Thread ID is 842 2024-06-03T18:20:02.654Z,1717438802.654 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-06-03T18:20:02.655Z,1717438802.655 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-06-03T18:20:02.659Z,1717438802.659 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-06-03T18:20:02.950Z,1717438802.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-06-03T18:20:02.952Z,1717438802.952 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-06-03T18:20:03.046Z,1717438803.046 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-06-03T18:20:03.598Z,1717438803.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-06-03T18:20:03.600Z,1717438803.600 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-06-03T18:20:03.962Z,1717438803.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-06-03T18:20:03.964Z,1717438803.964 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-06-03T18:20:04.072Z,1717438804.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-06-03T18:20:04.074Z,1717438804.074 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-06-03T18:20:04.495Z,1717438804.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-06-03T18:20:04.497Z,1717438804.497 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-06-03T18:20:04.727Z,1717438804.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-06-03T18:20:04.728Z,1717438804.728 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-06-03T18:20:05.271Z,1717438805.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-06-03T18:20:05.273Z,1717438805.273 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-06-03T18:20:05.422Z,1717438805.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-06-03T18:20:05.424Z,1717438805.424 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-06-03T18:20:05.516Z,1717438805.516 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-06-03T18:20:07.129Z,1717438807.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-06-03T18:20:07.129Z,1717438807.129 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-06-03T18:20:07.559Z,1717438807.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-06-03T18:20:07.559Z,1717438807.559 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-06-03T18:20:07.766Z,1717438807.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-06-03T18:20:07.767Z,1717438807.767 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-06-03T18:20:07.892Z,1717438807.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-06-03T18:20:07.893Z,1717438807.893 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-06-03T18:20:08.134Z,1717438808.134 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-06-03T18:20:08.135Z,1717438808.135 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-06-03T18:20:08.400Z,1717438808.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-06-03T18:20:08.402Z,1717438808.402 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-06-03T18:20:08.404Z,1717438808.404 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-06-03T18:20:08.508Z,1717438808.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-06-03T18:20:08.605Z,1717438808.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-06-03T18:20:08.720Z,1717438808.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-06-03T18:20:08.816Z,1717438808.816 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-06-03T18:20:08.931Z,1717438808.931 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-06-03T18:20:09.035Z,1717438809.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-06-03T18:20:09.160Z,1717438809.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-06-03T18:20:09.326Z,1717438809.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-06-03T18:20:09.478Z,1717438809.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-06-03T18:20:09.601Z,1717438809.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-06-03T18:20:09.877Z,1717438809.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-06-03T18:20:09.877Z,1717438809.877 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-06-03T18:20:09.890Z,1717438809.890 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-06-03T18:20:09.963Z,1717438809.963 [VerticalControl](DEBUG): Construct VerticalControl. 2024-06-03T18:20:10.024Z,1717438810.024 [VerticalControl] Loaded 2024-06-03T18:20:10.024Z,1717438810.024 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-06-03T18:20:10.027Z,1717438810.027 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-06-03T18:20:10.074Z,1717438810.074 [HorizontalControl] Loaded 2024-06-03T18:20:10.075Z,1717438810.075 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-06-03T18:20:10.077Z,1717438810.077 [SpeedControl](DEBUG): Construct SpeedControl. 2024-06-03T18:20:10.080Z,1717438810.080 [SpeedControl] Loaded 2024-06-03T18:20:10.080Z,1717438810.080 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-06-03T18:20:10.083Z,1717438810.083 [LoopControl](DEBUG): Construct LoopControl. 2024-06-03T18:20:10.084Z,1717438810.084 [LoopControl] Loaded 2024-06-03T18:20:10.084Z,1717438810.084 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-06-03T18:20:10.084Z,1717438810.084 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-06-03T18:20:10.085Z,1717438810.085 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-06-03T18:20:10.101Z,1717438810.101 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-06-03T18:20:10.102Z,1717438810.102 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-06-03T18:20:10.316Z,1717438810.316 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-06-03T18:20:10.316Z,1717438810.316 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-06-03T18:20:10.508Z,1717438810.508 [BuoyancyServo] Loaded 2024-06-03T18:20:10.508Z,1717438810.508 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-06-03T18:20:10.530Z,1717438810.530 [ElevatorServo] Loaded 2024-06-03T18:20:10.530Z,1717438810.530 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-06-03T18:20:10.551Z,1717438810.551 [MassServo] Loaded 2024-06-03T18:20:10.551Z,1717438810.551 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-06-03T18:20:10.571Z,1717438810.571 [RudderServo] Loaded 2024-06-03T18:20:10.571Z,1717438810.571 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-06-03T18:20:10.588Z,1717438810.588 [ThrusterHE] Loaded 2024-06-03T18:20:10.588Z,1717438810.588 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-06-03T18:20:10.588Z,1717438810.588 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-06-03T18:20:10.589Z,1717438810.589 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-06-03T18:20:10.726Z,1717438810.726 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-06-03T18:20:10.726Z,1717438810.726 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-06-03T18:20:10.740Z,1717438810.740 [NavChart] Loaded 2024-06-03T18:20:10.740Z,1717438810.740 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-06-03T18:20:10.746Z,1717438810.746 [UniversalFixResidualReporter] Loaded 2024-06-03T18:20:10.746Z,1717438810.746 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-06-03T18:20:10.746Z,1717438810.746 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-06-03T18:20:10.747Z,1717438810.747 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-06-03T18:20:10.831Z,1717438810.831 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-06-03T18:20:10.831Z,1717438810.831 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-06-03T18:20:11.597Z,1717438811.597 [AHRS_M2] Loaded 2024-06-03T18:20:11.597Z,1717438811.597 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-06-03T18:20:12.421Z,1717438812.421 [BPC1] Loaded 2024-06-03T18:20:12.421Z,1717438812.421 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-06-03T18:20:12.563Z,1717438812.563 [DataOverHttps] Loaded 2024-06-03T18:20:12.563Z,1717438812.563 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-06-03T18:20:12.564Z,1717438812.564 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0 2024-06-03T18:20:12.564Z,1717438812.564 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2024-06-03T18:20:12.588Z,1717438812.588 [Depth_Keller] Loaded 2024-06-03T18:20:12.588Z,1717438812.588 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-06-03T18:20:12.593Z,1717438812.593 [DropWeight] Loaded 2024-06-03T18:20:12.593Z,1717438812.593 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-06-03T18:20:12.657Z,1717438812.657 [NAL9602] Loaded 2024-06-03T18:20:12.657Z,1717438812.657 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-06-03T18:20:12.687Z,1717438812.687 [Onboard] Loaded 2024-06-03T18:20:12.687Z,1717438812.687 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-06-03T18:20:12.688Z,1717438812.688 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0 2024-06-03T18:20:12.689Z,1717438812.689 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2024-06-03T18:20:12.696Z,1717438812.696 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1 2024-06-03T18:20:12.709Z,1717438812.709 [PowerOnly] Loaded 2024-06-03T18:20:12.709Z,1717438812.709 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-06-03T18:20:12.723Z,1717438812.723 [Power24vConverter] Loaded 2024-06-03T18:20:12.723Z,1717438812.723 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-06-03T18:20:12.738Z,1717438812.738 [Radio_Surface] Loaded 2024-06-03T18:20:12.739Z,1717438812.739 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-06-03T18:20:12.740Z,1717438812.740 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0 2024-06-03T18:20:12.740Z,1717438812.740 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2024-06-03T18:20:12.836Z,1717438812.836 [DAT] Loaded 2024-06-03T18:20:12.836Z,1717438812.836 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-06-03T18:20:12.837Z,1717438812.837 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0 2024-06-03T18:20:12.837Z,1717438812.837 [DAT ThreadHandler](INFO): Protected caller Thread ID is 927 2024-06-03T18:20:12.838Z,1717438812.838 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-06-03T18:20:12.838Z,1717438812.838 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-06-03T18:20:13.282Z,1717438813.282 [DepthRateCalculator] Loaded 2024-06-03T18:20:13.282Z,1717438813.282 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-06-03T18:20:13.287Z,1717438813.287 [PitchRateCalculator] Loaded 2024-06-03T18:20:13.287Z,1717438813.287 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-06-03T18:20:13.296Z,1717438813.296 [SpeedCalculator] Loaded 2024-06-03T18:20:13.296Z,1717438813.296 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-06-03T18:20:13.302Z,1717438813.302 [YawRateCalculator] Loaded 2024-06-03T18:20:13.303Z,1717438813.303 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-06-03T18:20:13.433Z,1717438813.433 [ElevatorOffsetCalculator] Loaded 2024-06-03T18:20:13.433Z,1717438813.433 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-06-03T18:20:13.433Z,1717438813.433 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-06-03T18:20:13.434Z,1717438813.434 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-06-03T18:20:14.092Z,1717438814.092 [CANONSampler] Loaded 2024-06-03T18:20:14.092Z,1717438814.092 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-06-03T18:20:14.158Z,1717438814.158 [CTD_Seabird] Loaded 2024-06-03T18:20:14.158Z,1717438814.158 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-06-03T18:20:14.159Z,1717438814.159 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0 2024-06-03T18:20:14.159Z,1717438814.159 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2024-06-03T18:20:14.179Z,1717438814.179 [PAR_Licor] Loaded 2024-06-03T18:20:14.180Z,1717438814.180 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-06-03T18:20:14.224Z,1717438814.224 [WetLabsBB2FL] Loaded 2024-06-03T18:20:14.224Z,1717438814.224 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-06-03T18:20:14.225Z,1717438814.225 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0 2024-06-03T18:20:14.226Z,1717438814.226 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2024-06-03T18:20:14.226Z,1717438814.226 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-06-03T18:20:14.227Z,1717438814.227 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-06-03T18:20:14.271Z,1717438814.271 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-06-03T18:20:14.272Z,1717438814.272 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-06-03T18:20:14.673Z,1717438814.673 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-06-03T18:20:14.674Z,1717438814.674 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-06-03T18:20:14.845Z,1717438814.845 [SBIT](DEBUG): Construct Startup Built In Test. 2024-06-03T18:20:14.854Z,1717438814.854 [SBIT] Loaded 2024-06-03T18:20:14.854Z,1717438814.854 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-06-03T18:20:14.857Z,1717438814.857 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-06-03T18:20:14.870Z,1717438814.870 [IBIT] Loaded 2024-06-03T18:20:14.870Z,1717438814.870 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-06-03T18:20:14.876Z,1717438814.876 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-06-03T18:20:14.982Z,1717438814.982 [CBIT] Loaded 2024-06-03T18:20:14.982Z,1717438814.982 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-06-03T18:20:14.982Z,1717438814.982 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-06-03T18:20:14.983Z,1717438814.983 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-06-03T18:20:15.123Z,1717438815.123 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-06-03T18:20:15.130Z,1717438815.130 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-06-03T18:20:15.133Z,1717438815.133 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-06-03T18:20:15.145Z,1717438815.145 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-06-03T18:20:15.146Z,1717438815.146 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0 2024-06-03T18:20:15.146Z,1717438815.146 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930 2024-06-03T18:20:15.151Z,1717438815.151 [Supervisor](INFO): Main Thread ID is 831 2024-06-03T18:20:15.151Z,1717438815.151 [Supervisor](DEBUG): Running supervisor. 2024-06-03T18:20:15.152Z,1717438815.152 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931 2024-06-03T18:20:15.152Z,1717438815.152 [CommandExec](INFO): Initializing the command executive. 2024-06-03T18:20:15.154Z,1717438815.154 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932 2024-06-03T18:20:15.156Z,1717438815.156 [controlThread ThreadHandler](INFO): Handler Thread ID is 933 2024-06-03T18:20:15.157Z,1717438815.157 [controlThread](DEBUG): Initializing ControlThread 2024-06-03T18:20:15.157Z,1717438815.157 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-06-03T18:20:15.159Z,1717438815.159 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-06-03T18:20:15.160Z,1717438815.160 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-06-03T18:20:15.160Z,1717438815.160 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-06-03T18:20:15.162Z,1717438815.162 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-06-03T18:20:15.162Z,1717438815.162 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-06-03T18:20:15.166Z,1717438815.166 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-06-03T18:20:15.166Z,1717438815.166 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-06-03T18:20:15.166Z,1717438815.166 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-06-03T18:20:15.167Z,1717438815.167 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-06-03T18:20:15.167Z,1717438815.167 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-06-03T18:20:15.172Z,1717438815.172 [SBIT](INFO): Initialize SBIT Component. 2024-06-03T18:20:15.173Z,1717438815.173 [SBIT](IMPORTANT): git: 2024-06-03 2024-06-03T18:20:15.173Z,1717438815.173 [SBIT](INFO): git hash: 7fa19da3a148abf4baea4b65318d99ca26f8c915 2024-06-03T18:20:15.173Z,1717438815.173 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-06-03T18:20:15.174Z,1717438815.174 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-06-03T18:20:15.175Z,1717438815.175 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2024-06-03T18:20:15.176Z,1717438815.176 [IBIT](INFO): Initialize IBIT Component. 2024-06-03T18:20:15.177Z,1717438815.177 [CBIT](DEBUG): Initialize CBIT Component. 2024-06-03T18:20:15.178Z,1717438815.178 [logger ThreadHandler](INFO): Handler Thread ID is 934 2024-06-03T18:20:15.188Z,1717438815.188 [CBIT](DEBUG): Initialized mux pins. 2024-06-03T18:20:15.188Z,1717438815.188 [CBIT](DEBUG): Initializing the watchdog timer. 2024-06-03T18:20:15.196Z,1717438815.196 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935 2024-06-03T18:20:15.198Z,1717438815.198 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-06-03T18:20:15.208Z,1717438815.208 [Onboard ThreadHandler](INFO): Handler Thread ID is 936 2024-06-03T18:20:15.212Z,1717438815.212 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-06-03T18:20:15.213Z,1717438815.213 [CBIT](DEBUG): Initializing heartbeat. 2024-06-03T18:20:15.229Z,1717438815.229 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937 2024-06-03T18:20:15.240Z,1717438815.240 [DAT ThreadHandler](INFO): Handler Thread ID is 938 2024-06-03T18:20:15.241Z,1717438815.241 [DAT](INFO): Powering up 2024-06-03T18:20:15.242Z,1717438815.242 [DAT](DEBUG): Initializing DAT. 2024-06-03T18:20:15.244Z,1717438815.244 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940 2024-06-03T18:20:15.246Z,1717438815.246 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-06-03T18:20:15.248Z,1717438815.248 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942 2024-06-03T18:20:15.253Z,1717438815.253 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944 2024-06-03T18:20:15.253Z,1717438815.253 [WetLabsBB2FL](INFO): Powering up 2024-06-03T18:20:15.262Z,1717438815.262 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-06-03T18:20:15.262Z,1717438815.262 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-06-03T18:20:15.262Z,1717438815.262 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-06-03T18:20:15.262Z,1717438815.262 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-06-03T18:20:15.263Z,1717438815.263 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-06-03T18:20:15.263Z,1717438815.263 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-06-03T18:20:15.263Z,1717438815.263 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-06-03T18:20:15.263Z,1717438815.263 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-06-03T18:20:15.284Z,1717438815.284 [CBIT](DEBUG): Deactivating GF circuits. 2024-06-03T18:20:15.284Z,1717438815.284 [CBIT](DEBUG): Deactivating emergency mode. 2024-06-03T18:20:15.324Z,1717438815.324 [CBIT](DEBUG): Backplane powered. 2024-06-03T18:20:15.324Z,1717438815.324 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-06-03T18:20:15.325Z,1717438815.325 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-06-03T18:20:15.325Z,1717438815.325 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-06-03T18:20:15.330Z,1717438815.330 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-06-03T18:20:15.388Z,1717438815.388 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-06-03T18:20:15.404Z,1717438815.404 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-06-03T18:20:15.434Z,1717438815.434 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-06-03T18:20:15.435Z,1717438815.435 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-06-03T18:20:15.435Z,1717438815.435 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-06-03T18:20:15.456Z,1717438815.456 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-06-03T18:20:15.632Z,1717438815.632 [Radio_Surface](INFO): Powering up 2024-06-03T18:20:15.749Z,1717438815.749 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-06-03T18:20:15.759Z,1717438815.759 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-06-03T18:20:15.760Z,1717438815.760 [Default:A.Wait](DEBUG): Construct Wait. 2024-06-03T18:20:15.762Z,1717438815.762 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-06-03T18:20:15.802Z,1717438815.802 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-06-03T18:20:15.825Z,1717438815.825 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-06-03T18:20:15.831Z,1717438815.831 [Default:E.Execute](DEBUG): Construct Execute. 2024-06-03T18:20:15.854Z,1717438815.854 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-06-03T18:20:15.858Z,1717438815.858 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-06-03T18:20:15.881Z,1717438815.881 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-06-03T18:20:16.031Z,1717438816.031 [PowerOnly](INFO): Powering up loadControl 2024-06-03T18:20:16.048Z,1717438816.048 [Power24vConverter](INFO): Powering up. 2024-06-03T18:20:16.049Z,1717438816.049 [CANONSampler](INFO): Powering down 2024-06-03T18:20:16.130Z,1717438816.130 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-06-03T18:20:16.136Z,1717438816.136 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-06-03T18:20:16.137Z,1717438816.137 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-06-03T18:20:16.144Z,1717438816.144 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-06-03T18:20:16.145Z,1717438816.145 [MassServo](DEBUG): Initializing EZServoServo. 2024-06-03T18:20:16.152Z,1717438816.152 [MassServo](DEBUG): Initializing MassServo. 2024-06-03T18:20:16.153Z,1717438816.153 [RudderServo](DEBUG): Initializing EZServoServo. 2024-06-03T18:20:16.160Z,1717438816.160 [RudderServo](DEBUG): Initializing RudderServo. 2024-06-03T18:20:16.161Z,1717438816.161 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-06-03T18:20:16.168Z,1717438816.168 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-06-03T18:20:17.364Z,1717438817.364 [WetLabsBB2FL](INFO): Powering down 2024-06-03T18:20:21.773Z,1717438821.773 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-06-03T18:20:28.092Z,1717438828.092 [DAT](INFO): DAT read: 2024-06-03T18:20:28.094Z,1717438828.094 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-06-03T18:20:29.857Z,1717438829.857 [DAT](INFO): DAT read: MF Frequency Band 2024-06-03T18:20:29.858Z,1717438829.858 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-06-03T18:20:29.859Z,1717438829.859 [DAT](INFO): DAT read: Jun 3 2024 18:20:29 2024-06-03T18:20:30.866Z,1717438830.866 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-06-03T18:20:30.867Z,1717438830.867 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-06-03T18:20:30.867Z,1717438830.867 [DAT](INFO): commRate: 1200 2024-06-03T18:20:32.932Z,1717438832.932 [DAT](INFO): entering command mode 2024-06-03T18:20:33.132Z,1717438833.132 [DAT](INFO): DAT read: 2024-06-03T18:20:33.133Z,1717438833.133 [DAT](INFO): DAT read: user:1> 2024-06-03T18:20:33.133Z,1717438833.133 [DAT](INFO): setting verbose to 3 2024-06-03T18:20:33.385Z,1717438833.385 [DAT](INFO): DAT read: user:1> 2024-06-03T18:20:33.386Z,1717438833.386 [DAT](INFO): DAT read: Verbose | 3 2024-06-03T18:20:33.386Z,1717438833.386 [DAT](INFO): set verbose to 3 2024-06-03T18:20:33.386Z,1717438833.386 [DAT](INFO): setting DatVerbose to 27440 2024-06-03T18:20:33.641Z,1717438833.641 [DAT](INFO): DAT read: user:2> 2024-06-03T18:20:33.642Z,1717438833.642 [DAT](INFO): DAT read: DatVerbose | 27440 2024-06-03T18:20:33.642Z,1717438833.642 [DAT](INFO): set DatVerbose to 27440 2024-06-03T18:20:33.642Z,1717438833.642 [DAT](INFO): setting transmit power to 8 2024-06-03T18:20:33.892Z,1717438833.892 [DAT](INFO): DAT read: user:3> 2024-06-03T18:20:33.893Z,1717438833.893 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-06-03T18:20:33.894Z,1717438833.894 [DAT](INFO): set transmit power to 8 2024-06-03T18:20:33.894Z,1717438833.894 [DAT](INFO): setting local address to 8 2024-06-03T18:20:34.145Z,1717438834.145 [DAT](INFO): DAT read: user:4> 2024-06-03T18:20:34.145Z,1717438834.145 [DAT](INFO): DAT read: LocalAddr | 8 2024-06-03T18:20:34.146Z,1717438834.146 [DAT](INFO): set local address to 8 2024-06-03T18:20:34.147Z,1717438834.147 [DAT](INFO): Setting time to: 18:20:34 And date to:6/3/2024 2024-06-03T18:20:34.396Z,1717438834.396 [DAT](INFO): DAT read: user:5> 2024-06-03T18:20:34.397Z,1717438834.397 [DAT](INFO): DAT read: Mon Jun 3, 2024 18:20:34 2024-06-03T18:20:34.398Z,1717438834.398 [DAT](INFO): Local DAT time set to Mon Jun 3, 2024 18:20:34 2024-06-03T18:20:42.937Z,1717438842.937 [NAL9602](INFO): Powering up NAL9602 2024-06-03T18:20:53.845Z,1717438853.845 [NAL9602](INFO): NAL9602 initialized 2024-06-03T18:20:54.710Z,1717438854.710 [SBIT](IMPORTANT): Beginning Startup BIT 2024-06-03T18:20:54.719Z,1717438854.719 [CBIT](IMPORTANT): Beginning ground fault scan 2024-06-03T18:21:05.595Z,1717438865.595 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-06-03T18:21:06.003Z,1717438866.003 [CBIT](IMPORTANT): Beginning ground fault scan 2024-06-03T18:21:11.620Z,1717438871.620 [NAL9602](INFO): SBD MO Status=0, MOMSN=51646, MT Status=0, MTMSN=0 2024-06-03T18:21:11.620Z,1717438871.620 [NAL9602](INFO): No messages in MT queue 2024-06-03T18:21:17.015Z,1717438877.015 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-06-03T18:21:17.369Z,1717438877.369 [CBIT](IMPORTANT): Beginning ground fault scan 2024-06-03T18:21:28.219Z,1717438888.219 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-06-03T18:21:28.219Z,1717438888.219 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule. 2024-06-03T18:21:48.830Z,1717438908.830 [SBIT](IMPORTANT): SBIT PASSED 2024-06-03T18:21:48.830Z,1717438908.830 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-06-03T18:21:48.831Z,1717438908.831 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-06-03T18:21:48.831Z,1717438908.831 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=238.004663 cubic_centimeter; 2024-06-03T18:21:48.831Z,1717438908.831 [SBIT](IMPORTANT): VerticalControl.massDefault=4.845744 millimeter; 2024-06-03T18:21:48.832Z,1717438908.832 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool; 2024-06-03T18:21:48.832Z,1717438908.832 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool; 2024-06-03T18:21:49.237Z,1717438909.237 [MissionManager](IMPORTANT): Started mission Startup 2024-06-03T18:21:49.237Z,1717438909.237 [Startup] Running Loop=1 2024-06-03T18:21:49.237Z,1717438909.237 [Startup](DEBUG): Aggregate::initialize Startup 2024-06-03T18:21:49.237Z,1717438909.237 [Startup:A.GoToSurface] Running Loop=1 2024-06-03T18:21:49.237Z,1717438909.237 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-06-03T18:21:49.238Z,1717438909.238 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-06-03T18:21:49.238Z,1717438909.238 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-06-03T18:21:49.238Z,1717438909.238 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-06-03T18:21:49.239Z,1717438909.239 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-06-03T18:21:49.239Z,1717438909.239 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-06-03T18:21:49.239Z,1717438909.239 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-06-03T18:21:49.241Z,1717438909.241 [Startup:StartupSatComms] Running Loop=1 2024-06-03T18:21:49.241Z,1717438909.241 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-06-03T18:21:49.242Z,1717438909.242 [Startup:StartupSatComms:A] Running Loop=1 2024-06-03T18:21:49.686Z,1717438909.686 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-06-03T18:22:49.426Z,1717438969.426 [Startup:StartupSatComms:A](INFO): Timed out from 2024-06-03T18:21:49.2Z 2024-06-03T18:22:49.426Z,1717438969.426 [Startup:StartupSatComms:A] Stopped 2024-06-03T18:22:49.426Z,1717438969.426 [Startup:StartupSatComms:B] Running Loop=1 2024-06-03T18:22:49.821Z,1717438969.821 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-06-03T18:23:15.305Z,1717438995.305 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-06-03T18:23:15.305Z,1717438995.305 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-06-03T18:23:15.336Z,1717438995.336 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-06-03T18:23:15.717Z,1717438995.717 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-06-03T18:23:15.718Z,1717438995.718 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-06-03T18:23:17.726Z,1717438997.726 [NAL9602](INFO): SBD MO Status=2, MOMSN=51647, MT Status=2, MTMSN=0 2024-06-03T18:23:17.727Z,1717438997.727 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-06-03T18:23:18.935Z,1717438998.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182318.00,A,3648.15751,N,12147.22041,W,0.564,209.25,030624,,,A*7F 2024-06-03T18:23:18.938Z,1717438998.938 [NAL9602](INFO): GPS fix at 20240603T182318: (36.802625, -121.787007) 2024-06-03T18:23:34.742Z,1717439014.742 [NAL9602](INFO): SBD MO Status=1, MOMSN=51647, MT Status=0, MTMSN=0 2024-06-03T18:23:34.792Z,1717439014.792 [NAL9602](INFO): Sent 39 bytes from file Logs/20240603T151131/Courier0103.lzma 2024-06-03T18:23:34.792Z,1717439014.792 [NAL9602](INFO): Packets left to send: 0 2024-06-03T18:23:43.279Z,1717439023.279 [NAL9602](INFO): SBD MO Status=1, MOMSN=51648, MT Status=0, MTMSN=0 2024-06-03T18:23:43.336Z,1717439023.336 [NAL9602](INFO): Sent 151 bytes from file Logs/20240603T182002/Courier0000.lzma 2024-06-03T18:23:43.336Z,1717439023.336 [NAL9602](INFO): Packets left to send: 0 2024-06-03T18:23:49.465Z,1717439029.465 [Startup:StartupSatComms:B](INFO): Timed out from 2024-06-03T18:22:49.4Z 2024-06-03T18:23:49.465Z,1717439029.465 [Startup:StartupSatComms:B] Stopped 2024-06-03T18:23:49.465Z,1717439029.465 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-06-03T18:23:49.465Z,1717439029.465 [Startup:StartupSatComms] Stopped 2024-06-03T18:23:49.465Z,1717439029.465 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-06-03T18:23:49.466Z,1717439029.466 [Startup](INFO): Completed Startup 2024-06-03T18:23:49.467Z,1717439029.467 [MissionManager](INFO): Startup is completed. 2024-06-03T18:23:49.467Z,1717439029.467 [MissionManager](INFO): Uninitializing Mission Startup 2024-06-03T18:23:49.467Z,1717439029.467 [Startup] Stopped 2024-06-03T18:23:49.467Z,1717439029.467 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-06-03T18:23:49.467Z,1717439029.467 [Startup:A.GoToSurface] Stopped 2024-06-03T18:23:49.467Z,1717439029.467 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-06-03T18:23:49.843Z,1717439029.843 [MissionManager](IMPORTANT): Started mission Default 2024-06-03T18:23:49.843Z,1717439029.843 [Default] Running Loop=1 2024-06-03T18:23:49.844Z,1717439029.844 [Default](DEBUG): Aggregate::initialize Default 2024-06-03T18:23:49.844Z,1717439029.844 [Default:B.GoToSurface] Running Loop=1 2024-06-03T18:23:49.844Z,1717439029.844 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-06-03T18:23:49.844Z,1717439029.844 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-06-03T18:23:49.844Z,1717439029.844 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-06-03T18:23:49.845Z,1717439029.845 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-06-03T18:23:49.845Z,1717439029.845 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-06-03T18:23:49.845Z,1717439029.845 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-06-03T18:23:49.846Z,1717439029.846 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-06-03T18:23:49.846Z,1717439029.846 [Default:A.Wait] Running Loop=1 2024-06-03T18:23:49.846Z,1717439029.846 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-06-03T18:23:51.798Z,1717439031.798 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004188 2024-06-03T18:23:51.910Z,1717439031.910 [NAL9602](INFO): SBD MO Status=1, MOMSN=51649, MT Status=0, MTMSN=0 2024-06-03T18:23:51.961Z,1717439031.961 [NAL9602](INFO): Sent 138 bytes from file Logs/20240603T151131/Express0104.lzma 2024-06-03T18:23:51.962Z,1717439031.962 [NAL9602](INFO): Packets left to send: 0 2024-06-03T18:24:03.180Z,1717439043.180 [Default:A.Wait](INFO): Done Waiting. 2024-06-03T18:24:03.180Z,1717439043.180 [Default:A.Wait] Stopped 2024-06-03T18:24:03.181Z,1717439043.181 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T18:24:03.576Z,1717439043.576 [Default:CheckIn] Running Loop=1 2024-06-03T18:24:03.576Z,1717439043.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T18:24:03.576Z,1717439043.576 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T18:24:03.978Z,1717439043.978 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-06-03T18:24:05.648Z,1717439045.648 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182404.00,A,3648.15132,N,12147.22292,W,0.078,209.25,030624,,,A*72 2024-06-03T18:24:05.650Z,1717439045.650 [NAL9602](INFO): GPS fix at 20240603T182404: (36.802522, -121.787049) 2024-06-03T18:24:05.662Z,1717439045.662 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T18:24:05.662Z,1717439045.662 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T18:24:06.129Z,1717439046.129 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-06-03T18:24:37.557Z,1717439077.557 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T18:26:06.511Z,1717439166.511 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 4. 2024-06-03T18:26:06.514Z,1717439166.514 [BPC1](INFO): Received data from all battery sticks. 2024-06-03T18:37:26.360Z,1717439846.360 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20240603T182002/Courier0004.lzma 2024-06-03T18:37:27.362Z,1717439847.362 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0004.lzma.bak 2024-06-03T18:37:27.362Z,1717439847.362 [DataOverHttps](INFO): SBD MOMSN=21112543 2024-06-03T18:37:43.137Z,1717439863.137 [DataOverHttps](INFO): Sending 697 bytes from file Logs/20240603T182002/Express0001.lzma 2024-06-03T18:37:44.138Z,1717439864.138 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0001.lzma.bak 2024-06-03T18:37:44.138Z,1717439864.138 [DataOverHttps](INFO): SBD MOMSN=21112546 2024-06-03T18:37:59.900Z,1717439879.900 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20240603T182002/Express0005.lzma 2024-06-03T18:38:00.902Z,1717439880.902 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0005.lzma.bak 2024-06-03T18:38:00.902Z,1717439880.902 [DataOverHttps](INFO): SBD MOMSN=21112565 2024-06-03T18:38:02.166Z,1717439882.166 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T18:38:02.166Z,1717439882.166 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T18:38:02.167Z,1717439882.167 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T18:43:02.742Z,1717440182.742 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T18:43:02.742Z,1717440182.742 [Default:CheckIn:C.Wait] Stopped 2024-06-03T18:43:02.742Z,1717440182.742 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T18:43:02.742Z,1717440182.742 [Default:CheckIn:D] Running Loop=1 2024-06-03T18:43:03.151Z,1717440183.151 [Default:CheckIn:D] Stopped 2024-06-03T18:43:03.151Z,1717440183.151 [Default:CheckIn:E] Running Loop=1 2024-06-03T18:43:03.563Z,1717440183.563 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.221792 min 2024-06-03T18:43:03.564Z,1717440183.564 [Default:CheckIn:E] Stopped 2024-06-03T18:43:03.564Z,1717440183.564 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T18:43:03.564Z,1717440183.564 [Default:CheckIn] Stopped 2024-06-03T18:43:03.564Z,1717440183.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T18:43:03.564Z,1717440183.564 [Default:CheckIn](INFO): Running loop #2 2024-06-03T18:43:03.564Z,1717440183.564 [Default:CheckIn] Running Loop=2 2024-06-03T18:43:03.564Z,1717440183.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T18:43:03.565Z,1717440183.565 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T18:43:05.563Z,1717440185.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184305.00,A,3648.14957,N,12147.22422,W,0.233,56.97,030624,,,D*4C 2024-06-03T18:43:05.566Z,1717440185.566 [NAL9602](INFO): GPS fix at 20240603T184305: (36.802493, -121.787070) 2024-06-03T18:43:05.601Z,1717440185.601 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T18:43:05.601Z,1717440185.601 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T18:43:12.900Z,1717440192.900 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20240603T182002/Courier0007.lzma 2024-06-03T18:43:13.902Z,1717440193.902 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0007.lzma.bak 2024-06-03T18:43:13.902Z,1717440193.902 [DataOverHttps](INFO): SBD MOMSN=21112574 2024-06-03T18:43:29.736Z,1717440209.736 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20240603T182002/Express0008.lzma 2024-06-03T18:43:30.738Z,1717440210.738 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0008.lzma.bak 2024-06-03T18:43:30.738Z,1717440210.738 [DataOverHttps](INFO): SBD MOMSN=21112577 2024-06-03T18:43:31.852Z,1717440211.852 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T18:43:31.852Z,1717440211.852 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T18:43:31.852Z,1717440211.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T18:43:42.741Z,1717440222.741 [NAL9602](INFO): SBD MO Status=0, MOMSN=51650, MT Status=0, MTMSN=0 2024-06-03T18:43:42.741Z,1717440222.741 [NAL9602](INFO): No messages in MT queue 2024-06-03T18:44:13.449Z,1717440253.449 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T18:48:32.419Z,1717440512.419 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T18:48:32.419Z,1717440512.419 [Default:CheckIn:C.Wait] Stopped 2024-06-03T18:48:32.419Z,1717440512.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T18:48:32.420Z,1717440512.420 [Default:CheckIn:D] Running Loop=1 2024-06-03T18:48:32.834Z,1717440512.834 [Default:CheckIn:D] Stopped 2024-06-03T18:48:32.834Z,1717440512.834 [Default:CheckIn:E] Running Loop=1 2024-06-03T18:48:33.233Z,1717440513.233 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.716514 min 2024-06-03T18:48:33.233Z,1717440513.233 [Default:CheckIn:E] Stopped 2024-06-03T18:48:33.234Z,1717440513.234 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T18:48:33.234Z,1717440513.234 [Default:CheckIn] Stopped 2024-06-03T18:48:33.234Z,1717440513.234 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T18:48:33.234Z,1717440513.234 [Default:CheckIn](INFO): Running loop #3 2024-06-03T18:48:33.234Z,1717440513.234 [Default:CheckIn] Running Loop=3 2024-06-03T18:48:33.234Z,1717440513.234 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T18:48:33.234Z,1717440513.234 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T18:48:35.239Z,1717440515.239 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184835.00,A,3648.15021,N,12147.22242,W,0.000,56.97,030624,,,D*4F 2024-06-03T18:48:35.241Z,1717440515.241 [NAL9602](INFO): GPS fix at 20240603T184835: (36.802504, -121.787040) 2024-06-03T18:48:35.257Z,1717440515.257 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T18:48:35.257Z,1717440515.257 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T18:48:42.660Z,1717440522.660 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T182002/Courier0010.lzma 2024-06-03T18:48:43.662Z,1717440523.662 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0010.lzma.bak 2024-06-03T18:48:43.662Z,1717440523.662 [DataOverHttps](INFO): SBD MOMSN=21112589 2024-06-03T18:48:46.146Z,1717440526.146 [NAL9602](INFO): SBD MO Status=0, MOMSN=51651, MT Status=0, MTMSN=0 2024-06-03T18:48:46.146Z,1717440526.146 [NAL9602](INFO): No messages in MT queue 2024-06-03T18:49:02.383Z,1717440542.383 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240603T182002/Express0011.lzma 2024-06-03T18:49:03.386Z,1717440543.386 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0011.lzma.bak 2024-06-03T18:49:03.386Z,1717440543.386 [DataOverHttps](INFO): SBD MOMSN=21112593 2024-06-03T18:49:04.435Z,1717440544.435 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T18:49:04.435Z,1717440544.435 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T18:49:04.435Z,1717440544.435 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T18:49:16.923Z,1717440556.923 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T18:54:04.996Z,1717440844.996 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T18:54:04.996Z,1717440844.996 [Default:CheckIn:C.Wait] Stopped 2024-06-03T18:54:04.997Z,1717440844.997 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T18:54:04.997Z,1717440844.997 [Default:CheckIn:D] Running Loop=1 2024-06-03T18:54:05.407Z,1717440845.407 [Default:CheckIn:D] Stopped 2024-06-03T18:54:05.407Z,1717440845.407 [Default:CheckIn:E] Running Loop=1 2024-06-03T18:54:05.794Z,1717440845.794 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.259391 min 2024-06-03T18:54:05.794Z,1717440845.794 [Default:CheckIn:E] Stopped 2024-06-03T18:54:05.794Z,1717440845.794 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T18:54:05.794Z,1717440845.794 [Default:CheckIn] Stopped 2024-06-03T18:54:05.794Z,1717440845.794 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T18:54:05.795Z,1717440845.795 [Default:CheckIn](INFO): Running loop #4 2024-06-03T18:54:05.795Z,1717440845.795 [Default:CheckIn] Running Loop=4 2024-06-03T18:54:05.795Z,1717440845.795 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T18:54:05.795Z,1717440845.795 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T18:54:07.811Z,1717440847.811 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185408.00,A,3648.14993,N,12147.22157,W,0.156,56.97,030624,,,D*48 2024-06-03T18:54:07.813Z,1717440847.813 [NAL9602](INFO): GPS fix at 20240603T185408: (36.802499, -121.787026) 2024-06-03T18:54:07.824Z,1717440847.824 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T18:54:07.824Z,1717440847.824 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T18:54:15.631Z,1717440855.631 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T182002/Courier0013.lzma 2024-06-03T18:54:16.650Z,1717440856.650 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0013.lzma.bak 2024-06-03T18:54:16.650Z,1717440856.650 [DataOverHttps](INFO): SBD MOMSN=21112603 2024-06-03T18:54:35.236Z,1717440875.236 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240603T182002/Express0014.lzma 2024-06-03T18:54:36.238Z,1717440876.238 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0014.lzma.bak 2024-06-03T18:54:36.238Z,1717440876.238 [DataOverHttps](INFO): SBD MOMSN=21112606 2024-06-03T18:54:37.368Z,1717440877.368 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T18:54:37.368Z,1717440877.368 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T18:54:37.368Z,1717440877.368 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T18:54:42.606Z,1717440882.606 [NAL9602](INFO): SBD MO Status=2, MOMSN=51652, MT Status=2, MTMSN=0 2024-06-03T18:54:42.607Z,1717440882.607 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-06-03T18:54:49.878Z,1717440889.878 [NAL9602](INFO): SBD MO Status=0, MOMSN=51652, MT Status=0, MTMSN=0 2024-06-03T18:54:49.878Z,1717440889.878 [NAL9602](INFO): No messages in MT queue 2024-06-03T18:55:20.589Z,1717440920.589 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T18:59:37.960Z,1717441177.960 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T18:59:37.960Z,1717441177.960 [Default:CheckIn:C.Wait] Stopped 2024-06-03T18:59:37.961Z,1717441177.961 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T18:59:37.961Z,1717441177.961 [Default:CheckIn:D] Running Loop=1 2024-06-03T18:59:38.363Z,1717441178.363 [Default:CheckIn:D] Stopped 2024-06-03T18:59:38.363Z,1717441178.363 [Default:CheckIn:E] Running Loop=1 2024-06-03T18:59:38.764Z,1717441178.764 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.808663 min 2024-06-03T18:59:38.764Z,1717441178.764 [Default:CheckIn:E] Stopped 2024-06-03T18:59:38.765Z,1717441178.765 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T18:59:38.765Z,1717441178.765 [Default:CheckIn] Stopped 2024-06-03T18:59:38.765Z,1717441178.765 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T18:59:38.765Z,1717441178.765 [Default:CheckIn](INFO): Running loop #5 2024-06-03T18:59:38.765Z,1717441178.765 [Default:CheckIn] Running Loop=5 2024-06-03T18:59:38.765Z,1717441178.765 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T18:59:38.765Z,1717441178.765 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T18:59:40.779Z,1717441180.779 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185941.00,A,3648.14772,N,12147.21987,W,0.019,56.97,030624,,,D*45 2024-06-03T18:59:40.789Z,1717441180.789 [NAL9602](INFO): GPS fix at 20240603T185941: (36.802462, -121.786998) 2024-06-03T18:59:40.800Z,1717441180.800 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T18:59:40.800Z,1717441180.800 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T18:59:48.355Z,1717441188.355 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240603T182002/Courier0016.lzma 2024-06-03T18:59:49.358Z,1717441189.358 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0016.lzma.bak 2024-06-03T18:59:49.358Z,1717441189.358 [DataOverHttps](INFO): SBD MOMSN=21112616 2024-06-03T19:00:05.152Z,1717441205.152 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240603T182002/Express0017.lzma 2024-06-03T19:00:06.154Z,1717441206.154 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0017.lzma.bak 2024-06-03T19:00:06.154Z,1717441206.154 [DataOverHttps](INFO): SBD MOMSN=21112619 2024-06-03T19:00:07.455Z,1717441207.455 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T19:00:07.455Z,1717441207.455 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T19:00:07.455Z,1717441207.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T19:00:09.053Z,1717441209.053 [NAL9602](INFO): SBD MO Status=2, MOMSN=51653, MT Status=2, MTMSN=0 2024-06-03T19:00:09.053Z,1717441209.053 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-06-03T19:00:26.829Z,1717441226.829 [NAL9602](INFO): SBD MO Status=0, MOMSN=51653, MT Status=0, MTMSN=0 2024-06-03T19:00:26.829Z,1717441226.829 [NAL9602](INFO): No messages in MT queue 2024-06-03T19:00:57.533Z,1717441257.533 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T19:05:08.039Z,1717441508.039 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T19:05:08.039Z,1717441508.039 [Default:CheckIn:C.Wait] Stopped 2024-06-03T19:05:08.044Z,1717441508.044 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T19:05:08.044Z,1717441508.044 [Default:CheckIn:D] Running Loop=1 2024-06-03T19:05:08.439Z,1717441508.439 [Default:CheckIn:D] Stopped 2024-06-03T19:05:08.439Z,1717441508.439 [Default:CheckIn:E] Running Loop=1 2024-06-03T19:05:08.838Z,1717441508.838 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.309924 min 2024-06-03T19:05:08.838Z,1717441508.838 [Default:CheckIn:E] Stopped 2024-06-03T19:05:08.839Z,1717441508.839 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T19:05:08.839Z,1717441508.839 [Default:CheckIn] Stopped 2024-06-03T19:05:08.839Z,1717441508.839 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T19:05:08.839Z,1717441508.839 [Default:CheckIn](INFO): Running loop #6 2024-06-03T19:05:08.839Z,1717441508.839 [Default:CheckIn] Running Loop=6 2024-06-03T19:05:08.839Z,1717441508.839 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T19:05:08.839Z,1717441508.839 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T19:05:10.853Z,1717441510.853 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190511.00,A,3648.14933,N,12147.22211,W,0.039,0.00,030624,,,D*7B 2024-06-03T19:05:10.855Z,1717441510.855 [NAL9602](INFO): GPS fix at 20240603T190511: (36.802489, -121.787035) 2024-06-03T19:05:10.866Z,1717441510.866 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T19:05:10.866Z,1717441510.866 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T19:05:17.827Z,1717441517.827 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T182002/Courier0019.lzma 2024-06-03T19:05:18.830Z,1717441518.830 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0019.lzma.bak 2024-06-03T19:05:18.830Z,1717441518.830 [DataOverHttps](INFO): SBD MOMSN=21112630 2024-06-03T19:05:34.748Z,1717441534.748 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240603T182002/Express0020.lzma 2024-06-03T19:05:35.750Z,1717441535.750 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0020.lzma.bak 2024-06-03T19:05:35.750Z,1717441535.750 [DataOverHttps](INFO): SBD MOMSN=21112633 2024-06-03T19:05:36.860Z,1717441536.860 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T19:05:36.860Z,1717441536.860 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T19:05:36.860Z,1717441536.860 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T19:07:47.730Z,1717441667.730 [NAL9602](INFO): SBD MO Status=0, MOMSN=51654, MT Status=0, MTMSN=0 2024-06-03T19:07:47.730Z,1717441667.730 [NAL9602](INFO): No messages in MT queue 2024-06-03T19:08:18.432Z,1717441698.432 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T19:10:37.448Z,1717441837.448 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T19:10:37.448Z,1717441837.448 [Default:CheckIn:C.Wait] Stopped 2024-06-03T19:10:37.448Z,1717441837.448 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T19:10:37.448Z,1717441837.448 [Default:CheckIn:D] Running Loop=1 2024-06-03T19:10:37.867Z,1717441837.867 [Default:CheckIn:D] Stopped 2024-06-03T19:10:37.867Z,1717441837.867 [Default:CheckIn:E] Running Loop=1 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.800391 min 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn:E] Stopped 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn] Stopped 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn](INFO): Running loop #7 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn] Running Loop=7 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T19:10:38.257Z,1717441838.257 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T19:10:40.256Z,1717441840.256 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191040.00,A,3648.15063,N,12147.22263,W,0.019,0.00,030624,,,D*71 2024-06-03T19:10:40.258Z,1717441840.258 [NAL9602](INFO): GPS fix at 20240603T191040: (36.802510, -121.787044) 2024-06-03T19:10:40.270Z,1717441840.270 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T19:10:40.270Z,1717441840.270 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T19:10:47.472Z,1717441847.472 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240603T182002/Courier0022.lzma 2024-06-03T19:10:48.474Z,1717441848.474 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0022.lzma.bak 2024-06-03T19:10:48.474Z,1717441848.474 [DataOverHttps](INFO): SBD MOMSN=21112645 2024-06-03T19:11:07.419Z,1717441867.419 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240603T182002/Express0023.lzma 2024-06-03T19:11:08.422Z,1717441868.422 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0023.lzma.bak 2024-06-03T19:11:08.422Z,1717441868.422 [DataOverHttps](INFO): SBD MOMSN=21112648 2024-06-03T19:11:09.816Z,1717441869.816 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T19:11:09.816Z,1717441869.816 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T19:11:09.816Z,1717441869.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T19:11:13.826Z,1717441873.826 [NAL9602](INFO): SBD MO Status=0, MOMSN=51655, MT Status=0, MTMSN=0 2024-06-03T19:11:13.827Z,1717441873.827 [NAL9602](INFO): No messages in MT queue 2024-06-03T19:11:44.529Z,1717441904.529 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T19:16:10.415Z,1717442170.415 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T19:16:10.416Z,1717442170.416 [Default:CheckIn:C.Wait] Stopped 2024-06-03T19:16:10.416Z,1717442170.416 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T19:16:10.416Z,1717442170.416 [Default:CheckIn:D] Running Loop=1 2024-06-03T19:16:10.830Z,1717442170.830 [Default:CheckIn:D] Stopped 2024-06-03T19:16:10.830Z,1717442170.830 [Default:CheckIn:E] Running Loop=1 2024-06-03T19:16:11.239Z,1717442171.239 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.349780 min 2024-06-03T19:16:11.239Z,1717442171.239 [Default:CheckIn:E] Stopped 2024-06-03T19:16:11.239Z,1717442171.239 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T19:16:11.239Z,1717442171.239 [Default:CheckIn] Stopped 2024-06-03T19:16:11.240Z,1717442171.240 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T19:16:11.240Z,1717442171.240 [Default:CheckIn](INFO): Running loop #8 2024-06-03T19:16:11.240Z,1717442171.240 [Default:CheckIn] Running Loop=8 2024-06-03T19:16:11.240Z,1717442171.240 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T19:16:11.240Z,1717442171.240 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T19:16:13.237Z,1717442173.237 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191613.00,A,3648.31159,N,12147.12564,W,5.346,7.47,030624,,,D*74 2024-06-03T19:16:13.239Z,1717442173.239 [NAL9602](INFO): GPS fix at 20240603T191613: (36.805193, -121.785427) 2024-06-03T19:16:13.270Z,1717442173.270 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T19:16:13.270Z,1717442173.270 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T19:16:23.435Z,1717442183.435 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240603T182002/Courier0025.lzma 2024-06-03T19:16:24.438Z,1717442184.438 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0025.lzma.bak 2024-06-03T19:16:24.438Z,1717442184.438 [DataOverHttps](INFO): SBD MOMSN=21112659 2024-06-03T19:16:35.518Z,1717442195.518 [NAL9602](INFO): SBD MO Status=0, MOMSN=51656, MT Status=0, MTMSN=0 2024-06-03T19:16:35.518Z,1717442195.518 [NAL9602](INFO): No messages in MT queue 2024-06-03T19:16:40.812Z,1717442200.812 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240603T182002/Express0026.lzma 2024-06-03T19:16:41.814Z,1717442201.814 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0026.lzma.bak 2024-06-03T19:16:41.814Z,1717442201.814 [DataOverHttps](INFO): SBD MOMSN=21112662 2024-06-03T19:16:43.210Z,1717442203.210 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T19:16:43.210Z,1717442203.210 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T19:16:43.210Z,1717442203.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T19:17:06.249Z,1717442226.249 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T19:21:43.625Z,1717442503.625 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T19:21:43.625Z,1717442503.625 [Default:CheckIn:C.Wait] Stopped 2024-06-03T19:21:43.625Z,1717442503.625 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T19:21:43.625Z,1717442503.625 [Default:CheckIn:D] Running Loop=1 2024-06-03T19:21:44.008Z,1717442504.008 [Default:CheckIn:D] Stopped 2024-06-03T19:21:44.008Z,1717442504.008 [Default:CheckIn:E] Running Loop=1 2024-06-03T19:21:44.411Z,1717442504.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.902734 min 2024-06-03T19:21:44.411Z,1717442504.411 [Default:CheckIn:E] Stopped 2024-06-03T19:21:44.411Z,1717442504.411 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T19:21:44.412Z,1717442504.412 [Default:CheckIn] Stopped 2024-06-03T19:21:44.412Z,1717442504.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T19:21:44.412Z,1717442504.412 [Default:CheckIn](INFO): Running loop #9 2024-06-03T19:21:44.412Z,1717442504.412 [Default:CheckIn] Running Loop=9 2024-06-03T19:21:44.412Z,1717442504.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T19:21:44.412Z,1717442504.412 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T19:21:46.426Z,1717442506.426 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192146.00,A,3648.33092,N,12147.65133,W,6.667,274.33,030624,,,D*72 2024-06-03T19:21:46.429Z,1717442506.429 [NAL9602](INFO): GPS fix at 20240603T192146: (36.805515, -121.794189) 2024-06-03T19:21:46.460Z,1717442506.460 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T19:21:46.460Z,1717442506.460 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T19:21:52.979Z,1717442512.979 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T182002/Courier0028.lzma 2024-06-03T19:21:53.982Z,1717442513.982 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0028.lzma.bak 2024-06-03T19:21:53.982Z,1717442513.982 [DataOverHttps](INFO): SBD MOMSN=21112674 2024-06-03T19:21:59.750Z,1717442519.750 [NAL9602](INFO): SBD MO Status=0, MOMSN=51657, MT Status=0, MTMSN=0 2024-06-03T19:21:59.750Z,1717442519.750 [NAL9602](INFO): No messages in MT queue 2024-06-03T19:22:09.837Z,1717442529.837 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240603T182002/Express0029.lzma 2024-06-03T19:22:10.834Z,1717442530.834 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0029.lzma.bak 2024-06-03T19:22:10.834Z,1717442530.834 [DataOverHttps](INFO): SBD MOMSN=21112677 2024-06-03T19:22:11.899Z,1717442531.899 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T19:22:11.899Z,1717442531.899 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T19:22:11.899Z,1717442531.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T19:22:30.461Z,1717442550.461 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T19:27:12.655Z,1717442832.655 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T19:27:12.655Z,1717442832.655 [Default:CheckIn:C.Wait] Stopped 2024-06-03T19:27:12.655Z,1717442832.655 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T19:27:12.655Z,1717442832.655 [Default:CheckIn:D] Running Loop=1 2024-06-03T19:27:13.067Z,1717442833.067 [Default:CheckIn:D] Stopped 2024-06-03T19:27:13.067Z,1717442833.067 [Default:CheckIn:E] Running Loop=1 2024-06-03T19:27:13.444Z,1717442833.444 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-06-03T19:27:13.463Z,1717442833.463 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.387065 min 2024-06-03T19:27:13.463Z,1717442833.463 [Default:CheckIn:E] Stopped 2024-06-03T19:27:13.463Z,1717442833.463 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T19:27:13.463Z,1717442833.463 [Default:CheckIn] Stopped 2024-06-03T19:27:13.463Z,1717442833.463 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T19:27:13.484Z,1717442833.484 [Default:CheckIn](INFO): Running loop #10 2024-06-03T19:27:13.484Z,1717442833.484 [Default:CheckIn] Running Loop=10 2024-06-03T19:27:13.484Z,1717442833.484 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T19:27:13.484Z,1717442833.484 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T19:27:15.471Z,1717442835.471 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192715.00,A,3647.97817,N,12149.47525,W,19.011,239.14,030624,,,D*4E 2024-06-03T19:27:15.473Z,1717442835.473 [NAL9602](INFO): GPS fix at 20240603T192715: (36.799636, -121.824588) 2024-06-03T19:27:15.484Z,1717442835.484 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T19:27:15.484Z,1717442835.484 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T19:27:22.684Z,1717442842.684 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T182002/Courier0031.lzma 2024-06-03T19:27:23.687Z,1717442843.687 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0031.lzma.bak 2024-06-03T19:27:23.687Z,1717442843.687 [DataOverHttps](INFO): SBD MOMSN=21112688 2024-06-03T19:27:26.775Z,1717442846.775 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-06-03T19:27:39.712Z,1717442859.712 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-06-03T19:27:39.796Z,1717442859.796 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240603T182002/Express0032.lzma 2024-06-03T19:27:40.526Z,1717442860.526 [NAL9602](INFO): SBD MO Status=0, MOMSN=51658, MT Status=0, MTMSN=0 2024-06-03T19:27:40.526Z,1717442860.526 [NAL9602](INFO): No messages in MT queue 2024-06-03T19:27:40.798Z,1717442860.798 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0032.lzma.bak 2024-06-03T19:27:40.798Z,1717442860.798 [DataOverHttps](INFO): SBD MOMSN=21112691 2024-06-03T19:27:42.173Z,1717442862.173 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T19:27:42.173Z,1717442862.173 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T19:27:42.173Z,1717442862.173 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T19:27:53.043Z,1717442873.043 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-06-03T19:28:06.375Z,1717442886.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-06-03T19:28:11.229Z,1717442891.229 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T19:28:18.907Z,1717442898.907 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-06-03T19:28:31.426Z,1717442911.426 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-06-03T19:30:04.778Z,1717443004.778 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2024-06-03T19:31:11.570Z,1717443071.570 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-06-03T19:31:11.577Z,1717443071.577 [BPC1](INFO): Received data from all battery sticks. 2024-06-03T19:31:16.048Z,1717443076.048 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-06-03T19:32:42.923Z,1717443162.923 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T19:32:42.923Z,1717443162.923 [Default:CheckIn:C.Wait] Stopped 2024-06-03T19:32:42.923Z,1717443162.923 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T19:32:42.923Z,1717443162.923 [Default:CheckIn:D] Running Loop=1 2024-06-03T19:32:43.327Z,1717443163.327 [Default:CheckIn:D] Stopped 2024-06-03T19:32:43.327Z,1717443163.327 [Default:CheckIn:E] Running Loop=1 2024-06-03T19:32:43.737Z,1717443163.737 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.891390 min 2024-06-03T19:32:43.737Z,1717443163.737 [Default:CheckIn:E] Stopped 2024-06-03T19:32:43.737Z,1717443163.737 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T19:32:43.737Z,1717443163.737 [Default:CheckIn] Stopped 2024-06-03T19:32:43.737Z,1717443163.737 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T19:32:43.737Z,1717443163.737 [Default:CheckIn](INFO): Running loop #11 2024-06-03T19:32:43.737Z,1717443163.737 [Default:CheckIn] Running Loop=11 2024-06-03T19:32:43.738Z,1717443163.738 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T19:32:43.738Z,1717443163.738 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T19:32:45.743Z,1717443165.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193245.00,A,3647.83804,N,12150.82777,W,0.894,71.62,030624,,,D*4E 2024-06-03T19:32:45.746Z,1717443165.746 [NAL9602](INFO): GPS fix at 20240603T193245: (36.797301, -121.847129) 2024-06-03T19:32:45.757Z,1717443165.757 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T19:32:45.757Z,1717443165.757 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T19:33:05.578Z,1717443185.578 [NAL9602](INFO): SBD MO Status=2, MOMSN=51659, MT Status=2, MTMSN=0 2024-06-03T19:33:05.579Z,1717443185.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-06-03T19:33:11.256Z,1717443191.256 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20240603T182002/Courier0034.lzma 2024-06-03T19:33:12.258Z,1717443192.258 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0034.lzma.bak 2024-06-03T19:33:12.258Z,1717443192.258 [DataOverHttps](INFO): SBD MOMSN=21112701 2024-06-03T19:33:14.497Z,1717443194.497 [NAL9602](INFO): SBD MO Status=0, MOMSN=51659, MT Status=0, MTMSN=0 2024-06-03T19:33:14.497Z,1717443194.497 [NAL9602](INFO): No messages in MT queue 2024-06-03T19:33:28.232Z,1717443208.232 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240603T182002/Express0035.lzma 2024-06-03T19:33:29.234Z,1717443209.234 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Express0035.lzma.bak 2024-06-03T19:33:29.234Z,1717443209.234 [DataOverHttps](INFO): SBD MOMSN=21112704 2024-06-03T19:33:30.279Z,1717443210.279 [Default:CheckIn:Read_Iridium] Stopped 2024-06-03T19:33:30.280Z,1717443210.280 [Default:CheckIn:C.Wait] Running Loop=1 2024-06-03T19:33:30.280Z,1717443210.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-06-03T19:33:45.205Z,1717443225.205 [NAL9602](INFO): Not Powering down - fast GPS 2024-06-03T19:38:30.855Z,1717443510.855 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-06-03T19:38:30.855Z,1717443510.855 [Default:CheckIn:C.Wait] Stopped 2024-06-03T19:38:30.855Z,1717443510.855 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-06-03T19:38:30.855Z,1717443510.855 [Default:CheckIn:D] Running Loop=1 2024-06-03T19:38:31.325Z,1717443511.325 [Default:CheckIn:D] Stopped 2024-06-03T19:38:31.325Z,1717443511.325 [Default:CheckIn:E] Running Loop=1 2024-06-03T19:38:31.664Z,1717443511.664 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.691349 min 2024-06-03T19:38:31.664Z,1717443511.664 [Default:CheckIn:E] Stopped 2024-06-03T19:38:31.664Z,1717443511.664 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-06-03T19:38:31.664Z,1717443511.664 [Default:CheckIn] Stopped 2024-06-03T19:38:31.664Z,1717443511.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-06-03T19:38:31.664Z,1717443511.664 [Default:CheckIn](INFO): Running loop #12 2024-06-03T19:38:31.664Z,1717443511.664 [Default:CheckIn] Running Loop=12 2024-06-03T19:38:31.664Z,1717443511.664 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-06-03T19:38:31.665Z,1717443511.665 [Default:CheckIn:Read_GPS] Running Loop=1 2024-06-03T19:38:33.670Z,1717443513.670 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193833.00,A,3647.88071,N,12150.78128,W,0.603,352.91,030624,,,D*73 2024-06-03T19:38:33.673Z,1717443513.673 [NAL9602](INFO): GPS fix at 20240603T193833: (36.798012, -121.846355) 2024-06-03T19:38:33.684Z,1717443513.684 [Default:CheckIn:Read_GPS] Stopped 2024-06-03T19:38:33.684Z,1717443513.684 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-06-03T19:38:41.084Z,1717443521.084 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240603T182002/Courier0037.lzma 2024-06-03T19:38:42.082Z,1717443522.082 [DataOverHttps](INFO): Moved sent file to Logs/20240603T182002/Courier0037.lzma.bak 2024-06-03T19:38:42.082Z,1717443522.082 [DataOverHttps](INFO): SBD MOMSN=21112714 2024-06-03T19:38:44.986Z,1717443524.986 [NAL9602](INFO): SBD MO Status=0, MOMSN=51660, MT Status=0, MTMSN=0 2024-06-03T19:38:44.986Z,1717443524.986 [NAL9602](INFO): No messages in MT queue 2024-06-03T19:38:52.169Z,1717443532.169 [DataOverHttps](IMPORTANT): SBD MTMSN=20240603T193852