2024-07-10T15:35:24.968Z,1720625724.968 [Supervisor](DEBUG): Initializing supervisor. 2024-07-10T15:35:24.973Z,1720625724.973 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-07-10T15:35:24.973Z,1720625724.973 [SyncHandler](INFO): Protected caller Thread ID is 1136 2024-07-10T15:35:24.974Z,1720625724.974 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-07-10T15:35:24.975Z,1720625724.975 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-07-10T15:35:24.975Z,1720625724.975 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1137 2024-07-10T15:35:24.979Z,1720625724.979 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-07-10T15:35:24.997Z,1720625724.997 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-07-10T15:35:24.998Z,1720625724.998 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-07-10T15:35:24.998Z,1720625724.998 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1138 2024-07-10T15:35:25.002Z,1720625725.002 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-07-10T15:35:25.003Z,1720625725.003 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-07-10T15:35:25.003Z,1720625725.003 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1139 2024-07-10T15:35:25.005Z,1720625725.005 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-07-10T15:35:25.006Z,1720625725.006 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-07-10T15:35:25.007Z,1720625725.007 [logger ThreadHandler](INFO): Protected caller Thread ID is 1140 2024-07-10T15:35:25.010Z,1720625725.010 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-07-10T15:35:25.011Z,1720625725.011 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-07-10T15:35:25.016Z,1720625725.016 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-07-10T15:35:25.567Z,1720625725.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-07-10T15:35:25.569Z,1720625725.569 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-07-10T15:35:25.800Z,1720625725.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-07-10T15:35:25.802Z,1720625725.802 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-07-10T15:35:25.898Z,1720625725.898 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-07-10T15:35:26.150Z,1720625726.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-07-10T15:35:26.151Z,1720625726.151 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-07-10T15:35:26.245Z,1720625726.245 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-07-10T15:35:26.357Z,1720625726.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-07-10T15:35:26.358Z,1720625726.358 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-07-10T15:35:26.722Z,1720625726.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-07-10T15:35:26.723Z,1720625726.723 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-07-10T15:35:26.871Z,1720625726.871 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-07-10T15:35:26.872Z,1720625726.872 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-07-10T15:35:27.430Z,1720625727.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-07-10T15:35:27.432Z,1720625727.432 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-07-10T15:35:27.636Z,1720625727.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-07-10T15:35:27.637Z,1720625727.637 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-07-10T15:35:28.077Z,1720625728.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-07-10T15:35:28.078Z,1720625728.078 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-07-10T15:35:28.384Z,1720625728.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-07-10T15:35:28.385Z,1720625728.385 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-07-10T15:35:28.508Z,1720625728.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-07-10T15:35:28.509Z,1720625728.509 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-07-10T15:35:29.813Z,1720625729.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-07-10T15:35:29.815Z,1720625729.815 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-07-10T15:35:30.188Z,1720625730.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-07-10T15:35:30.190Z,1720625730.190 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-07-10T15:35:30.436Z,1720625730.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-07-10T15:35:30.438Z,1720625730.438 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2024-07-10T15:35:30.442Z,1720625730.442 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2024-07-10T15:35:30.577Z,1720625730.577 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2024-07-10T15:35:30.673Z,1720625730.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2024-07-10T15:35:30.825Z,1720625730.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2024-07-10T15:35:30.926Z,1720625730.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2024-07-10T15:35:31.201Z,1720625731.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-07-10T15:35:31.201Z,1720625731.201 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2024-07-10T15:35:31.320Z,1720625731.320 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2024-07-10T15:35:31.440Z,1720625731.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2024-07-10T15:35:31.550Z,1720625731.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2024-07-10T15:35:31.714Z,1720625731.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2024-07-10T15:35:31.825Z,1720625731.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2024-07-10T15:35:31.924Z,1720625731.924 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-07-10T15:35:31.932Z,1720625731.932 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-07-10T15:35:31.945Z,1720625731.945 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-07-10T15:35:31.945Z,1720625731.945 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-07-10T15:35:32.075Z,1720625732.075 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-07-10T15:35:32.076Z,1720625732.076 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-07-10T15:35:32.135Z,1720625732.135 [VerticalControl](DEBUG): Construct VerticalControl. 2024-07-10T15:35:32.194Z,1720625732.194 [VerticalControl] Loaded 2024-07-10T15:35:32.195Z,1720625732.195 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-07-10T15:35:32.197Z,1720625732.197 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-07-10T15:35:32.325Z,1720625732.325 [HorizontalControl] Loaded 2024-07-10T15:35:32.325Z,1720625732.325 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-07-10T15:35:32.327Z,1720625732.327 [SpeedControl](DEBUG): Construct SpeedControl. 2024-07-10T15:35:32.335Z,1720625732.335 [SpeedControl] Loaded 2024-07-10T15:35:32.336Z,1720625732.336 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-07-10T15:35:32.338Z,1720625732.338 [LoopControl](DEBUG): Construct LoopControl. 2024-07-10T15:35:32.339Z,1720625732.339 [LoopControl] Loaded 2024-07-10T15:35:32.339Z,1720625732.339 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-07-10T15:35:32.340Z,1720625732.340 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-07-10T15:35:32.340Z,1720625732.340 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-07-10T15:35:32.561Z,1720625732.561 [BuoyancyServo] Loaded 2024-07-10T15:35:32.561Z,1720625732.561 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-07-10T15:35:32.583Z,1720625732.583 [ElevatorServo] Loaded 2024-07-10T15:35:32.584Z,1720625732.584 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-07-10T15:35:32.604Z,1720625732.604 [MassServo] Loaded 2024-07-10T15:35:32.605Z,1720625732.605 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-07-10T15:35:32.625Z,1720625732.625 [RudderServo] Loaded 2024-07-10T15:35:32.625Z,1720625732.625 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-07-10T15:35:32.641Z,1720625732.641 [ThrusterHE] Loaded 2024-07-10T15:35:32.642Z,1720625732.642 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-07-10T15:35:32.642Z,1720625732.642 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-07-10T15:35:32.643Z,1720625732.643 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-07-10T15:35:32.699Z,1720625732.699 [DepthRateCalculator] Loaded 2024-07-10T15:35:32.699Z,1720625732.699 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-07-10T15:35:32.704Z,1720625732.704 [PitchRateCalculator] Loaded 2024-07-10T15:35:32.704Z,1720625732.704 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-07-10T15:35:32.714Z,1720625732.714 [SpeedCalculator] Loaded 2024-07-10T15:35:32.714Z,1720625732.714 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-07-10T15:35:32.718Z,1720625732.718 [YawRateCalculator] Loaded 2024-07-10T15:35:32.718Z,1720625732.718 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-07-10T15:35:32.737Z,1720625732.737 [ElevatorOffsetCalculator] Loaded 2024-07-10T15:35:32.737Z,1720625732.737 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-07-10T15:35:32.737Z,1720625732.737 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-07-10T15:35:32.738Z,1720625732.738 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-07-10T15:35:32.867Z,1720625732.867 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-07-10T15:35:32.868Z,1720625732.868 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-07-10T15:35:32.881Z,1720625732.881 [NavChart] Loaded 2024-07-10T15:35:32.881Z,1720625732.881 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-07-10T15:35:32.887Z,1720625732.887 [UniversalFixResidualReporter] Loaded 2024-07-10T15:35:32.887Z,1720625732.887 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-07-10T15:35:32.888Z,1720625732.888 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-07-10T15:35:32.888Z,1720625732.888 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-07-10T15:35:33.553Z,1720625733.553 [AHRS_M2] Loaded 2024-07-10T15:35:33.553Z,1720625733.553 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-07-10T15:35:33.807Z,1720625733.807 [BackseatComponent] Loaded 2024-07-10T15:35:33.807Z,1720625733.807 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-07-10T15:35:33.895Z,1720625733.895 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0 2024-07-10T15:35:33.895Z,1720625733.895 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1224 2024-07-10T15:35:33.898Z,1720625733.898 [LcmUniversalReporter] Loaded 2024-07-10T15:35:33.899Z,1720625733.899 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-07-10T15:35:33.906Z,1720625733.906 [BioacousticsDataBridge] Loaded 2024-07-10T15:35:33.907Z,1720625733.907 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2024-07-10T15:35:34.746Z,1720625734.746 [BPC1] Loaded 2024-07-10T15:35:34.747Z,1720625734.747 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-07-10T15:35:34.842Z,1720625734.842 [DataOverHttps] Loaded 2024-07-10T15:35:34.842Z,1720625734.842 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-07-10T15:35:34.843Z,1720625734.843 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0 2024-07-10T15:35:34.844Z,1720625734.844 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1225 2024-07-10T15:35:34.867Z,1720625734.867 [Depth_Keller] Loaded 2024-07-10T15:35:34.867Z,1720625734.867 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-07-10T15:35:34.872Z,1720625734.872 [DropWeight] Loaded 2024-07-10T15:35:34.873Z,1720625734.873 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-07-10T15:35:34.937Z,1720625734.937 [NAL9602] Loaded 2024-07-10T15:35:34.938Z,1720625734.938 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-07-10T15:35:34.968Z,1720625734.968 [Onboard] Loaded 2024-07-10T15:35:34.968Z,1720625734.968 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-07-10T15:35:34.969Z,1720625734.969 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0 2024-07-10T15:35:34.970Z,1720625734.970 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1226 2024-07-10T15:35:34.984Z,1720625734.984 [Power24vConverter] Loaded 2024-07-10T15:35:34.985Z,1720625734.985 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-07-10T15:35:35.000Z,1720625735.000 [Radio_Surface] Loaded 2024-07-10T15:35:35.001Z,1720625735.001 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-07-10T15:35:35.002Z,1720625735.002 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0 2024-07-10T15:35:35.002Z,1720625735.002 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1227 2024-07-10T15:35:35.017Z,1720625735.017 [Sonardyne_Nano] Loaded 2024-07-10T15:35:35.018Z,1720625735.018 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-07-10T15:35:35.018Z,1720625735.018 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-07-10T15:35:35.019Z,1720625735.019 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-07-10T15:35:35.798Z,1720625735.798 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-07-10T15:35:35.799Z,1720625735.799 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-07-10T15:35:36.212Z,1720625736.212 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-07-10T15:35:36.213Z,1720625736.213 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-07-10T15:35:36.252Z,1720625736.252 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-07-10T15:35:36.253Z,1720625736.253 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-07-10T15:35:36.456Z,1720625736.456 [CTD_Seabird] Loaded 2024-07-10T15:35:36.456Z,1720625736.456 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-07-10T15:35:36.491Z,1720625736.491 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0 2024-07-10T15:35:36.492Z,1720625736.492 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1228 2024-07-10T15:35:36.514Z,1720625736.514 [PAR_Licor] Loaded 2024-07-10T15:35:36.514Z,1720625736.514 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-07-10T15:35:36.559Z,1720625736.559 [WetLabsBB2FL] Loaded 2024-07-10T15:35:36.559Z,1720625736.559 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-07-10T15:35:36.560Z,1720625736.560 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0 2024-07-10T15:35:36.561Z,1720625736.561 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1229 2024-07-10T15:35:36.561Z,1720625736.561 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-07-10T15:35:36.562Z,1720625736.562 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-07-10T15:35:36.937Z,1720625736.937 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-07-10T15:35:36.937Z,1720625736.937 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-07-10T15:35:37.094Z,1720625737.094 [SBIT](DEBUG): Construct Startup Built In Test. 2024-07-10T15:35:37.103Z,1720625737.103 [SBIT] Loaded 2024-07-10T15:35:37.103Z,1720625737.103 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-07-10T15:35:37.106Z,1720625737.106 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-07-10T15:35:37.118Z,1720625737.118 [IBIT] Loaded 2024-07-10T15:35:37.119Z,1720625737.119 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-07-10T15:35:37.125Z,1720625737.125 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-07-10T15:35:37.228Z,1720625737.228 [CBIT] Loaded 2024-07-10T15:35:37.229Z,1720625737.229 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-07-10T15:35:37.229Z,1720625737.229 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-07-10T15:35:37.235Z,1720625737.235 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-07-10T15:35:37.238Z,1720625737.238 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-07-10T15:35:37.250Z,1720625737.250 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-07-10T15:35:37.256Z,1720625737.256 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0 2024-07-10T15:35:37.257Z,1720625737.257 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1230 2024-07-10T15:35:37.261Z,1720625737.261 [Supervisor](INFO): Main Thread ID is 1135 2024-07-10T15:35:37.261Z,1720625737.261 [Supervisor](DEBUG): Running supervisor. 2024-07-10T15:35:37.262Z,1720625737.262 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1231 2024-07-10T15:35:37.263Z,1720625737.263 [CommandExec](INFO): Initializing the command executive. 2024-07-10T15:35:37.265Z,1720625737.265 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1232 2024-07-10T15:35:37.267Z,1720625737.267 [controlThread ThreadHandler](INFO): Handler Thread ID is 1233 2024-07-10T15:35:37.268Z,1720625737.268 [controlThread](DEBUG): Initializing ControlThread 2024-07-10T15:35:37.269Z,1720625737.269 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-07-10T15:35:37.270Z,1720625737.270 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-07-10T15:35:37.271Z,1720625737.271 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-07-10T15:35:37.271Z,1720625737.271 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-07-10T15:35:37.273Z,1720625737.273 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-07-10T15:35:37.273Z,1720625737.273 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-07-10T15:35:37.274Z,1720625737.274 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-07-10T15:35:37.274Z,1720625737.274 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-07-10T15:35:37.274Z,1720625737.274 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-07-10T15:35:37.275Z,1720625737.275 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-07-10T15:35:37.276Z,1720625737.276 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-07-10T15:35:37.284Z,1720625737.284 [SBIT](INFO): Initialize SBIT Component. 2024-07-10T15:35:37.284Z,1720625737.284 [SBIT](IMPORTANT): git: 2024-06-03 2024-07-10T15:35:37.285Z,1720625737.285 [SBIT](INFO): git hash: 7fa19da3a148abf4baea4b65318d99ca26f8c915 2024-07-10T15:35:37.285Z,1720625737.285 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-07-10T15:35:37.286Z,1720625737.286 [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 2024-07-10T15:35:37.287Z,1720625737.287 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2024-07-10T15:35:37.288Z,1720625737.288 [IBIT](INFO): Initialize IBIT Component. 2024-07-10T15:35:37.289Z,1720625737.289 [CBIT](DEBUG): Initialize CBIT Component. 2024-07-10T15:35:37.289Z,1720625737.289 [logger ThreadHandler](INFO): Handler Thread ID is 1234 2024-07-10T15:35:37.300Z,1720625737.300 [CBIT](DEBUG): Initialized mux pins. 2024-07-10T15:35:37.300Z,1720625737.300 [CBIT](DEBUG): Initializing the watchdog timer. 2024-07-10T15:35:37.308Z,1720625737.308 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1235 2024-07-10T15:35:37.320Z,1720625737.320 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1236 2024-07-10T15:35:37.322Z,1720625737.322 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-07-10T15:35:37.324Z,1720625737.324 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-07-10T15:35:37.325Z,1720625737.325 [CBIT](DEBUG): Initializing heartbeat. 2024-07-10T15:35:37.332Z,1720625737.332 [Onboard ThreadHandler](INFO): Handler Thread ID is 1237 2024-07-10T15:35:37.350Z,1720625737.350 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1238 2024-07-10T15:35:37.372Z,1720625737.372 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1239 2024-07-10T15:35:37.373Z,1720625737.373 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-07-10T15:35:37.377Z,1720625737.377 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1241 2024-07-10T15:35:37.380Z,1720625737.380 [WetLabsBB2FL](INFO): Powering up 2024-07-10T15:35:37.381Z,1720625737.381 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1243 2024-07-10T15:35:37.389Z,1720625737.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-07-10T15:35:37.389Z,1720625737.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-07-10T15:35:37.389Z,1720625737.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-07-10T15:35:37.389Z,1720625737.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-07-10T15:35:37.389Z,1720625737.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-07-10T15:35:37.390Z,1720625737.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-07-10T15:35:37.390Z,1720625737.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-07-10T15:35:37.390Z,1720625737.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-07-10T15:35:37.396Z,1720625737.396 [CBIT](DEBUG): Deactivating GF circuits. 2024-07-10T15:35:37.396Z,1720625737.396 [CBIT](DEBUG): Deactivating emergency mode. 2024-07-10T15:35:37.436Z,1720625737.436 [CBIT](DEBUG): Backplane powered. 2024-07-10T15:35:37.436Z,1720625737.436 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-07-10T15:35:37.437Z,1720625737.437 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-07-10T15:35:37.437Z,1720625737.437 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-07-10T15:35:37.443Z,1720625737.443 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-07-10T15:35:37.508Z,1720625737.508 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-07-10T15:35:37.529Z,1720625737.529 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-07-10T15:35:37.549Z,1720625737.549 [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-07-10T15:35:37.550Z,1720625737.550 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-07-10T15:35:37.550Z,1720625737.550 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-07-10T15:35:37.568Z,1720625737.568 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-07-10T15:35:37.764Z,1720625737.764 [Radio_Surface](INFO): Powering up 2024-07-10T15:35:37.899Z,1720625737.899 [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-07-10T15:35:37.914Z,1720625737.914 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-07-10T15:35:37.928Z,1720625737.928 [Default:A.Wait](DEBUG): Construct Wait. 2024-07-10T15:35:37.930Z,1720625737.930 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-07-10T15:35:37.976Z,1720625737.976 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-07-10T15:35:37.978Z,1720625737.978 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-07-10T15:35:38.004Z,1720625738.004 [Default:E.Execute](DEBUG): Construct Execute. 2024-07-10T15:35:38.007Z,1720625738.007 [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-07-10T15:35:38.012Z,1720625738.012 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,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,LcmUniversalReporter,Reporter,LogSplitter, 2024-07-10T15:35:38.032Z,1720625738.032 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-07-10T15:35:38.098Z,1720625738.098 [Power24vConverter](INFO): Powering up. 2024-07-10T15:35:38.099Z,1720625738.099 [Sonardyne_Nano](INFO): Initializing. 2024-07-10T15:35:38.162Z,1720625738.162 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-07-10T15:35:38.169Z,1720625738.169 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-07-10T15:35:38.169Z,1720625738.169 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-07-10T15:35:38.176Z,1720625738.176 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-07-10T15:35:38.177Z,1720625738.177 [MassServo](DEBUG): Initializing EZServoServo. 2024-07-10T15:35:38.184Z,1720625738.184 [MassServo](DEBUG): Initializing MassServo. 2024-07-10T15:35:38.185Z,1720625738.185 [RudderServo](DEBUG): Initializing EZServoServo. 2024-07-10T15:35:38.192Z,1720625738.192 [RudderServo](DEBUG): Initializing RudderServo. 2024-07-10T15:35:38.193Z,1720625738.193 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-07-10T15:35:38.200Z,1720625738.200 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-07-10T15:35:39.488Z,1720625739.488 [WetLabsBB2FL](INFO): Powering down 2024-07-10T15:35:40.042Z,1720625740.042 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-07-10T15:35:41.684Z,1720625741.684 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2024-07-10T15:35:43.980Z,1720625743.980 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-07-10T15:35:44.428Z,1720625744.428 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-07-10T15:35:51.538Z,1720625751.538 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004719 2024-07-10T15:35:56.099Z,1720625756.099 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-07-10T15:35:56.099Z,1720625756.099 [CommandExec](IMPORTANT): Onboard.Pressure 10.132038 psi 2024-07-10T15:35:57.859Z,1720625757.859 [SBIT](IMPORTANT): Beginning Startup BIT 2024-07-10T15:35:57.880Z,1720625757.880 [CBIT](IMPORTANT): Beginning ground fault scan 2024-07-10T15:36:05.093Z,1720625765.093 [NAL9602](INFO): Powering up NAL9602 2024-07-10T15:36:08.797Z,1720625768.797 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.019359 CHAN A1 (24V): -0.006647 CHAN A2 (12V): -0.003957 CHAN A3 (5V): -0.003363 CHAN B0 (3.3V): -0.000828 CHAN B1 (3.15aV): -0.000457 CHAN B2 (3.15bV): -0.000850 CHAN B3 (GND): 0.000226 OPEN: 0.003958 Full Scale: +/- 1 mA 2024-07-10T15:36:16.001Z,1720625776.001 [NAL9602](INFO): NAL9602 initialized 2024-07-10T15:36:18.951Z,1720625778.951 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-07-10T15:36:18.956Z,1720625778.956 [CommandExec](IMPORTANT): Onboard.Pressure 10.097657 psi 2024-07-10T15:36:29.079Z,1720625789.079 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2024-07-10T15:36:29.080Z,1720625789.080 [CommandExec](IMPORTANT): Onboard.Humidity 10.725186 % 2024-07-10T15:36:47.600Z,1720625807.600 [CommandExec](IMPORTANT): got command failComponent 2024-07-10T15:36:47.600Z,1720625807.600 [CommandExec](IMPORTANT): Failed components: 2024-07-10T15:36:47.601Z,1720625807.601 [CommandExec](IMPORTANT): No failed Components. 2024-07-10T15:36:52.001Z,1720625812.001 [SBIT](IMPORTANT): SBIT PASSED 2024-07-10T15:36:52.001Z,1720625812.001 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-07-10T15:36:52.002Z,1720625812.002 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2024-07-10T15:36:52.002Z,1720625812.002 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2024-07-10T15:36:52.002Z,1720625812.002 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-07-10T15:36:52.378Z,1720625812.378 [MissionManager](IMPORTANT): Started mission Startup 2024-07-10T15:36:52.378Z,1720625812.378 [Startup] Running Loop=1 2024-07-10T15:36:52.378Z,1720625812.378 [Startup](DEBUG): Aggregate::initialize Startup 2024-07-10T15:36:52.378Z,1720625812.378 [Startup:A.GoToSurface] Running Loop=1 2024-07-10T15:36:52.378Z,1720625812.378 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-07-10T15:36:52.379Z,1720625812.379 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-07-10T15:36:52.379Z,1720625812.379 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-07-10T15:36:52.384Z,1720625812.384 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-07-10T15:36:52.384Z,1720625812.384 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-07-10T15:36:52.385Z,1720625812.385 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-07-10T15:36:52.385Z,1720625812.385 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-07-10T15:36:52.387Z,1720625812.387 [Startup:StartupSatComms] Running Loop=1 2024-07-10T15:36:52.387Z,1720625812.387 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-07-10T15:36:52.387Z,1720625812.387 [Startup:StartupSatComms:A] Running Loop=1 2024-07-10T15:36:52.789Z,1720625812.789 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-07-10T15:37:52.629Z,1720625872.629 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-10T15:36:52.4Z 2024-07-10T15:37:52.629Z,1720625872.629 [Startup:StartupSatComms:A] Stopped 2024-07-10T15:37:52.630Z,1720625872.630 [Startup:StartupSatComms:B] Running Loop=1 2024-07-10T15:37:53.012Z,1720625873.012 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-07-10T15:38:02.253Z,1720625882.253 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0121.lzma 2024-07-10T15:38:04.027Z,1720625884.027 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0121.lzma.bak 2024-07-10T15:38:04.027Z,1720625884.027 [DataOverHttps](INFO): SBD MOMSN=21348307 2024-07-10T15:38:23.703Z,1720625903.703 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240710T153524/Courier0000.lzma 2024-07-10T15:38:24.706Z,1720625904.706 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0000.lzma.bak 2024-07-10T15:38:24.706Z,1720625904.706 [DataOverHttps](INFO): SBD MOMSN=21348309 2024-07-10T15:38:37.409Z,1720625917.409 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-07-10T15:38:37.409Z,1720625917.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-10T15:38:37.420Z,1720625917.420 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-10T15:38:37.831Z,1720625917.831 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-10T15:38:37.831Z,1720625917.831 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-07-10T15:38:44.933Z,1720625924.933 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20240708T190513/Express0122.lzma 2024-07-10T15:38:45.934Z,1720625925.934 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0122.lzma.bak 2024-07-10T15:38:45.934Z,1720625925.934 [DataOverHttps](INFO): SBD MOMSN=21348312 2024-07-10T15:38:52.793Z,1720625932.793 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-10T15:37:52.6Z 2024-07-10T15:38:52.793Z,1720625932.793 [Startup:StartupSatComms:B] Stopped 2024-07-10T15:38:52.794Z,1720625932.794 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-07-10T15:38:52.794Z,1720625932.794 [Startup:StartupSatComms] Stopped 2024-07-10T15:38:52.794Z,1720625932.794 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-07-10T15:38:52.795Z,1720625932.795 [Startup](INFO): Completed Startup 2024-07-10T15:38:52.795Z,1720625932.795 [MissionManager](INFO): Startup is completed. 2024-07-10T15:38:52.795Z,1720625932.795 [MissionManager](INFO): Uninitializing Mission Startup 2024-07-10T15:38:52.795Z,1720625932.795 [Startup] Stopped 2024-07-10T15:38:52.795Z,1720625932.795 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-07-10T15:38:52.795Z,1720625932.795 [Startup:A.GoToSurface] Stopped 2024-07-10T15:38:52.795Z,1720625932.795 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-07-10T15:38:53.193Z,1720625933.193 [MissionManager](IMPORTANT): Started mission Default 2024-07-10T15:38:53.193Z,1720625933.193 [Default] Running Loop=1 2024-07-10T15:38:53.193Z,1720625933.193 [Default](DEBUG): Aggregate::initialize Default 2024-07-10T15:38:53.193Z,1720625933.193 [Default:B.GoToSurface] Running Loop=1 2024-07-10T15:38:53.193Z,1720625933.193 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-07-10T15:38:53.193Z,1720625933.193 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-07-10T15:38:53.194Z,1720625933.194 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-07-10T15:38:53.194Z,1720625933.194 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-07-10T15:38:53.194Z,1720625933.194 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-07-10T15:38:53.195Z,1720625933.195 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-07-10T15:38:53.195Z,1720625933.195 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-07-10T15:38:53.195Z,1720625933.195 [Default:A.Wait] Running Loop=1 2024-07-10T15:38:53.195Z,1720625933.195 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-07-10T15:39:06.509Z,1720625946.509 [Default:A.Wait](INFO): Done Waiting. 2024-07-10T15:39:06.509Z,1720625946.509 [Default:A.Wait] Stopped 2024-07-10T15:39:06.509Z,1720625946.509 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T15:39:06.906Z,1720625946.906 [Default:CheckIn] Running Loop=1 2024-07-10T15:39:06.907Z,1720625946.907 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T15:39:06.907Z,1720625946.907 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T15:39:07.309Z,1720625947.309 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-07-10T15:39:35.645Z,1720625975.645 [DataOverHttps](INFO): Sending 888 bytes from file Logs/20240710T153524/Express0001.lzma 2024-07-10T15:39:36.645Z,1720625976.645 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0001.lzma.bak 2024-07-10T15:39:36.646Z,1720625976.646 [DataOverHttps](INFO): SBD MOMSN=21348316 2024-07-10T15:39:38.412Z,1720625978.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0 2024-07-10T15:39:38.412Z,1720625978.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T15:40:14.765Z,1720626014.765 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0 2024-07-10T15:40:14.765Z,1720626014.765 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T15:40:32.948Z,1720626032.948 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:40:39.809Z,1720626039.809 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:40:55.565Z,1720626055.565 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:41:10.513Z,1720626070.513 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:41:18.597Z,1720626078.597 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-07-10T15:41:25.928Z,1720626085.928 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:41:38.404Z,1720626098.404 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-07-10T15:41:38.404Z,1720626098.404 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-10T15:41:38.433Z,1720626098.433 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-10T15:41:38.887Z,1720626098.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-10T15:41:38.887Z,1720626098.887 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-07-10T15:41:41.221Z,1720626101.221 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:41:56.974Z,1720626116.974 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:42:13.133Z,1720626133.133 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:42:28.485Z,1720626148.485 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:42:43.849Z,1720626163.849 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T15:42:59.633Z,1720626179.633 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 5. 2024-07-10T15:42:59.640Z,1720626179.640 [BPC1](INFO): Received data from all battery sticks. 2024-07-10T15:44:07.106Z,1720626247.106 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-10T15:39:06.9Z 2024-07-10T15:44:07.106Z,1720626247.106 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T15:44:07.106Z,1720626247.106 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T15:44:07.500Z,1720626247.500 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-07-10T15:44:16.507Z,1720626256.507 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240710T153524/Courier0004.lzma 2024-07-10T15:44:17.510Z,1720626257.510 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0004.lzma.bak 2024-07-10T15:44:17.510Z,1720626257.510 [DataOverHttps](INFO): SBD MOMSN=21348354 2024-07-10T15:44:39.409Z,1720626279.409 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-07-10T15:44:39.409Z,1720626279.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-10T15:44:39.442Z,1720626279.442 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-10T15:44:39.849Z,1720626279.849 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-10T15:44:39.849Z,1720626279.849 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-07-10T15:44:40.095Z,1720626280.095 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20240710T153524/Express0005.lzma 2024-07-10T15:44:41.098Z,1720626281.098 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0005.lzma.bak 2024-07-10T15:44:41.098Z,1720626281.098 [DataOverHttps](INFO): SBD MOMSN=21348356 2024-07-10T15:44:47.090Z,1720626287.090 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T15:44:47.090Z,1720626287.090 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T15:44:47.090Z,1720626287.090 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T15:45:39.190Z,1720626339.190 [Power24vConverter](INFO): Powering down. 2024-07-10T15:46:18.785Z,1720626378.785 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-07-10T15:46:18.785Z,1720626378.785 [NAL9602] Data Fault, FailCount= 1 2024-07-10T15:46:18.785Z,1720626378.785 [NAL9602](ERROR): Data Fault 2024-07-10T15:46:18.803Z,1720626378.803 [CBIT](ERROR): Data Fault in component: NAL9602 2024-07-10T15:46:19.189Z,1720626379.189 [NAL9602](INFO): Powering down 2024-07-10T15:46:20.021Z,1720626380.021 [CBIT](INFO): Clearing failed state for component NAL9602 2024-07-10T15:46:20.021Z,1720626380.021 [NAL9602] No Fault, FailCount= 1 2024-07-10T15:46:49.486Z,1720626409.486 [NAL9602](INFO): Powering up NAL9602 2024-07-10T15:47:00.397Z,1720626420.397 [NAL9602](INFO): NAL9602 initialized 2024-07-10T15:47:31.099Z,1720626451.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154730.00,A,3648.17684,N,12147.28224,W,0.311,0.00,100724,,,A*71 2024-07-10T15:47:31.102Z,1720626451.102 [NAL9602](INFO): GPS fix at 20240710T154730: (36.802947, -121.788037) 2024-07-10T15:48:03.421Z,1720626483.421 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T15:49:47.666Z,1720626587.666 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T15:49:47.666Z,1720626587.666 [Default:CheckIn:C.Wait] Stopped 2024-07-10T15:49:47.666Z,1720626587.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T15:49:47.666Z,1720626587.666 [Default:CheckIn:D] Running Loop=1 2024-07-10T15:49:48.096Z,1720626588.096 [Default:CheckIn:D] Stopped 2024-07-10T15:49:48.096Z,1720626588.096 [Default:CheckIn:E] Running Loop=1 2024-07-10T15:49:48.484Z,1720626588.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.915052 min 2024-07-10T15:49:48.484Z,1720626588.484 [Default:CheckIn:E] Stopped 2024-07-10T15:49:48.484Z,1720626588.484 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T15:49:48.484Z,1720626588.484 [Default:CheckIn] Stopped 2024-07-10T15:49:48.484Z,1720626588.484 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T15:49:48.484Z,1720626588.484 [Default:CheckIn](INFO): Running loop #2 2024-07-10T15:49:48.484Z,1720626588.484 [Default:CheckIn] Running Loop=2 2024-07-10T15:49:48.485Z,1720626588.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T15:49:48.485Z,1720626588.485 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T15:49:50.487Z,1720626590.487 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154949.00,A,3648.17532,N,12147.27755,W,0.078,0.00,100724,,,A*7F 2024-07-10T15:49:50.489Z,1720626590.489 [NAL9602](INFO): GPS fix at 20240710T154949: (36.802922, -121.787959) 2024-07-10T15:49:50.516Z,1720626590.516 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T15:49:50.516Z,1720626590.516 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T15:50:00.071Z,1720626600.071 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240710T153524/Courier0007.lzma 2024-07-10T15:50:01.074Z,1720626601.074 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0007.lzma.bak 2024-07-10T15:50:01.074Z,1720626601.074 [DataOverHttps](INFO): SBD MOMSN=21348372 2024-07-10T15:50:25.907Z,1720626625.907 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20240710T153524/Express0008.lzma 2024-07-10T15:50:26.909Z,1720626626.909 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0008.lzma.bak 2024-07-10T15:50:26.909Z,1720626626.909 [DataOverHttps](INFO): SBD MOMSN=21348375 2024-07-10T15:50:32.998Z,1720626632.999 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T15:50:32.999Z,1720626632.999 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T15:50:32.999Z,1720626632.999 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T15:50:40.638Z,1720626640.638 [NAL9602](INFO): SBD MO Status=0, MOMSN=5078, MT Status=0, MTMSN=0 2024-07-10T15:50:40.638Z,1720626640.638 [NAL9602](INFO): No messages in MT queue 2024-07-10T15:51:11.344Z,1720626671.344 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T15:55:33.558Z,1720626933.558 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T15:55:33.558Z,1720626933.558 [Default:CheckIn:C.Wait] Stopped 2024-07-10T15:55:33.558Z,1720626933.558 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T15:55:33.559Z,1720626933.559 [Default:CheckIn:D] Running Loop=1 2024-07-10T15:55:33.968Z,1720626933.968 [Default:CheckIn:D] Stopped 2024-07-10T15:55:33.968Z,1720626933.968 [Default:CheckIn:E] Running Loop=1 2024-07-10T15:55:34.372Z,1720626934.372 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.679585 min 2024-07-10T15:55:34.372Z,1720626934.372 [Default:CheckIn:E] Stopped 2024-07-10T15:55:34.372Z,1720626934.372 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T15:55:34.372Z,1720626934.372 [Default:CheckIn] Stopped 2024-07-10T15:55:34.372Z,1720626934.372 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T15:55:34.373Z,1720626934.373 [Default:CheckIn](INFO): Running loop #3 2024-07-10T15:55:34.373Z,1720626934.373 [Default:CheckIn] Running Loop=3 2024-07-10T15:55:34.373Z,1720626934.373 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T15:55:34.373Z,1720626934.373 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T15:55:36.370Z,1720626936.370 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155536.00,A,3648.17602,N,12147.27759,W,0.058,0.00,100724,,,D*71 2024-07-10T15:55:36.372Z,1720626936.372 [NAL9602](INFO): GPS fix at 20240710T155536: (36.802934, -121.787960) 2024-07-10T15:55:36.382Z,1720626936.382 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T15:55:36.383Z,1720626936.383 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T15:55:46.118Z,1720626946.118 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0010.lzma 2024-07-10T15:55:47.121Z,1720626947.121 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0010.lzma.bak 2024-07-10T15:55:47.121Z,1720626947.121 [DataOverHttps](INFO): SBD MOMSN=21348394 2024-07-10T15:56:07.831Z,1720626967.831 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240710T153524/Express0011.lzma 2024-07-10T15:56:08.833Z,1720626968.833 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0011.lzma.bak 2024-07-10T15:56:08.833Z,1720626968.833 [DataOverHttps](INFO): SBD MOMSN=21348403 2024-07-10T15:56:17.606Z,1720626977.606 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T15:56:17.606Z,1720626977.606 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T15:56:17.606Z,1720626977.606 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T15:59:47.662Z,1720627187.662 [NAL9602](INFO): SBD MO Status=0, MOMSN=5079, MT Status=0, MTMSN=0 2024-07-10T15:59:47.662Z,1720627187.662 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:00:18.341Z,1720627218.341 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:01:18.153Z,1720627278.153 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:01:18.153Z,1720627278.153 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:01:18.153Z,1720627278.153 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:01:18.153Z,1720627278.153 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:01:18.590Z,1720627278.590 [Default:CheckIn:D] Stopped 2024-07-10T16:01:18.590Z,1720627278.590 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:01:18.950Z,1720627278.950 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.423285 min 2024-07-10T16:01:18.950Z,1720627278.950 [Default:CheckIn:E] Stopped 2024-07-10T16:01:18.950Z,1720627278.950 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:01:18.950Z,1720627278.950 [Default:CheckIn] Stopped 2024-07-10T16:01:18.950Z,1720627278.950 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:01:18.950Z,1720627278.950 [Default:CheckIn](INFO): Running loop #4 2024-07-10T16:01:18.950Z,1720627278.950 [Default:CheckIn] Running Loop=4 2024-07-10T16:01:18.951Z,1720627278.951 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:01:18.951Z,1720627278.951 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:01:20.963Z,1720627280.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160120.00,A,3648.17518,N,12147.27833,W,0.019,0.00,100724,,,D*7A 2024-07-10T16:01:20.966Z,1720627280.966 [NAL9602](INFO): GPS fix at 20240710T160120: (36.802920, -121.787972) 2024-07-10T16:01:20.981Z,1720627280.981 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:01:20.981Z,1720627280.981 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:01:30.720Z,1720627290.720 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0013.lzma 2024-07-10T16:01:31.722Z,1720627291.722 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0013.lzma.bak 2024-07-10T16:01:31.722Z,1720627291.722 [DataOverHttps](INFO): SBD MOMSN=21348420 2024-07-10T16:01:47.218Z,1720627307.218 [NAL9602](INFO): SBD MO Status=2, MOMSN=5080, MT Status=2, MTMSN=0 2024-07-10T16:01:47.218Z,1720627307.218 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:01:55.303Z,1720627315.303 [NAL9602](INFO): SBD MO Status=0, MOMSN=5080, MT Status=0, MTMSN=0 2024-07-10T16:01:55.303Z,1720627315.303 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:01:55.816Z,1720627315.816 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240710T153524/Express0014.lzma 2024-07-10T16:01:56.818Z,1720627316.818 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0014.lzma.bak 2024-07-10T16:01:56.818Z,1720627316.818 [DataOverHttps](INFO): SBD MOMSN=21348423 2024-07-10T16:02:02.997Z,1720627322.997 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:02:02.997Z,1720627322.997 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:02:02.997Z,1720627322.997 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:02:26.000Z,1720627346.000 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:05:19.610Z,1720627519.610 [DataOverHttps](IMPORTANT): SBD MTMSN=20240710T160518 2024-07-10T16:05:26.947Z,1720627526.947 [DataOverHttps](INFO): Received command: get platform_mass_position mm 2024-07-10T16:05:27.687Z,1720627527.687 [CommandExec](IMPORTANT): got command get platform_mass_position millimeter 2024-07-10T16:05:27.688Z,1720627527.688 [CommandExec](IMPORTANT): platform_mass_position -0.030178 mm 2024-07-10T16:05:27.942Z,1720627527.942 [DataOverHttps](IMPORTANT): SBD MTMSN=20240710T160527 2024-07-10T16:05:35.164Z,1720627535.164 [DataOverHttps](INFO): Received command: get platform_buoyancy_position 2024-07-10T16:05:36.008Z,1720627536.008 [CommandExec](IMPORTANT): got command get platform_buoyancy_position 2024-07-10T16:05:36.009Z,1720627536.009 [CommandExec](IMPORTANT): platform_buoyancy_position 997.015991 cc 2024-07-10T16:07:03.582Z,1720627623.582 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:07:03.583Z,1720627623.583 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:07:03.583Z,1720627623.583 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:07:03.583Z,1720627623.583 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:07:03.997Z,1720627623.997 [Default:CheckIn:D] Stopped 2024-07-10T16:07:03.997Z,1720627623.997 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:07:04.408Z,1720627624.408 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.180064 min 2024-07-10T16:07:04.409Z,1720627624.409 [Default:CheckIn:E] Stopped 2024-07-10T16:07:04.409Z,1720627624.409 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:07:04.409Z,1720627624.409 [Default:CheckIn] Stopped 2024-07-10T16:07:04.409Z,1720627624.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:07:04.409Z,1720627624.409 [Default:CheckIn](INFO): Running loop #5 2024-07-10T16:07:04.409Z,1720627624.409 [Default:CheckIn] Running Loop=5 2024-07-10T16:07:04.409Z,1720627624.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:07:04.409Z,1720627624.409 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:07:06.407Z,1720627626.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160705.00,A,3648.17567,N,12147.27914,W,0.039,0.00,100724,,,D*75 2024-07-10T16:07:06.410Z,1720627626.410 [NAL9602](INFO): GPS fix at 20240710T160705: (36.802928, -121.787986) 2024-07-10T16:07:06.445Z,1720627626.445 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:07:06.445Z,1720627626.445 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:07:16.540Z,1720627636.540 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0016.lzma 2024-07-10T16:07:17.542Z,1720627637.542 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0016.lzma.bak 2024-07-10T16:07:17.542Z,1720627637.542 [DataOverHttps](INFO): SBD MOMSN=21348445 2024-07-10T16:07:38.660Z,1720627658.660 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20240710T153524/Express0017.lzma 2024-07-10T16:07:39.663Z,1720627659.663 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0017.lzma.bak 2024-07-10T16:07:39.663Z,1720627659.663 [DataOverHttps](INFO): SBD MOMSN=21348448 2024-07-10T16:07:46.020Z,1720627666.020 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:07:46.020Z,1720627666.020 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:07:46.020Z,1720627666.020 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:08:06.178Z,1720627686.178 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitFwd millimeter 2024-07-10T16:08:06.178Z,1720627686.178 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd 27.000000 mm 2024-07-10T16:08:35.909Z,1720627715.909 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 17 millimeter 2024-07-10T16:08:35.915Z,1720627715.915 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2024-07-10T16:08:36.144Z,1720627716.144 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-07-10T16:09:44.990Z,1720627784.990 [CommandExec](IMPORTANT): got command get platform_mass_position millimeter 2024-07-10T16:09:44.990Z,1720627784.990 [CommandExec](IMPORTANT): platform_mass_position 17.003506 mm 2024-07-10T16:09:58.111Z,1720627798.111 [NAL9602](INFO): SBD MO Status=0, MOMSN=5081, MT Status=0, MTMSN=0 2024-07-10T16:09:58.111Z,1720627798.111 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:10:28.813Z,1720627828.813 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:12:46.592Z,1720627966.592 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:12:46.592Z,1720627966.592 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:12:46.592Z,1720627966.592 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:12:46.592Z,1720627966.592 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:12:47.003Z,1720627967.003 [Default:CheckIn:D] Stopped 2024-07-10T16:12:47.003Z,1720627967.003 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:12:47.399Z,1720627967.399 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.896844 min 2024-07-10T16:12:47.399Z,1720627967.399 [Default:CheckIn:E] Stopped 2024-07-10T16:12:47.399Z,1720627967.399 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:12:47.399Z,1720627967.399 [Default:CheckIn] Stopped 2024-07-10T16:12:47.399Z,1720627967.399 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:12:47.399Z,1720627967.399 [Default:CheckIn](INFO): Running loop #6 2024-07-10T16:12:47.400Z,1720627967.400 [Default:CheckIn] Running Loop=6 2024-07-10T16:12:47.400Z,1720627967.400 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:12:47.400Z,1720627967.400 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:12:49.407Z,1720627969.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161249.00,A,3648.17487,N,12147.27965,W,0.058,0.00,100724,,,D*77 2024-07-10T16:12:49.417Z,1720627969.417 [NAL9602](INFO): GPS fix at 20240710T161249: (36.802914, -121.787994) 2024-07-10T16:12:49.437Z,1720627969.437 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:12:49.437Z,1720627969.437 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:12:54.751Z,1720627974.751 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0021.lzma 2024-07-10T16:12:55.758Z,1720627975.758 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0021.lzma.bak 2024-07-10T16:12:55.758Z,1720627975.758 [DataOverHttps](INFO): SBD MOMSN=21348473 2024-07-10T16:13:06.828Z,1720627986.828 [NAL9602](INFO): SBD MO Status=0, MOMSN=5082, MT Status=0, MTMSN=0 2024-07-10T16:13:06.828Z,1720627986.828 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:13:19.280Z,1720627999.280 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20240710T153524/Express0023.lzma 2024-07-10T16:13:20.283Z,1720628000.283 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0023.lzma.bak 2024-07-10T16:13:20.283Z,1720628000.283 [DataOverHttps](INFO): SBD MOMSN=21348480 2024-07-10T16:13:26.640Z,1720628006.640 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:13:26.640Z,1720628006.640 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:13:26.640Z,1720628006.640 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:13:37.529Z,1720628017.529 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:18:27.255Z,1720628307.255 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:18:27.255Z,1720628307.255 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:18:27.255Z,1720628307.255 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:18:27.255Z,1720628307.255 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:18:27.663Z,1720628307.663 [Default:CheckIn:D] Stopped 2024-07-10T16:18:27.663Z,1720628307.663 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:18:28.071Z,1720628308.071 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.574512 min 2024-07-10T16:18:28.071Z,1720628308.071 [Default:CheckIn:E] Stopped 2024-07-10T16:18:28.071Z,1720628308.071 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:18:28.071Z,1720628308.071 [Default:CheckIn] Stopped 2024-07-10T16:18:28.071Z,1720628308.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:18:28.072Z,1720628308.072 [Default:CheckIn](INFO): Running loop #7 2024-07-10T16:18:28.072Z,1720628308.072 [Default:CheckIn] Running Loop=7 2024-07-10T16:18:28.072Z,1720628308.072 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:18:28.072Z,1720628308.072 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:18:30.067Z,1720628310.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161829.00,A,3648.17569,N,12147.28036,W,0.097,0.00,100724,,,D*79 2024-07-10T16:18:30.070Z,1720628310.070 [NAL9602](INFO): GPS fix at 20240710T161829: (36.802928, -121.788006) 2024-07-10T16:18:30.081Z,1720628310.081 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:18:30.081Z,1720628310.081 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:18:39.744Z,1720628319.744 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0025.lzma 2024-07-10T16:18:40.746Z,1720628320.746 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0025.lzma.bak 2024-07-10T16:18:40.746Z,1720628320.746 [DataOverHttps](INFO): SBD MOMSN=21348495 2024-07-10T16:18:54.134Z,1720628334.134 [CommandExec](IMPORTANT): got command get platform_mass_position millimeter 2024-07-10T16:18:54.134Z,1720628334.134 [CommandExec](IMPORTANT): platform_mass_position 17.003506 mm 2024-07-10T16:19:02.524Z,1720628342.524 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0026.lzma 2024-07-10T16:19:03.526Z,1720628343.526 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0026.lzma.bak 2024-07-10T16:19:03.526Z,1720628343.526 [DataOverHttps](INFO): SBD MOMSN=21348503 2024-07-10T16:19:09.887Z,1720628349.887 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:19:09.887Z,1720628349.887 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:19:09.887Z,1720628349.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:19:11.081Z,1720628351.081 [NAL9602](INFO): SBD MO Status=2, MOMSN=5083, MT Status=2, MTMSN=0 2024-07-10T16:19:11.081Z,1720628351.081 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:19:36.368Z,1720628376.368 [CommandExec](IMPORTANT): got command failComponent 2024-07-10T16:19:36.368Z,1720628376.368 [CommandExec](IMPORTANT): Failed components: 2024-07-10T16:19:36.368Z,1720628376.368 [CommandExec](IMPORTANT): No failed Components. 2024-07-10T16:19:40.535Z,1720628380.535 [CommandExec](IMPORTANT): got command gfscan 2024-07-10T16:19:40.612Z,1720628380.612 [CBIT](IMPORTANT): Beginning ground fault scan 2024-07-10T16:19:43.424Z,1720628383.424 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-07-10T16:19:51.726Z,1720628391.726 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005424 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.004523 CHAN A3 (5V): -0.002704 CHAN B0 (3.3V): -0.000622 CHAN B1 (3.15aV): -0.001043 CHAN B2 (3.15bV): -0.001193 CHAN B3 (GND): -0.000586 OPEN: 0.003910 Full Scale: +/- 1 mA 2024-07-10T16:20:03.607Z,1720628403.607 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-07-10T16:20:03.607Z,1720628403.607 [CommandExec](IMPORTANT): Onboard.Pressure 10.114576 psi 2024-07-10T16:21:11.473Z,1720628471.473 [NAL9602](INFO): SBD MO Status=0, MOMSN=5083, MT Status=0, MTMSN=0 2024-07-10T16:21:11.473Z,1720628471.473 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:21:42.174Z,1720628502.174 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:23:32.349Z,1720628612.349 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 20 millimeter 2024-07-10T16:24:10.479Z,1720628650.479 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:24:10.479Z,1720628650.479 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:24:10.479Z,1720628650.479 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:24:10.479Z,1720628650.479 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:24:10.856Z,1720628650.856 [Default:CheckIn:D] Stopped 2024-07-10T16:24:10.856Z,1720628650.856 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:24:11.279Z,1720628651.279 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.294393 min 2024-07-10T16:24:11.279Z,1720628651.279 [Default:CheckIn:E] Stopped 2024-07-10T16:24:11.279Z,1720628651.279 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:24:11.279Z,1720628651.279 [Default:CheckIn] Stopped 2024-07-10T16:24:11.279Z,1720628651.279 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:24:11.280Z,1720628651.280 [Default:CheckIn](INFO): Running loop #8 2024-07-10T16:24:11.280Z,1720628651.280 [Default:CheckIn] Running Loop=8 2024-07-10T16:24:11.280Z,1720628651.280 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:24:11.280Z,1720628651.280 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:24:13.282Z,1720628653.282 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162412.00,A,3648.17532,N,12147.27882,W,0.078,0.00,100724,,,D*79 2024-07-10T16:24:13.284Z,1720628653.284 [NAL9602](INFO): GPS fix at 20240710T162412: (36.802922, -121.787980) 2024-07-10T16:24:13.295Z,1720628653.295 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:24:13.295Z,1720628653.295 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:24:22.946Z,1720628662.946 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0028.lzma 2024-07-10T16:24:23.948Z,1720628663.948 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0028.lzma.bak 2024-07-10T16:24:23.948Z,1720628663.948 [DataOverHttps](INFO): SBD MOMSN=21348525 2024-07-10T16:24:44.699Z,1720628684.699 [DataOverHttps](INFO): Sending 528 bytes from file Logs/20240710T153524/Express0029.lzma 2024-07-10T16:24:45.700Z,1720628685.700 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0029.lzma.bak 2024-07-10T16:24:45.701Z,1720628685.701 [DataOverHttps](INFO): SBD MOMSN=21348528 2024-07-10T16:24:52.888Z,1720628692.888 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:24:52.888Z,1720628692.888 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:24:52.888Z,1720628692.888 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:25:04.396Z,1720628704.396 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 800 cubic_centimeter 2024-07-10T16:25:04.403Z,1720628704.403 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2024-07-10T16:25:04.646Z,1720628704.646 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-07-10T16:25:31.982Z,1720628731.982 [CommandExec](IMPORTANT): got command report mod 2024-07-10T16:25:31.982Z,1720628731.982 [CommandExec](FAULT): Incomplete syntax. Try: help report 2024-07-10T16:25:43.366Z,1720628743.366 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging 2024-07-10T16:25:44.115Z,1720628744.115 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position 2024-07-10T16:25:44.214Z,1720628744.214 [Reporter](INFO): platform_buoyancy_position 807.652954 cc 2024-07-10T16:26:05.017Z,1720628765.017 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 600 cubic_centimeter 2024-07-10T16:26:06.869Z,1720628766.869 [Reporter](INFO): platform_buoyancy_position 809.130249 cc 2024-07-10T16:26:07.272Z,1720628767.272 [Reporter](INFO): platform_buoyancy_position 807.921570 cc 2024-07-10T16:26:07.681Z,1720628767.681 [Reporter](INFO): platform_buoyancy_position 807.115784 cc 2024-07-10T16:26:08.082Z,1720628768.082 [Reporter](INFO): platform_buoyancy_position 797.043274 cc 2024-07-10T16:26:08.473Z,1720628768.473 [Reporter](INFO): platform_buoyancy_position 796.103149 cc 2024-07-10T16:26:08.888Z,1720628768.888 [Reporter](INFO): platform_buoyancy_position 792.745667 cc 2024-07-10T16:26:09.300Z,1720628769.300 [Reporter](INFO): platform_buoyancy_position 788.313782 cc 2024-07-10T16:26:09.693Z,1720628769.693 [Reporter](INFO): platform_buoyancy_position 783.747559 cc 2024-07-10T16:26:10.081Z,1720628770.081 [Reporter](INFO): platform_buoyancy_position 779.718567 cc 2024-07-10T16:26:10.497Z,1720628770.497 [Reporter](INFO): platform_buoyancy_position 773.406494 cc 2024-07-10T16:26:10.913Z,1720628770.913 [Reporter](INFO): platform_buoyancy_position 769.108887 cc 2024-07-10T16:26:11.315Z,1720628771.315 [Reporter](INFO): platform_buoyancy_position 764.676941 cc 2024-07-10T16:26:11.693Z,1720628771.693 [Reporter](INFO): platform_buoyancy_position 758.230591 cc 2024-07-10T16:26:12.113Z,1720628772.113 [Reporter](INFO): platform_buoyancy_position 753.932983 cc 2024-07-10T16:26:12.509Z,1720628772.509 [Reporter](INFO): platform_buoyancy_position 747.620850 cc 2024-07-10T16:26:12.925Z,1720628772.925 [Reporter](INFO): platform_buoyancy_position 741.443054 cc 2024-07-10T16:26:13.313Z,1720628773.313 [Reporter](INFO): platform_buoyancy_position 736.876892 cc 2024-07-10T16:26:13.733Z,1720628773.733 [Reporter](INFO): platform_buoyancy_position 732.310669 cc 2024-07-10T16:26:14.125Z,1720628774.125 [Reporter](INFO): platform_buoyancy_position 726.132874 cc 2024-07-10T16:26:14.537Z,1720628774.537 [Reporter](INFO): platform_buoyancy_position 719.552185 cc 2024-07-10T16:26:14.925Z,1720628774.925 [Reporter](INFO): platform_buoyancy_position 715.388855 cc 2024-07-10T16:26:15.352Z,1720628775.352 [Reporter](INFO): platform_buoyancy_position 710.956970 cc 2024-07-10T16:26:15.741Z,1720628775.741 [Reporter](INFO): platform_buoyancy_position 704.510559 cc 2024-07-10T16:26:16.136Z,1720628776.136 [Reporter](INFO): platform_buoyancy_position 700.078674 cc 2024-07-10T16:26:16.545Z,1720628776.545 [Reporter](INFO): platform_buoyancy_position 693.766541 cc 2024-07-10T16:26:16.968Z,1720628776.968 [Reporter](INFO): platform_buoyancy_position 689.468994 cc 2024-07-10T16:26:17.366Z,1720628777.366 [Reporter](INFO): platform_buoyancy_position 683.022583 cc 2024-07-10T16:26:17.753Z,1720628777.753 [Reporter](INFO): platform_buoyancy_position 678.724976 cc 2024-07-10T16:26:18.168Z,1720628778.168 [Reporter](INFO): platform_buoyancy_position 672.547180 cc 2024-07-10T16:26:18.581Z,1720628778.581 [Reporter](INFO): platform_buoyancy_position 667.980957 cc 2024-07-10T16:26:18.977Z,1720628778.977 [Reporter](INFO): platform_buoyancy_position 661.668884 cc 2024-07-10T16:26:19.373Z,1720628779.373 [Reporter](INFO): platform_buoyancy_position 657.371277 cc 2024-07-10T16:26:19.788Z,1720628779.788 [Reporter](INFO): platform_buoyancy_position 650.924866 cc 2024-07-10T16:26:20.200Z,1720628780.200 [Reporter](INFO): platform_buoyancy_position 646.492981 cc 2024-07-10T16:26:20.609Z,1720628780.609 [Reporter](INFO): platform_buoyancy_position 642.061035 cc 2024-07-10T16:26:20.985Z,1720628780.985 [Reporter](INFO): platform_buoyancy_position 635.748962 cc 2024-07-10T16:26:21.396Z,1720628781.396 [Reporter](INFO): platform_buoyancy_position 631.585632 cc 2024-07-10T16:26:21.820Z,1720628781.820 [Reporter](INFO): platform_buoyancy_position 625.676453 cc 2024-07-10T16:26:22.153Z,1720628782.153 [NAL9602](INFO): SBD MO Status=2, MOMSN=5084, MT Status=2, MTMSN=0 2024-07-10T16:26:22.153Z,1720628782.153 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:26:22.241Z,1720628782.241 [Reporter](INFO): platform_buoyancy_position 620.573059 cc 2024-07-10T16:26:22.647Z,1720628782.647 [Reporter](INFO): platform_buoyancy_position 614.529541 cc 2024-07-10T16:26:23.077Z,1720628783.077 [Reporter](INFO): platform_buoyancy_position 609.963379 cc 2024-07-10T16:26:50.152Z,1720628810.152 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 700 cubic_centimeter 2024-07-10T16:26:52.140Z,1720628812.140 [Reporter](INFO): platform_buoyancy_position 607.814575 cc 2024-07-10T16:26:52.497Z,1720628812.497 [Reporter](INFO): platform_buoyancy_position 607.948853 cc 2024-07-10T16:26:52.917Z,1720628812.917 [Reporter](INFO): platform_buoyancy_position 609.694763 cc 2024-07-10T16:26:53.329Z,1720628813.329 [Reporter](INFO): platform_buoyancy_position 608.754639 cc 2024-07-10T16:26:53.725Z,1720628813.725 [Reporter](INFO): platform_buoyancy_position 614.260986 cc 2024-07-10T16:26:54.121Z,1720628814.121 [Reporter](INFO): platform_buoyancy_position 620.707336 cc 2024-07-10T16:26:54.536Z,1720628814.536 [Reporter](INFO): platform_buoyancy_position 625.407837 cc 2024-07-10T16:26:54.929Z,1720628814.929 [Reporter](INFO): platform_buoyancy_position 631.585632 cc 2024-07-10T16:26:55.325Z,1720628815.325 [Reporter](INFO): platform_buoyancy_position 636.017578 cc 2024-07-10T16:26:55.777Z,1720628815.777 [Reporter](INFO): platform_buoyancy_position 642.329651 cc 2024-07-10T16:26:56.153Z,1720628816.153 [Reporter](INFO): platform_buoyancy_position 647.970276 cc 2024-07-10T16:26:56.557Z,1720628816.557 [Reporter](INFO): platform_buoyancy_position 653.073669 cc 2024-07-10T16:26:56.941Z,1720628816.941 [Reporter](INFO): platform_buoyancy_position 659.385742 cc 2024-07-10T16:26:57.352Z,1720628817.352 [Reporter](INFO): platform_buoyancy_position 663.817688 cc 2024-07-10T16:26:57.796Z,1720628817.796 [Reporter](INFO): platform_buoyancy_position 670.129761 cc 2024-07-10T16:26:58.189Z,1720628818.189 [Reporter](INFO): platform_buoyancy_position 674.561646 cc 2024-07-10T16:26:58.595Z,1720628818.595 [Reporter](INFO): platform_buoyancy_position 680.873779 cc 2024-07-10T16:26:58.988Z,1720628818.988 [Reporter](INFO): platform_buoyancy_position 685.439941 cc 2024-07-10T16:26:59.404Z,1720628819.404 [Reporter](INFO): platform_buoyancy_position 691.617737 cc 2024-07-10T16:27:16.553Z,1720628836.553 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 750 cubic_centimeter 2024-07-10T16:27:18.479Z,1720628838.479 [Reporter](INFO): platform_buoyancy_position 702.361755 cc 2024-07-10T16:27:18.876Z,1720628838.876 [Reporter](INFO): platform_buoyancy_position 704.510559 cc 2024-07-10T16:27:19.679Z,1720628839.679 [Reporter](INFO): platform_buoyancy_position 704.644836 cc 2024-07-10T16:27:20.071Z,1720628840.071 [Reporter](INFO): platform_buoyancy_position 709.345337 cc 2024-07-10T16:27:20.497Z,1720628840.497 [Reporter](INFO): platform_buoyancy_position 715.254578 cc 2024-07-10T16:27:20.887Z,1720628840.887 [Reporter](INFO): platform_buoyancy_position 721.700989 cc 2024-07-10T16:27:21.299Z,1720628841.299 [Reporter](INFO): platform_buoyancy_position 728.147339 cc 2024-07-10T16:27:21.687Z,1720628841.687 [Reporter](INFO): platform_buoyancy_position 732.579285 cc 2024-07-10T16:27:22.113Z,1720628842.113 [Reporter](INFO): platform_buoyancy_position 737.145447 cc 2024-07-10T16:27:22.534Z,1720628842.534 [Reporter](INFO): platform_buoyancy_position 741.980286 cc 2024-07-10T16:27:30.526Z,1720628850.526 [NAL9602](INFO): SBD MO Status=2, MOMSN=5084, MT Status=2, MTMSN=0 2024-07-10T16:27:30.526Z,1720628850.526 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:27:47.095Z,1720628867.095 [NAL9602](INFO): SBD MO Status=0, MOMSN=5084, MT Status=0, MTMSN=0 2024-07-10T16:27:47.095Z,1720628867.095 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:28:17.793Z,1720628897.793 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:29:53.562Z,1720628993.562 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:29:53.562Z,1720628993.562 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:29:53.562Z,1720628993.562 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:29:53.562Z,1720628993.562 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:29:53.959Z,1720628993.959 [Default:CheckIn:D] Stopped 2024-07-10T16:29:53.959Z,1720628993.959 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:29:54.363Z,1720628994.363 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.012777 min 2024-07-10T16:29:54.363Z,1720628994.363 [Default:CheckIn:E] Stopped 2024-07-10T16:29:54.363Z,1720628994.363 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:29:54.363Z,1720628994.363 [Default:CheckIn] Stopped 2024-07-10T16:29:54.363Z,1720628994.363 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:29:54.364Z,1720628994.364 [Default:CheckIn](INFO): Running loop #9 2024-07-10T16:29:54.364Z,1720628994.364 [Default:CheckIn] Running Loop=9 2024-07-10T16:29:54.364Z,1720628994.364 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:29:54.364Z,1720628994.364 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:29:56.375Z,1720628996.375 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162955.00,A,3648.17399,N,12147.27812,W,0.019,0.00,100724,,,D*7E 2024-07-10T16:29:56.377Z,1720628996.377 [NAL9602](INFO): GPS fix at 20240710T162955: (36.802900, -121.787969) 2024-07-10T16:29:56.397Z,1720628996.397 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:29:56.397Z,1720628996.397 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:30:00.830Z,1720629000.830 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0031.lzma 2024-07-10T16:30:01.838Z,1720629001.838 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0031.lzma.bak 2024-07-10T16:30:01.839Z,1720629001.839 [DataOverHttps](INFO): SBD MOMSN=21348555 2024-07-10T16:30:23.478Z,1720629023.478 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20240710T153524/Express0032.lzma 2024-07-10T16:30:24.478Z,1720629024.478 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0032.lzma.bak 2024-07-10T16:30:24.478Z,1720629024.478 [DataOverHttps](INFO): SBD MOMSN=21348558 2024-07-10T16:30:30.823Z,1720629030.823 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:30:30.823Z,1720629030.823 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:30:30.824Z,1720629030.824 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:32:25.959Z,1720629145.959 [NAL9602](INFO): SBD MO Status=2, MOMSN=5085, MT Status=2, MTMSN=0 2024-07-10T16:32:25.959Z,1720629145.959 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:34:33.219Z,1720629273.219 [NAL9602](INFO): SBD MO Status=2, MOMSN=5085, MT Status=2, MTMSN=0 2024-07-10T16:34:33.219Z,1720629273.219 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:34:50.999Z,1720629290.999 [NAL9602](INFO): SBD MO Status=0, MOMSN=5085, MT Status=0, MTMSN=0 2024-07-10T16:34:50.000Z,1720629291.000 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:35:21.697Z,1720629321.697 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:35:31.398Z,1720629331.398 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:35:31.398Z,1720629331.398 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:35:31.398Z,1720629331.398 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:35:31.398Z,1720629331.398 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:35:31.817Z,1720629331.817 [Default:CheckIn:D] Stopped 2024-07-10T16:35:31.817Z,1720629331.817 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:35:32.225Z,1720629332.225 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.643738 min 2024-07-10T16:35:32.225Z,1720629332.225 [Default:CheckIn:E] Stopped 2024-07-10T16:35:32.225Z,1720629332.225 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:35:32.225Z,1720629332.225 [Default:CheckIn] Stopped 2024-07-10T16:35:32.225Z,1720629332.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:35:32.225Z,1720629332.225 [Default:CheckIn](INFO): Running loop #10 2024-07-10T16:35:32.225Z,1720629332.225 [Default:CheckIn] Running Loop=10 2024-07-10T16:35:32.226Z,1720629332.226 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:35:32.226Z,1720629332.226 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:35:34.218Z,1720629334.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163533.00,A,3648.17494,N,12147.28113,W,0.097,0.00,100724,,,D*78 2024-07-10T16:35:34.220Z,1720629334.220 [NAL9602](INFO): GPS fix at 20240710T163533: (36.802916, -121.788019) 2024-07-10T16:35:34.231Z,1720629334.231 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:35:34.231Z,1720629334.231 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:35:44.404Z,1720629344.404 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0034.lzma 2024-07-10T16:35:45.406Z,1720629345.406 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0034.lzma.bak 2024-07-10T16:35:45.406Z,1720629345.406 [DataOverHttps](INFO): SBD MOMSN=21348583 2024-07-10T16:35:49.571Z,1720629349.571 [NAL9602](INFO): SBD MO Status=0, MOMSN=5086, MT Status=0, MTMSN=0 2024-07-10T16:35:49.571Z,1720629349.571 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:36:06.108Z,1720629366.108 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240710T153524/Express0035.lzma 2024-07-10T16:36:07.110Z,1720629367.110 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0035.lzma.bak 2024-07-10T16:36:07.111Z,1720629367.111 [DataOverHttps](INFO): SBD MOMSN=21348586 2024-07-10T16:36:13.027Z,1720629373.027 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:36:13.028Z,1720629373.028 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:36:13.028Z,1720629373.028 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:36:20.273Z,1720629380.273 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:41:13.445Z,1720629673.445 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:41:13.445Z,1720629673.445 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:41:13.445Z,1720629673.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:41:13.445Z,1720629673.445 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:41:13.864Z,1720629673.864 [Default:CheckIn:D] Stopped 2024-07-10T16:41:13.864Z,1720629673.864 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:41:14.273Z,1720629674.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.344523 min 2024-07-10T16:41:14.273Z,1720629674.273 [Default:CheckIn:E] Stopped 2024-07-10T16:41:14.273Z,1720629674.273 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:41:14.274Z,1720629674.274 [Default:CheckIn] Stopped 2024-07-10T16:41:14.274Z,1720629674.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:41:14.274Z,1720629674.274 [Default:CheckIn](INFO): Running loop #11 2024-07-10T16:41:14.274Z,1720629674.274 [Default:CheckIn] Running Loop=11 2024-07-10T16:41:14.274Z,1720629674.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:41:14.274Z,1720629674.274 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:41:16.266Z,1720629676.266 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164115.00,A,3648.17514,N,12147.28285,W,0.039,0.00,100724,,,D*7E 2024-07-10T16:41:16.268Z,1720629676.268 [NAL9602](INFO): GPS fix at 20240710T164115: (36.802919, -121.788048) 2024-07-10T16:41:16.304Z,1720629676.304 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:41:16.304Z,1720629676.304 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:41:26.391Z,1720629686.391 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0037.lzma 2024-07-10T16:41:27.393Z,1720629687.393 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0037.lzma.bak 2024-07-10T16:41:27.393Z,1720629687.393 [DataOverHttps](INFO): SBD MOMSN=21348603 2024-07-10T16:41:43.338Z,1720629703.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=5087, MT Status=2, MTMSN=0 2024-07-10T16:41:43.338Z,1720629703.338 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:41:49.463Z,1720629709.463 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0038.lzma 2024-07-10T16:41:50.465Z,1720629710.465 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0038.lzma.bak 2024-07-10T16:41:50.465Z,1720629710.465 [DataOverHttps](INFO): SBD MOMSN=21348606 2024-07-10T16:41:56.683Z,1720629716.683 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:41:56.683Z,1720629716.683 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:41:56.683Z,1720629716.683 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:42:00.302Z,1720629720.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=5087, MT Status=2, MTMSN=0 2024-07-10T16:42:00.302Z,1720629720.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:42:27.778Z,1720629747.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=5087, MT Status=2, MTMSN=0 2024-07-10T16:42:27.778Z,1720629747.778 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:43:04.538Z,1720629784.538 [NAL9602](INFO): SBD MO Status=2, MOMSN=5087, MT Status=2, MTMSN=0 2024-07-10T16:43:04.538Z,1720629784.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:43:41.709Z,1720629821.709 [NAL9602](INFO): SBD MO Status=0, MOMSN=5087, MT Status=0, MTMSN=0 2024-07-10T16:43:41.709Z,1720629821.709 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:44:12.412Z,1720629852.412 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:46:57.260Z,1720630017.260 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:46:57.260Z,1720630017.260 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:46:57.260Z,1720630017.260 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:46:57.260Z,1720630017.260 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:46:57.660Z,1720630017.660 [Default:CheckIn:D] Stopped 2024-07-10T16:46:57.660Z,1720630017.660 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:46:58.082Z,1720630018.082 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.074455 min 2024-07-10T16:46:58.082Z,1720630018.082 [Default:CheckIn:E] Stopped 2024-07-10T16:46:58.082Z,1720630018.082 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:46:58.082Z,1720630018.082 [Default:CheckIn] Stopped 2024-07-10T16:46:58.082Z,1720630018.082 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:46:58.083Z,1720630018.083 [Default:CheckIn](INFO): Running loop #12 2024-07-10T16:46:58.083Z,1720630018.083 [Default:CheckIn] Running Loop=12 2024-07-10T16:46:58.083Z,1720630018.083 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:46:58.083Z,1720630018.083 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:47:00.115Z,1720630020.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164659.00,A,3648.17333,N,12147.28016,W,0.019,0.00,100724,,,D*78 2024-07-10T16:47:00.125Z,1720630020.125 [NAL9602](INFO): GPS fix at 20240710T164659: (36.802889, -121.788003) 2024-07-10T16:47:00.152Z,1720630020.152 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:47:00.152Z,1720630020.152 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:47:04.327Z,1720630024.327 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0040.lzma 2024-07-10T16:47:05.333Z,1720630025.333 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0040.lzma.bak 2024-07-10T16:47:05.333Z,1720630025.333 [DataOverHttps](INFO): SBD MOMSN=21348625 2024-07-10T16:47:26.235Z,1720630046.235 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0041.lzma 2024-07-10T16:47:27.237Z,1720630047.237 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0041.lzma.bak 2024-07-10T16:47:27.237Z,1720630047.237 [DataOverHttps](INFO): SBD MOMSN=21348628 2024-07-10T16:47:33.733Z,1720630053.733 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:47:33.733Z,1720630053.733 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:47:33.733Z,1720630053.733 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:47:54.300Z,1720630074.300 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:48:00.750Z,1720630080.750 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:48:03.985Z,1720630083.985 [NAL9602](INFO): SBD MO Status=2, MOMSN=5088, MT Status=2, MTMSN=0 2024-07-10T16:48:03.985Z,1720630083.985 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:48:16.505Z,1720630096.505 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:48:25.805Z,1720630105.805 [NAL9602](INFO): SBD MO Status=0, MOMSN=5088, MT Status=0, MTMSN=0 2024-07-10T16:48:25.805Z,1720630105.805 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:48:32.263Z,1720630112.263 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:48:47.673Z,1720630127.673 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:48:56.504Z,1720630136.504 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:49:02.965Z,1720630142.965 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:49:18.317Z,1720630158.317 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:49:33.668Z,1720630173.668 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:49:48.622Z,1720630188.622 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:50:04.372Z,1720630204.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T16:50:21.761Z,1720630221.761 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2024-07-10T16:50:21.763Z,1720630221.763 [BPC1](INFO): Received data from all battery sticks. 2024-07-10T16:52:34.268Z,1720630354.268 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:52:34.269Z,1720630354.269 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:52:34.269Z,1720630354.269 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:52:34.269Z,1720630354.269 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:52:34.687Z,1720630354.687 [Default:CheckIn:D] Stopped 2024-07-10T16:52:34.687Z,1720630354.687 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:52:35.091Z,1720630355.091 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.691569 min 2024-07-10T16:52:35.091Z,1720630355.091 [Default:CheckIn:E] Stopped 2024-07-10T16:52:35.091Z,1720630355.091 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:52:35.091Z,1720630355.091 [Default:CheckIn] Stopped 2024-07-10T16:52:35.091Z,1720630355.091 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:52:35.091Z,1720630355.091 [Default:CheckIn](INFO): Running loop #13 2024-07-10T16:52:35.092Z,1720630355.092 [Default:CheckIn] Running Loop=13 2024-07-10T16:52:35.092Z,1720630355.092 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:52:35.092Z,1720630355.092 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:52:37.093Z,1720630357.093 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165236.00,A,3648.17378,N,12147.28144,W,0.078,0.00,100724,,,D*7A 2024-07-10T16:52:37.095Z,1720630357.095 [NAL9602](INFO): GPS fix at 20240710T165236: (36.802896, -121.788024) 2024-07-10T16:52:37.106Z,1720630357.106 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:52:37.106Z,1720630357.106 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:52:47.133Z,1720630367.133 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240710T153524/Courier0043.lzma 2024-07-10T16:52:48.136Z,1720630368.136 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0043.lzma.bak 2024-07-10T16:52:48.136Z,1720630368.136 [DataOverHttps](INFO): SBD MOMSN=21348645 2024-07-10T16:53:04.561Z,1720630384.561 [NAL9602](INFO): SBD MO Status=2, MOMSN=5089, MT Status=2, MTMSN=0 2024-07-10T16:53:04.561Z,1720630384.561 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T16:53:09.574Z,1720630389.574 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0044.lzma 2024-07-10T16:53:10.576Z,1720630390.576 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0044.lzma.bak 2024-07-10T16:53:10.576Z,1720630390.576 [DataOverHttps](INFO): SBD MOMSN=21348648 2024-07-10T16:53:17.106Z,1720630397.106 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:53:17.106Z,1720630397.106 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:53:17.110Z,1720630397.110 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:53:39.713Z,1720630419.713 [NAL9602](INFO): SBD MO Status=0, MOMSN=5089, MT Status=0, MTMSN=0 2024-07-10T16:53:39.713Z,1720630419.713 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:54:10.409Z,1720630450.409 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T16:58:17.615Z,1720630697.615 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T16:58:17.616Z,1720630697.616 [Default:CheckIn:C.Wait] Stopped 2024-07-10T16:58:17.616Z,1720630697.616 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T16:58:17.616Z,1720630697.616 [Default:CheckIn:D] Running Loop=1 2024-07-10T16:58:18.037Z,1720630698.037 [Default:CheckIn:D] Stopped 2024-07-10T16:58:18.037Z,1720630698.037 [Default:CheckIn:E] Running Loop=1 2024-07-10T16:58:18.448Z,1720630698.448 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.414071 min 2024-07-10T16:58:18.448Z,1720630698.448 [Default:CheckIn:E] Stopped 2024-07-10T16:58:18.448Z,1720630698.448 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T16:58:18.448Z,1720630698.448 [Default:CheckIn] Stopped 2024-07-10T16:58:18.448Z,1720630698.448 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T16:58:18.448Z,1720630698.448 [Default:CheckIn](INFO): Running loop #14 2024-07-10T16:58:18.449Z,1720630698.449 [Default:CheckIn] Running Loop=14 2024-07-10T16:58:18.449Z,1720630698.449 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T16:58:18.449Z,1720630698.449 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T16:58:20.437Z,1720630700.437 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165819.00,A,3648.17183,N,12147.27532,W,0.194,302.79,100724,,,D*7D 2024-07-10T16:58:20.439Z,1720630700.439 [NAL9602](INFO): GPS fix at 20240710T165819: (36.802864, -121.787922) 2024-07-10T16:58:20.449Z,1720630700.449 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T16:58:20.449Z,1720630700.449 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T16:58:30.557Z,1720630710.557 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0046.lzma 2024-07-10T16:58:31.560Z,1720630711.560 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0046.lzma.bak 2024-07-10T16:58:31.560Z,1720630711.560 [DataOverHttps](INFO): SBD MOMSN=21348664 2024-07-10T16:58:35.788Z,1720630715.788 [NAL9602](INFO): SBD MO Status=0, MOMSN=5090, MT Status=0, MTMSN=0 2024-07-10T16:58:35.788Z,1720630715.788 [NAL9602](INFO): No messages in MT queue 2024-07-10T16:58:52.530Z,1720630732.530 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0047.lzma 2024-07-10T16:58:53.528Z,1720630733.528 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0047.lzma.bak 2024-07-10T16:58:53.528Z,1720630733.528 [DataOverHttps](INFO): SBD MOMSN=21348667 2024-07-10T16:59:00.861Z,1720630740.861 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T16:59:00.866Z,1720630740.866 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T16:59:00.866Z,1720630740.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T16:59:06.490Z,1720630746.490 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:04:01.433Z,1720631041.433 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:04:01.433Z,1720631041.433 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:04:01.454Z,1720631041.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:04:01.454Z,1720631041.454 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:04:01.875Z,1720631041.875 [Default:CheckIn:D] Stopped 2024-07-10T17:04:01.875Z,1720631041.875 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:04:02.241Z,1720631042.241 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.144702 min 2024-07-10T17:04:02.241Z,1720631042.241 [Default:CheckIn:E] Stopped 2024-07-10T17:04:02.246Z,1720631042.246 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:04:02.246Z,1720631042.246 [Default:CheckIn] Stopped 2024-07-10T17:04:02.246Z,1720631042.246 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:04:02.246Z,1720631042.246 [Default:CheckIn](INFO): Running loop #15 2024-07-10T17:04:02.246Z,1720631042.246 [Default:CheckIn] Running Loop=15 2024-07-10T17:04:02.246Z,1720631042.246 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:04:02.246Z,1720631042.246 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:04:04.240Z,1720631044.240 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170403.00,A,3648.17424,N,12147.27815,W,0.058,310.45,100724,,,D*73 2024-07-10T17:04:04.252Z,1720631044.252 [NAL9602](INFO): GPS fix at 20240710T170403: (36.802904, -121.787969) 2024-07-10T17:04:04.263Z,1720631044.263 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:04:04.263Z,1720631044.263 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:04:14.449Z,1720631054.449 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0049.lzma 2024-07-10T17:04:15.452Z,1720631055.452 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0049.lzma.bak 2024-07-10T17:04:15.452Z,1720631055.452 [DataOverHttps](INFO): SBD MOMSN=21348685 2024-07-10T17:04:22.825Z,1720631062.825 [NAL9602](INFO): SBD MO Status=0, MOMSN=5091, MT Status=0, MTMSN=0 2024-07-10T17:04:22.825Z,1720631062.825 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:04:36.162Z,1720631076.162 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0050.lzma 2024-07-10T17:04:37.164Z,1720631077.164 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0050.lzma.bak 2024-07-10T17:04:37.164Z,1720631077.164 [DataOverHttps](INFO): SBD MOMSN=21348688 2024-07-10T17:04:45.064Z,1720631085.064 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:04:45.064Z,1720631085.064 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:04:45.064Z,1720631085.064 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:04:53.527Z,1720631093.527 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:09:45.586Z,1720631385.586 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:09:45.587Z,1720631385.587 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:09:45.591Z,1720631385.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:09:45.591Z,1720631385.591 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:09:46.007Z,1720631386.007 [Default:CheckIn:D] Stopped 2024-07-10T17:09:46.007Z,1720631386.007 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:09:46.381Z,1720631386.381 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.880241 min 2024-07-10T17:09:46.381Z,1720631386.381 [Default:CheckIn:E] Stopped 2024-07-10T17:09:46.381Z,1720631386.381 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:09:46.381Z,1720631386.381 [Default:CheckIn] Stopped 2024-07-10T17:09:46.381Z,1720631386.381 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:09:46.382Z,1720631386.382 [Default:CheckIn](INFO): Running loop #16 2024-07-10T17:09:46.382Z,1720631386.382 [Default:CheckIn] Running Loop=16 2024-07-10T17:09:46.382Z,1720631386.382 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:09:46.382Z,1720631386.382 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:09:48.390Z,1720631388.390 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170947.00,A,3648.17577,N,12147.27896,W,0.117,310.45,100724,,,D*78 2024-07-10T17:09:48.399Z,1720631388.399 [NAL9602](INFO): GPS fix at 20240710T170947: (36.802929, -121.787983) 2024-07-10T17:09:48.410Z,1720631388.410 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:09:48.410Z,1720631388.410 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:09:58.174Z,1720631398.174 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240710T153524/Courier0052.lzma 2024-07-10T17:09:59.177Z,1720631399.177 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0052.lzma.bak 2024-07-10T17:09:59.177Z,1720631399.177 [DataOverHttps](INFO): SBD MOMSN=21348705 2024-07-10T17:10:16.257Z,1720631416.257 [NAL9602](INFO): SBD MO Status=0, MOMSN=5092, MT Status=0, MTMSN=0 2024-07-10T17:10:16.258Z,1720631416.258 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:10:19.850Z,1720631419.850 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240710T153524/Express0053.lzma 2024-07-10T17:10:20.853Z,1720631420.853 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0053.lzma.bak 2024-07-10T17:10:20.853Z,1720631420.853 [DataOverHttps](INFO): SBD MOMSN=21348709 2024-07-10T17:10:27.186Z,1720631427.186 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:10:27.187Z,1720631427.187 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:10:27.191Z,1720631427.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:10:46.960Z,1720631446.960 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:10:49.919Z,1720631449.919 [CommandExec](IMPORTANT): got command failComponent 2024-07-10T17:10:49.920Z,1720631449.920 [CommandExec](IMPORTANT): Failed components: 2024-07-10T17:10:49.920Z,1720631449.920 [CommandExec](IMPORTANT): No failed Components. 2024-07-10T17:13:51.074Z,1720631631.074 [CommandExec](IMPORTANT): got command get platform_mass_position millimeter 2024-07-10T17:13:51.076Z,1720631631.076 [CommandExec](IMPORTANT): platform_mass_position 19.999706 mm 2024-07-10T17:14:07.718Z,1720631647.718 [CommandExec](IMPORTANT): got command get platform_buoyancy_position 2024-07-10T17:14:07.719Z,1720631647.719 [CommandExec](IMPORTANT): platform_buoyancy_position 741.980286 cc 2024-07-10T17:14:54.939Z,1720631694.939 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 950 cubic_centimeter 2024-07-10T17:14:57.109Z,1720631697.109 [Reporter](INFO): platform_buoyancy_position 751.918457 cc 2024-07-10T17:14:57.505Z,1720631697.505 [Reporter](INFO): platform_buoyancy_position 753.932983 cc 2024-07-10T17:14:58.321Z,1720631698.321 [Reporter](INFO): platform_buoyancy_position 754.067261 cc 2024-07-10T17:14:58.697Z,1720631698.697 [Reporter](INFO): platform_buoyancy_position 758.364868 cc 2024-07-10T17:14:59.129Z,1720631699.129 [Reporter](INFO): platform_buoyancy_position 764.811279 cc 2024-07-10T17:14:59.517Z,1720631699.517 [Reporter](INFO): platform_buoyancy_position 770.989075 cc 2024-07-10T17:14:59.913Z,1720631699.913 [Reporter](INFO): platform_buoyancy_position 775.555298 cc 2024-07-10T17:15:00.321Z,1720631700.321 [Reporter](INFO): platform_buoyancy_position 781.867371 cc 2024-07-10T17:15:00.741Z,1720631700.741 [Reporter](INFO): platform_buoyancy_position 786.433594 cc 2024-07-10T17:15:01.151Z,1720631701.151 [Reporter](INFO): platform_buoyancy_position 792.611389 cc 2024-07-10T17:15:01.525Z,1720631701.525 [Reporter](INFO): platform_buoyancy_position 796.908997 cc 2024-07-10T17:15:01.954Z,1720631701.954 [Reporter](INFO): platform_buoyancy_position 803.489685 cc 2024-07-10T17:15:02.354Z,1720631702.354 [Reporter](INFO): platform_buoyancy_position 807.652954 cc 2024-07-10T17:15:02.761Z,1720631702.761 [Reporter](INFO): platform_buoyancy_position 814.099365 cc 2024-07-10T17:15:03.149Z,1720631703.149 [Reporter](INFO): platform_buoyancy_position 818.665588 cc 2024-07-10T17:15:03.565Z,1720631703.565 [Reporter](INFO): platform_buoyancy_position 824.843384 cc 2024-07-10T17:15:03.961Z,1720631703.961 [Reporter](INFO): platform_buoyancy_position 829.275269 cc 2024-07-10T17:15:04.385Z,1720631704.385 [Reporter](INFO): platform_buoyancy_position 835.587402 cc 2024-07-10T17:15:04.762Z,1720631704.762 [Reporter](INFO): platform_buoyancy_position 840.019287 cc 2024-07-10T17:15:05.182Z,1720631705.182 [Reporter](INFO): platform_buoyancy_position 846.331421 cc 2024-07-10T17:15:05.597Z,1720631705.597 [Reporter](INFO): platform_buoyancy_position 850.763306 cc 2024-07-10T17:15:05.973Z,1720631705.973 [Reporter](INFO): platform_buoyancy_position 855.463745 cc 2024-07-10T17:15:06.398Z,1720631706.398 [Reporter](INFO): platform_buoyancy_position 861.372925 cc 2024-07-10T17:15:06.789Z,1720631706.789 [Reporter](INFO): platform_buoyancy_position 866.207764 cc 2024-07-10T17:15:07.185Z,1720631707.185 [Reporter](INFO): platform_buoyancy_position 872.116943 cc 2024-07-10T17:15:07.589Z,1720631707.589 [Reporter](INFO): platform_buoyancy_position 876.683228 cc 2024-07-10T17:15:08.021Z,1720631708.021 [Reporter](INFO): platform_buoyancy_position 882.860962 cc 2024-07-10T17:15:08.412Z,1720631708.412 [Reporter](INFO): platform_buoyancy_position 887.292847 cc 2024-07-10T17:15:08.817Z,1720631708.817 [Reporter](INFO): platform_buoyancy_position 893.604980 cc 2024-07-10T17:15:09.205Z,1720631709.205 [Reporter](INFO): platform_buoyancy_position 898.036865 cc 2024-07-10T17:15:09.629Z,1720631709.629 [Reporter](INFO): platform_buoyancy_position 904.348999 cc 2024-07-10T17:15:10.021Z,1720631710.021 [Reporter](INFO): platform_buoyancy_position 910.795410 cc 2024-07-10T17:15:10.457Z,1720631710.457 [Reporter](INFO): platform_buoyancy_position 915.093018 cc 2024-07-10T17:15:10.829Z,1720631710.829 [Reporter](INFO): platform_buoyancy_position 919.659180 cc 2024-07-10T17:15:11.246Z,1720631711.246 [Reporter](INFO): platform_buoyancy_position 925.971313 cc 2024-07-10T17:15:11.654Z,1720631711.654 [Reporter](INFO): platform_buoyancy_position 930.268921 cc 2024-07-10T17:15:12.041Z,1720631712.041 [Reporter](INFO): platform_buoyancy_position 936.043823 cc 2024-07-10T17:15:12.469Z,1720631712.469 [Reporter](INFO): platform_buoyancy_position 940.878540 cc 2024-07-10T17:15:27.754Z,1720631727.754 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:15:27.754Z,1720631727.754 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:15:27.754Z,1720631727.754 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:15:27.754Z,1720631727.754 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:15:28.172Z,1720631728.172 [Default:CheckIn:D] Stopped 2024-07-10T17:15:28.172Z,1720631728.172 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:15:28.579Z,1720631728.579 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.582983 min 2024-07-10T17:15:28.579Z,1720631728.579 [Default:CheckIn:E] Stopped 2024-07-10T17:15:28.579Z,1720631728.579 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:15:28.579Z,1720631728.579 [Default:CheckIn] Stopped 2024-07-10T17:15:28.579Z,1720631728.579 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:15:28.580Z,1720631728.580 [Default:CheckIn](INFO): Running loop #17 2024-07-10T17:15:28.580Z,1720631728.580 [Default:CheckIn] Running Loop=17 2024-07-10T17:15:28.580Z,1720631728.580 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:15:28.580Z,1720631728.580 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:15:30.574Z,1720631730.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171530.00,A,3648.17707,N,12147.27867,W,0.058,310.45,100724,,,D*74 2024-07-10T17:15:30.576Z,1720631730.576 [NAL9602](INFO): GPS fix at 20240710T171530: (36.802951, -121.787978) 2024-07-10T17:15:30.608Z,1720631730.608 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:15:30.608Z,1720631730.608 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:15:40.830Z,1720631740.830 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0055.lzma 2024-07-10T17:15:41.833Z,1720631741.833 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0055.lzma.bak 2024-07-10T17:15:41.833Z,1720631741.833 [DataOverHttps](INFO): SBD MOMSN=21348731 2024-07-10T17:15:47.645Z,1720631747.645 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 600 cubic_centimeter 2024-07-10T17:15:50.069Z,1720631750.069 [Reporter](INFO): platform_buoyancy_position 955.920166 cc 2024-07-10T17:15:50.485Z,1720631750.485 [Reporter](INFO): platform_buoyancy_position 955.114380 cc 2024-07-10T17:15:50.857Z,1720631750.857 [Reporter](INFO): platform_buoyancy_position 953.771362 cc 2024-07-10T17:15:51.261Z,1720631751.261 [Reporter](INFO): platform_buoyancy_position 943.161743 cc 2024-07-10T17:15:51.634Z,1720631751.634 [Reporter](INFO): platform_buoyancy_position 943.027344 cc 2024-07-10T17:15:52.054Z,1720631752.054 [Reporter](INFO): platform_buoyancy_position 938.864136 cc 2024-07-10T17:15:52.445Z,1720631752.445 [Reporter](INFO): platform_buoyancy_position 934.566528 cc 2024-07-10T17:15:52.797Z,1720631752.797 [NAL9602](INFO): SBD MO Status=0, MOMSN=5093, MT Status=0, MTMSN=0 2024-07-10T17:15:52.797Z,1720631752.797 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:15:52.873Z,1720631752.873 [Reporter](INFO): platform_buoyancy_position 930.268921 cc 2024-07-10T17:15:53.245Z,1720631753.245 [Reporter](INFO): platform_buoyancy_position 925.971313 cc 2024-07-10T17:15:53.673Z,1720631753.673 [Reporter](INFO): platform_buoyancy_position 919.793457 cc 2024-07-10T17:15:54.086Z,1720631754.086 [Reporter](INFO): platform_buoyancy_position 915.227295 cc 2024-07-10T17:15:54.489Z,1720631754.489 [Reporter](INFO): platform_buoyancy_position 910.526733 cc 2024-07-10T17:15:54.885Z,1720631754.885 [Reporter](INFO): platform_buoyancy_position 904.483276 cc 2024-07-10T17:15:55.297Z,1720631755.297 [Reporter](INFO): platform_buoyancy_position 898.842651 cc 2024-07-10T17:15:55.709Z,1720631755.709 [Reporter](INFO): platform_buoyancy_position 893.739258 cc 2024-07-10T17:15:56.125Z,1720631756.125 [Reporter](INFO): platform_buoyancy_position 889.173096 cc 2024-07-10T17:15:56.504Z,1720631756.504 [Reporter](INFO): platform_buoyancy_position 882.995239 cc 2024-07-10T17:15:56.916Z,1720631756.916 [Reporter](INFO): platform_buoyancy_position 878.697632 cc 2024-07-10T17:15:57.313Z,1720631757.313 [Reporter](INFO): platform_buoyancy_position 873.325684 cc 2024-07-10T17:15:57.689Z,1720631757.689 [Reporter](INFO): platform_buoyancy_position 867.819336 cc 2024-07-10T17:15:58.113Z,1720631758.113 [Reporter](INFO): platform_buoyancy_position 863.656128 cc 2024-07-10T17:15:58.505Z,1720631758.505 [Reporter](INFO): platform_buoyancy_position 857.075317 cc 2024-07-10T17:15:58.921Z,1720631758.921 [Reporter](INFO): platform_buoyancy_position 852.777832 cc 2024-07-10T17:15:59.305Z,1720631759.305 [Reporter](INFO): platform_buoyancy_position 846.331421 cc 2024-07-10T17:15:59.725Z,1720631759.725 [Reporter](INFO): platform_buoyancy_position 842.033813 cc 2024-07-10T17:16:00.125Z,1720631760.125 [Reporter](INFO): platform_buoyancy_position 835.587402 cc 2024-07-10T17:16:00.513Z,1720631760.513 [Reporter](INFO): platform_buoyancy_position 831.289795 cc 2024-07-10T17:16:00.930Z,1720631760.930 [Reporter](INFO): platform_buoyancy_position 824.843384 cc 2024-07-10T17:16:01.345Z,1720631761.345 [Reporter](INFO): platform_buoyancy_position 820.545776 cc 2024-07-10T17:16:01.741Z,1720631761.741 [Reporter](INFO): platform_buoyancy_position 814.367981 cc 2024-07-10T17:16:02.171Z,1720631762.171 [Reporter](INFO): platform_buoyancy_position 809.667480 cc 2024-07-10T17:16:02.558Z,1720631762.558 [Reporter](INFO): platform_buoyancy_position 803.489685 cc 2024-07-10T17:16:02.974Z,1720631762.974 [Reporter](INFO): platform_buoyancy_position 799.057800 cc 2024-07-10T17:16:03.357Z,1720631763.357 [Reporter](INFO): platform_buoyancy_position 792.745667 cc 2024-07-10T17:16:03.786Z,1720631763.786 [Reporter](INFO): platform_buoyancy_position 788.313782 cc 2024-07-10T17:16:04.177Z,1720631764.177 [Reporter](INFO): platform_buoyancy_position 782.001648 cc 2024-07-10T17:16:04.593Z,1720631764.593 [Reporter](INFO): platform_buoyancy_position 777.569763 cc 2024-07-10T17:16:04.997Z,1720631764.997 [Reporter](INFO): platform_buoyancy_position 772.063477 cc 2024-07-10T17:16:05.401Z,1720631765.401 [Reporter](INFO): platform_buoyancy_position 766.960083 cc 2024-07-10T17:16:05.778Z,1720631765.778 [Reporter](INFO): platform_buoyancy_position 762.796753 cc 2024-07-10T17:16:06.190Z,1720631766.190 [Reporter](INFO): platform_buoyancy_position 756.081787 cc 2024-07-10T17:16:06.589Z,1720631766.589 [Reporter](INFO): platform_buoyancy_position 751.784180 cc 2024-07-10T17:16:06.599Z,1720631766.599 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20240710T153524/Express0056.lzma 2024-07-10T17:16:06.989Z,1720631766.989 [Reporter](INFO): platform_buoyancy_position 745.472046 cc 2024-07-10T17:16:07.389Z,1720631767.389 [Reporter](INFO): platform_buoyancy_position 741.040161 cc 2024-07-10T17:16:07.601Z,1720631767.601 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0056.lzma.bak 2024-07-10T17:16:07.601Z,1720631767.601 [DataOverHttps](INFO): SBD MOMSN=21348741 2024-07-10T17:16:07.805Z,1720631767.805 [Reporter](INFO): platform_buoyancy_position 736.339661 cc 2024-07-10T17:16:08.221Z,1720631768.221 [Reporter](INFO): platform_buoyancy_position 730.296143 cc 2024-07-10T17:16:08.594Z,1720631768.594 [Reporter](INFO): platform_buoyancy_position 723.984070 cc 2024-07-10T17:16:09.030Z,1720631769.030 [Reporter](INFO): platform_buoyancy_position 719.552185 cc 2024-07-10T17:16:09.436Z,1720631769.436 [Reporter](INFO): platform_buoyancy_position 713.240051 cc 2024-07-10T17:16:09.847Z,1720631769.847 [Reporter](INFO): platform_buoyancy_position 708.808167 cc 2024-07-10T17:16:10.233Z,1720631770.233 [Reporter](INFO): platform_buoyancy_position 702.496094 cc 2024-07-10T17:16:10.649Z,1720631770.649 [Reporter](INFO): platform_buoyancy_position 698.198486 cc 2024-07-10T17:16:11.059Z,1720631771.059 [Reporter](INFO): platform_buoyancy_position 691.752075 cc 2024-07-10T17:16:11.458Z,1720631771.458 [Reporter](INFO): platform_buoyancy_position 687.454468 cc 2024-07-10T17:16:11.857Z,1720631771.857 [Reporter](INFO): platform_buoyancy_position 681.813843 cc 2024-07-10T17:16:12.262Z,1720631772.262 [Reporter](INFO): platform_buoyancy_position 676.576172 cc 2024-07-10T17:16:12.676Z,1720631772.676 [Reporter](INFO): platform_buoyancy_position 670.398376 cc 2024-07-10T17:16:13.045Z,1720631773.045 [Reporter](INFO): platform_buoyancy_position 665.832153 cc 2024-07-10T17:16:13.473Z,1720631773.473 [Reporter](INFO): platform_buoyancy_position 661.534546 cc 2024-07-10T17:16:13.881Z,1720631773.881 [Reporter](INFO): platform_buoyancy_position 655.222473 cc 2024-07-10T17:16:14.218Z,1720631774.218 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:16:14.218Z,1720631774.218 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:16:14.219Z,1720631774.219 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:16:14.274Z,1720631774.274 [Reporter](INFO): platform_buoyancy_position 650.790588 cc 2024-07-10T17:16:14.677Z,1720631774.677 [Reporter](INFO): platform_buoyancy_position 644.478455 cc 2024-07-10T17:16:15.090Z,1720631775.090 [Reporter](INFO): platform_buoyancy_position 640.046570 cc 2024-07-10T17:16:15.537Z,1720631775.537 [Reporter](INFO): platform_buoyancy_position 633.734436 cc 2024-07-10T17:16:15.919Z,1720631775.919 [Reporter](INFO): platform_buoyancy_position 629.436890 cc 2024-07-10T17:16:16.330Z,1720631776.330 [Reporter](INFO): platform_buoyancy_position 623.124756 cc 2024-07-10T17:16:16.705Z,1720631776.705 [Reporter](INFO): platform_buoyancy_position 618.692871 cc 2024-07-10T17:16:17.129Z,1720631777.129 [Reporter](INFO): platform_buoyancy_position 613.589478 cc 2024-07-10T17:16:17.549Z,1720631777.549 [Reporter](INFO): platform_buoyancy_position 608.083130 cc 2024-07-10T17:16:23.497Z,1720631783.497 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:16:54.840Z,1720631814.840 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 500 cubic_centimeter 2024-07-10T17:16:56.701Z,1720631816.701 [Reporter](INFO): platform_buoyancy_position 607.545959 cc 2024-07-10T17:16:57.143Z,1720631817.143 [Reporter](INFO): platform_buoyancy_position 605.934387 cc 2024-07-10T17:16:57.530Z,1720631817.530 [Reporter](INFO): platform_buoyancy_position 605.665771 cc 2024-07-10T17:16:57.991Z,1720631817.991 [Reporter](INFO): platform_buoyancy_position 597.070557 cc 2024-07-10T17:16:58.310Z,1720631818.310 [Reporter](INFO): platform_buoyancy_position 595.056030 cc 2024-07-10T17:16:58.698Z,1720631818.698 [Reporter](INFO): platform_buoyancy_position 590.758484 cc 2024-07-10T17:16:59.101Z,1720631819.101 [Reporter](INFO): platform_buoyancy_position 586.460876 cc 2024-07-10T17:16:59.501Z,1720631819.501 [Reporter](INFO): platform_buoyancy_position 582.163269 cc 2024-07-10T17:16:59.925Z,1720631819.925 [Reporter](INFO): platform_buoyancy_position 577.597046 cc 2024-07-10T17:17:00.330Z,1720631820.330 [Reporter](INFO): platform_buoyancy_position 571.553528 cc 2024-07-10T17:17:00.714Z,1720631820.714 [Reporter](INFO): platform_buoyancy_position 567.121643 cc 2024-07-10T17:17:01.136Z,1720631821.136 [Reporter](INFO): platform_buoyancy_position 562.555481 cc 2024-07-10T17:17:01.529Z,1720631821.529 [Reporter](INFO): platform_buoyancy_position 556.243347 cc 2024-07-10T17:17:01.945Z,1720631821.945 [Reporter](INFO): platform_buoyancy_position 551.005676 cc 2024-07-10T17:17:02.332Z,1720631822.332 [Reporter](INFO): platform_buoyancy_position 545.767944 cc 2024-07-10T17:17:02.761Z,1720631822.761 [Reporter](INFO): platform_buoyancy_position 540.933167 cc 2024-07-10T17:17:03.149Z,1720631823.149 [Reporter](INFO): platform_buoyancy_position 534.889648 cc 2024-07-10T17:17:03.537Z,1720631823.537 [Reporter](INFO): platform_buoyancy_position 530.457764 cc 2024-07-10T17:17:03.954Z,1720631823.954 [Reporter](INFO): platform_buoyancy_position 524.279968 cc 2024-07-10T17:17:04.366Z,1720631824.366 [Reporter](INFO): platform_buoyancy_position 519.713745 cc 2024-07-10T17:17:04.771Z,1720631824.771 [Reporter](INFO): platform_buoyancy_position 513.401672 cc 2024-07-10T17:17:05.187Z,1720631825.187 [Reporter](INFO): platform_buoyancy_position 509.238342 cc 2024-07-10T17:19:03.772Z,1720631943.772 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 400 cubic_centimeter 2024-07-10T17:19:05.989Z,1720631945.989 [Reporter](INFO): platform_buoyancy_position 504.672180 cc 2024-07-10T17:19:06.390Z,1720631946.390 [Reporter](INFO): platform_buoyancy_position 504.806458 cc 2024-07-10T17:19:06.765Z,1720631946.765 [Reporter](INFO): platform_buoyancy_position 502.657684 cc 2024-07-10T17:19:07.188Z,1720631947.188 [Reporter](INFO): platform_buoyancy_position 496.076935 cc 2024-07-10T17:19:07.577Z,1720631947.577 [Reporter](INFO): platform_buoyancy_position 491.779358 cc 2024-07-10T17:19:07.973Z,1720631947.973 [Reporter](INFO): platform_buoyancy_position 489.764862 cc 2024-07-10T17:19:08.381Z,1720631948.381 [Reporter](INFO): platform_buoyancy_position 485.332947 cc 2024-07-10T17:19:08.794Z,1720631948.794 [Reporter](INFO): platform_buoyancy_position 479.155151 cc 2024-07-10T17:19:09.210Z,1720631949.210 [Reporter](INFO): platform_buoyancy_position 474.588928 cc 2024-07-10T17:19:09.597Z,1720631949.597 [Reporter](INFO): platform_buoyancy_position 470.157043 cc 2024-07-10T17:19:10.002Z,1720631950.002 [Reporter](INFO): platform_buoyancy_position 463.979248 cc 2024-07-10T17:19:10.414Z,1720631950.414 [Reporter](INFO): platform_buoyancy_position 459.547363 cc 2024-07-10T17:19:10.829Z,1720631950.829 [Reporter](INFO): platform_buoyancy_position 453.235229 cc 2024-07-10T17:19:11.231Z,1720631951.231 [Reporter](INFO): platform_buoyancy_position 448.803345 cc 2024-07-10T17:19:11.610Z,1720631951.610 [Reporter](INFO): platform_buoyancy_position 442.625549 cc 2024-07-10T17:19:12.029Z,1720631952.029 [Reporter](INFO): platform_buoyancy_position 438.059326 cc 2024-07-10T17:19:12.425Z,1720631952.425 [Reporter](INFO): platform_buoyancy_position 431.747253 cc 2024-07-10T17:19:12.841Z,1720631952.841 [Reporter](INFO): platform_buoyancy_position 427.315369 cc 2024-07-10T17:19:13.225Z,1720631953.225 [Reporter](INFO): platform_buoyancy_position 421.003235 cc 2024-07-10T17:19:13.649Z,1720631953.649 [Reporter](INFO): platform_buoyancy_position 416.571350 cc 2024-07-10T17:19:14.073Z,1720631954.073 [Reporter](INFO): platform_buoyancy_position 410.259216 cc 2024-07-10T17:19:14.513Z,1720631954.513 [Reporter](INFO): platform_buoyancy_position 405.961670 cc 2024-07-10T17:20:02.669Z,1720632002.669 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 300 cubic_centimeter 2024-07-10T17:20:04.562Z,1720632004.562 [Reporter](INFO): platform_buoyancy_position 403.678528 cc 2024-07-10T17:20:04.949Z,1720632004.949 [Reporter](INFO): platform_buoyancy_position 403.947144 cc 2024-07-10T17:20:05.423Z,1720632005.423 [Reporter](INFO): platform_buoyancy_position 401.664062 cc 2024-07-10T17:20:05.778Z,1720632005.778 [Reporter](INFO): platform_buoyancy_position 395.217651 cc 2024-07-10T17:20:06.189Z,1720632006.189 [Reporter](INFO): platform_buoyancy_position 391.725830 cc 2024-07-10T17:20:06.582Z,1720632006.582 [Reporter](INFO): platform_buoyancy_position 388.636963 cc 2024-07-10T17:20:06.993Z,1720632006.993 [Reporter](INFO): platform_buoyancy_position 382.459167 cc 2024-07-10T17:20:07.400Z,1720632007.400 [Reporter](INFO): platform_buoyancy_position 377.892944 cc 2024-07-10T17:20:07.810Z,1720632007.810 [Reporter](INFO): platform_buoyancy_position 373.461060 cc 2024-07-10T17:20:08.189Z,1720632008.189 [Reporter](INFO): platform_buoyancy_position 367.283264 cc 2024-07-10T17:20:08.605Z,1720632008.605 [Reporter](INFO): platform_buoyancy_position 362.717041 cc 2024-07-10T17:20:08.981Z,1720632008.981 [Reporter](INFO): platform_buoyancy_position 358.016541 cc 2024-07-10T17:20:09.385Z,1720632009.385 [Reporter](INFO): platform_buoyancy_position 352.107361 cc 2024-07-10T17:20:09.797Z,1720632009.797 [Reporter](INFO): platform_buoyancy_position 347.809753 cc 2024-07-10T17:20:10.213Z,1720632010.213 [Reporter](INFO): platform_buoyancy_position 341.631958 cc 2024-07-10T17:20:10.609Z,1720632010.609 [Reporter](INFO): platform_buoyancy_position 337.065735 cc 2024-07-10T17:20:10.998Z,1720632010.998 [Reporter](INFO): platform_buoyancy_position 330.619324 cc 2024-07-10T17:20:11.413Z,1720632011.413 [Reporter](INFO): platform_buoyancy_position 326.321716 cc 2024-07-10T17:20:11.813Z,1720632011.813 [Reporter](INFO): platform_buoyancy_position 320.009644 cc 2024-07-10T17:20:12.229Z,1720632012.229 [Reporter](INFO): platform_buoyancy_position 315.577759 cc 2024-07-10T17:20:12.637Z,1720632012.637 [Reporter](INFO): platform_buoyancy_position 309.265625 cc 2024-07-10T17:21:14.805Z,1720632074.805 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:21:14.805Z,1720632074.805 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:21:14.805Z,1720632074.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:21:14.805Z,1720632074.805 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:21:15.198Z,1720632075.198 [Default:CheckIn:D] Stopped 2024-07-10T17:21:15.198Z,1720632075.198 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:21:15.598Z,1720632075.598 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.366748 min 2024-07-10T17:21:15.598Z,1720632075.598 [Default:CheckIn:E] Stopped 2024-07-10T17:21:15.599Z,1720632075.599 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:21:15.599Z,1720632075.599 [Default:CheckIn] Stopped 2024-07-10T17:21:15.599Z,1720632075.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:21:15.599Z,1720632075.599 [Default:CheckIn](INFO): Running loop #18 2024-07-10T17:21:15.599Z,1720632075.599 [Default:CheckIn] Running Loop=18 2024-07-10T17:21:15.599Z,1720632075.599 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:21:15.599Z,1720632075.599 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:21:17.610Z,1720632077.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172117.00,A,3648.17606,N,12147.27807,W,0.097,310.45,100724,,,D*73 2024-07-10T17:21:17.612Z,1720632077.612 [NAL9602](INFO): GPS fix at 20240710T172117: (36.802934, -121.787968) 2024-07-10T17:21:17.667Z,1720632077.667 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:21:17.667Z,1720632077.667 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:21:22.869Z,1720632082.869 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0058.lzma 2024-07-10T17:21:23.877Z,1720632083.877 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0058.lzma.bak 2024-07-10T17:21:23.877Z,1720632083.877 [DataOverHttps](INFO): SBD MOMSN=21348759 2024-07-10T17:21:31.041Z,1720632091.041 [NAL9602](INFO): SBD MO Status=0, MOMSN=5094, MT Status=0, MTMSN=0 2024-07-10T17:21:31.041Z,1720632091.041 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:21:45.555Z,1720632105.555 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20240710T153524/Express0059.lzma 2024-07-10T17:21:46.557Z,1720632106.557 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0059.lzma.bak 2024-07-10T17:21:46.557Z,1720632106.557 [DataOverHttps](INFO): SBD MOMSN=21348762 2024-07-10T17:21:52.871Z,1720632112.871 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:21:52.875Z,1720632112.875 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:21:52.875Z,1720632112.875 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:22:01.732Z,1720632121.732 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:24:54.478Z,1720632294.478 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 250 cubic_centimeter 2024-07-10T17:24:56.337Z,1720632296.337 [Reporter](INFO): platform_buoyancy_position 304.833740 cc 2024-07-10T17:24:57.137Z,1720632297.137 [Reporter](INFO): platform_buoyancy_position 302.684937 cc 2024-07-10T17:24:57.553Z,1720632297.553 [Reporter](INFO): platform_buoyancy_position 296.372833 cc 2024-07-10T17:24:57.933Z,1720632297.933 [Reporter](INFO): platform_buoyancy_position 293.955444 cc 2024-07-10T17:24:58.333Z,1720632298.333 [Reporter](INFO): platform_buoyancy_position 289.657837 cc 2024-07-10T17:24:58.768Z,1720632298.768 [Reporter](INFO): platform_buoyancy_position 283.480042 cc 2024-07-10T17:24:59.154Z,1720632299.154 [Reporter](INFO): platform_buoyancy_position 279.048126 cc 2024-07-10T17:24:59.546Z,1720632299.546 [Reporter](INFO): platform_buoyancy_position 274.750549 cc 2024-07-10T17:24:59.947Z,1720632299.947 [Reporter](INFO): platform_buoyancy_position 268.438446 cc 2024-07-10T17:25:00.353Z,1720632300.353 [Reporter](INFO): platform_buoyancy_position 263.872223 cc 2024-07-10T17:25:00.805Z,1720632300.805 [Reporter](INFO): platform_buoyancy_position 257.828735 cc 2024-07-10T17:26:53.462Z,1720632413.462 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:26:53.462Z,1720632413.462 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:26:53.462Z,1720632413.462 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:26:53.462Z,1720632413.462 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:26:53.866Z,1720632413.866 [Default:CheckIn:D] Stopped 2024-07-10T17:26:53.866Z,1720632413.866 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:26:54.258Z,1720632414.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.011222 min 2024-07-10T17:26:54.258Z,1720632414.258 [Default:CheckIn:E] Stopped 2024-07-10T17:26:54.258Z,1720632414.258 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:26:54.258Z,1720632414.258 [Default:CheckIn] Stopped 2024-07-10T17:26:54.258Z,1720632414.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:26:54.258Z,1720632414.258 [Default:CheckIn](INFO): Running loop #19 2024-07-10T17:26:54.258Z,1720632414.258 [Default:CheckIn] Running Loop=19 2024-07-10T17:26:54.259Z,1720632414.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:26:54.259Z,1720632414.259 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:26:56.274Z,1720632416.274 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172656.00,A,3648.17621,N,12147.27841,W,0.019,1.34,100724,,,D*75 2024-07-10T17:26:56.276Z,1720632416.276 [NAL9602](INFO): GPS fix at 20240710T172656: (36.802937, -121.787974) 2024-07-10T17:26:56.287Z,1720632416.287 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:26:56.287Z,1720632416.287 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:27:05.942Z,1720632425.942 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0061.lzma 2024-07-10T17:27:06.945Z,1720632426.945 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0061.lzma.bak 2024-07-10T17:27:06.945Z,1720632426.945 [DataOverHttps](INFO): SBD MOMSN=21348791 2024-07-10T17:27:22.528Z,1720632442.528 [NAL9602](INFO): SBD MO Status=0, MOMSN=5095, MT Status=0, MTMSN=0 2024-07-10T17:27:22.528Z,1720632442.528 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:27:31.359Z,1720632451.359 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20240710T153524/Express0062.lzma 2024-07-10T17:27:32.361Z,1720632452.361 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0062.lzma.bak 2024-07-10T17:27:32.362Z,1720632452.362 [DataOverHttps](INFO): SBD MOMSN=21348795 2024-07-10T17:27:38.315Z,1720632458.315 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:27:38.315Z,1720632458.315 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:27:38.319Z,1720632458.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:27:53.232Z,1720632473.232 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:30:07.865Z,1720632607.865 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 300 cubic_centimeter 2024-07-10T17:30:10.191Z,1720632610.191 [Reporter](INFO): platform_buoyancy_position 253.262558 cc 2024-07-10T17:30:10.555Z,1720632610.555 [Reporter](INFO): platform_buoyancy_position 253.396805 cc 2024-07-10T17:30:10.979Z,1720632610.979 [Reporter](INFO): platform_buoyancy_position 255.411346 cc 2024-07-10T17:30:11.387Z,1720632611.387 [Reporter](INFO): platform_buoyancy_position 257.560120 cc 2024-07-10T17:30:11.771Z,1720632611.771 [Reporter](INFO): platform_buoyancy_position 261.857727 cc 2024-07-10T17:30:12.179Z,1720632612.179 [Reporter](INFO): platform_buoyancy_position 268.304138 cc 2024-07-10T17:30:12.602Z,1720632612.602 [Reporter](INFO): platform_buoyancy_position 272.601746 cc 2024-07-10T17:30:12.992Z,1720632612.992 [Reporter](INFO): platform_buoyancy_position 277.033630 cc 2024-07-10T17:30:13.391Z,1720632613.391 [Reporter](INFO): platform_buoyancy_position 283.345734 cc 2024-07-10T17:30:13.819Z,1720632613.819 [Reporter](INFO): platform_buoyancy_position 287.643341 cc 2024-07-10T17:30:14.235Z,1720632614.235 [Reporter](INFO): platform_buoyancy_position 291.940948 cc 2024-07-10T17:31:27.953Z,1720632687.953 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 400 cubic_centimeter 2024-07-10T17:31:29.835Z,1720632689.835 [Reporter](INFO): platform_buoyancy_position 300.536133 cc 2024-07-10T17:31:30.610Z,1720632690.610 [Reporter](INFO): platform_buoyancy_position 302.684937 cc 2024-07-10T17:31:30.979Z,1720632690.979 [Reporter](INFO): platform_buoyancy_position 302.819244 cc 2024-07-10T17:31:31.387Z,1720632691.387 [Reporter](INFO): platform_buoyancy_position 309.265625 cc 2024-07-10T17:31:31.823Z,1720632691.823 [Reporter](INFO): platform_buoyancy_position 313.428955 cc 2024-07-10T17:31:32.212Z,1720632692.212 [Reporter](INFO): platform_buoyancy_position 319.875366 cc 2024-07-10T17:31:32.595Z,1720632692.595 [Reporter](INFO): platform_buoyancy_position 324.172913 cc 2024-07-10T17:31:33.020Z,1720632693.020 [Reporter](INFO): platform_buoyancy_position 328.739136 cc 2024-07-10T17:31:33.411Z,1720632693.411 [Reporter](INFO): platform_buoyancy_position 334.916931 cc 2024-07-10T17:31:33.832Z,1720632693.832 [Reporter](INFO): platform_buoyancy_position 341.363342 cc 2024-07-10T17:31:34.222Z,1720632694.222 [Reporter](INFO): platform_buoyancy_position 345.660950 cc 2024-07-10T17:31:34.634Z,1720632694.634 [Reporter](INFO): platform_buoyancy_position 350.092834 cc 2024-07-10T17:31:35.027Z,1720632695.027 [Reporter](INFO): platform_buoyancy_position 356.136353 cc 2024-07-10T17:31:35.447Z,1720632695.447 [Reporter](INFO): platform_buoyancy_position 360.971130 cc 2024-07-10T17:31:35.834Z,1720632695.834 [Reporter](INFO): platform_buoyancy_position 367.014648 cc 2024-07-10T17:31:36.254Z,1720632696.254 [Reporter](INFO): platform_buoyancy_position 371.715149 cc 2024-07-10T17:31:36.659Z,1720632696.659 [Reporter](INFO): platform_buoyancy_position 378.027222 cc 2024-07-10T17:31:37.035Z,1720632697.035 [Reporter](INFO): platform_buoyancy_position 382.324829 cc 2024-07-10T17:31:37.443Z,1720632697.443 [Reporter](INFO): platform_buoyancy_position 388.502625 cc 2024-07-10T17:31:37.871Z,1720632697.871 [Reporter](INFO): platform_buoyancy_position 392.934570 cc 2024-07-10T17:32:38.823Z,1720632758.823 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:32:38.823Z,1720632758.823 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:32:38.824Z,1720632758.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:32:38.824Z,1720632758.824 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:32:39.232Z,1720632759.232 [Default:CheckIn:D] Stopped 2024-07-10T17:32:39.236Z,1720632759.236 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:32:39.666Z,1720632759.666 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.767318 min 2024-07-10T17:32:39.666Z,1720632759.666 [Default:CheckIn:E] Stopped 2024-07-10T17:32:39.667Z,1720632759.667 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:32:39.667Z,1720632759.667 [Default:CheckIn] Stopped 2024-07-10T17:32:39.667Z,1720632759.667 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:32:39.667Z,1720632759.667 [Default:CheckIn](INFO): Running loop #20 2024-07-10T17:32:39.667Z,1720632759.667 [Default:CheckIn] Running Loop=20 2024-07-10T17:32:39.667Z,1720632759.667 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:32:39.667Z,1720632759.667 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:32:41.650Z,1720632761.650 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173241.00,A,3648.17516,N,12147.27924,W,0.097,1.34,100724,,,D*75 2024-07-10T17:32:41.665Z,1720632761.665 [NAL9602](INFO): GPS fix at 20240710T173241: (36.802919, -121.787987) 2024-07-10T17:32:41.675Z,1720632761.675 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:32:41.675Z,1720632761.675 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:32:51.280Z,1720632771.280 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0064.lzma 2024-07-10T17:32:52.334Z,1720632772.334 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0064.lzma.bak 2024-07-10T17:32:52.334Z,1720632772.334 [DataOverHttps](INFO): SBD MOMSN=21348811 2024-07-10T17:33:15.972Z,1720632795.972 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240710T153524/Express0065.lzma 2024-07-10T17:33:16.974Z,1720632796.974 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0065.lzma.bak 2024-07-10T17:33:16.975Z,1720632796.975 [DataOverHttps](INFO): SBD MOMSN=21348814 2024-07-10T17:33:23.263Z,1720632803.263 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:33:23.263Z,1720632803.263 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:33:23.263Z,1720632803.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:34:12.542Z,1720632852.542 [NAL9602](INFO): SBD MO Status=0, MOMSN=5096, MT Status=0, MTMSN=0 2024-07-10T17:34:12.542Z,1720632852.542 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:34:43.249Z,1720632883.249 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:35:03.162Z,1720632903.162 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 350 cubic_centimeter 2024-07-10T17:35:05.158Z,1720632905.158 [Reporter](INFO): platform_buoyancy_position 399.515259 cc 2024-07-10T17:35:05.934Z,1720632905.934 [Reporter](INFO): platform_buoyancy_position 399.380920 cc 2024-07-10T17:35:06.326Z,1720632906.326 [Reporter](INFO): platform_buoyancy_position 392.934570 cc 2024-07-10T17:35:06.771Z,1720632906.771 [Reporter](INFO): platform_buoyancy_position 388.636963 cc 2024-07-10T17:35:07.139Z,1720632907.139 [Reporter](INFO): platform_buoyancy_position 384.473633 cc 2024-07-10T17:35:07.563Z,1720632907.563 [Reporter](INFO): platform_buoyancy_position 380.041748 cc 2024-07-10T17:35:07.956Z,1720632907.956 [Reporter](INFO): platform_buoyancy_position 373.595337 cc 2024-07-10T17:35:08.349Z,1720632908.349 [Reporter](INFO): platform_buoyancy_position 369.432068 cc 2024-07-10T17:35:08.764Z,1720632908.764 [Reporter](INFO): platform_buoyancy_position 365.000122 cc 2024-07-10T17:35:09.183Z,1720632909.183 [Reporter](INFO): platform_buoyancy_position 358.553711 cc 2024-07-10T17:38:23.759Z,1720633103.759 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:38:23.759Z,1720633103.759 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:38:23.759Z,1720633103.759 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:38:23.759Z,1720633103.759 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:38:24.163Z,1720633104.163 [Default:CheckIn:D] Stopped 2024-07-10T17:38:24.163Z,1720633104.163 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:38:24.586Z,1720633104.586 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.516178 min 2024-07-10T17:38:24.586Z,1720633104.586 [Default:CheckIn:E] Stopped 2024-07-10T17:38:24.586Z,1720633104.586 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:38:24.586Z,1720633104.586 [Default:CheckIn] Stopped 2024-07-10T17:38:24.586Z,1720633104.586 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:38:24.587Z,1720633104.587 [Default:CheckIn](INFO): Running loop #21 2024-07-10T17:38:24.587Z,1720633104.587 [Default:CheckIn] Running Loop=21 2024-07-10T17:38:24.587Z,1720633104.587 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:38:24.587Z,1720633104.587 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:38:26.566Z,1720633106.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173826.00,A,3648.17506,N,12147.27916,W,0.097,1.34,100724,,,D*7E 2024-07-10T17:38:26.568Z,1720633106.568 [NAL9602](INFO): GPS fix at 20240710T173826: (36.802918, -121.787986) 2024-07-10T17:38:26.621Z,1720633106.621 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:38:26.621Z,1720633106.621 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:38:36.414Z,1720633116.414 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0067.lzma 2024-07-10T17:38:37.416Z,1720633117.416 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0067.lzma.bak 2024-07-10T17:38:37.416Z,1720633117.416 [DataOverHttps](INFO): SBD MOMSN=21348832 2024-07-10T17:38:38.681Z,1720633118.681 [NAL9602](INFO): SBD MO Status=0, MOMSN=5097, MT Status=0, MTMSN=0 2024-07-10T17:38:38.681Z,1720633118.681 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:38:58.318Z,1720633138.318 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240710T153524/Express0068.lzma 2024-07-10T17:38:59.320Z,1720633139.320 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0068.lzma.bak 2024-07-10T17:38:59.320Z,1720633139.320 [DataOverHttps](INFO): SBD MOMSN=21348835 2024-07-10T17:39:05.360Z,1720633145.360 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:39:05.360Z,1720633145.360 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:39:05.360Z,1720633145.360 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:39:09.383Z,1720633149.383 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:44:05.955Z,1720633445.955 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:44:05.955Z,1720633445.955 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:44:05.955Z,1720633445.955 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:44:05.956Z,1720633445.956 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:44:06.333Z,1720633446.333 [Default:CheckIn:D] Stopped 2024-07-10T17:44:06.333Z,1720633446.333 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:44:06.751Z,1720633446.751 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.219002 min 2024-07-10T17:44:06.752Z,1720633446.752 [Default:CheckIn:E] Stopped 2024-07-10T17:44:06.752Z,1720633446.752 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:44:06.752Z,1720633446.752 [Default:CheckIn] Stopped 2024-07-10T17:44:06.752Z,1720633446.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:44:06.752Z,1720633446.752 [Default:CheckIn](INFO): Running loop #22 2024-07-10T17:44:06.752Z,1720633446.752 [Default:CheckIn] Running Loop=22 2024-07-10T17:44:06.752Z,1720633446.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:44:06.752Z,1720633446.752 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:44:08.749Z,1720633448.749 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174408.00,A,3648.17452,N,12147.27827,W,0.039,1.34,100724,,,D*7E 2024-07-10T17:44:08.751Z,1720633448.751 [NAL9602](INFO): GPS fix at 20240710T174408: (36.802909, -121.787971) 2024-07-10T17:44:08.797Z,1720633448.797 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:44:08.797Z,1720633448.797 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:44:18.886Z,1720633458.886 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0070.lzma 2024-07-10T17:44:19.888Z,1720633459.888 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0070.lzma.bak 2024-07-10T17:44:19.889Z,1720633459.889 [DataOverHttps](INFO): SBD MOMSN=21348854 2024-07-10T17:44:40.634Z,1720633480.634 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240710T153524/Express0071.lzma 2024-07-10T17:44:41.647Z,1720633481.647 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0071.lzma.bak 2024-07-10T17:44:41.648Z,1720633481.648 [DataOverHttps](INFO): SBD MOMSN=21348857 2024-07-10T17:44:47.586Z,1720633487.586 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:44:47.586Z,1720633487.586 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:44:47.586Z,1720633487.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:45:01.677Z,1720633501.677 [NAL9602](INFO): SBD MO Status=0, MOMSN=5098, MT Status=0, MTMSN=0 2024-07-10T17:45:01.677Z,1720633501.677 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:45:32.379Z,1720633532.379 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:49:48.122Z,1720633788.122 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:49:48.122Z,1720633788.122 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:49:48.122Z,1720633788.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:49:48.122Z,1720633788.122 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:49:48.501Z,1720633788.501 [Default:CheckIn:D] Stopped 2024-07-10T17:49:48.501Z,1720633788.501 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.921802 min 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn:E] Stopped 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn] Stopped 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn](INFO): Running loop #23 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn] Running Loop=23 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:49:48.924Z,1720633788.924 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:49:50.917Z,1720633790.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174950.00,A,3648.17443,N,12147.27829,W,0.000,1.34,100724,,,D*7A 2024-07-10T17:49:50.919Z,1720633790.919 [NAL9602](INFO): GPS fix at 20240710T174950: (36.802907, -121.787971) 2024-07-10T17:49:51.033Z,1720633791.033 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:49:51.033Z,1720633791.033 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:50:00.758Z,1720633800.758 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0073.lzma 2024-07-10T17:50:01.760Z,1720633801.760 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0073.lzma.bak 2024-07-10T17:50:01.760Z,1720633801.760 [DataOverHttps](INFO): SBD MOMSN=21348873 2024-07-10T17:50:22.750Z,1720633822.750 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0074.lzma 2024-07-10T17:50:23.752Z,1720633823.752 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0074.lzma.bak 2024-07-10T17:50:23.753Z,1720633823.753 [DataOverHttps](INFO): SBD MOMSN=21348876 2024-07-10T17:50:30.126Z,1720633830.126 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:50:30.126Z,1720633830.126 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:50:30.126Z,1720633830.126 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:53:36.752Z,1720634016.752 [NAL9602](INFO): SBD MO Status=0, MOMSN=5099, MT Status=0, MTMSN=0 2024-07-10T17:53:36.752Z,1720634016.752 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:54:07.459Z,1720634047.459 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:55:18.961Z,1720634118.961 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:55:30.722Z,1720634130.722 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T17:55:30.722Z,1720634130.722 [Default:CheckIn:C.Wait] Stopped 2024-07-10T17:55:30.722Z,1720634130.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T17:55:30.722Z,1720634130.722 [Default:CheckIn:D] Running Loop=1 2024-07-10T17:55:31.128Z,1720634131.128 [Default:CheckIn:D] Stopped 2024-07-10T17:55:31.128Z,1720634131.128 [Default:CheckIn:E] Running Loop=1 2024-07-10T17:55:31.560Z,1720634131.560 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.632259 min 2024-07-10T17:55:31.561Z,1720634131.561 [Default:CheckIn:E] Stopped 2024-07-10T17:55:31.561Z,1720634131.561 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T17:55:31.561Z,1720634131.561 [Default:CheckIn] Stopped 2024-07-10T17:55:31.561Z,1720634131.561 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T17:55:31.561Z,1720634131.561 [Default:CheckIn](INFO): Running loop #24 2024-07-10T17:55:31.561Z,1720634131.561 [Default:CheckIn] Running Loop=24 2024-07-10T17:55:31.561Z,1720634131.561 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T17:55:31.561Z,1720634131.561 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T17:55:33.517Z,1720634133.517 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175533.00,A,3648.17237,N,12147.27768,W,0.136,0.00,100724,,,D*7F 2024-07-10T17:55:33.520Z,1720634133.520 [NAL9602](INFO): GPS fix at 20240710T175533: (36.802873, -121.787961) 2024-07-10T17:55:33.530Z,1720634133.530 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T17:55:33.530Z,1720634133.530 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T17:55:34.717Z,1720634134.717 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:55:43.542Z,1720634143.542 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0076.lzma 2024-07-10T17:55:44.544Z,1720634144.544 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0076.lzma.bak 2024-07-10T17:55:44.544Z,1720634144.544 [DataOverHttps](INFO): SBD MOMSN=21348896 2024-07-10T17:55:48.053Z,1720634148.053 [NAL9602](INFO): SBD MO Status=0, MOMSN=5100, MT Status=0, MTMSN=0 2024-07-10T17:55:48.053Z,1720634148.053 [NAL9602](INFO): No messages in MT queue 2024-07-10T17:55:49.665Z,1720634149.665 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:56:04.613Z,1720634164.613 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:56:05.475Z,1720634165.475 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0077.lzma 2024-07-10T17:56:06.477Z,1720634166.477 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0077.lzma.bak 2024-07-10T17:56:06.477Z,1720634166.477 [DataOverHttps](INFO): SBD MOMSN=21348900 2024-07-10T17:56:12.721Z,1720634172.721 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T17:56:12.721Z,1720634172.721 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T17:56:12.722Z,1720634172.722 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T17:56:18.759Z,1720634178.759 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T17:56:19.965Z,1720634179.965 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:56:35.317Z,1720634195.317 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:56:51.439Z,1720634211.439 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:57:06.745Z,1720634226.745 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:57:22.905Z,1720634242.905 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:57:38.258Z,1720634258.258 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T17:57:44.746Z,1720634264.746 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2024-07-10T17:57:44.748Z,1720634264.748 [BPC1](INFO): Received data from all battery sticks. 2024-07-10T18:01:13.237Z,1720634473.237 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:01:13.237Z,1720634473.237 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:01:13.237Z,1720634473.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:01:13.237Z,1720634473.237 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:01:13.614Z,1720634473.614 [Default:CheckIn:D] Stopped 2024-07-10T18:01:13.618Z,1720634473.618 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:01:14.005Z,1720634474.005 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.340348 min 2024-07-10T18:01:14.005Z,1720634474.005 [Default:CheckIn:E] Stopped 2024-07-10T18:01:14.005Z,1720634474.005 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:01:14.005Z,1720634474.005 [Default:CheckIn] Stopped 2024-07-10T18:01:14.005Z,1720634474.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:01:14.006Z,1720634474.006 [Default:CheckIn](INFO): Running loop #25 2024-07-10T18:01:14.006Z,1720634474.006 [Default:CheckIn] Running Loop=25 2024-07-10T18:01:14.006Z,1720634474.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:01:14.006Z,1720634474.006 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:01:16.021Z,1720634476.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180115.00,A,3648.17407,N,12147.27818,W,0.117,0.00,100724,,,D*7B 2024-07-10T18:01:16.024Z,1720634476.024 [NAL9602](INFO): GPS fix at 20240710T180115: (36.802901, -121.787970) 2024-07-10T18:01:16.034Z,1720634476.034 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:01:16.034Z,1720634476.034 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:01:26.162Z,1720634486.162 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240710T153524/Courier0079.lzma 2024-07-10T18:01:27.164Z,1720634487.164 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0079.lzma.bak 2024-07-10T18:01:27.164Z,1720634487.164 [DataOverHttps](INFO): SBD MOMSN=21348916 2024-07-10T18:01:47.854Z,1720634507.854 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0080.lzma 2024-07-10T18:01:48.856Z,1720634508.856 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0080.lzma.bak 2024-07-10T18:01:48.857Z,1720634508.857 [DataOverHttps](INFO): SBD MOMSN=21348919 2024-07-10T18:01:57.251Z,1720634517.251 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:01:57.251Z,1720634517.251 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:01:57.251Z,1720634517.251 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:03:01.460Z,1720634581.460 [NAL9602](INFO): SBD MO Status=2, MOMSN=5101, MT Status=2, MTMSN=0 2024-07-10T18:03:01.460Z,1720634581.460 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T18:03:36.213Z,1720634616.213 [NAL9602](INFO): SBD MO Status=2, MOMSN=5101, MT Status=2, MTMSN=0 2024-07-10T18:03:36.213Z,1720634616.213 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T18:03:50.349Z,1720634630.349 [NAL9602](INFO): SBD MO Status=2, MOMSN=5101, MT Status=2, MTMSN=0 2024-07-10T18:03:50.349Z,1720634630.349 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T18:04:54.581Z,1720634694.581 [NAL9602](INFO): SBD MO Status=2, MOMSN=5101, MT Status=2, MTMSN=0 2024-07-10T18:04:54.581Z,1720634694.581 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T18:05:01.453Z,1720634701.453 [NAL9602](INFO): SBD MO Status=0, MOMSN=5101, MT Status=0, MTMSN=0 2024-07-10T18:05:01.453Z,1720634701.453 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:05:32.147Z,1720634732.147 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:06:57.835Z,1720634817.835 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:06:57.835Z,1720634817.835 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:06:57.835Z,1720634817.835 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:06:57.835Z,1720634817.835 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:06:58.229Z,1720634818.229 [Default:CheckIn:D] Stopped 2024-07-10T18:06:58.229Z,1720634818.229 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:06:58.635Z,1720634818.635 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.083936 min 2024-07-10T18:06:58.635Z,1720634818.635 [Default:CheckIn:E] Stopped 2024-07-10T18:06:58.635Z,1720634818.635 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:06:58.635Z,1720634818.635 [Default:CheckIn] Stopped 2024-07-10T18:06:58.636Z,1720634818.636 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:06:58.636Z,1720634818.636 [Default:CheckIn](INFO): Running loop #26 2024-07-10T18:06:58.636Z,1720634818.636 [Default:CheckIn] Running Loop=26 2024-07-10T18:06:58.636Z,1720634818.636 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:06:58.636Z,1720634818.636 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:07:00.629Z,1720634820.629 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180700.00,A,3648.17554,N,12147.27700,W,0.039,0.00,100724,,,D*75 2024-07-10T18:07:00.631Z,1720634820.631 [NAL9602](INFO): GPS fix at 20240710T180700: (36.802926, -121.787950) 2024-07-10T18:07:00.658Z,1720634820.658 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:07:00.658Z,1720634820.658 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:07:10.321Z,1720634830.321 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0082.lzma 2024-07-10T18:07:11.128Z,1720634831.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=5102, MT Status=0, MTMSN=0 2024-07-10T18:07:11.128Z,1720634831.128 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:07:11.324Z,1720634831.324 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0082.lzma.bak 2024-07-10T18:07:11.324Z,1720634831.324 [DataOverHttps](INFO): SBD MOMSN=21348939 2024-07-10T18:07:35.682Z,1720634855.682 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0083.lzma 2024-07-10T18:07:36.684Z,1720634856.684 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0083.lzma.bak 2024-07-10T18:07:36.684Z,1720634856.684 [DataOverHttps](INFO): SBD MOMSN=21348943 2024-07-10T18:07:41.839Z,1720634861.839 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:07:43.083Z,1720634863.083 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:07:43.083Z,1720634863.083 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:07:43.083Z,1720634863.083 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:12:43.651Z,1720635163.651 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:12:43.651Z,1720635163.651 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:12:43.651Z,1720635163.651 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:12:43.651Z,1720635163.651 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:12:44.060Z,1720635164.060 [Default:CheckIn:D] Stopped 2024-07-10T18:12:44.060Z,1720635164.060 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:12:44.467Z,1720635164.467 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.847786 min 2024-07-10T18:12:44.467Z,1720635164.467 [Default:CheckIn:E] Stopped 2024-07-10T18:12:44.467Z,1720635164.467 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:12:44.467Z,1720635164.467 [Default:CheckIn] Stopped 2024-07-10T18:12:44.468Z,1720635164.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:12:44.468Z,1720635164.468 [Default:CheckIn](INFO): Running loop #27 2024-07-10T18:12:44.468Z,1720635164.468 [Default:CheckIn] Running Loop=27 2024-07-10T18:12:44.468Z,1720635164.468 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:12:44.468Z,1720635164.468 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:12:46.461Z,1720635166.461 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181246.00,A,3648.17579,N,12147.27717,W,0.039,0.00,100724,,,A*7F 2024-07-10T18:12:46.464Z,1720635166.464 [NAL9602](INFO): GPS fix at 20240710T181246: (36.802930, -121.787953) 2024-07-10T18:12:46.474Z,1720635166.474 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:12:46.474Z,1720635166.474 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:12:56.370Z,1720635176.370 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0085.lzma 2024-07-10T18:12:57.372Z,1720635177.372 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0085.lzma.bak 2024-07-10T18:12:57.372Z,1720635177.372 [DataOverHttps](INFO): SBD MOMSN=21348962 2024-07-10T18:13:05.041Z,1720635185.041 [NAL9602](INFO): SBD MO Status=0, MOMSN=5103, MT Status=0, MTMSN=0 2024-07-10T18:13:05.041Z,1720635185.041 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:13:18.022Z,1720635198.022 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0086.lzma 2024-07-10T18:13:19.024Z,1720635199.024 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0086.lzma.bak 2024-07-10T18:13:19.024Z,1720635199.024 [DataOverHttps](INFO): SBD MOMSN=21348965 2024-07-10T18:13:27.711Z,1720635207.711 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:13:27.711Z,1720635207.711 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:13:27.711Z,1720635207.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:13:35.743Z,1720635215.743 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:18:28.441Z,1720635508.441 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:18:28.441Z,1720635508.441 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:18:28.446Z,1720635508.446 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:18:28.446Z,1720635508.446 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:18:28.877Z,1720635508.877 [Default:CheckIn:D] Stopped 2024-07-10T18:18:28.877Z,1720635508.877 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:18:29.233Z,1720635509.233 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.594727 min 2024-07-10T18:18:29.233Z,1720635509.233 [Default:CheckIn:E] Stopped 2024-07-10T18:18:29.233Z,1720635509.233 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:18:29.233Z,1720635509.233 [Default:CheckIn] Stopped 2024-07-10T18:18:29.233Z,1720635509.233 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:18:29.234Z,1720635509.234 [Default:CheckIn](INFO): Running loop #28 2024-07-10T18:18:29.234Z,1720635509.234 [Default:CheckIn] Running Loop=28 2024-07-10T18:18:29.234Z,1720635509.234 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:18:29.234Z,1720635509.234 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:18:31.252Z,1720635511.252 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181830.00,A,3648.17338,N,12147.27873,W,0.058,0.00,100724,,,A*7D 2024-07-10T18:18:31.254Z,1720635511.254 [NAL9602](INFO): GPS fix at 20240710T181830: (36.802890, -121.787979) 2024-07-10T18:18:31.265Z,1720635511.265 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:18:31.265Z,1720635511.265 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:18:41.117Z,1720635521.117 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0088.lzma 2024-07-10T18:18:42.120Z,1720635522.120 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0088.lzma.bak 2024-07-10T18:18:42.120Z,1720635522.120 [DataOverHttps](INFO): SBD MOMSN=21348983 2024-07-10T18:19:02.853Z,1720635542.853 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240710T153524/Express0089.lzma 2024-07-10T18:19:03.856Z,1720635543.856 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0089.lzma.bak 2024-07-10T18:19:03.856Z,1720635543.856 [DataOverHttps](INFO): SBD MOMSN=21348990 2024-07-10T18:19:12.504Z,1720635552.504 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:19:12.504Z,1720635552.504 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:19:12.504Z,1720635552.504 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:22:00.541Z,1720635720.541 [NAL9602](INFO): SBD MO Status=0, MOMSN=5104, MT Status=0, MTMSN=0 2024-07-10T18:22:00.541Z,1720635720.541 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:22:31.246Z,1720635751.246 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:24:13.169Z,1720635853.169 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:24:13.169Z,1720635853.169 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:24:13.169Z,1720635853.169 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:24:13.169Z,1720635853.169 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:24:13.540Z,1720635853.540 [Default:CheckIn:D] Stopped 2024-07-10T18:24:13.540Z,1720635853.540 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:24:13.970Z,1720635853.970 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.339128 min 2024-07-10T18:24:13.970Z,1720635853.970 [Default:CheckIn:E] Stopped 2024-07-10T18:24:13.970Z,1720635853.970 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:24:13.970Z,1720635853.970 [Default:CheckIn] Stopped 2024-07-10T18:24:13.970Z,1720635853.970 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:24:13.970Z,1720635853.970 [Default:CheckIn](INFO): Running loop #29 2024-07-10T18:24:13.971Z,1720635853.971 [Default:CheckIn] Running Loop=29 2024-07-10T18:24:13.971Z,1720635853.971 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:24:13.971Z,1720635853.971 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:24:15.946Z,1720635855.946 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182415.00,A,3648.17275,N,12147.28113,W,0.058,0.00,100724,,,A*7D 2024-07-10T18:24:15.959Z,1720635855.959 [NAL9602](INFO): GPS fix at 20240710T182415: (36.802879, -121.788019) 2024-07-10T18:24:15.969Z,1720635855.969 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:24:15.969Z,1720635855.969 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:24:27.115Z,1720635867.115 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0091.lzma 2024-07-10T18:24:28.640Z,1720635868.640 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0091.lzma.bak 2024-07-10T18:24:28.641Z,1720635868.641 [DataOverHttps](INFO): SBD MOMSN=21349000 2024-07-10T18:24:49.011Z,1720635889.011 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0092.lzma 2024-07-10T18:24:50.020Z,1720635890.020 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0092.lzma.bak 2024-07-10T18:24:50.020Z,1720635890.020 [DataOverHttps](INFO): SBD MOMSN=21349006 2024-07-10T18:24:56.407Z,1720635896.407 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:24:56.407Z,1720635896.407 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:24:56.407Z,1720635896.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:25:09.283Z,1720635909.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=5105, MT Status=2, MTMSN=0 2024-07-10T18:25:09.283Z,1720635909.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T18:25:29.470Z,1720635929.470 [NAL9602](INFO): SBD MO Status=0, MOMSN=5105, MT Status=0, MTMSN=0 2024-07-10T18:25:29.470Z,1720635929.470 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:26:00.169Z,1720635960.169 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:29:56.956Z,1720636196.956 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:29:56.956Z,1720636196.956 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:29:56.956Z,1720636196.956 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:29:56.956Z,1720636196.956 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:29:57.342Z,1720636197.342 [Default:CheckIn:D] Stopped 2024-07-10T18:29:57.343Z,1720636197.343 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:29:57.743Z,1720636197.743 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.069157 min 2024-07-10T18:29:57.743Z,1720636197.743 [Default:CheckIn:E] Stopped 2024-07-10T18:29:57.747Z,1720636197.747 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:29:57.748Z,1720636197.748 [Default:CheckIn] Stopped 2024-07-10T18:29:57.748Z,1720636197.748 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:29:57.748Z,1720636197.748 [Default:CheckIn](INFO): Running loop #30 2024-07-10T18:29:57.748Z,1720636197.748 [Default:CheckIn] Running Loop=30 2024-07-10T18:29:57.748Z,1720636197.748 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:29:57.748Z,1720636197.748 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:29:59.742Z,1720636199.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182959.00,A,3648.17512,N,12147.27885,W,0.097,0.00,100724,,,A*74 2024-07-10T18:29:59.744Z,1720636199.744 [NAL9602](INFO): GPS fix at 20240710T182959: (36.802919, -121.787981) 2024-07-10T18:29:59.791Z,1720636199.791 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:29:59.792Z,1720636199.792 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:30:03.501Z,1720636203.501 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0094.lzma 2024-07-10T18:30:04.506Z,1720636204.506 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0094.lzma.bak 2024-07-10T18:30:04.506Z,1720636204.506 [DataOverHttps](INFO): SBD MOMSN=21349029 2024-07-10T18:30:21.559Z,1720636221.559 [NAL9602](INFO): SBD MO Status=2, MOMSN=5106, MT Status=2, MTMSN=0 2024-07-10T18:30:21.559Z,1720636221.559 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T18:30:26.511Z,1720636226.511 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0095.lzma 2024-07-10T18:30:27.514Z,1720636227.514 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0095.lzma.bak 2024-07-10T18:30:27.514Z,1720636227.514 [DataOverHttps](INFO): SBD MOMSN=21349038 2024-07-10T18:30:33.703Z,1720636233.703 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:30:33.703Z,1720636233.703 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:30:33.703Z,1720636233.703 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:30:38.122Z,1720636238.122 [NAL9602](INFO): SBD MO Status=0, MOMSN=5106, MT Status=0, MTMSN=0 2024-07-10T18:30:38.122Z,1720636238.122 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:31:08.832Z,1720636268.832 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:35:34.314Z,1720636534.314 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:35:34.314Z,1720636534.314 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:35:34.314Z,1720636534.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:35:34.314Z,1720636534.314 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:35:34.683Z,1720636534.683 [Default:CheckIn:D] Stopped 2024-07-10T18:35:34.687Z,1720636534.687 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.691504 min 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn:E] Stopped 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn] Stopped 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn](INFO): Running loop #31 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn] Running Loop=31 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:35:35.089Z,1720636535.089 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:35:37.082Z,1720636537.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183536.00,A,3648.17668,N,12147.28240,W,0.058,0.00,100724,,,A*71 2024-07-10T18:35:37.084Z,1720636537.084 [NAL9602](INFO): GPS fix at 20240710T183536: (36.802945, -121.788040) 2024-07-10T18:35:37.094Z,1720636537.094 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:35:37.094Z,1720636537.094 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:35:46.759Z,1720636546.759 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0097.lzma 2024-07-10T18:35:47.762Z,1720636547.762 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0097.lzma.bak 2024-07-10T18:35:47.762Z,1720636547.762 [DataOverHttps](INFO): SBD MOMSN=21349056 2024-07-10T18:36:08.955Z,1720636568.955 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0098.lzma 2024-07-10T18:36:09.958Z,1720636569.958 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0098.lzma.bak 2024-07-10T18:36:09.958Z,1720636569.958 [DataOverHttps](INFO): SBD MOMSN=21349059 2024-07-10T18:36:16.287Z,1720636576.287 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:36:16.308Z,1720636576.308 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:36:16.308Z,1720636576.308 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:36:35.666Z,1720636595.666 [NAL9602](INFO): SBD MO Status=0, MOMSN=5107, MT Status=0, MTMSN=0 2024-07-10T18:36:35.666Z,1720636595.666 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:37:06.364Z,1720636626.364 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:41:16.894Z,1720636876.894 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:41:16.894Z,1720636876.894 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:41:16.894Z,1720636876.894 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:41:16.895Z,1720636876.895 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:41:17.265Z,1720636877.265 [Default:CheckIn:D] Stopped 2024-07-10T18:41:17.266Z,1720636877.266 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:41:17.663Z,1720636877.663 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.401204 min 2024-07-10T18:41:17.663Z,1720636877.663 [Default:CheckIn:E] Stopped 2024-07-10T18:41:17.663Z,1720636877.663 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:41:17.667Z,1720636877.667 [Default:CheckIn] Stopped 2024-07-10T18:41:17.667Z,1720636877.667 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:41:17.668Z,1720636877.668 [Default:CheckIn](INFO): Running loop #32 2024-07-10T18:41:17.668Z,1720636877.668 [Default:CheckIn] Running Loop=32 2024-07-10T18:41:17.668Z,1720636877.668 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:41:17.668Z,1720636877.668 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:41:19.674Z,1720636879.674 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184119.00,A,3648.17872,N,12147.28007,W,0.000,0.00,100724,,,A*76 2024-07-10T18:41:19.686Z,1720636879.686 [NAL9602](INFO): GPS fix at 20240710T184119: (36.802979, -121.788001) 2024-07-10T18:41:19.702Z,1720636879.702 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:41:19.702Z,1720636879.702 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:41:29.867Z,1720636889.867 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0100.lzma 2024-07-10T18:41:30.870Z,1720636890.870 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0100.lzma.bak 2024-07-10T18:41:30.870Z,1720636890.870 [DataOverHttps](INFO): SBD MOMSN=21349075 2024-07-10T18:41:42.698Z,1720636902.698 [NAL9602](INFO): SBD MO Status=2, MOMSN=5108, MT Status=2, MTMSN=0 2024-07-10T18:41:42.698Z,1720636902.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T18:41:53.663Z,1720636913.663 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0101.lzma 2024-07-10T18:41:54.665Z,1720636914.665 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0101.lzma.bak 2024-07-10T18:41:54.666Z,1720636914.666 [DataOverHttps](INFO): SBD MOMSN=21349078 2024-07-10T18:42:00.895Z,1720636920.895 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:42:00.915Z,1720636920.915 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:42:00.915Z,1720636920.915 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:43:17.637Z,1720636997.637 [NAL9602](INFO): SBD MO Status=2, MOMSN=5108, MT Status=2, MTMSN=0 2024-07-10T18:43:17.637Z,1720636997.637 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T18:44:11.778Z,1720637051.778 [NAL9602](INFO): SBD MO Status=0, MOMSN=5108, MT Status=0, MTMSN=0 2024-07-10T18:44:11.778Z,1720637051.778 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:44:42.475Z,1720637082.475 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:47:01.516Z,1720637221.516 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:47:01.516Z,1720637221.516 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:47:01.516Z,1720637221.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:47:01.516Z,1720637221.516 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:47:01.885Z,1720637221.885 [Default:CheckIn:D] Stopped 2024-07-10T18:47:01.885Z,1720637221.885 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 188.144873 min 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn:E] Stopped 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn] Stopped 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn](INFO): Running loop #33 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn] Running Loop=33 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:47:02.293Z,1720637222.293 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:47:04.282Z,1720637224.282 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184703.00,A,3648.17634,N,12147.28335,W,0.058,0.00,100724,,,A*78 2024-07-10T18:47:04.284Z,1720637224.284 [NAL9602](INFO): GPS fix at 20240710T184703: (36.802939, -121.788056) 2024-07-10T18:47:04.300Z,1720637224.300 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:47:04.300Z,1720637224.300 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:47:07.674Z,1720637227.674 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0103.lzma 2024-07-10T18:47:08.681Z,1720637228.681 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0103.lzma.bak 2024-07-10T18:47:08.682Z,1720637228.682 [DataOverHttps](INFO): SBD MOMSN=21349095 2024-07-10T18:47:26.926Z,1720637246.926 [NAL9602](INFO): SBD MO Status=0, MOMSN=5109, MT Status=0, MTMSN=0 2024-07-10T18:47:26.926Z,1720637246.926 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:47:29.643Z,1720637249.643 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0104.lzma 2024-07-10T18:47:30.645Z,1720637250.645 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0104.lzma.bak 2024-07-10T18:47:30.646Z,1720637250.646 [DataOverHttps](INFO): SBD MOMSN=21349098 2024-07-10T18:47:37.023Z,1720637257.023 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:47:37.023Z,1720637257.023 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:47:37.023Z,1720637257.023 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:47:57.612Z,1720637277.612 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:52:37.598Z,1720637557.598 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:52:37.598Z,1720637557.598 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:52:37.598Z,1720637557.598 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:52:37.599Z,1720637557.599 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:52:38.052Z,1720637558.052 [Default:CheckIn:D] Stopped 2024-07-10T18:52:38.052Z,1720637558.052 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.747656 min 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn:E] Stopped 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn] Stopped 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn](INFO): Running loop #34 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn] Running Loop=34 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:52:38.423Z,1720637558.423 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:52:40.414Z,1720637560.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185240.00,A,3648.17428,N,12147.28338,W,0.019,0.00,100724,,,A*7C 2024-07-10T18:52:40.416Z,1720637560.416 [NAL9602](INFO): GPS fix at 20240710T185240: (36.802905, -121.788056) 2024-07-10T18:52:40.447Z,1720637560.447 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:52:40.447Z,1720637560.447 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:52:50.359Z,1720637570.359 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0106.lzma 2024-07-10T18:52:51.361Z,1720637571.361 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0106.lzma.bak 2024-07-10T18:52:51.362Z,1720637571.362 [DataOverHttps](INFO): SBD MOMSN=21349117 2024-07-10T18:53:15.455Z,1720637595.455 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0107.lzma 2024-07-10T18:53:16.458Z,1720637596.458 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0107.lzma.bak 2024-07-10T18:53:16.458Z,1720637596.458 [DataOverHttps](INFO): SBD MOMSN=21349120 2024-07-10T18:53:22.848Z,1720637602.848 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:53:22.848Z,1720637602.848 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:53:22.848Z,1720637602.848 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:53:35.358Z,1720637615.358 [NAL9602](INFO): SBD MO Status=0, MOMSN=5110, MT Status=0, MTMSN=0 2024-07-10T18:53:35.358Z,1720637615.358 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:54:06.056Z,1720637646.056 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T18:58:23.439Z,1720637903.439 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T18:58:23.439Z,1720637903.439 [Default:CheckIn:C.Wait] Stopped 2024-07-10T18:58:23.439Z,1720637903.439 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T18:58:23.439Z,1720637903.439 [Default:CheckIn:D] Running Loop=1 2024-07-10T18:58:23.818Z,1720637903.818 [Default:CheckIn:D] Stopped 2024-07-10T18:58:23.818Z,1720637903.818 [Default:CheckIn:E] Running Loop=1 2024-07-10T18:58:24.232Z,1720637904.232 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 199.510417 min 2024-07-10T18:58:24.232Z,1720637904.232 [Default:CheckIn:E] Stopped 2024-07-10T18:58:24.232Z,1720637904.232 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T18:58:24.233Z,1720637904.233 [Default:CheckIn] Stopped 2024-07-10T18:58:24.233Z,1720637904.233 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T18:58:24.233Z,1720637904.233 [Default:CheckIn](INFO): Running loop #35 2024-07-10T18:58:24.233Z,1720637904.233 [Default:CheckIn] Running Loop=35 2024-07-10T18:58:24.233Z,1720637904.233 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T18:58:24.233Z,1720637904.233 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T18:58:26.235Z,1720637906.235 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185826.00,A,3648.17508,N,12147.27967,W,0.039,0.00,100724,,,A*78 2024-07-10T18:58:26.237Z,1720637906.237 [NAL9602](INFO): GPS fix at 20240710T185826: (36.802918, -121.787994) 2024-07-10T18:58:26.264Z,1720637906.264 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T18:58:26.264Z,1720637906.264 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T18:58:36.035Z,1720637916.035 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0109.lzma 2024-07-10T18:58:37.037Z,1720637917.037 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0109.lzma.bak 2024-07-10T18:58:37.038Z,1720637917.038 [DataOverHttps](INFO): SBD MOMSN=21349136 2024-07-10T18:58:45.643Z,1720637925.643 [NAL9602](INFO): SBD MO Status=0, MOMSN=5111, MT Status=0, MTMSN=0 2024-07-10T18:58:45.643Z,1720637925.643 [NAL9602](INFO): No messages in MT queue 2024-07-10T18:58:59.415Z,1720637939.415 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0110.lzma 2024-07-10T18:59:00.418Z,1720637940.418 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0110.lzma.bak 2024-07-10T18:59:00.418Z,1720637940.418 [DataOverHttps](INFO): SBD MOMSN=21349139 2024-07-10T18:59:06.689Z,1720637946.689 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T18:59:06.689Z,1720637946.689 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T18:59:06.689Z,1720637946.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T18:59:16.337Z,1720637956.337 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T19:02:42.472Z,1720638162.472 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:02:57.406Z,1720638177.406 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:03:12.758Z,1720638192.758 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:03:27.706Z,1720638207.706 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:03:43.058Z,1720638223.058 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:03:58.816Z,1720638238.816 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:04:07.329Z,1720638247.329 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T19:04:07.329Z,1720638247.329 [Default:CheckIn:C.Wait] Stopped 2024-07-10T19:04:07.329Z,1720638247.329 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T19:04:07.329Z,1720638247.329 [Default:CheckIn:D] Running Loop=1 2024-07-10T19:04:07.741Z,1720638247.741 [Default:CheckIn:D] Stopped 2024-07-10T19:04:07.741Z,1720638247.741 [Default:CheckIn:E] Running Loop=1 2024-07-10T19:04:08.127Z,1720638248.127 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 205.242464 min 2024-07-10T19:04:08.127Z,1720638248.127 [Default:CheckIn:E] Stopped 2024-07-10T19:04:08.127Z,1720638248.127 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T19:04:08.128Z,1720638248.128 [Default:CheckIn] Stopped 2024-07-10T19:04:08.128Z,1720638248.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T19:04:08.128Z,1720638248.128 [Default:CheckIn](INFO): Running loop #36 2024-07-10T19:04:08.128Z,1720638248.128 [Default:CheckIn] Running Loop=36 2024-07-10T19:04:08.128Z,1720638248.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T19:04:08.128Z,1720638248.128 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T19:04:10.138Z,1720638250.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190409.00,A,3648.17205,N,12147.27756,W,0.058,0.00,100724,,,A*7C 2024-07-10T19:04:10.141Z,1720638250.141 [NAL9602](INFO): GPS fix at 20240710T190409: (36.802867, -121.787959) 2024-07-10T19:04:10.151Z,1720638250.151 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T19:04:10.151Z,1720638250.151 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T19:04:14.166Z,1720638254.166 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:04:20.167Z,1720638260.167 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0112.lzma 2024-07-10T19:04:21.172Z,1720638261.172 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0112.lzma.bak 2024-07-10T19:04:21.172Z,1720638261.172 [DataOverHttps](INFO): SBD MOMSN=21349156 2024-07-10T19:04:29.114Z,1720638269.114 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:04:34.778Z,1720638274.778 [NAL9602](INFO): SBD MO Status=0, MOMSN=5112, MT Status=0, MTMSN=0 2024-07-10T19:04:34.778Z,1720638274.778 [NAL9602](INFO): No messages in MT queue 2024-07-10T19:04:43.875Z,1720638283.875 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0113.lzma 2024-07-10T19:04:44.468Z,1720638284.468 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:04:44.878Z,1720638284.878 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0113.lzma.bak 2024-07-10T19:04:44.878Z,1720638284.878 [DataOverHttps](INFO): SBD MOMSN=21349159 2024-07-10T19:04:50.960Z,1720638290.960 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T19:04:50.960Z,1720638290.960 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T19:04:50.960Z,1720638290.960 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T19:04:59.819Z,1720638299.819 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-10T19:05:05.476Z,1720638305.476 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T19:05:09.925Z,1720638309.925 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2024-07-10T19:05:09.927Z,1720638309.927 [BPC1](INFO): Received data from all battery sticks. 2024-07-10T19:09:51.559Z,1720638591.559 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T19:09:51.559Z,1720638591.559 [Default:CheckIn:C.Wait] Stopped 2024-07-10T19:09:51.563Z,1720638591.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T19:09:51.563Z,1720638591.563 [Default:CheckIn:D] Running Loop=1 2024-07-10T19:09:51.956Z,1720638591.956 [Default:CheckIn:D] Stopped 2024-07-10T19:09:51.956Z,1720638591.956 [Default:CheckIn:E] Running Loop=1 2024-07-10T19:09:52.393Z,1720638592.393 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 210.979395 min 2024-07-10T19:09:52.393Z,1720638592.393 [Default:CheckIn:E] Stopped 2024-07-10T19:09:52.394Z,1720638592.394 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T19:09:52.394Z,1720638592.394 [Default:CheckIn] Stopped 2024-07-10T19:09:52.394Z,1720638592.394 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T19:09:52.394Z,1720638592.394 [Default:CheckIn](INFO): Running loop #37 2024-07-10T19:09:52.394Z,1720638592.394 [Default:CheckIn] Running Loop=37 2024-07-10T19:09:52.394Z,1720638592.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T19:09:52.394Z,1720638592.394 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T19:09:54.371Z,1720638594.371 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190954.00,A,3648.17680,N,12147.28017,W,0.292,0.00,100724,,,A*79 2024-07-10T19:09:54.373Z,1720638594.373 [NAL9602](INFO): GPS fix at 20240710T190954: (36.802947, -121.788003) 2024-07-10T19:09:54.383Z,1720638594.383 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T19:09:54.383Z,1720638594.383 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T19:10:04.635Z,1720638604.635 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240710T153524/Courier0115.lzma 2024-07-10T19:10:05.638Z,1720638605.638 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0115.lzma.bak 2024-07-10T19:10:05.638Z,1720638605.638 [DataOverHttps](INFO): SBD MOMSN=21349177 2024-07-10T19:10:26.507Z,1720638626.507 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0116.lzma 2024-07-10T19:10:27.509Z,1720638627.509 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0116.lzma.bak 2024-07-10T19:10:27.510Z,1720638627.510 [DataOverHttps](INFO): SBD MOMSN=21349180 2024-07-10T19:10:37.218Z,1720638637.218 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T19:10:37.218Z,1720638637.218 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T19:10:37.218Z,1720638637.218 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T19:10:40.014Z,1720638640.014 [NAL9602](INFO): SBD MO Status=0, MOMSN=5113, MT Status=0, MTMSN=0 2024-07-10T19:10:40.014Z,1720638640.014 [NAL9602](INFO): No messages in MT queue 2024-07-10T19:11:10.720Z,1720638670.720 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T19:15:37.862Z,1720638937.862 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T19:15:37.862Z,1720638937.862 [Default:CheckIn:C.Wait] Stopped 2024-07-10T19:15:37.862Z,1720638937.862 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T19:15:37.862Z,1720638937.862 [Default:CheckIn:D] Running Loop=1 2024-07-10T19:15:38.276Z,1720638938.276 [Default:CheckIn:D] Stopped 2024-07-10T19:15:38.276Z,1720638938.276 [Default:CheckIn:E] Running Loop=1 2024-07-10T19:15:38.686Z,1720638938.686 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 216.751383 min 2024-07-10T19:15:38.687Z,1720638938.687 [Default:CheckIn:E] Stopped 2024-07-10T19:15:38.687Z,1720638938.687 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T19:15:38.687Z,1720638938.687 [Default:CheckIn] Stopped 2024-07-10T19:15:38.687Z,1720638938.687 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T19:15:38.687Z,1720638938.687 [Default:CheckIn](INFO): Running loop #38 2024-07-10T19:15:38.687Z,1720638938.687 [Default:CheckIn] Running Loop=38 2024-07-10T19:15:38.687Z,1720638938.687 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T19:15:38.687Z,1720638938.687 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T19:15:40.677Z,1720638940.677 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191540.00,A,3648.17458,N,12147.27931,W,0.039,0.00,100724,,,A*77 2024-07-10T19:15:40.679Z,1720638940.679 [NAL9602](INFO): GPS fix at 20240710T191540: (36.802910, -121.787988) 2024-07-10T19:15:40.710Z,1720638940.710 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T19:15:40.710Z,1720638940.710 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T19:15:50.758Z,1720638950.758 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0118.lzma 2024-07-10T19:15:51.761Z,1720638951.761 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0118.lzma.bak 2024-07-10T19:15:51.761Z,1720638951.761 [DataOverHttps](INFO): SBD MOMSN=21349196 2024-07-10T19:16:16.138Z,1720638976.138 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240710T153524/Express0119.lzma 2024-07-10T19:16:17.141Z,1720638977.141 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0119.lzma.bak 2024-07-10T19:16:17.141Z,1720638977.141 [DataOverHttps](INFO): SBD MOMSN=21349199 2024-07-10T19:16:19.461Z,1720638979.461 [NAL9602](INFO): SBD MO Status=0, MOMSN=5114, MT Status=0, MTMSN=0 2024-07-10T19:16:19.461Z,1720638979.461 [NAL9602](INFO): No messages in MT queue 2024-07-10T19:16:23.523Z,1720638983.523 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T19:16:23.523Z,1720638983.523 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T19:16:23.523Z,1720638983.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T19:16:50.167Z,1720639010.167 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T19:21:24.133Z,1720639284.133 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T19:21:24.133Z,1720639284.133 [Default:CheckIn:C.Wait] Stopped 2024-07-10T19:21:24.133Z,1720639284.133 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T19:21:24.134Z,1720639284.134 [Default:CheckIn:D] Running Loop=1 2024-07-10T19:21:24.589Z,1720639284.589 [Default:CheckIn:D] Stopped 2024-07-10T19:21:24.590Z,1720639284.590 [Default:CheckIn:E] Running Loop=1 2024-07-10T19:21:24.925Z,1720639284.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 222.523258 min 2024-07-10T19:21:24.925Z,1720639284.925 [Default:CheckIn:E] Stopped 2024-07-10T19:21:24.925Z,1720639284.925 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T19:21:24.925Z,1720639284.925 [Default:CheckIn] Stopped 2024-07-10T19:21:24.925Z,1720639284.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T19:21:24.926Z,1720639284.926 [Default:CheckIn](INFO): Running loop #39 2024-07-10T19:21:24.926Z,1720639284.926 [Default:CheckIn] Running Loop=39 2024-07-10T19:21:24.926Z,1720639284.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T19:21:24.926Z,1720639284.926 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T19:21:26.937Z,1720639286.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192126.00,A,3648.17419,N,12147.27611,W,0.039,0.00,100724,,,A*78 2024-07-10T19:21:26.939Z,1720639286.939 [NAL9602](INFO): GPS fix at 20240710T192126: (36.802903, -121.787935) 2024-07-10T19:21:26.949Z,1720639286.949 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T19:21:26.949Z,1720639286.949 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T19:21:37.037Z,1720639297.037 [NAL9602](INFO): SBD MO Status=0, MOMSN=5115, MT Status=0, MTMSN=0 2024-07-10T19:21:37.037Z,1720639297.037 [NAL9602](INFO): No messages in MT queue 2024-07-10T19:21:37.158Z,1720639297.158 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0121.lzma 2024-07-10T19:21:38.161Z,1720639298.161 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0121.lzma.bak 2024-07-10T19:21:38.161Z,1720639298.161 [DataOverHttps](INFO): SBD MOMSN=21349217 2024-07-10T19:21:59.738Z,1720639319.738 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240710T153524/Express0122.lzma 2024-07-10T19:22:00.741Z,1720639320.741 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0122.lzma.bak 2024-07-10T19:22:00.741Z,1720639320.741 [DataOverHttps](INFO): SBD MOMSN=21349223 2024-07-10T19:22:06.980Z,1720639326.980 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T19:22:06.980Z,1720639326.980 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T19:22:06.980Z,1720639326.980 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T19:22:07.744Z,1720639327.744 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T19:27:07.556Z,1720639627.556 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T19:27:07.556Z,1720639627.556 [Default:CheckIn:C.Wait] Stopped 2024-07-10T19:27:07.556Z,1720639627.556 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T19:27:07.556Z,1720639627.556 [Default:CheckIn:D] Running Loop=1 2024-07-10T19:27:07.921Z,1720639627.921 [Default:CheckIn:D] Stopped 2024-07-10T19:27:07.921Z,1720639627.921 [Default:CheckIn:E] Running Loop=1 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.245475 min 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn:E] Stopped 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn] Stopped 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn](INFO): Running loop #40 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn] Running Loop=40 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T19:27:08.339Z,1720639628.339 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T19:27:10.345Z,1720639630.345 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192709.00,A,3648.16838,N,12147.28443,W,0.350,155.00,100724,,,A*7A 2024-07-10T19:27:10.347Z,1720639630.347 [NAL9602](INFO): GPS fix at 20240710T192709: (36.802806, -121.788074) 2024-07-10T19:27:10.380Z,1720639630.380 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T19:27:10.380Z,1720639630.380 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T19:27:20.070Z,1720639640.070 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240710T153524/Courier0124.lzma 2024-07-10T19:27:21.073Z,1720639641.073 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0124.lzma.bak 2024-07-10T19:27:21.073Z,1720639641.073 [DataOverHttps](INFO): SBD MOMSN=21349237 2024-07-10T19:27:44.378Z,1720639664.378 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240710T153524/Express0125.lzma 2024-07-10T19:27:45.381Z,1720639665.381 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0125.lzma.bak 2024-07-10T19:27:45.381Z,1720639665.381 [DataOverHttps](INFO): SBD MOMSN=21349240 2024-07-10T19:27:51.585Z,1720639671.585 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T19:27:51.585Z,1720639671.585 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T19:27:51.586Z,1720639671.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T19:28:54.573Z,1720639734.573 [NAL9602](INFO): SBD MO Status=2, MOMSN=5116, MT Status=2, MTMSN=0 2024-07-10T19:28:54.573Z,1720639734.573 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T19:29:55.573Z,1720639795.573 [NAL9602](INFO): SBD MO Status=2, MOMSN=5116, MT Status=2, MTMSN=0 2024-07-10T19:29:55.573Z,1720639795.573 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T19:30:12.545Z,1720639812.545 [NAL9602](INFO): SBD MO Status=2, MOMSN=5116, MT Status=2, MTMSN=0 2024-07-10T19:30:12.545Z,1720639812.545 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-07-10T19:31:56.959Z,1720639916.959 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 20 millimeter persist 2024-07-10T19:32:12.972Z,1720639932.972 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-07-10T19:32:43.683Z,1720639963.683 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-10T19:32:52.202Z,1720639972.202 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-07-10T19:32:52.202Z,1720639972.202 [Default:CheckIn:C.Wait] Stopped 2024-07-10T19:32:52.202Z,1720639972.202 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T19:32:52.202Z,1720639972.202 [Default:CheckIn:D] Running Loop=1 2024-07-10T19:32:52.606Z,1720639972.606 [Default:CheckIn:D] Stopped 2024-07-10T19:32:52.606Z,1720639972.606 [Default:CheckIn:E] Running Loop=1 2024-07-10T19:32:53.002Z,1720639973.002 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 233.990218 min 2024-07-10T19:32:53.002Z,1720639973.002 [Default:CheckIn:E] Stopped 2024-07-10T19:32:53.003Z,1720639973.003 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-07-10T19:32:53.003Z,1720639973.003 [Default:CheckIn] Stopped 2024-07-10T19:32:53.003Z,1720639973.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T19:32:53.003Z,1720639973.003 [Default:CheckIn](INFO): Running loop #41 2024-07-10T19:32:53.003Z,1720639973.003 [Default:CheckIn] Running Loop=41 2024-07-10T19:32:53.003Z,1720639973.003 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-10T19:32:53.003Z,1720639973.003 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-10T19:32:54.997Z,1720639974.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193254.00,A,3648.18430,N,12147.27923,W,0.680,37.92,100724,,,A*4E 2024-07-10T19:32:54.999Z,1720639974.999 [NAL9602](INFO): GPS fix at 20240710T193254: (36.803072, -121.787987) 2024-07-10T19:32:55.067Z,1720639975.067 [Default:CheckIn:Read_GPS] Stopped 2024-07-10T19:32:55.067Z,1720639975.067 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-10T19:33:05.183Z,1720639985.183 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240710T153524/Courier0127.lzma 2024-07-10T19:33:05.253Z,1720639985.253 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Courier0127.lzma.bak 2024-07-10T19:33:05.253Z,1720639985.253 [DataOverHttps](INFO): SBD MOMSN=21349266 2024-07-10T19:33:16.494Z,1720639996.494 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 350 cubic_centimeter 2024-07-10T19:33:25.830Z,1720640005.830 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-07-10T19:33:25.906Z,1720640005.906 [NAL9602](ERROR): received: +CSQ:0 OK16, 2, 0, 0, 0 OK 2024-07-10T19:33:30.952Z,1720640010.952 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 350 cubic_centimeter persist 2024-07-10T19:33:32.822Z,1720640012.822 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20240710T153524/Express0128.lzma 2024-07-10T19:33:33.825Z,1720640013.825 [DataOverHttps](INFO): Moved sent file to Logs/20240710T153524/Express0128.lzma.bak 2024-07-10T19:33:33.825Z,1720640013.825 [DataOverHttps](INFO): SBD MOMSN=21349269 2024-07-10T19:33:41.191Z,1720640021.191 [Default:CheckIn:Read_Iridium] Stopped 2024-07-10T19:33:41.191Z,1720640021.191 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-10T19:33:41.194Z,1720640021.194 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-10T19:33:41.392Z,1720640021.392 [CommandExec](IMPORTANT): got command configSet list 2024-07-10T19:33:41.392Z,1720640021.392 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-07-10T19:33:41.393Z,1720640021.393 [CommandExec](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2024-07-10T19:33:41.394Z,1720640021.394 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2024-07-10T19:33:41.394Z,1720640021.394 [CommandExec](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-07-10T19:33:41.395Z,1720640021.395 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=350 cubic_centimeter; 2024-07-10T19:33:41.396Z,1720640021.396 [CommandExec](IMPORTANT): VerticalControl.massDefault=20 millimeter; 2024-07-10T19:34:01.467Z,1720640041.467 [CommandExec](IMPORTANT): got command quit 2024-07-10T19:34:02.470Z,1720640042.470 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-07-10T19:34:02.471Z,1720640042.471 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:02.471Z,1720640042.471 [CommandExec](INFO): Uninitializing the command executive. 2024-07-10T19:34:02.471Z,1720640042.471 [CommandExec](INFO): Uninitializing the command scheduler. 2024-07-10T19:34:02.471Z,1720640042.471 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:02.654Z,1720640042.654 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-07-10T19:34:02.654Z,1720640042.654 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-07-10T19:34:02.655Z,1720640042.655 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:02.655Z,1720640042.655 [NavChartDb](INFO): Join timeout helper Thread ID is 1298 2024-07-10T19:34:02.979Z,1720640042.979 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:02.979Z,1720640042.979 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:02.987Z,1720640042.987 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-07-10T19:34:02.987Z,1720640042.987 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:02.988Z,1720640042.988 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1299 2024-07-10T19:34:03.187Z,1720640043.187 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:03.187Z,1720640043.187 [WetLabsBB2FL](INFO): Powering down 2024-07-10T19:34:03.188Z,1720640043.188 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:03.190Z,1720640043.190 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-07-10T19:34:03.191Z,1720640043.191 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:03.191Z,1720640043.191 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1300 2024-07-10T19:34:03.515Z,1720640043.515 [CTD_Seabird](INFO): Powering down 2024-07-10T19:34:03.526Z,1720640043.526 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:03.527Z,1720640043.527 [CTD_Seabird](INFO): Powering down 2024-07-10T19:34:03.538Z,1720640043.538 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:03.551Z,1720640043.551 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-07-10T19:34:03.551Z,1720640043.551 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:03.552Z,1720640043.552 [Radio_Surface](INFO): Join timeout helper Thread ID is 1301 2024-07-10T19:34:03.882Z,1720640043.882 [Radio_Surface](INFO): Powering down 2024-07-10T19:34:03.883Z,1720640043.883 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:03.884Z,1720640043.884 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:03.894Z,1720640043.894 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-07-10T19:34:03.894Z,1720640043.894 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:03.895Z,1720640043.895 [Onboard](INFO): Join timeout helper Thread ID is 1302 2024-07-10T19:34:04.347Z,1720640044.347 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-07-10T19:34:05.814Z,1720640045.814 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:05.815Z,1720640045.815 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:05.817Z,1720640045.817 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-07-10T19:34:05.817Z,1720640045.817 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:05.817Z,1720640045.817 [DataOverHttps](INFO): Join timeout helper Thread ID is 1303 2024-07-10T19:34:06.354Z,1720640046.354 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:06.355Z,1720640046.355 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.363Z,1720640046.363 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-07-10T19:34:06.363Z,1720640046.363 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.364Z,1720640046.364 [BackseatComponent](INFO): Join timeout helper Thread ID is 1304 2024-07-10T19:34:06.527Z,1720640046.527 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:06.527Z,1720640046.527 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.531Z,1720640046.531 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-07-10T19:34:06.531Z,1720640046.531 [logger ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.532Z,1720640046.532 [logger](INFO): Join timeout helper Thread ID is 1305 2024-07-10T19:34:06.574Z,1720640046.574 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:06.575Z,1720640046.575 [logger ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.591Z,1720640046.591 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-07-10T19:34:06.591Z,1720640046.591 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.592Z,1720640046.592 [CommandLine](INFO): Join timeout helper Thread ID is 1306 2024-07-10T19:34:06.635Z,1720640046.635 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:06.635Z,1720640046.635 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.643Z,1720640046.643 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-07-10T19:34:06.643Z,1720640046.643 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.644Z,1720640046.644 [CommandExec](INFO): Join timeout helper Thread ID is 1307 2024-07-10T19:34:06.646Z,1720640046.646 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-07-10T19:34:06.646Z,1720640046.646 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:06.646Z,1720640046.646 [controlThread](INFO): Join timeout helper Thread ID is 1308 2024-07-10T19:34:07.007Z,1720640047.007 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-07-10T19:34:07.007Z,1720640047.007 [controlThread](DEBUG): Uninitializing ControlThread 2024-07-10T19:34:07.007Z,1720640047.007 [AHRS_M2](INFO): Powering down 2024-07-10T19:34:07.079Z,1720640047.079 [NAL9602](INFO): Powering down 2024-07-10T19:34:07.081Z,1720640047.081 [Sonardyne_Nano](INFO): Powering down 2024-07-10T19:34:07.199Z,1720640047.199 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-07-10T19:34:07.200Z,1720640047.200 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-07-10T19:34:07.200Z,1720640047.200 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-07-10T19:34:07.201Z,1720640047.201 [MissionManager](INFO): Uninitializing Mission Default 2024-07-10T19:34:07.201Z,1720640047.201 [Default] Stopped 2024-07-10T19:34:07.201Z,1720640047.201 [Default](DEBUG): Aggregate::uninitialize Default 2024-07-10T19:34:07.201Z,1720640047.201 [Default:B.GoToSurface] Stopped 2024-07-10T19:34:07.201Z,1720640047.201 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-07-10T19:34:07.201Z,1720640047.201 [Default:CheckIn] Stopped 2024-07-10T19:34:07.201Z,1720640047.201 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-07-10T19:34:07.201Z,1720640047.201 [Default:CheckIn:C.Wait] Stopped 2024-07-10T19:34:07.201Z,1720640047.201 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-07-10T19:34:07.204Z,1720640047.204 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-07-10T19:34:07.205Z,1720640047.205 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-07-10T19:34:07.205Z,1720640047.205 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-07-10T19:34:07.205Z,1720640047.205 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-07-10T19:34:07.206Z,1720640047.206 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-07-10T19:34:07.206Z,1720640047.206 [BuoyancyServo](INFO): Powering down 2024-07-10T19:34:07.219Z,1720640047.219 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-07-10T19:34:07.219Z,1720640047.219 [ElevatorServo](INFO): Powering down 2024-07-10T19:34:07.220Z,1720640047.220 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-07-10T19:34:07.220Z,1720640047.220 [MassServo](INFO): Powering down 2024-07-10T19:34:07.221Z,1720640047.221 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-07-10T19:34:07.221Z,1720640047.221 [RudderServo](INFO): Powering down 2024-07-10T19:34:07.222Z,1720640047.222 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-07-10T19:34:07.222Z,1720640047.222 [ThrusterHE](INFO): Powering down 2024-07-10T19:34:07.223Z,1720640047.223 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-07-10T19:34:07.223Z,1720640047.223 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-07-10T19:34:07.224Z,1720640047.224 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-07-10T19:34:07.224Z,1720640047.224 [CBIT](DEBUG): Powering off loads. 2024-07-10T19:34:07.234Z,1720640047.234 [CBIT](DEBUG): Disabling WDT. 2024-07-10T19:34:07.246Z,1720640047.246 [CBIT](DEBUG): Opening all GF detection circuits. 2024-07-10T19:34:07.247Z,1720640047.247 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:07.309Z,1720640047.309 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:07.312Z,1720640047.312 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:07.318Z,1720640047.318 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:07.361Z,1720640047.361 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:07.400Z,1720640047.400 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:07.404Z,1720640047.404 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:07.437Z,1720640047.437 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-07-10T19:34:07.508Z,1720640047.508 [logger ThreadHandler](INFO): Thread cancelled.