2024-09-19T21:29:24.304Z,1726781364.304 [Supervisor](DEBUG): Initializing supervisor. 2024-09-19T21:29:24.308Z,1726781364.308 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-09-19T21:29:24.308Z,1726781364.308 [SyncHandler](INFO): Protected caller Thread ID is 1630 2024-09-19T21:29:24.309Z,1726781364.309 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-09-19T21:29:24.310Z,1726781364.310 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-09-19T21:29:24.310Z,1726781364.310 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1631 2024-09-19T21:29:24.314Z,1726781364.314 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-09-19T21:29:24.332Z,1726781364.332 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-09-19T21:29:24.333Z,1726781364.333 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-09-19T21:29:24.334Z,1726781364.334 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1632 2024-09-19T21:29:24.338Z,1726781364.338 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-09-19T21:29:24.339Z,1726781364.339 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-09-19T21:29:24.339Z,1726781364.339 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1633 2024-09-19T21:29:24.341Z,1726781364.341 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-09-19T21:29:24.342Z,1726781364.342 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-09-19T21:29:24.343Z,1726781364.343 [logger ThreadHandler](INFO): Protected caller Thread ID is 1634 2024-09-19T21:29:24.346Z,1726781364.346 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-09-19T21:29:24.347Z,1726781364.347 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-09-19T21:29:24.348Z,1726781364.348 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-09-19T21:29:24.459Z,1726781364.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-09-19T21:29:24.459Z,1726781364.459 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-09-19T21:29:24.721Z,1726781364.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-09-19T21:29:24.722Z,1726781364.722 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-09-19T21:29:25.018Z,1726781365.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-09-19T21:29:25.018Z,1726781365.018 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-09-19T21:29:25.142Z,1726781365.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-09-19T21:29:25.143Z,1726781365.143 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-09-19T21:29:26.043Z,1726781366.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-09-19T21:29:26.044Z,1726781366.044 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-09-19T21:29:26.737Z,1726781366.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-09-19T21:29:26.738Z,1726781366.738 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-09-19T21:29:27.330Z,1726781367.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-09-19T21:29:27.331Z,1726781367.331 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-09-19T21:29:27.795Z,1726781367.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-09-19T21:29:27.796Z,1726781367.796 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-09-19T21:29:28.178Z,1726781368.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-09-19T21:29:28.179Z,1726781368.179 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-09-19T21:29:28.599Z,1726781368.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-09-19T21:29:28.599Z,1726781368.599 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-09-19T21:29:28.856Z,1726781368.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-09-19T21:29:28.857Z,1726781368.857 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-09-19T21:29:29.014Z,1726781369.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-09-19T21:29:29.014Z,1726781369.014 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-09-19T21:29:29.109Z,1726781369.109 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-09-19T21:29:29.202Z,1726781369.202 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-09-19T21:29:29.417Z,1726781369.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-09-19T21:29:29.417Z,1726781369.417 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-09-19T21:29:29.675Z,1726781369.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-09-19T21:29:29.676Z,1726781369.676 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2024-09-19T21:29:29.677Z,1726781369.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2024-09-19T21:29:29.774Z,1726781369.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2024-09-19T21:29:30.053Z,1726781370.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-09-19T21:29:30.053Z,1726781370.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2024-09-19T21:29:30.163Z,1726781370.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2024-09-19T21:29:30.351Z,1726781370.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2024-09-19T21:29:30.465Z,1726781370.465 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2024-09-19T21:29:30.593Z,1726781370.593 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2024-09-19T21:29:30.722Z,1726781370.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2024-09-19T21:29:30.845Z,1726781370.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2024-09-19T21:29:31.030Z,1726781371.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2024-09-19T21:29:31.274Z,1726781371.274 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg 2024-09-19T21:29:31.372Z,1726781371.372 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2024-09-19T21:29:31.637Z,1726781371.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2024-09-19T21:29:32.141Z,1726781372.141 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-09-19T21:29:32.157Z,1726781372.157 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-09-19T21:29:32.331Z,1726781372.331 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-09-19T21:29:32.332Z,1726781372.332 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-09-19T21:29:32.490Z,1726781372.490 [BuoyancyServo] Loaded 2024-09-19T21:29:32.491Z,1726781372.491 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-09-19T21:29:32.514Z,1726781372.514 [ElevatorServo] Loaded 2024-09-19T21:29:32.514Z,1726781372.514 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-09-19T21:29:32.536Z,1726781372.536 [MassServo] Loaded 2024-09-19T21:29:32.537Z,1726781372.537 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-09-19T21:29:32.558Z,1726781372.558 [RudderServo] Loaded 2024-09-19T21:29:32.558Z,1726781372.558 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-09-19T21:29:32.576Z,1726781372.576 [ThrusterHE] Loaded 2024-09-19T21:29:32.576Z,1726781372.576 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-09-19T21:29:32.576Z,1726781372.576 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-09-19T21:29:32.577Z,1726781372.577 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-09-19T21:29:32.717Z,1726781372.717 [SBIT](DEBUG): Construct Startup Built In Test. 2024-09-19T21:29:32.732Z,1726781372.732 [SBIT] Loaded 2024-09-19T21:29:32.732Z,1726781372.732 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-09-19T21:29:32.735Z,1726781372.735 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-09-19T21:29:32.756Z,1726781372.756 [IBIT] Loaded 2024-09-19T21:29:32.756Z,1726781372.756 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-09-19T21:29:32.762Z,1726781372.762 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-09-19T21:29:32.981Z,1726781372.981 [CBIT] Loaded 2024-09-19T21:29:32.981Z,1726781372.981 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-09-19T21:29:32.981Z,1726781372.981 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-09-19T21:29:32.982Z,1726781372.982 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-09-19T21:29:33.152Z,1726781373.152 [CTD_Seabird] Loaded 2024-09-19T21:29:33.152Z,1726781373.152 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-09-19T21:29:33.154Z,1726781373.154 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0 2024-09-19T21:29:33.154Z,1726781373.154 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1722 2024-09-19T21:29:33.188Z,1726781373.188 [ESPComponent] Loaded 2024-09-19T21:29:33.189Z,1726781373.189 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-09-19T21:29:33.210Z,1726781373.210 [PAR_Licor] Loaded 2024-09-19T21:29:33.210Z,1726781373.210 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-09-19T21:29:33.254Z,1726781373.254 [WetLabsBB2FL] Loaded 2024-09-19T21:29:33.254Z,1726781373.254 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-09-19T21:29:33.255Z,1726781373.255 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0 2024-09-19T21:29:33.255Z,1726781373.255 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1723 2024-09-19T21:29:33.256Z,1726781373.256 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-09-19T21:29:33.257Z,1726781373.257 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-09-19T21:29:33.307Z,1726781373.307 [DepthRateCalculator] Loaded 2024-09-19T21:29:33.307Z,1726781373.307 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-09-19T21:29:33.313Z,1726781373.313 [PitchRateCalculator] Loaded 2024-09-19T21:29:33.313Z,1726781373.313 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-09-19T21:29:33.323Z,1726781373.323 [SpeedCalculator] Loaded 2024-09-19T21:29:33.323Z,1726781373.323 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-09-19T21:29:33.328Z,1726781373.328 [YawRateCalculator] Loaded 2024-09-19T21:29:33.328Z,1726781373.328 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-09-19T21:29:33.348Z,1726781373.348 [ElevatorOffsetCalculator] Loaded 2024-09-19T21:29:33.348Z,1726781373.348 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-09-19T21:29:33.349Z,1726781373.349 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-09-19T21:29:33.349Z,1726781373.349 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-09-19T21:29:33.460Z,1726781373.460 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-09-19T21:29:33.460Z,1726781373.460 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-09-19T21:29:33.487Z,1726781373.487 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-09-19T21:29:33.487Z,1726781373.487 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-09-19T21:29:33.597Z,1726781373.597 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-09-19T21:29:33.598Z,1726781373.598 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-09-19T21:29:33.611Z,1726781373.611 [NavChart] Loaded 2024-09-19T21:29:33.612Z,1726781373.612 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-09-19T21:29:33.617Z,1726781373.617 [UniversalFixResidualReporter] Loaded 2024-09-19T21:29:33.617Z,1726781373.617 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-09-19T21:29:33.618Z,1726781373.618 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-09-19T21:29:33.619Z,1726781373.619 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-09-19T21:29:34.075Z,1726781374.075 [AHRS_M2] Loaded 2024-09-19T21:29:34.075Z,1726781374.075 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-09-19T21:29:34.113Z,1726781374.113 [BackseatComponent] Loaded 2024-09-19T21:29:34.113Z,1726781374.113 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-09-19T21:29:34.114Z,1726781374.114 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A694E0 2024-09-19T21:29:34.115Z,1726781374.115 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1724 2024-09-19T21:29:34.117Z,1726781374.117 [LcmUniversalReporter] Loaded 2024-09-19T21:29:34.118Z,1726781374.118 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-09-19T21:29:34.847Z,1726781374.847 [BPC1] Loaded 2024-09-19T21:29:34.847Z,1726781374.847 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-09-19T21:29:34.936Z,1726781374.936 [DATMMP] Loaded 2024-09-19T21:29:34.936Z,1726781374.936 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-09-19T21:29:34.938Z,1726781374.938 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0 2024-09-19T21:29:34.938Z,1726781374.938 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1725 2024-09-19T21:29:35.011Z,1726781375.011 [DataOverHttps] Loaded 2024-09-19T21:29:35.011Z,1726781375.011 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-09-19T21:29:35.012Z,1726781375.012 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0 2024-09-19T21:29:35.012Z,1726781375.012 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1726 2024-09-19T21:29:35.035Z,1726781375.035 [Depth_Keller] Loaded 2024-09-19T21:29:35.036Z,1726781375.036 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-09-19T21:29:35.097Z,1726781375.097 [NAL9602] Loaded 2024-09-19T21:29:35.097Z,1726781375.097 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-09-19T21:29:35.123Z,1726781375.123 [Onboard] Loaded 2024-09-19T21:29:35.123Z,1726781375.123 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-09-19T21:29:35.124Z,1726781375.124 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0 2024-09-19T21:29:35.125Z,1726781375.125 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1727 2024-09-19T21:29:35.139Z,1726781375.139 [Power24vConverter] Loaded 2024-09-19T21:29:35.139Z,1726781375.139 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-09-19T21:29:35.154Z,1726781375.154 [Radio_Surface] Loaded 2024-09-19T21:29:35.154Z,1726781375.154 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-09-19T21:29:35.155Z,1726781375.155 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B2A4E0 2024-09-19T21:29:35.156Z,1726781375.156 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1728 2024-09-19T21:29:35.171Z,1726781375.171 [Sonardyne_Nano] Loaded 2024-09-19T21:29:35.171Z,1726781375.171 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-09-19T21:29:35.172Z,1726781375.172 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-09-19T21:29:35.172Z,1726781375.172 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-09-19T21:29:35.242Z,1726781375.242 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-09-19T21:29:35.242Z,1726781375.242 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-09-19T21:29:35.295Z,1726781375.295 [VerticalControl](DEBUG): Construct VerticalControl. 2024-09-19T21:29:35.345Z,1726781375.345 [VerticalControl] Loaded 2024-09-19T21:29:35.346Z,1726781375.346 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-09-19T21:29:35.348Z,1726781375.348 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-09-19T21:29:35.393Z,1726781375.393 [HorizontalControl] Loaded 2024-09-19T21:29:35.393Z,1726781375.393 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-09-19T21:29:35.395Z,1726781375.395 [SpeedControl](DEBUG): Construct SpeedControl. 2024-09-19T21:29:35.397Z,1726781375.397 [SpeedControl] Loaded 2024-09-19T21:29:35.397Z,1726781375.397 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-09-19T21:29:35.400Z,1726781375.400 [LoopControl](DEBUG): Construct LoopControl. 2024-09-19T21:29:35.401Z,1726781375.401 [LoopControl] Loaded 2024-09-19T21:29:35.401Z,1726781375.401 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-09-19T21:29:35.401Z,1726781375.401 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-09-19T21:29:35.402Z,1726781375.402 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-09-19T21:29:35.412Z,1726781375.412 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-09-19T21:29:35.413Z,1726781375.413 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-09-19T21:29:35.749Z,1726781375.749 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-09-19T21:29:35.756Z,1726781375.756 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-09-19T21:29:35.759Z,1726781375.759 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-09-19T21:29:35.770Z,1726781375.770 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-09-19T21:29:35.771Z,1726781375.771 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0 2024-09-19T21:29:35.771Z,1726781375.771 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1729 2024-09-19T21:29:35.776Z,1726781375.776 [Supervisor](INFO): Main Thread ID is 822 2024-09-19T21:29:35.776Z,1726781375.776 [Supervisor](DEBUG): Running supervisor. 2024-09-19T21:29:35.777Z,1726781375.777 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1730 2024-09-19T21:29:35.777Z,1726781375.777 [CommandExec](INFO): Initializing the command executive. 2024-09-19T21:29:35.779Z,1726781375.779 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1731 2024-09-19T21:29:35.780Z,1726781375.780 [controlThread ThreadHandler](INFO): Handler Thread ID is 1732 2024-09-19T21:29:35.781Z,1726781375.781 [controlThread](DEBUG): Initializing ControlThread 2024-09-19T21:29:35.783Z,1726781375.783 [SBIT](INFO): Initialize SBIT Component. 2024-09-19T21:29:35.783Z,1726781375.783 [SBIT](IMPORTANT): git: 2024-09-17 2024-09-19T21:29:35.784Z,1726781375.784 [SBIT](INFO): git hash: 40e9ce87bff78b617219447905233bfcf6062b37 2024-09-19T21:29:35.784Z,1726781375.784 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-09-19T21:29:35.785Z,1726781375.785 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-09-19T21:29:35.786Z,1726781375.786 [SBIT](INFO): Beginning SBIT in 72.000000 seconds. 2024-09-19T21:29:35.787Z,1726781375.787 [IBIT](INFO): Initialize IBIT Component. 2024-09-19T21:29:35.788Z,1726781375.788 [CBIT](DEBUG): Initialize CBIT Component. 2024-09-19T21:29:35.789Z,1726781375.789 [logger ThreadHandler](INFO): Handler Thread ID is 1733 2024-09-19T21:29:35.801Z,1726781375.801 [CBIT](DEBUG): Initialized mux pins. 2024-09-19T21:29:35.801Z,1726781375.801 [CBIT](DEBUG): Initializing the watchdog timer. 2024-09-19T21:29:35.813Z,1726781375.813 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1734 2024-09-19T21:29:35.814Z,1726781375.814 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-09-19T21:29:35.819Z,1726781375.819 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1736 2024-09-19T21:29:35.821Z,1726781375.821 [WetLabsBB2FL](INFO): Powering up 2024-09-19T21:29:35.823Z,1726781375.823 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1738 2024-09-19T21:29:35.825Z,1726781375.825 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-09-19T21:29:35.826Z,1726781375.826 [CBIT](DEBUG): Initializing heartbeat. 2024-09-19T21:29:35.841Z,1726781375.841 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1739 2024-09-19T21:29:35.849Z,1726781375.849 [DATMMP](INFO): Start 2024-09-19T21:29:35.849Z,1726781375.849 [DATMMP](INFO): Powering up 2024-09-19T21:29:35.850Z,1726781375.850 [DATMMP](DEBUG): Initializing DATMMP. 2024-09-19T21:29:35.851Z,1726781375.851 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1741 2024-09-19T21:29:35.852Z,1726781375.852 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-09-19T21:29:35.862Z,1726781375.862 [Onboard ThreadHandler](INFO): Handler Thread ID is 1742 2024-09-19T21:29:35.879Z,1726781375.879 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1743 2024-09-19T21:29:35.897Z,1726781375.897 [CBIT](DEBUG): Deactivating GF circuits. 2024-09-19T21:29:35.897Z,1726781375.897 [CBIT](DEBUG): Deactivating emergency mode. 2024-09-19T21:29:35.898Z,1726781375.898 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1744 2024-09-19T21:29:35.900Z,1726781375.900 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-09-19T21:29:35.900Z,1726781375.900 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-09-19T21:29:35.901Z,1726781375.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-09-19T21:29:35.901Z,1726781375.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-09-19T21:29:35.901Z,1726781375.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-09-19T21:29:35.901Z,1726781375.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-09-19T21:29:35.901Z,1726781375.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-09-19T21:29:35.901Z,1726781375.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-09-19T21:29:35.937Z,1726781375.937 [CBIT](DEBUG): Backplane powered. 2024-09-19T21:29:35.941Z,1726781375.941 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-09-19T21:29:35.942Z,1726781375.942 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-09-19T21:29:35.942Z,1726781375.942 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-09-19T21:29:35.942Z,1726781375.942 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-09-19T21:29:35.943Z,1726781375.943 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-09-19T21:29:35.944Z,1726781375.944 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-09-19T21:29:35.944Z,1726781375.944 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-09-19T21:29:35.950Z,1726781375.950 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-09-19T21:29:35.952Z,1726781375.952 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-09-19T21:29:35.952Z,1726781375.952 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-09-19T21:29:35.953Z,1726781375.953 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-09-19T21:29:35.954Z,1726781375.954 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-09-19T21:29:35.955Z,1726781375.955 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-09-19T21:29:35.955Z,1726781375.955 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-09-19T21:29:35.956Z,1726781375.956 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-09-19T21:29:36.016Z,1726781376.016 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-09-19T21:29:36.041Z,1726781376.041 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-09-19T21:29:36.083Z,1726781376.083 [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-09-19T21:29:36.083Z,1726781376.083 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-09-19T21:29:36.084Z,1726781376.084 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-09-19T21:29:36.085Z,1726781376.085 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-09-19T21:29:36.101Z,1726781376.101 [DATMMP](INFO): Starting 2024-09-19T21:29:36.285Z,1726781376.285 [Radio_Surface](INFO): Powering up 2024-09-19T21:29:36.353Z,1726781376.353 [DATMMP](INFO): Starting 2024-09-19T21:29:36.363Z,1726781376.363 [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-09-19T21:29:36.393Z,1726781376.393 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-09-19T21:29:36.394Z,1726781376.394 [Default:A.Wait](DEBUG): Construct Wait. 2024-09-19T21:29:36.397Z,1726781376.397 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-09-19T21:29:36.458Z,1726781376.458 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-09-19T21:29:36.460Z,1726781376.460 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-09-19T21:29:36.495Z,1726781376.495 [Default:E.Execute](DEBUG): Construct Execute. 2024-09-19T21:29:36.498Z,1726781376.498 [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-09-19T21:29:36.509Z,1726781376.509 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,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-09-19T21:29:36.542Z,1726781376.542 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-09-19T21:29:36.605Z,1726781376.605 [DATMMP](INFO): Starting 2024-09-19T21:29:36.699Z,1726781376.699 [Power24vConverter](INFO): Powering up. 2024-09-19T21:29:36.700Z,1726781376.700 [Sonardyne_Nano](INFO): Initializing. 2024-09-19T21:29:36.763Z,1726781376.763 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-09-19T21:29:36.769Z,1726781376.769 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-09-19T21:29:36.770Z,1726781376.770 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-09-19T21:29:36.778Z,1726781376.778 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-09-19T21:29:36.778Z,1726781376.778 [MassServo](DEBUG): Initializing EZServoServo. 2024-09-19T21:29:36.785Z,1726781376.785 [MassServo](DEBUG): Initializing MassServo. 2024-09-19T21:29:36.786Z,1726781376.786 [RudderServo](DEBUG): Initializing EZServoServo. 2024-09-19T21:29:36.793Z,1726781376.793 [RudderServo](DEBUG): Initializing RudderServo. 2024-09-19T21:29:36.794Z,1726781376.794 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-09-19T21:29:36.801Z,1726781376.801 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-09-19T21:29:36.857Z,1726781376.857 [DATMMP](INFO): Starting 2024-09-19T21:29:37.109Z,1726781377.109 [DATMMP](INFO): Starting 2024-09-19T21:29:37.361Z,1726781377.361 [DATMMP](INFO): Starting 2024-09-19T21:29:37.613Z,1726781377.613 [DATMMP](INFO): Starting 2024-09-19T21:29:37.865Z,1726781377.865 [DATMMP](INFO): Starting 2024-09-19T21:29:37.929Z,1726781377.929 [WetLabsBB2FL](INFO): Powering down 2024-09-19T21:29:38.117Z,1726781378.117 [DATMMP](INFO): Starting 2024-09-19T21:29:38.369Z,1726781378.369 [DATMMP](INFO): Starting 2024-09-19T21:29:38.621Z,1726781378.621 [DATMMP](INFO): Starting 2024-09-19T21:29:38.873Z,1726781378.873 [DATMMP](INFO): Starting 2024-09-19T21:29:38.947Z,1726781378.947 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-09-19T21:29:39.125Z,1726781379.125 [DATMMP](INFO): Starting 2024-09-19T21:29:39.378Z,1726781379.378 [DATMMP](INFO): Starting 2024-09-19T21:29:39.629Z,1726781379.629 [DATMMP](INFO): Starting 2024-09-19T21:29:39.881Z,1726781379.881 [DATMMP](INFO): Starting 2024-09-19T21:29:40.133Z,1726781380.133 [DATMMP](INFO): Starting 2024-09-19T21:29:40.185Z,1726781380.185 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2024-09-19T21:29:40.385Z,1726781380.385 [DATMMP](INFO): Starting 2024-09-19T21:29:40.637Z,1726781380.637 [DATMMP](INFO): Starting 2024-09-19T21:29:40.889Z,1726781380.889 [DATMMP](INFO): Starting 2024-09-19T21:29:41.142Z,1726781381.142 [DATMMP](INFO): Starting 2024-09-19T21:29:41.393Z,1726781381.393 [DATMMP](INFO): Starting 2024-09-19T21:29:41.645Z,1726781381.645 [DATMMP](INFO): Starting 2024-09-19T21:29:41.897Z,1726781381.897 [DATMMP](INFO): Starting 2024-09-19T21:29:42.149Z,1726781382.149 [DATMMP](INFO): Starting 2024-09-19T21:29:42.274Z,1726781382.274 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-09-19T21:29:42.401Z,1726781382.401 [DATMMP](INFO): Starting 2024-09-19T21:29:42.654Z,1726781382.654 [DATMMP](INFO): Starting 2024-09-19T21:29:42.905Z,1726781382.905 [DATMMP](INFO): Starting 2024-09-19T21:29:43.157Z,1726781383.157 [DATMMP](INFO): Starting 2024-09-19T21:29:43.409Z,1726781383.409 [DATMMP](INFO): Starting 2024-09-19T21:29:43.661Z,1726781383.661 [DATMMP](INFO): Starting 2024-09-19T21:29:43.913Z,1726781383.913 [DATMMP](INFO): Starting 2024-09-19T21:29:44.165Z,1726781384.165 [DATMMP](INFO): Starting 2024-09-19T21:29:44.417Z,1726781384.417 [DATMMP](INFO): Starting 2024-09-19T21:29:44.669Z,1726781384.669 [DATMMP](INFO): Starting 2024-09-19T21:29:44.921Z,1726781384.921 [DATMMP](INFO): Starting 2024-09-19T21:29:45.173Z,1726781385.173 [DATMMP](INFO): Starting 2024-09-19T21:29:45.425Z,1726781385.425 [DATMMP](INFO): Starting 2024-09-19T21:29:45.677Z,1726781385.677 [DATMMP](INFO): Starting 2024-09-19T21:29:45.929Z,1726781385.929 [DATMMP](INFO): Starting 2024-09-19T21:29:46.181Z,1726781386.181 [DATMMP](INFO): Starting 2024-09-19T21:29:46.433Z,1726781386.433 [DATMMP](INFO): Starting 2024-09-19T21:29:46.685Z,1726781386.685 [DATMMP](INFO): Starting 2024-09-19T21:29:46.937Z,1726781386.937 [DATMMP](INFO): Starting 2024-09-19T21:29:47.189Z,1726781387.189 [DATMMP](INFO): Starting 2024-09-19T21:29:47.441Z,1726781387.441 [DATMMP](INFO): Starting 2024-09-19T21:29:47.693Z,1726781387.693 [DATMMP](INFO): Starting 2024-09-19T21:29:47.945Z,1726781387.945 [DATMMP](INFO): Starting 2024-09-19T21:29:47.946Z,1726781387.946 [DATMMP](INFO): DAT read: 2024-09-19T21:29:47.947Z,1726781387.947 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-09-19T21:29:48.197Z,1726781388.197 [DATMMP](INFO): Starting 2024-09-19T21:29:48.449Z,1726781388.449 [DATMMP](INFO): Starting 2024-09-19T21:29:48.701Z,1726781388.701 [DATMMP](INFO): Starting 2024-09-19T21:29:48.953Z,1726781388.953 [DATMMP](INFO): Starting 2024-09-19T21:29:49.205Z,1726781389.205 [DATMMP](INFO): Starting 2024-09-19T21:29:49.457Z,1726781389.457 [DATMMP](INFO): Starting 2024-09-19T21:29:49.709Z,1726781389.709 [DATMMP](INFO): Starting 2024-09-19T21:29:49.710Z,1726781389.710 [DATMMP](INFO): DAT read: MF Frequency Band 2024-09-19T21:29:49.711Z,1726781389.711 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-09-19T21:29:49.712Z,1726781389.712 [DATMMP](INFO): DAT read: Aug 19 2000 21:29:46 2024-09-19T21:29:49.961Z,1726781389.961 [DATMMP](INFO): Starting 2024-09-19T21:29:50.213Z,1726781390.213 [DATMMP](INFO): Starting 2024-09-19T21:29:50.465Z,1726781390.465 [DATMMP](INFO): Starting 2024-09-19T21:29:50.717Z,1726781390.717 [DATMMP](INFO): Starting 2024-09-19T21:29:50.718Z,1726781390.718 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-09-19T21:29:50.719Z,1726781390.719 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-09-19T21:29:50.719Z,1726781390.719 [DATMMP](INFO): commRate: 1200 2024-09-19T21:29:50.969Z,1726781390.969 [DATMMP](INFO): Starting 2024-09-19T21:29:51.221Z,1726781391.221 [DATMMP](INFO): Starting 2024-09-19T21:29:51.473Z,1726781391.473 [DATMMP](INFO): Starting 2024-09-19T21:29:51.725Z,1726781391.725 [DATMMP](INFO): Starting 2024-09-19T21:29:51.977Z,1726781391.977 [DATMMP](INFO): Starting 2024-09-19T21:29:52.229Z,1726781392.229 [DATMMP](INFO): Starting 2024-09-19T21:29:52.481Z,1726781392.481 [DATMMP](INFO): Starting 2024-09-19T21:29:52.733Z,1726781392.733 [DATMMP](INFO): Starting 2024-09-19T21:29:52.785Z,1726781392.785 [DATMMP](INFO): entering command mode 2024-09-19T21:29:52.985Z,1726781392.985 [DATMMP](INFO): Starting 2024-09-19T21:29:52.986Z,1726781392.986 [DATMMP](INFO): DAT read: 2024-09-19T21:29:52.986Z,1726781392.986 [DATMMP](INFO): DAT read: user:1> 2024-09-19T21:29:52.986Z,1726781392.986 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-09-19T21:29:52.987Z,1726781392.987 [DATMMP](INFO): entering MMP mode 2024-09-19T21:29:53.237Z,1726781393.237 [DATMMP](INFO): Starting 2024-09-19T21:29:53.238Z,1726781393.238 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:29:53.240Z,1726781393.240 [DATMMP](INFO): status rx: x1 2024-09-19T21:29:53.240Z,1726781393.240 [DATMMP](INFO): MMP status: 1: started 2024-09-19T21:29:53.240Z,1726781393.240 [DATMMP](INFO): Received message type: status 2024-09-19T21:29:53.240Z,1726781393.240 [DATMMP](INFO): Handled 2024-09-19T21:29:53.489Z,1726781393.489 [DATMMP](INFO): Starting 2024-09-19T21:29:53.490Z,1726781393.490 [DATMMP](INFO): Sent Tx Power Config 8 2024-09-19T21:29:53.491Z,1726781393.491 [DATMMP](INFO): Setting local address: 5 2024-09-19T21:29:53.492Z,1726781393.492 [DATMMP](INFO): Setting time to: 21:29:53 And date to:9/19/2024 2024-09-19T21:29:53.741Z,1726781393.741 [DATMMP](INFO): Starting 2024-09-19T21:29:53.742Z,1726781393.742 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:29:53.742Z,1726781393.742 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-09-19T21:29:53.743Z,1726781393.743 [DATMMP](INFO): Received message type: mdmpower 2024-09-19T21:29:53.743Z,1726781393.743 [DATMMP](INFO): Handled 2024-09-19T21:29:53.993Z,1726781393.993 [DATMMP](INFO): Starting 2024-09-19T21:29:53.994Z,1726781393.994 [DATMMP](INFO): GSXN notify for xid: 10 2024-09-19T21:29:53.994Z,1726781393.994 [DATMMP](INFO): sreg_txpower rx: x8 2024-09-19T21:29:53.994Z,1726781393.994 [DATMMP](INFO): Received message type: sreg_txpower 2024-09-19T21:29:53.995Z,1726781393.995 [DATMMP](INFO): Match for : sreg_txpower 2024-09-19T21:29:53.995Z,1726781393.995 [DATMMP](INFO): ACK 2024-09-19T21:29:54.246Z,1726781394.246 [DATMMP](INFO): Starting 2024-09-19T21:29:54.248Z,1726781394.248 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:29:54.251Z,1726781394.251 [DATMMP](INFO): mdmlocal rx: x5 x0 x0 x0 2024-09-19T21:29:54.251Z,1726781394.251 [DATMMP](INFO): Received message type: mdmlocal 2024-09-19T21:29:54.251Z,1726781394.251 [DATMMP](INFO): Handled 2024-09-19T21:29:54.497Z,1726781394.497 [DATMMP](INFO): Starting 2024-09-19T21:29:54.498Z,1726781394.498 [DATMMP](INFO): GSXN notify for xid: 11 2024-09-19T21:29:54.498Z,1726781394.498 [DATMMP](INFO): sreg_locaddr rx: x5 2024-09-19T21:29:54.498Z,1726781394.498 [DATMMP](INFO): Received message type: sreg_locaddr 2024-09-19T21:29:54.498Z,1726781394.498 [DATMMP](INFO): Match for : sreg_locaddr 2024-09-19T21:29:54.499Z,1726781394.499 [DATMMP](INFO): ACK 2024-09-19T21:29:54.749Z,1726781394.749 [DATMMP](INFO): Starting 2024-09-19T21:29:54.750Z,1726781394.750 [DATMMP](INFO): GSXN notify for xid: 12 2024-09-19T21:29:54.751Z,1726781394.751 [DATMMP](INFO): timedate rx: x0 x15 x1D x35 x8 x13 x7C x0 2024-09-19T21:29:54.751Z,1726781394.751 [DATMMP](INFO): Received message type: timedate 2024-09-19T21:29:54.751Z,1726781394.751 [DATMMP](INFO): Match for : timedate 2024-09-19T21:29:54.751Z,1726781394.751 [DATMMP](INFO): ACK 2024-09-19T21:29:55.001Z,1726781395.001 [DATMMP](INFO): Starting 2024-09-19T21:29:55.002Z,1726781395.002 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:29:55.003Z,1726781395.003 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-09-19T21:29:55.003Z,1726781395.003 [DATMMP](INFO): Received message type: sys_verb 2024-09-19T21:29:55.003Z,1726781395.003 [DATMMP](INFO): Handled 2024-09-19T21:29:55.253Z,1726781395.253 [DATMMP](INFO): Starting 2024-09-19T21:29:55.254Z,1726781395.254 [DATMMP](INFO): GSXN notify for xid: 13 2024-09-19T21:29:55.254Z,1726781395.254 [DATMMP](INFO): sreg_verbosity rx: x0 2024-09-19T21:29:55.254Z,1726781395.254 [DATMMP](INFO): Received message type: sreg_verbosity 2024-09-19T21:29:55.255Z,1726781395.255 [DATMMP](INFO): Match for : sreg_verbosity 2024-09-19T21:29:55.255Z,1726781395.255 [DATMMP](INFO): ACK 2024-09-19T21:29:55.505Z,1726781395.505 [DATMMP](INFO): Starting 2024-09-19T21:29:55.505Z,1726781395.505 [DATMMP](INFO): GSXN notify for xid: 14 2024-09-19T21:29:55.506Z,1726781395.506 [DATMMP](INFO): version rx: x2 x5 2024-09-19T21:29:55.506Z,1726781395.506 [DATMMP](INFO): DatMMPVersion: 2.5 2024-09-19T21:29:55.507Z,1726781395.507 [DATMMP](INFO): Received message type: version 2024-09-19T21:29:55.507Z,1726781395.507 [DATMMP](INFO): Match for : version 2024-09-19T21:29:55.507Z,1726781395.507 [DATMMP](INFO): ACK 2024-09-19T21:29:55.507Z,1726781395.507 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-09-19T21:30:05.211Z,1726781405.211 [NAL9602](INFO): Powering up NAL9602 2024-09-19T21:30:08.616Z,1726781408.616 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:30:08.617Z,1726781408.617 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-09-19T21:30:08.617Z,1726781408.617 [DATMMP](INFO): Received message type: timesync 2024-09-19T21:30:08.617Z,1726781408.617 [DATMMP](INFO): Handled 2024-09-19T21:30:16.119Z,1726781416.119 [NAL9602](INFO): NAL9602 initialized 2024-09-19T21:30:18.702Z,1726781418.702 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:30:18.703Z,1726781418.703 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-09-19T21:30:18.703Z,1726781418.703 [DATMMP](INFO): Received message type: timesync 2024-09-19T21:30:18.703Z,1726781418.703 [DATMMP](INFO): Handled 2024-09-19T21:30:48.466Z,1726781448.466 [SBIT](IMPORTANT): Beginning Startup BIT 2024-09-19T21:30:48.470Z,1726781448.470 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-19T21:30:59.434Z,1726781459.434 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.007035 CHAN A1 (24V): -0.030326 CHAN A2 (12V): -0.004593 CHAN A3 (5V): -0.002464 CHAN B0 (3.3V): -0.001004 CHAN B1 (3.15aV): -0.001497 CHAN B2 (3.15bV): -0.001267 CHAN B3 (GND): 0.000362 OPEN: 0.006562 Full Scale: +/- 1 mA 2024-09-19T21:31:42.592Z,1726781502.592 [SBIT](IMPORTANT): SBIT PASSED 2024-09-19T21:31:42.592Z,1726781502.592 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-09-19T21:31:42.597Z,1726781502.597 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count; 2024-09-19T21:31:42.598Z,1726781502.598 [SBIT](IMPORTANT): CBIT.gf24Offset=180 microampere; 2024-09-19T21:31:42.600Z,1726781502.600 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-09-19T21:31:42.600Z,1726781502.600 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-09-19T21:31:42.600Z,1726781502.600 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-09-19T21:31:42.600Z,1726781502.600 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2024-09-19T21:31:42.600Z,1726781502.600 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2024-09-19T21:31:42.600Z,1726781502.600 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2024-09-19T21:31:42.600Z,1726781502.600 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=30 minute; 2024-09-19T21:31:42.601Z,1726781502.601 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2024-09-19T21:31:42.625Z,1726781502.625 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2024-09-19T21:31:42.625Z,1726781502.625 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature; 2024-09-19T21:31:42.625Z,1726781502.625 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2024-09-19T21:31:42.625Z,1726781502.625 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2024-09-19T21:31:42.626Z,1726781502.626 [SBIT](IMPORTANT): Express none platform_average_current; 2024-09-19T21:31:42.626Z,1726781502.626 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-0.1 ampere_hour; 2024-09-19T21:31:42.626Z,1726781502.626 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11.5 volt; 2024-09-19T21:31:42.626Z,1726781502.626 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=177.865153 cubic_centimeter; 2024-09-19T21:31:42.626Z,1726781502.626 [SBIT](IMPORTANT): VerticalControl.massDefault=13.010236 millimeter; 2024-09-19T21:31:42.999Z,1726781502.999 [MissionManager](IMPORTANT): Started mission Startup 2024-09-19T21:31:42.999Z,1726781502.999 [Startup] Running Loop=1 2024-09-19T21:31:42.999Z,1726781502.999 [Startup](DEBUG): Aggregate::initialize Startup 2024-09-19T21:31:42.999Z,1726781502.999 [Startup:A.GoToSurface] Running Loop=1 2024-09-19T21:31:42.000Z,1726781503.000 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-09-19T21:31:43.000Z,1726781503.000 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-09-19T21:31:43.000Z,1726781503.000 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-09-19T21:31:43.005Z,1726781503.005 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-09-19T21:31:43.005Z,1726781503.005 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-09-19T21:31:43.006Z,1726781503.006 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-09-19T21:31:43.006Z,1726781503.006 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-09-19T21:31:43.008Z,1726781503.008 [Startup:StartupSatComms] Running Loop=1 2024-09-19T21:31:43.008Z,1726781503.008 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-09-19T21:31:43.008Z,1726781503.008 [Startup:StartupSatComms:A] Running Loop=1 2024-09-19T21:31:43.407Z,1726781503.407 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-09-19T21:31:47.952Z,1726781507.952 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005228 2024-09-19T21:32:36.377Z,1726781556.377 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-09-19T21:32:36.378Z,1726781556.378 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-19T21:32:36.389Z,1726781556.389 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-19T21:32:36.760Z,1726781556.760 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-19T21:32:36.760Z,1726781556.760 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-09-19T21:32:41.565Z,1726781561.565 [NAL9602](INFO): SBD MO Status=0, MOMSN=17193, MT Status=0, MTMSN=0 2024-09-19T21:32:41.565Z,1726781561.565 [NAL9602](INFO): No messages in MT queue 2024-09-19T21:32:43.207Z,1726781563.207 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-19T21:31:43.0Z 2024-09-19T21:32:43.207Z,1726781563.207 [Startup:StartupSatComms:A] Stopped 2024-09-19T21:32:43.207Z,1726781563.207 [Startup:StartupSatComms:B] Running Loop=1 2024-09-19T21:32:43.594Z,1726781563.594 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-09-19T21:33:11.477Z,1726781591.477 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:33:35.621Z,1726781615.621 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240919T182616/Courier0058.lzma 2024-09-19T21:33:36.623Z,1726781616.623 [DataOverHttps](INFO): Moved sent file to Logs/20240919T182616/Courier0058.lzma.bak 2024-09-19T21:33:36.623Z,1726781616.623 [DataOverHttps](INFO): SBD MOMSN=23205553 2024-09-19T21:33:43.442Z,1726781623.442 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-19T21:32:43.2Z 2024-09-19T21:33:43.442Z,1726781623.442 [Startup:StartupSatComms:B] Stopped 2024-09-19T21:33:43.442Z,1726781623.442 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-09-19T21:33:43.442Z,1726781623.442 [Startup:StartupSatComms] Stopped 2024-09-19T21:33:43.442Z,1726781623.442 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-09-19T21:33:43.443Z,1726781623.443 [Startup](INFO): Completed Startup 2024-09-19T21:33:43.443Z,1726781623.443 [MissionManager](INFO): Startup is completed. 2024-09-19T21:33:43.443Z,1726781623.443 [MissionManager](INFO): Uninitializing Mission Startup 2024-09-19T21:33:43.443Z,1726781623.443 [Startup] Stopped 2024-09-19T21:33:43.444Z,1726781623.444 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-09-19T21:33:43.444Z,1726781623.444 [Startup:A.GoToSurface] Stopped 2024-09-19T21:33:43.444Z,1726781623.444 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-09-19T21:33:43.810Z,1726781623.810 [MissionManager](IMPORTANT): Started mission Default 2024-09-19T21:33:43.811Z,1726781623.811 [Default] Running Loop=1 2024-09-19T21:33:43.811Z,1726781623.811 [Default](DEBUG): Aggregate::initialize Default 2024-09-19T21:33:43.811Z,1726781623.811 [Default:B.GoToSurface] Running Loop=1 2024-09-19T21:33:43.811Z,1726781623.811 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-09-19T21:33:43.811Z,1726781623.811 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-09-19T21:33:43.811Z,1726781623.811 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-09-19T21:33:43.812Z,1726781623.812 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-09-19T21:33:43.812Z,1726781623.812 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-09-19T21:33:43.812Z,1726781623.812 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-09-19T21:33:43.813Z,1726781623.813 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-09-19T21:33:43.817Z,1726781623.817 [Default:A.Wait] Running Loop=1 2024-09-19T21:33:43.818Z,1726781623.818 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-09-19T21:33:53.748Z,1726781633.748 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20240919T212924/Courier0000.lzma 2024-09-19T21:33:54.751Z,1726781634.751 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0000.lzma.bak 2024-09-19T21:33:54.751Z,1726781634.751 [DataOverHttps](INFO): SBD MOMSN=23205555 2024-09-19T21:33:57.134Z,1726781637.134 [Default:A.Wait](INFO): Done Waiting. 2024-09-19T21:33:57.134Z,1726781637.134 [Default:A.Wait] Stopped 2024-09-19T21:33:57.134Z,1726781637.134 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T21:33:57.553Z,1726781637.553 [Default:CheckIn] Running Loop=1 2024-09-19T21:33:57.553Z,1726781637.553 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T21:33:57.553Z,1726781637.553 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T21:33:57.958Z,1726781637.958 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-09-19T21:34:03.994Z,1726781643.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213400.00,A,3648.14264,N,12147.22154,W,0.408,0.00,190924,,,A*7E 2024-09-19T21:34:03.998Z,1726781643.998 [NAL9602](INFO): GPS fix at 20240919T213400: (36.802377, -121.787026) 2024-09-19T21:34:04.008Z,1726781644.008 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T21:34:04.008Z,1726781644.008 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T21:34:04.425Z,1726781644.425 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-09-19T21:34:21.209Z,1726781661.209 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240919T212924/Courier0004.lzma 2024-09-19T21:34:22.227Z,1726781662.227 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0004.lzma.bak 2024-09-19T21:34:22.227Z,1726781662.227 [DataOverHttps](INFO): SBD MOMSN=23205591 2024-09-19T21:34:36.374Z,1726781676.374 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T21:34:38.494Z,1726781678.494 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20240919T182616/Express0059.lzma 2024-09-19T21:34:39.495Z,1726781679.495 [DataOverHttps](INFO): Moved sent file to Logs/20240919T182616/Express0059.lzma.bak 2024-09-19T21:34:39.495Z,1726781679.495 [DataOverHttps](INFO): SBD MOMSN=23205594 2024-09-19T21:34:55.354Z,1726781695.354 [DataOverHttps](INFO): Sending 1037 bytes from file Logs/20240919T212924/Express0001.lzma 2024-09-19T21:34:56.355Z,1726781696.355 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0001.lzma.bak 2024-09-19T21:34:56.355Z,1726781696.355 [DataOverHttps](INFO): SBD MOMSN=23205606 2024-09-19T21:35:14.809Z,1726781714.809 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20240919T212924/Express0005.lzma 2024-09-19T21:35:15.811Z,1726781715.811 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0005.lzma.bak 2024-09-19T21:35:15.811Z,1726781715.811 [DataOverHttps](INFO): SBD MOMSN=23205639 2024-09-19T21:35:17.216Z,1726781717.216 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T21:35:17.217Z,1726781717.217 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T21:35:17.217Z,1726781717.217 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T21:35:32.620Z,1726781732.620 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality 2024-09-19T21:36:17.505Z,1726781777.505 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:36:48.641Z,1726781808.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:37:10.727Z,1726781830.727 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2024-09-19T21:37:10.731Z,1726781830.731 [BPC1](INFO): Received data from all battery sticks. 2024-09-19T21:37:19.765Z,1726781839.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:37:50.897Z,1726781870.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:38:22.022Z,1726781902.022 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:38:25.034Z,1726781905.034 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739609 2024-09-19T21:39:23.285Z,1726781963.285 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:39:54.414Z,1726781994.414 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:40:17.782Z,1726782017.782 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T21:40:17.782Z,1726782017.782 [Default:CheckIn:C.Wait] Stopped 2024-09-19T21:40:17.782Z,1726782017.782 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T21:40:17.782Z,1726782017.782 [Default:CheckIn:D] Running Loop=1 2024-09-19T21:40:18.192Z,1726782018.192 [Default:CheckIn:D] Stopped 2024-09-19T21:40:18.192Z,1726782018.192 [Default:CheckIn:E] Running Loop=1 2024-09-19T21:40:18.596Z,1726782018.596 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.573028 min 2024-09-19T21:40:18.596Z,1726782018.596 [Default:CheckIn:E] Stopped 2024-09-19T21:40:18.596Z,1726782018.596 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T21:40:18.596Z,1726782018.596 [Default:CheckIn] Stopped 2024-09-19T21:40:18.596Z,1726782018.596 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T21:40:18.597Z,1726782018.597 [Default:CheckIn](INFO): Running loop #2 2024-09-19T21:40:18.597Z,1726782018.597 [Default:CheckIn] Running Loop=2 2024-09-19T21:40:18.597Z,1726782018.597 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T21:40:18.597Z,1726782018.597 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T21:40:18.694Z,1726782018.694 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:40:18.694Z,1726782018.694 [DATMMP](INFO): timesync rx: x2 x0 x0 x0 2024-09-19T21:40:18.695Z,1726782018.695 [DATMMP](INFO): Received message type: timesync 2024-09-19T21:40:18.695Z,1726782018.695 [DATMMP](INFO): Handled 2024-09-19T21:40:20.602Z,1726782020.602 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214019.00,A,3648.13714,N,12147.21372,W,0.039,0.00,190924,,,D*76 2024-09-19T21:40:20.604Z,1726782020.604 [NAL9602](INFO): GPS fix at 20240919T214019: (36.802286, -121.786895) 2024-09-19T21:40:20.626Z,1726782020.626 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T21:40:20.626Z,1726782020.626 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T21:40:25.487Z,1726782025.487 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T21:40:25.538Z,1726782025.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:40:28.774Z,1726782028.774 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:40:28.774Z,1726782028.774 [DATMMP](INFO): timesync rx: x2 x1 x1 x0 2024-09-19T21:40:28.775Z,1726782028.775 [DATMMP](INFO): Received message type: timesync 2024-09-19T21:40:28.775Z,1726782028.775 [DATMMP](INFO): Handled 2024-09-19T21:40:38.866Z,1726782038.866 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:40:38.867Z,1726782038.867 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-09-19T21:40:38.867Z,1726782038.867 [DATMMP](INFO): Received message type: timesync 2024-09-19T21:40:38.867Z,1726782038.867 [DATMMP](INFO): Handled 2024-09-19T21:40:41.263Z,1726782041.263 [NAL9602](INFO): SBD MO Status=1, MOMSN=17194, MT Status=0, MTMSN=0 2024-09-19T21:40:41.313Z,1726782041.313 [NAL9602](INFO): Sent 207 bytes from file Logs/20240919T212924/Courier0007.lzma 2024-09-19T21:40:41.313Z,1726782041.313 [NAL9602](INFO): Packets left to send: 0 2024-09-19T21:40:56.661Z,1726782056.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:41:01.903Z,1726782061.903 [NAL9602](INFO): SBD MO Status=2, MOMSN=17195, MT Status=2, MTMSN=0 2024-09-19T21:41:01.904Z,1726782061.904 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:41:06.392Z,1726782066.392 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T21:41:25.400Z,1726782085.400 [NAL9602](INFO): SBD MO Status=2, MOMSN=17195, MT Status=2, MTMSN=0 2024-09-19T21:41:25.400Z,1726782085.400 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:41:27.786Z,1726782087.786 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:41:29.892Z,1726782089.892 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:41:33.131Z,1726782093.131 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:41:37.164Z,1726782097.164 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T21:41:52.534Z,1726782112.534 [NAL9602](INFO): SBD MO Status=2, MOMSN=17195, MT Status=2, MTMSN=0 2024-09-19T21:41:52.534Z,1726782112.534 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:41:56.635Z,1726782116.635 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:41:58.913Z,1726782118.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:42:09.550Z,1726782129.550 [NAL9602](INFO): SBD MO Status=1, MOMSN=17195, MT Status=0, MTMSN=0 2024-09-19T21:42:09.600Z,1726782129.600 [NAL9602](INFO): Sent 213 bytes from file Logs/20240919T212924/Express0008.lzma 2024-09-19T21:42:09.601Z,1726782129.601 [NAL9602](INFO): Packets left to send: 0 2024-09-19T21:42:20.459Z,1726782140.459 [NAL9602](INFO): SBD MO Status=0, MOMSN=17196, MT Status=0, MTMSN=0 2024-09-19T21:42:20.557Z,1726782140.557 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T21:42:20.557Z,1726782140.557 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T21:42:20.557Z,1726782140.557 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T21:42:30.037Z,1726782150.037 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:42:51.156Z,1726782171.156 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T21:42:51.202Z,1726782171.202 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:43:01.173Z,1726782181.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:43:32.297Z,1726782212.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:44:03.421Z,1726782243.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:44:34.545Z,1726782274.545 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:45:05.669Z,1726782305.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:45:36.801Z,1726782336.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:46:07.946Z,1726782367.946 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:46:39.073Z,1726782399.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:47:10.198Z,1726782430.198 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:47:21.048Z,1726782441.048 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T21:47:21.048Z,1726782441.048 [Default:CheckIn:C.Wait] Stopped 2024-09-19T21:47:21.048Z,1726782441.048 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T21:47:21.048Z,1726782441.048 [Default:CheckIn:D] Running Loop=1 2024-09-19T21:47:21.444Z,1726782441.444 [Default:CheckIn:D] Stopped 2024-09-19T21:47:21.444Z,1726782441.444 [Default:CheckIn:E] Running Loop=1 2024-09-19T21:47:21.850Z,1726782441.850 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.627219 min 2024-09-19T21:47:21.850Z,1726782441.850 [Default:CheckIn:E] Stopped 2024-09-19T21:47:21.850Z,1726782441.850 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T21:47:21.850Z,1726782441.850 [Default:CheckIn] Stopped 2024-09-19T21:47:21.850Z,1726782441.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T21:47:21.850Z,1726782441.850 [Default:CheckIn](INFO): Running loop #3 2024-09-19T21:47:21.851Z,1726782441.851 [Default:CheckIn] Running Loop=3 2024-09-19T21:47:21.851Z,1726782441.851 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T21:47:21.851Z,1726782441.851 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T21:47:23.859Z,1726782443.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214723.00,A,3648.13623,N,12147.21297,W,0.019,0.00,190924,,,D*75 2024-09-19T21:47:23.871Z,1726782443.871 [NAL9602](INFO): GPS fix at 20240919T214723: (36.802270, -121.786883) 2024-09-19T21:47:23.880Z,1726782443.880 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T21:47:23.880Z,1726782443.880 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T21:47:31.979Z,1726782451.979 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T21:47:41.321Z,1726782461.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:47:49.350Z,1726782469.350 [NAL9602](INFO): SBD MO Status=2, MOMSN=17197, MT Status=2, MTMSN=0 2024-09-19T21:47:49.350Z,1726782469.350 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:47:53.831Z,1726782473.831 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:48:01.555Z,1726782481.555 [NAL9602](INFO): SBD MO Status=1, MOMSN=17197, MT Status=0, MTMSN=0 2024-09-19T21:48:01.605Z,1726782481.605 [NAL9602](INFO): Sent 76 bytes from file Logs/20240919T212924/Courier0010.lzma 2024-09-19T21:48:01.605Z,1726782481.605 [NAL9602](INFO): Packets left to send: 0 2024-09-19T21:48:12.451Z,1726782492.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:48:20.259Z,1726782500.259 [NAL9602](INFO): SBD MO Status=2, MOMSN=17198, MT Status=2, MTMSN=0 2024-09-19T21:48:20.260Z,1726782500.260 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:48:24.682Z,1726782504.682 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T21:48:37.213Z,1726782517.213 [NAL9602](INFO): SBD MO Status=2, MOMSN=17198, MT Status=2, MTMSN=0 2024-09-19T21:48:37.213Z,1726782517.213 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:48:41.697Z,1726782521.697 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T21:48:43.573Z,1726782523.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:48:55.848Z,1726782535.848 [NAL9602](INFO): SBD MO Status=2, MOMSN=17198, MT Status=2, MTMSN=0 2024-09-19T21:48:55.848Z,1726782535.848 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:49:00.339Z,1726782540.339 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T21:49:14.487Z,1726782554.487 [NAL9602](INFO): SBD MO Status=2, MOMSN=17198, MT Status=2, MTMSN=0 2024-09-19T21:49:14.487Z,1726782554.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:49:14.705Z,1726782554.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:49:18.968Z,1726782558.968 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:22.233Z,1726782562.233 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:32.296Z,1726782572.296 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:35.131Z,1726782575.131 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:39.584Z,1726782579.584 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:44.027Z,1726782584.027 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:45.829Z,1726782585.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:49:52.504Z,1726782592.504 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:50:13.118Z,1726782613.118 [NAL9602](INFO): SBD MO Status=2, MOMSN=17198, MT Status=2, MTMSN=0 2024-09-19T21:50:13.118Z,1726782613.118 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:50:16.953Z,1726782616.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:50:17.193Z,1726782617.193 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:50:36.676Z,1726782636.676 [NAL9602](INFO): SBD MO Status=2, MOMSN=17198, MT Status=2, MTMSN=0 2024-09-19T21:50:36.676Z,1726782636.676 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:50:41.151Z,1726782641.151 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T21:50:46.843Z,1726782646.843 [NAL9602](INFO): SBD MO Status=1, MOMSN=17198, MT Status=0, MTMSN=0 2024-09-19T21:50:46.897Z,1726782646.897 [NAL9602](INFO): Sent 122 bytes from file Logs/20240919T212924/Express0011.lzma 2024-09-19T21:50:46.897Z,1726782646.897 [NAL9602](INFO): Packets left to send: 0 2024-09-19T21:50:48.094Z,1726782648.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:51:07.048Z,1726782667.048 [NAL9602](INFO): SBD MO Status=2, MOMSN=17199, MT Status=2, MTMSN=0 2024-09-19T21:51:07.049Z,1726782667.049 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:51:11.518Z,1726782671.518 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:51:19.217Z,1726782679.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:51:24.414Z,1726782684.414 [NAL9602](INFO): SBD MO Status=2, MOMSN=17199, MT Status=2, MTMSN=0 2024-09-19T21:51:24.414Z,1726782684.414 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:51:28.895Z,1726782688.895 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:51:29.443Z,1726782689.443 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2024-09-19T21:51:32.123Z,1726782692.123 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:51:32.676Z,1726782692.676 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2024-09-19T21:51:40.623Z,1726782700.623 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T21:51:46.878Z,1726782706.878 [NAL9602](INFO): SBD MO Status=0, MOMSN=17199, MT Status=0, MTMSN=0 2024-09-19T21:51:46.989Z,1726782706.989 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T21:51:46.989Z,1726782706.989 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T21:51:46.989Z,1726782706.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T21:51:50.341Z,1726782710.341 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:52:17.586Z,1726782737.586 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T21:52:17.635Z,1726782737.635 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:52:21.465Z,1726782741.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:52:52.625Z,1726782772.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:53:23.749Z,1726782803.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:53:54.877Z,1726782834.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:54:26.001Z,1726782866.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:54:57.125Z,1726782897.125 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:54:58.770Z,1726782898.770 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:54:58.771Z,1726782898.771 [DATMMP](INFO): timesync rx: x2 x0 x0 x0 2024-09-19T21:54:58.771Z,1726782898.771 [DATMMP](INFO): Received message type: timesync 2024-09-19T21:54:58.771Z,1726782898.771 [DATMMP](INFO): Handled 2024-09-19T21:55:08.851Z,1726782908.851 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:55:08.852Z,1726782908.852 [DATMMP](INFO): timesync rx: x2 x1 x1 x0 2024-09-19T21:55:08.852Z,1726782908.852 [DATMMP](INFO): Received message type: timesync 2024-09-19T21:55:08.852Z,1726782908.852 [DATMMP](INFO): Handled 2024-09-19T21:55:18.678Z,1726782918.678 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-19T21:55:18.679Z,1726782918.679 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-09-19T21:55:18.679Z,1726782918.679 [DATMMP](INFO): Received message type: timesync 2024-09-19T21:55:18.679Z,1726782918.679 [DATMMP](INFO): Handled 2024-09-19T21:55:28.253Z,1726782928.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:55:59.377Z,1726782959.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:56:30.513Z,1726782990.513 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:56:47.471Z,1726783007.471 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T21:56:47.471Z,1726783007.471 [Default:CheckIn:C.Wait] Stopped 2024-09-19T21:56:47.471Z,1726783007.471 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T21:56:47.471Z,1726783007.471 [Default:CheckIn:D] Running Loop=1 2024-09-19T21:56:47.871Z,1726783007.871 [Default:CheckIn:D] Stopped 2024-09-19T21:56:47.871Z,1726783007.871 [Default:CheckIn:E] Running Loop=1 2024-09-19T21:56:48.284Z,1726783008.284 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.067676 min 2024-09-19T21:56:48.284Z,1726783008.284 [Default:CheckIn:E] Stopped 2024-09-19T21:56:48.284Z,1726783008.284 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T21:56:48.284Z,1726783008.284 [Default:CheckIn] Stopped 2024-09-19T21:56:48.284Z,1726783008.284 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T21:56:48.284Z,1726783008.284 [Default:CheckIn](INFO): Running loop #4 2024-09-19T21:56:48.285Z,1726783008.285 [Default:CheckIn] Running Loop=4 2024-09-19T21:56:48.285Z,1726783008.285 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T21:56:48.285Z,1726783008.285 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T21:56:50.284Z,1726783010.284 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215649.00,A,3648.13642,N,12147.21208,W,0.058,0.00,190924,,,D*7D 2024-09-19T21:56:50.286Z,1726783010.286 [NAL9602](INFO): GPS fix at 20240919T215649: (36.802274, -121.786868) 2024-09-19T21:56:50.296Z,1726783010.296 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T21:56:50.296Z,1726783010.296 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T21:56:54.360Z,1726783014.360 [Reporter](INFO): NAL9602.sigQuality 4 count 2024-09-19T21:57:01.637Z,1726783021.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:57:17.816Z,1726783037.816 [NAL9602](INFO): SBD MO Status=2, MOMSN=17200, MT Status=2, MTMSN=0 2024-09-19T21:57:17.817Z,1726783037.817 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:57:22.343Z,1726783042.343 [Reporter](INFO): NAL9602.sigQuality 4 count 2024-09-19T21:57:32.762Z,1726783052.762 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:57:43.715Z,1726783063.715 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=17200, MT Status=1, MTMSN=4109 2024-09-19T21:57:43.765Z,1726783063.765 [NAL9602](INFO): Sent 75 bytes from file Logs/20240919T212924/Courier0013.lzma 2024-09-19T21:57:43.765Z,1726783063.765 [NAL9602](INFO): Packets left to send: 0 2024-09-19T21:57:44.262Z,1726783064.262 [NAL9602](INFO): Received command: ! grep -i sigQuality /mnt/mmc/LRAUV/Logs/latest/syslog 2024-09-19T21:57:44.348Z,1726783064.348 [CommandExec](IMPORTANT): got command ! grep -i sigQuality /mnt/mmc/LRAUV/Logs/latest/syslog 2024-09-19T21:57:44.691Z,1726783064.691 [CommandExec](IMPORTANT): 2024-09-19T21:35:32.620Z,1726781732.620 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality 2024-09-19T21:40:25.487Z,1726782025.487 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T21:41:06.392Z,1726782066.392 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T21:41:29.892Z,1726782089.892 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:41:33.131Z,1726782093.131 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:41:37.164Z,1726782097.164 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T21:41:56.635Z,1726782116.635 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:42:51.202Z,1726782171.202 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:47:31.979Z,1726782451.979 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T21:47:53.831Z,1726782473.831 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:48:24.682Z,1726782504.682 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T21:48:41.697Z,1726782521.697 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T21:49:00.339Z,1726782540.339 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T21:49:18.968Z,1726782558.968 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:22.233Z,1726782562.233 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:32.296Z,1726782572.296 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:35.131Z,1726782575.131 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:39.584Z,1726782579.584 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:44.027Z,1726782584.027 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:49:52.504Z,1726782592.504 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:50:17.193Z,1726782617.193 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:50:41.151Z,1726782641.151 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T21:51:11.518Z,1726782671.518 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:51:28.895Z,1726782688.895 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:51:32.123Z,1726782692.123 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:51:40.623Z,1726782700.623 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T21:52:17.635Z,1726782737.635 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T21:57:48.203Z,1726783068.203 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:57:55.060Z,1726783075.060 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=17201, MT Status=1, MTMSN=4110 2024-09-19T21:57:55.121Z,1726783075.121 [NAL9602](INFO): Sent 120 bytes from file Logs/20240919T212924/Express0014.lzma 2024-09-19T21:57:55.121Z,1726783075.121 [NAL9602](INFO): Packets left to send: 0 2024-09-19T21:57:55.569Z,1726783075.569 [NAL9602](INFO): Received command: ! echo 1 > /dev/loadB6 2024-09-19T21:57:55.639Z,1726783075.639 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB6 2024-09-19T21:57:59.551Z,1726783079.551 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T21:58:02.330Z,1726783082.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=17202, MT Status=0, MTMSN=0 2024-09-19T21:58:03.901Z,1726783083.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:58:06.818Z,1726783086.818 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T21:58:27.827Z,1726783107.827 [NAL9602](INFO): SBD MO Status=2, MOMSN=17203, MT Status=2, MTMSN=0 2024-09-19T21:58:27.827Z,1726783107.827 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T21:58:31.923Z,1726783111.923 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T21:58:35.033Z,1726783115.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:58:39.204Z,1726783119.204 [NAL9602](INFO): SBD MO Status=1, MOMSN=17203, MT Status=0, MTMSN=0 2024-09-19T21:58:39.265Z,1726783119.265 [NAL9602](INFO): Sent 40 bytes from file Logs/20240919T212924/Courier0016.lzma 2024-09-19T21:58:39.265Z,1726783119.265 [NAL9602](INFO): Packets left to send: 0 2024-09-19T21:59:03.101Z,1726783143.101 [NAL9602](INFO): SBD MO Status=1, MOMSN=17204, MT Status=0, MTMSN=0 2024-09-19T21:59:03.150Z,1726783143.150 [NAL9602](INFO): Sent 332 bytes from file Logs/20240919T212924/Express0017.lzma 2024-09-19T21:59:03.151Z,1726783143.151 [NAL9602](INFO): Packets left to send: 1 2024-09-19T21:59:06.165Z,1726783146.165 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:59:13.642Z,1726783153.642 [NAL9602](INFO): SBD MO Status=1, MOMSN=17205, MT Status=0, MTMSN=0 2024-09-19T21:59:13.689Z,1726783153.689 [NAL9602](INFO): Sent 293 bytes from file Logs/20240919T212924/Express0017.lzma 2024-09-19T21:59:13.689Z,1726783153.689 [NAL9602](INFO): Packets left to send: 0 2024-09-19T21:59:24.155Z,1726783164.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=17206, MT Status=0, MTMSN=0 2024-09-19T21:59:24.238Z,1726783164.238 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T21:59:24.238Z,1726783164.238 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T21:59:24.238Z,1726783164.238 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T21:59:37.289Z,1726783177.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T21:59:54.865Z,1726783194.865 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T21:59:54.907Z,1726783194.907 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:00:06.673Z,1726783206.673 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002737 2024-09-19T22:04:24.761Z,1726783464.761 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:04:24.761Z,1726783464.761 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:04:24.761Z,1726783464.761 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:04:24.761Z,1726783464.761 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:04:25.150Z,1726783465.150 [Default:CheckIn:D] Stopped 2024-09-19T22:04:25.150Z,1726783465.150 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:04:25.554Z,1726783465.554 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.688987 min 2024-09-19T22:04:25.554Z,1726783465.554 [Default:CheckIn:E] Stopped 2024-09-19T22:04:25.554Z,1726783465.554 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:04:25.555Z,1726783465.555 [Default:CheckIn] Stopped 2024-09-19T22:04:25.555Z,1726783465.555 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:04:25.555Z,1726783465.555 [Default:CheckIn](INFO): Running loop #5 2024-09-19T22:04:25.555Z,1726783465.555 [Default:CheckIn] Running Loop=5 2024-09-19T22:04:25.555Z,1726783465.555 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:04:25.555Z,1726783465.555 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:04:27.571Z,1726783467.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220426.00,A,3648.13655,N,12147.20893,W,0.058,36.79,190924,,,D*44 2024-09-19T22:04:27.574Z,1726783467.574 [NAL9602](INFO): GPS fix at 20240919T220426: (36.802276, -121.786816) 2024-09-19T22:04:27.584Z,1726783467.584 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:04:27.584Z,1726783467.584 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:04:37.476Z,1726783477.476 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240919T212924/Courier0019.lzma 2024-09-19T22:04:37.703Z,1726783477.703 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T22:04:42.495Z,1726783482.495 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0019.lzma.bak 2024-09-19T22:04:42.495Z,1726783482.495 [DataOverHttps](INFO): SBD MOMSN=23205789 2024-09-19T22:04:44.138Z,1726783484.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=17207, MT Status=0, MTMSN=0 2024-09-19T22:04:44.138Z,1726783484.138 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:04:58.240Z,1726783498.240 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20240919T212924/Express0020.lzma 2024-09-19T22:05:00.247Z,1726783500.247 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0020.lzma.bak 2024-09-19T22:05:00.247Z,1726783500.247 [DataOverHttps](INFO): SBD MOMSN=23205794 2024-09-19T22:05:01.531Z,1726783501.531 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:05:01.531Z,1726783501.531 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:05:01.531Z,1726783501.531 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:05:14.852Z,1726783514.852 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:05:14.873Z,1726783514.873 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:10:02.122Z,1726783802.122 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:10:02.122Z,1726783802.122 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:10:02.122Z,1726783802.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:10:02.122Z,1726783802.122 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:10:02.523Z,1726783802.523 [Default:CheckIn:D] Stopped 2024-09-19T22:10:02.523Z,1726783802.523 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:10:02.935Z,1726783802.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.311865 min 2024-09-19T22:10:02.935Z,1726783802.935 [Default:CheckIn:E] Stopped 2024-09-19T22:10:02.935Z,1726783802.935 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:10:02.935Z,1726783802.935 [Default:CheckIn] Stopped 2024-09-19T22:10:02.935Z,1726783802.935 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:10:02.936Z,1726783802.936 [Default:CheckIn](INFO): Running loop #6 2024-09-19T22:10:02.936Z,1726783802.936 [Default:CheckIn] Running Loop=6 2024-09-19T22:10:02.936Z,1726783802.936 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:10:02.936Z,1726783802.936 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:10:04.943Z,1726783804.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221004.00,A,3648.13910,N,12147.20917,W,0.000,21.19,190924,,,A*4A 2024-09-19T22:10:04.945Z,1726783804.945 [NAL9602](INFO): GPS fix at 20240919T221004: (36.802318, -121.786820) 2024-09-19T22:10:04.973Z,1726783804.973 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:10:04.973Z,1726783804.973 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:10:11.777Z,1726783811.777 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240919T212924/Courier0022.lzma 2024-09-19T22:10:12.779Z,1726783812.779 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0022.lzma.bak 2024-09-19T22:10:12.779Z,1726783812.779 [DataOverHttps](INFO): SBD MOMSN=23205806 2024-09-19T22:10:15.479Z,1726783815.479 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T22:10:21.502Z,1726783821.502 [NAL9602](INFO): SBD MO Status=0, MOMSN=17208, MT Status=0, MTMSN=0 2024-09-19T22:10:21.502Z,1726783821.502 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:10:30.873Z,1726783830.873 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20240919T212924/Express0023.lzma 2024-09-19T22:10:36.896Z,1726783836.896 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0023.lzma.bak 2024-09-19T22:10:36.897Z,1726783836.897 [DataOverHttps](INFO): SBD MOMSN=23205809 2024-09-19T22:10:38.507Z,1726783838.507 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:10:38.507Z,1726783838.507 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:10:38.507Z,1726783838.507 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:10:52.230Z,1726783852.230 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:10:52.251Z,1726783852.251 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:15:39.079Z,1726784139.079 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:15:39.079Z,1726784139.079 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:15:39.079Z,1726784139.079 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:15:39.079Z,1726784139.079 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:15:39.488Z,1726784139.488 [Default:CheckIn:D] Stopped 2024-09-19T22:15:39.488Z,1726784139.488 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:15:39.887Z,1726784139.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.927950 min 2024-09-19T22:15:39.887Z,1726784139.887 [Default:CheckIn:E] Stopped 2024-09-19T22:15:39.887Z,1726784139.887 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:15:39.887Z,1726784139.887 [Default:CheckIn] Stopped 2024-09-19T22:15:39.887Z,1726784139.887 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:15:39.888Z,1726784139.888 [Default:CheckIn](INFO): Running loop #7 2024-09-19T22:15:39.888Z,1726784139.888 [Default:CheckIn] Running Loop=7 2024-09-19T22:15:39.888Z,1726784139.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:15:39.888Z,1726784139.888 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:15:41.903Z,1726784141.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221541.00,A,3648.13955,N,12147.20969,W,0.019,21.19,190924,,,A*4E 2024-09-19T22:15:41.905Z,1726784141.905 [NAL9602](INFO): GPS fix at 20240919T221541: (36.802326, -121.786828) 2024-09-19T22:15:41.915Z,1726784141.915 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:15:41.915Z,1726784141.915 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:15:48.936Z,1726784148.936 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240919T212924/Courier0025.lzma 2024-09-19T22:15:49.939Z,1726784149.939 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0025.lzma.bak 2024-09-19T22:15:49.939Z,1726784149.939 [DataOverHttps](INFO): SBD MOMSN=23205827 2024-09-19T22:15:50.438Z,1726784150.438 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T22:16:06.041Z,1726784166.041 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20240919T212924/Express0026.lzma 2024-09-19T22:16:07.043Z,1726784167.043 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0026.lzma.bak 2024-09-19T22:16:07.043Z,1726784167.043 [DataOverHttps](INFO): SBD MOMSN=23205830 2024-09-19T22:16:08.579Z,1726784168.579 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:16:08.579Z,1726784168.579 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:16:08.579Z,1726784168.579 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:16:13.074Z,1726784173.074 [NAL9602](INFO): SBD MO Status=2, MOMSN=17209, MT Status=2, MTMSN=0 2024-09-19T22:16:13.074Z,1726784173.074 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T22:16:17.515Z,1726784177.515 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:16:18.315Z,1726784178.315 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:16:22.355Z,1726784182.355 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:16:24.799Z,1726784184.799 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:16:30.844Z,1726784190.844 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:16:39.731Z,1726784199.731 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T22:16:53.026Z,1726784213.026 [NAL9602](INFO): SBD MO Status=0, MOMSN=17209, MT Status=0, MTMSN=0 2024-09-19T22:16:53.026Z,1726784213.026 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:17:23.736Z,1726784243.736 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:17:23.786Z,1726784243.786 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:17:47.830Z,1726784267.830 [DataOverHttps](IMPORTANT): SBD MTMSN=20240919T221747 2024-09-19T22:17:56.141Z,1726784276.141 [DataOverHttps](INFO): Received command: ibit 2024-09-19T22:17:56.166Z,1726784276.166 [CommandExec](IMPORTANT): got command ibit 2024-09-19T22:17:56.483Z,1726784276.483 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-09-19T22:17:56.483Z,1726784276.483 [IBIT](IMPORTANT): Beginning control surface checks. 2024-09-19T22:17:56.486Z,1726784276.486 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-19T22:17:58.089Z,1726784278.089 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221757.00,A,3648.13853,N,12147.20970,W,0.039,21.19,190924,,,A*46 2024-09-19T22:17:58.091Z,1726784278.091 [NAL9602](INFO): GPS fix at 20240919T221757: (36.802309, -121.786828) 2024-09-19T22:18:06.196Z,1726784286.196 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T22:18:07.392Z,1726784287.392 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.006908 CHAN A1 (24V): -0.030614 CHAN A2 (12V): -0.005082 CHAN A3 (5V): -0.002213 CHAN B0 (3.3V): -0.000957 CHAN B1 (3.15aV): -0.000560 CHAN B2 (3.15bV): -0.001055 CHAN B3 (GND): 0.000329 OPEN: 0.006450 Full Scale: +/- 1 mA 2024-09-19T22:18:15.147Z,1726784295.147 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T22:18:24.341Z,1726784304.341 [NAL9602](INFO): SBD MO Status=0, MOMSN=17210, MT Status=0, MTMSN=0 2024-09-19T22:18:24.341Z,1726784304.341 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:18:25.554Z,1726784305.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221824.00,A,3648.13862,N,12147.20991,W,0.078,21.19,190924,,,A*45 2024-09-19T22:18:25.556Z,1726784305.556 [NAL9602](INFO): GPS fix at 20240919T221824: (36.802310, -121.786832) 2024-09-19T22:18:27.976Z,1726784307.976 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221827.00,A,3648.13865,N,12147.20994,W,0.019,21.19,190924,,,A*43 2024-09-19T22:18:27.979Z,1726784307.979 [NAL9602](INFO): GPS fix at 20240919T221827: (36.802311, -121.786832) 2024-09-19T22:18:31.288Z,1726784311.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221830.00,A,3648.13870,N,12147.21006,W,0.019,21.19,190924,,,A*42 2024-09-19T22:18:31.290Z,1726784311.290 [NAL9602](INFO): GPS fix at 20240919T221830: (36.802312, -121.786834) 2024-09-19T22:18:34.050Z,1726784314.050 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221833.00,A,3648.13873,N,12147.21016,W,0.000,21.19,190924,,,A*4B 2024-09-19T22:18:34.052Z,1726784314.052 [NAL9602](INFO): GPS fix at 20240919T221833: (36.802312, -121.786836) 2024-09-19T22:18:36.862Z,1726784316.862 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221836.00,A,3648.13879,N,12147.21029,W,0.019,21.19,190924,,,A*40 2024-09-19T22:18:36.864Z,1726784316.864 [NAL9602](INFO): GPS fix at 20240919T221836: (36.802313, -121.786838) 2024-09-19T22:18:40.091Z,1726784320.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221839.00,A,3648.13878,N,12147.21035,W,0.039,21.19,190924,,,A*41 2024-09-19T22:18:40.093Z,1726784320.093 [NAL9602](INFO): GPS fix at 20240919T221839: (36.802313, -121.786839) 2024-09-19T22:18:42.922Z,1726784322.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221842.00,A,3648.13881,N,12147.21045,W,0.019,21.19,190924,,,A*4E 2024-09-19T22:18:42.924Z,1726784322.924 [NAL9602](INFO): GPS fix at 20240919T221842: (36.802313, -121.786841) 2024-09-19T22:18:42.956Z,1726784322.956 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802315 Longitude: -121.786842 2024-09-19T22:18:43.374Z,1726784323.374 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.611000 2024-09-19T22:18:43.374Z,1726784323.374 [IBIT](IMPORTANT): batteryCapacityThreshold: -0.100000 Ah 2024-09-19T22:18:43.375Z,1726784323.375 [IBIT](IMPORTANT): batteryVoltageThreshold: 11.500000 V 2024-09-19T22:18:43.741Z,1726784323.741 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-09-19T22:18:43.741Z,1726784323.741 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-09-19T22:18:43.742Z,1726784323.742 [IBIT](IMPORTANT): Pressure:9.868352 PSI 2024-09-19T22:18:43.742Z,1726784323.742 [IBIT](IMPORTANT): Humidity:9.345623 % 2024-09-19T22:18:44.153Z,1726784324.153 [IBIT](IMPORTANT): Vehicle Pitch:-2.322136 degrees 2024-09-19T22:18:44.154Z,1726784324.154 [IBIT](IMPORTANT): Vehicle Roll:-0.169295 degrees 2024-09-19T22:18:44.154Z,1726784324.154 [IBIT](IMPORTANT): Vehicle Heading:198.075668 degrees 2024-09-19T22:18:44.547Z,1726784324.547 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-09-19T22:18:44.547Z,1726784324.547 [IBIT](IMPORTANT): buoyancyNeutral: 177.865158 cc 2024-09-19T22:18:44.548Z,1726784324.548 [IBIT](IMPORTANT): massDefault: 1.301024 cm 2024-09-19T22:18:44.548Z,1726784324.548 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2024-09-19T22:18:44.548Z,1726784324.548 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2024-09-19T22:18:44.548Z,1726784324.548 [IBIT](IMPORTANT): IBIT PASSED 2024-09-19T22:19:15.644Z,1726784355.644 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:19:15.686Z,1726784355.686 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:21:09.204Z,1726784469.204 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:21:09.204Z,1726784469.204 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:21:09.204Z,1726784469.204 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:21:09.204Z,1726784469.204 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:21:09.606Z,1726784469.606 [Default:CheckIn:D] Stopped 2024-09-19T22:21:09.607Z,1726784469.607 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:21:10.016Z,1726784470.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.429928 min 2024-09-19T22:21:10.016Z,1726784470.016 [Default:CheckIn:E] Stopped 2024-09-19T22:21:10.016Z,1726784470.016 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:21:10.016Z,1726784470.016 [Default:CheckIn] Stopped 2024-09-19T22:21:10.017Z,1726784470.017 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:21:10.017Z,1726784470.017 [Default:CheckIn](INFO): Running loop #8 2024-09-19T22:21:10.018Z,1726784470.018 [Default:CheckIn] Running Loop=8 2024-09-19T22:21:10.018Z,1726784470.018 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:21:10.018Z,1726784470.018 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:21:12.023Z,1726784472.023 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222111.00,A,3648.13929,N,12147.20570,W,0.039,79.37,190924,,,A*40 2024-09-19T22:21:12.025Z,1726784472.025 [NAL9602](INFO): GPS fix at 20240919T222111: (36.802321, -121.786762) 2024-09-19T22:21:12.057Z,1726784472.057 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:21:12.057Z,1726784472.057 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:21:13.661Z,1726784473.661 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240919T212924/Courier0028.lzma 2024-09-19T22:21:16.667Z,1726784476.667 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0028.lzma.bak 2024-09-19T22:21:16.667Z,1726784476.667 [DataOverHttps](INFO): SBD MOMSN=23205845 2024-09-19T22:21:19.356Z,1726784479.356 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T22:21:35.455Z,1726784495.455 [NAL9602](INFO): SBD MO Status=2, MOMSN=17211, MT Status=2, MTMSN=0 2024-09-19T22:21:35.456Z,1726784495.456 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T22:21:39.942Z,1726784499.942 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:21:40.747Z,1726784500.747 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:21:42.526Z,1726784502.526 [DataOverHttps](INFO): Sending 807 bytes from file Logs/20240919T212924/Express0029.lzma 2024-09-19T22:21:44.791Z,1726784504.791 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:21:50.555Z,1726784510.555 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0029.lzma.bak 2024-09-19T22:21:50.555Z,1726784510.555 [DataOverHttps](INFO): SBD MOMSN=23205848 2024-09-19T22:21:51.255Z,1726784511.255 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:00.151Z,1726784520.151 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:04.583Z,1726784524.583 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:08.615Z,1726784528.615 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:09.424Z,1726784529.424 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:13.503Z,1726784533.503 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:14.279Z,1726784534.279 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:18.323Z,1726784538.323 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:19.124Z,1726784539.124 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:23.166Z,1726784543.166 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:25.995Z,1726784545.995 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:26.799Z,1726784546.799 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:30.465Z,1726784550.465 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20240919T212924/Express0030.lzma 2024-09-19T22:22:30.843Z,1726784550.843 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:31.465Z,1726784551.465 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-09-19T22:22:34.486Z,1726784554.486 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:40.849Z,1726784560.849 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20240919T212924/Express0030.lzma 2024-09-19T22:22:41.851Z,1726784561.851 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0030.lzma.bak 2024-09-19T22:22:41.851Z,1726784561.851 [DataOverHttps](INFO): SBD MOMSN=23205874 2024-09-19T22:22:43.361Z,1726784563.361 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:22:43.361Z,1726784563.361 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:22:43.362Z,1726784563.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:22:43.372Z,1726784563.372 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:51.855Z,1726784571.855 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:54.287Z,1726784574.287 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:22:58.335Z,1726784578.335 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:23:00.353Z,1726784580.353 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T22:23:18.874Z,1726784598.874 [NAL9602](INFO): SBD MO Status=2, MOMSN=17211, MT Status=2, MTMSN=0 2024-09-19T22:23:18.874Z,1726784598.874 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T22:23:23.355Z,1726784603.355 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T22:23:26.606Z,1726784606.606 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T22:23:33.459Z,1726784613.459 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T22:23:35.071Z,1726784615.071 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T22:23:42.002Z,1726784622.002 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T22:23:51.608Z,1726784631.608 [NAL9602](INFO): SBD MO Status=0, MOMSN=17211, MT Status=0, MTMSN=0 2024-09-19T22:23:51.608Z,1726784631.608 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:24:22.302Z,1726784662.302 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:24:22.355Z,1726784662.355 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:27:43.906Z,1726784863.906 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:27:43.906Z,1726784863.906 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:27:43.906Z,1726784863.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:27:43.906Z,1726784863.906 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:27:44.316Z,1726784864.316 [Default:CheckIn:D] Stopped 2024-09-19T22:27:44.316Z,1726784864.316 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:27:44.714Z,1726784864.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.008415 min 2024-09-19T22:27:44.714Z,1726784864.714 [Default:CheckIn:E] Stopped 2024-09-19T22:27:44.714Z,1726784864.714 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:27:44.714Z,1726784864.714 [Default:CheckIn] Stopped 2024-09-19T22:27:44.714Z,1726784864.714 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:27:44.714Z,1726784864.714 [Default:CheckIn](INFO): Running loop #9 2024-09-19T22:27:44.714Z,1726784864.714 [Default:CheckIn] Running Loop=9 2024-09-19T22:27:44.714Z,1726784864.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:27:44.715Z,1726784864.715 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:27:46.730Z,1726784866.730 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222745.00,A,3648.13977,N,12147.20488,W,0.019,79.37,190924,,,D*4D 2024-09-19T22:27:46.732Z,1726784866.732 [NAL9602](INFO): GPS fix at 20240919T222745: (36.802329, -121.786748) 2024-09-19T22:27:46.743Z,1726784866.743 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:27:46.743Z,1726784866.743 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:27:53.872Z,1726784873.872 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240919T212924/Courier0033.lzma 2024-09-19T22:27:54.875Z,1726784874.875 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0033.lzma.bak 2024-09-19T22:27:54.875Z,1726784874.875 [DataOverHttps](INFO): SBD MOMSN=23205884 2024-09-19T22:27:57.671Z,1726784877.671 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T22:28:02.146Z,1726784882.146 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T22:28:08.177Z,1726784888.177 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:28:12.236Z,1726784892.236 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:28:14.709Z,1726784894.709 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20240919T212924/Express0035.lzma 2024-09-19T22:28:20.731Z,1726784900.731 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0035.lzma.bak 2024-09-19T22:28:20.731Z,1726784900.731 [DataOverHttps](INFO): SBD MOMSN=23205887 2024-09-19T22:28:21.131Z,1726784901.131 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T22:28:22.290Z,1726784902.290 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:28:22.290Z,1726784902.290 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:28:22.290Z,1726784902.290 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:28:33.194Z,1726784913.194 [NAL9602](INFO): SBD MO Status=0, MOMSN=17212, MT Status=0, MTMSN=0 2024-09-19T22:28:33.194Z,1726784913.194 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:29:03.896Z,1726784943.896 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:29:03.952Z,1726784943.952 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:33:22.886Z,1726785202.886 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:33:22.886Z,1726785202.886 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:33:22.886Z,1726785202.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:33:22.886Z,1726785202.886 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:33:23.286Z,1726785203.286 [Default:CheckIn:D] Stopped 2024-09-19T22:33:23.286Z,1726785203.286 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:33:23.683Z,1726785203.683 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.657926 min 2024-09-19T22:33:23.683Z,1726785203.683 [Default:CheckIn:E] Stopped 2024-09-19T22:33:23.683Z,1726785203.683 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:33:23.683Z,1726785203.683 [Default:CheckIn] Stopped 2024-09-19T22:33:23.683Z,1726785203.683 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:33:23.684Z,1726785203.684 [Default:CheckIn](INFO): Running loop #10 2024-09-19T22:33:23.684Z,1726785203.684 [Default:CheckIn] Running Loop=10 2024-09-19T22:33:23.684Z,1726785203.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:33:23.684Z,1726785203.684 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:33:25.703Z,1726785205.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223324.00,A,3648.13944,N,12147.20280,W,0.097,79.37,190924,,,D*47 2024-09-19T22:33:25.706Z,1726785205.706 [NAL9602](INFO): GPS fix at 20240919T223324: (36.802324, -121.786713) 2024-09-19T22:33:25.716Z,1726785205.716 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:33:25.716Z,1726785205.716 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:33:33.560Z,1726785213.560 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240919T212924/Courier0037.lzma 2024-09-19T22:33:34.563Z,1726785214.563 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0037.lzma.bak 2024-09-19T22:33:34.563Z,1726785214.563 [DataOverHttps](INFO): SBD MOMSN=23205899 2024-09-19T22:33:35.479Z,1726785215.479 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T22:33:50.645Z,1726785230.645 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20240919T212924/Express0038.lzma 2024-09-19T22:33:51.647Z,1726785231.647 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0038.lzma.bak 2024-09-19T22:33:51.647Z,1726785231.647 [DataOverHttps](INFO): SBD MOMSN=23205902 2024-09-19T22:33:53.225Z,1726785233.225 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:33:53.226Z,1726785233.226 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:33:53.226Z,1726785233.226 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:33:56.847Z,1726785236.847 [NAL9602](INFO): SBD MO Status=0, MOMSN=17213, MT Status=0, MTMSN=0 2024-09-19T22:33:56.848Z,1726785236.848 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:34:27.591Z,1726785267.591 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:34:27.668Z,1726785267.668 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:38:53.827Z,1726785533.827 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:38:53.827Z,1726785533.827 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:38:53.827Z,1726785533.827 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:38:53.827Z,1726785533.827 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:38:54.290Z,1726785534.290 [Default:CheckIn:D] Stopped 2024-09-19T22:38:54.291Z,1726785534.291 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.174613 min 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn:E] Stopped 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn] Stopped 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn](INFO): Running loop #11 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn] Running Loop=11 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:38:54.666Z,1726785534.666 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:38:56.647Z,1726785536.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223855.00,A,3648.13908,N,12147.20459,W,0.292,79.37,190924,,,D*47 2024-09-19T22:38:56.649Z,1726785536.649 [NAL9602](INFO): GPS fix at 20240919T223855: (36.802318, -121.786743) 2024-09-19T22:38:56.659Z,1726785536.659 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:38:56.659Z,1726785536.659 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:39:06.428Z,1726785546.428 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T22:39:18.964Z,1726785558.964 [NAL9602](INFO): SBD MO Status=2, MOMSN=17214, MT Status=2, MTMSN=0 2024-09-19T22:39:18.964Z,1726785558.964 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T22:39:23.351Z,1726785563.351 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T22:39:27.742Z,1726785567.742 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T22:39:39.135Z,1726785579.135 [NAL9602](INFO): SBD MO Status=0, MOMSN=17214, MT Status=0, MTMSN=0 2024-09-19T22:39:39.135Z,1726785579.135 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:40:05.700Z,1726785605.700 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240919T212924/Courier0040.lzma 2024-09-19T22:40:07.707Z,1726785607.707 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0040.lzma.bak 2024-09-19T22:40:07.707Z,1726785607.707 [DataOverHttps](INFO): SBD MOMSN=23205923 2024-09-19T22:40:09.769Z,1726785609.769 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:40:09.815Z,1726785609.815 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:40:23.765Z,1726785623.765 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20240919T212924/Express0041.lzma 2024-09-19T22:40:24.767Z,1726785624.767 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0041.lzma.bak 2024-09-19T22:40:24.767Z,1726785624.767 [DataOverHttps](INFO): SBD MOMSN=23205926 2024-09-19T22:40:26.342Z,1726785626.342 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:40:26.342Z,1726785626.342 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:40:26.342Z,1726785626.342 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:44:49.398Z,1726785889.398 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2024-09-19T22:44:49.400Z,1726785889.400 [BPC1](INFO): Received data from all battery sticks. 2024-09-19T22:45:26.967Z,1726785926.967 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:45:26.967Z,1726785926.967 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:45:26.967Z,1726785926.967 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:45:26.967Z,1726785926.967 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:45:27.376Z,1726785927.376 [Default:CheckIn:D] Stopped 2024-09-19T22:45:27.376Z,1726785927.376 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:45:27.793Z,1726785927.793 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.726090 min 2024-09-19T22:45:27.794Z,1726785927.794 [Default:CheckIn:E] Stopped 2024-09-19T22:45:27.794Z,1726785927.794 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:45:27.794Z,1726785927.794 [Default:CheckIn] Stopped 2024-09-19T22:45:27.794Z,1726785927.794 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:45:27.794Z,1726785927.794 [Default:CheckIn](INFO): Running loop #12 2024-09-19T22:45:27.794Z,1726785927.794 [Default:CheckIn] Running Loop=12 2024-09-19T22:45:27.794Z,1726785927.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:45:27.794Z,1726785927.794 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:45:29.790Z,1726785929.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224529.00,A,3648.31133,N,12147.14748,W,0.117,223.36,190924,,,D*70 2024-09-19T22:45:29.792Z,1726785929.792 [NAL9602](INFO): GPS fix at 20240919T224529: (36.805189, -121.785791) 2024-09-19T22:45:29.802Z,1726785929.802 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:45:29.802Z,1726785929.802 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:45:37.416Z,1726785937.416 [DataOverHttps](INFO): Sending 88 bytes from file Logs/20240919T212924/Courier0043.lzma 2024-09-19T22:45:37.507Z,1726785937.507 [Reporter](INFO): NAL9602.sigQuality 4 count 2024-09-19T22:45:38.419Z,1726785938.419 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0043.lzma.bak 2024-09-19T22:45:38.419Z,1726785938.419 [DataOverHttps](INFO): SBD MOMSN=23205959 2024-09-19T22:45:52.022Z,1726785952.022 [NAL9602](INFO): SBD MO Status=2, MOMSN=17215, MT Status=2, MTMSN=0 2024-09-19T22:45:52.022Z,1726785952.022 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T22:45:54.169Z,1726785954.169 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240919T212924/Express0044.lzma 2024-09-19T22:45:55.171Z,1726785955.171 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0044.lzma.bak 2024-09-19T22:45:55.171Z,1726785955.171 [DataOverHttps](INFO): SBD MOMSN=23205982 2024-09-19T22:45:56.484Z,1726785956.484 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:45:56.484Z,1726785956.484 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:45:56.485Z,1726785956.485 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:45:56.495Z,1726785956.495 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T22:46:10.591Z,1726785970.591 [NAL9602](INFO): SBD MO Status=0, MOMSN=17215, MT Status=0, MTMSN=0 2024-09-19T22:46:10.591Z,1726785970.591 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:46:41.292Z,1726786001.292 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:46:41.338Z,1726786001.338 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:50:57.063Z,1726786257.063 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:50:57.063Z,1726786257.063 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:50:57.063Z,1726786257.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:50:57.063Z,1726786257.063 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:50:57.468Z,1726786257.468 [Default:CheckIn:D] Stopped 2024-09-19T22:50:57.468Z,1726786257.468 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:50:57.867Z,1726786257.867 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.227620 min 2024-09-19T22:50:57.867Z,1726786257.867 [Default:CheckIn:E] Stopped 2024-09-19T22:50:57.867Z,1726786257.867 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:50:57.868Z,1726786257.868 [Default:CheckIn] Stopped 2024-09-19T22:50:57.868Z,1726786257.868 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:50:57.868Z,1726786257.868 [Default:CheckIn](INFO): Running loop #13 2024-09-19T22:50:57.868Z,1726786257.868 [Default:CheckIn] Running Loop=13 2024-09-19T22:50:57.868Z,1726786257.868 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:50:57.868Z,1726786257.868 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:50:59.912Z,1726786259.912 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225059.00,A,3648.30673,N,12147.15150,W,0.311,223.36,190924,,,A*7E 2024-09-19T22:50:59.914Z,1726786259.914 [NAL9602](INFO): GPS fix at 20240919T225059: (36.805112, -121.785858) 2024-09-19T22:50:59.924Z,1726786259.924 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:50:59.924Z,1726786259.924 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:51:07.302Z,1726786267.302 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T22:51:07.900Z,1726786267.900 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20240919T212924/Courier0046.lzma 2024-09-19T22:51:08.903Z,1726786268.903 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0046.lzma.bak 2024-09-19T22:51:08.903Z,1726786268.903 [DataOverHttps](INFO): SBD MOMSN=23205997 2024-09-19T22:51:18.151Z,1726786278.151 [NAL9602](INFO): SBD MO Status=0, MOMSN=17216, MT Status=0, MTMSN=0 2024-09-19T22:51:18.151Z,1726786278.151 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:51:24.537Z,1726786284.537 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20240919T212924/Express0047.lzma 2024-09-19T22:51:25.539Z,1726786285.539 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0047.lzma.bak 2024-09-19T22:51:25.539Z,1726786285.539 [DataOverHttps](INFO): SBD MOMSN=23206001 2024-09-19T22:51:27.052Z,1726786287.052 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:51:27.052Z,1726786287.052 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:51:27.053Z,1726786287.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:51:48.848Z,1726786308.848 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:51:48.869Z,1726786308.869 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T22:56:27.645Z,1726786587.645 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T22:56:27.645Z,1726786587.645 [Default:CheckIn:C.Wait] Stopped 2024-09-19T22:56:27.645Z,1726786587.645 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T22:56:27.645Z,1726786587.645 [Default:CheckIn:D] Running Loop=1 2024-09-19T22:56:28.053Z,1726786588.053 [Default:CheckIn:D] Stopped 2024-09-19T22:56:28.053Z,1726786588.053 [Default:CheckIn:E] Running Loop=1 2024-09-19T22:56:28.468Z,1726786588.468 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.737362 min 2024-09-19T22:56:28.468Z,1726786588.468 [Default:CheckIn:E] Stopped 2024-09-19T22:56:28.469Z,1726786588.469 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T22:56:28.469Z,1726786588.469 [Default:CheckIn] Stopped 2024-09-19T22:56:28.477Z,1726786588.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T22:56:28.477Z,1726786588.477 [Default:CheckIn](INFO): Running loop #14 2024-09-19T22:56:28.477Z,1726786588.477 [Default:CheckIn] Running Loop=14 2024-09-19T22:56:28.477Z,1726786588.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T22:56:28.477Z,1726786588.477 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T22:56:30.464Z,1726786590.464 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225629.00,A,3648.31181,N,12147.14856,W,0.039,223.36,190924,,,D*76 2024-09-19T22:56:30.466Z,1726786590.466 [NAL9602](INFO): GPS fix at 20240919T225629: (36.805197, -121.785809) 2024-09-19T22:56:30.485Z,1726786590.485 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T22:56:30.485Z,1726786590.485 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T22:56:37.769Z,1726786597.769 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T22:56:38.084Z,1726786598.084 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240919T212924/Courier0049.lzma 2024-09-19T22:56:39.087Z,1726786599.087 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0049.lzma.bak 2024-09-19T22:56:39.087Z,1726786599.087 [DataOverHttps](INFO): SBD MOMSN=23206015 2024-09-19T22:56:50.669Z,1726786610.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=17217, MT Status=2, MTMSN=0 2024-09-19T22:56:50.669Z,1726786610.669 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T22:56:54.781Z,1726786614.781 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20240919T212924/Express0050.lzma 2024-09-19T22:56:55.163Z,1726786615.163 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T22:56:55.783Z,1726786615.783 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0050.lzma.bak 2024-09-19T22:56:55.783Z,1726786615.783 [DataOverHttps](INFO): SBD MOMSN=23206019 2024-09-19T22:56:57.148Z,1726786617.148 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T22:56:57.148Z,1726786617.148 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T22:56:57.148Z,1726786617.148 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T22:56:59.250Z,1726786619.250 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T22:57:12.886Z,1726786632.886 [NAL9602](INFO): SBD MO Status=0, MOMSN=17217, MT Status=0, MTMSN=0 2024-09-19T22:57:12.886Z,1726786632.886 [NAL9602](INFO): No messages in MT queue 2024-09-19T22:57:43.590Z,1726786663.590 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T22:57:43.612Z,1726786663.612 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:01:57.722Z,1726786917.722 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:01:57.722Z,1726786917.722 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:01:57.722Z,1726786917.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:01:57.722Z,1726786917.722 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:01:58.119Z,1726786918.119 [Default:CheckIn:D] Stopped 2024-09-19T23:01:58.119Z,1726786918.119 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:01:58.523Z,1726786918.523 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.238468 min 2024-09-19T23:01:58.523Z,1726786918.523 [Default:CheckIn:E] Stopped 2024-09-19T23:01:58.523Z,1726786918.523 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:01:58.523Z,1726786918.523 [Default:CheckIn] Stopped 2024-09-19T23:01:58.523Z,1726786918.523 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:01:58.523Z,1726786918.523 [Default:CheckIn](INFO): Running loop #15 2024-09-19T23:01:58.524Z,1726786918.524 [Default:CheckIn] Running Loop=15 2024-09-19T23:01:58.524Z,1726786918.524 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:01:58.524Z,1726786918.524 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:02:00.535Z,1726786920.535 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230159.00,A,3648.31082,N,12147.14685,W,0.019,223.36,190924,,,D*72 2024-09-19T23:02:00.538Z,1726786920.538 [NAL9602](INFO): GPS fix at 20240919T230159: (36.805180, -121.785781) 2024-09-19T23:02:00.547Z,1726786920.547 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:02:00.548Z,1726786920.548 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:02:03.438Z,1726786923.438 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T23:02:07.564Z,1726786927.564 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240919T212924/Courier0052.lzma 2024-09-19T23:02:08.567Z,1726786928.567 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0052.lzma.bak 2024-09-19T23:02:08.567Z,1726786928.567 [DataOverHttps](INFO): SBD MOMSN=23206034 2024-09-19T23:02:14.674Z,1726786934.674 [NAL9602](INFO): SBD MO Status=0, MOMSN=17218, MT Status=0, MTMSN=0 2024-09-19T23:02:14.674Z,1726786934.674 [NAL9602](INFO): No messages in MT queue 2024-09-19T23:02:24.216Z,1726786944.216 [DataOverHttps](INFO): Sending 123 bytes from file Logs/20240919T212924/Express0053.lzma 2024-09-19T23:02:25.219Z,1726786945.219 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0053.lzma.bak 2024-09-19T23:02:25.219Z,1726786945.219 [DataOverHttps](INFO): SBD MOMSN=23206037 2024-09-19T23:02:26.803Z,1726786946.803 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:02:26.803Z,1726786946.803 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:02:26.803Z,1726786946.803 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:02:45.380Z,1726786965.380 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:02:45.426Z,1726786965.426 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:03:05.514Z,1726786985.514 [DataOverHttps](IMPORTANT): SBD MTMSN=20240919T230304 2024-09-19T23:03:13.961Z,1726786993.961 [DataOverHttps](INFO): Received command: strobe off 2024-09-19T23:03:13.986Z,1726786993.986 [CommandExec](IMPORTANT): got command strobe off 2024-09-19T23:03:13.986Z,1726786993.986 [CommandExec](IMPORTANT): Deactivating strobe 2024-09-19T23:07:27.416Z,1726787247.416 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:07:27.416Z,1726787247.416 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:07:27.416Z,1726787247.416 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:07:27.416Z,1726787247.416 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:07:27.801Z,1726787247.801 [Default:CheckIn:D] Stopped 2024-09-19T23:07:27.801Z,1726787247.801 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.733171 min 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn:E] Stopped 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn] Stopped 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn](INFO): Running loop #16 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn] Running Loop=16 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:07:28.207Z,1726787248.207 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:07:30.224Z,1726787250.224 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230729.00,A,3648.47096,N,12147.11798,W,5.851,348.27,190924,,,D*72 2024-09-19T23:07:30.227Z,1726787250.227 [NAL9602](INFO): GPS fix at 20240919T230729: (36.807849, -121.785300) 2024-09-19T23:07:30.237Z,1726787250.237 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:07:30.237Z,1726787250.237 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:07:38.746Z,1726787258.746 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T23:07:47.635Z,1726787267.635 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T23:07:51.231Z,1726787271.231 [NAL9602](INFO): SBD MO Status=0, MOMSN=17219, MT Status=0, MTMSN=0 2024-09-19T23:07:51.231Z,1726787271.231 [NAL9602](INFO): No messages in MT queue 2024-09-19T23:08:20.644Z,1726787300.644 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240919T212924/Courier0055.lzma 2024-09-19T23:08:21.930Z,1726787301.930 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:08:21.972Z,1726787301.972 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:08:24.659Z,1726787304.659 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0055.lzma.bak 2024-09-19T23:08:24.659Z,1726787304.659 [DataOverHttps](INFO): SBD MOMSN=23206051 2024-09-19T23:08:40.485Z,1726787320.485 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240919T212924/Courier0058.lzma 2024-09-19T23:08:41.491Z,1726787321.491 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Courier0058.lzma.bak 2024-09-19T23:08:41.491Z,1726787321.491 [DataOverHttps](INFO): SBD MOMSN=23206054 2024-09-19T23:08:57.234Z,1726787337.234 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20240919T212924/Express0056.lzma 2024-09-19T23:08:58.235Z,1726787338.235 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0056.lzma.bak 2024-09-19T23:08:58.235Z,1726787338.235 [DataOverHttps](INFO): SBD MOMSN=23206056 2024-09-19T23:09:15.837Z,1726787355.837 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20240919T212924/Express0059.lzma 2024-09-19T23:09:16.839Z,1726787356.839 [DataOverHttps](INFO): Moved sent file to Logs/20240919T212924/Express0059.lzma.bak 2024-09-19T23:09:16.839Z,1726787356.839 [DataOverHttps](INFO): SBD MOMSN=23206062 2024-09-19T23:09:18.131Z,1726787358.131 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:09:18.131Z,1726787358.131 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:09:18.131Z,1726787358.131 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:11:19.529Z,1726787479.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:11:50.653Z,1726787510.653 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:12:21.797Z,1726787541.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:13:24.057Z,1726787604.057 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:13:27.070Z,1726787607.070 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755051 2024-09-19T23:14:18.705Z,1726787658.705 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:14:18.718Z,1726787658.718 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:14:18.718Z,1726787658.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:14:18.718Z,1726787658.718 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:14:19.102Z,1726787659.102 [Default:CheckIn:D] Stopped 2024-09-19T23:14:19.102Z,1726787659.102 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:14:19.511Z,1726787659.511 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.588184 min 2024-09-19T23:14:19.511Z,1726787659.511 [Default:CheckIn:E] Stopped 2024-09-19T23:14:19.511Z,1726787659.511 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:14:19.511Z,1726787659.511 [Default:CheckIn] Stopped 2024-09-19T23:14:19.512Z,1726787659.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:14:19.512Z,1726787659.512 [Default:CheckIn](INFO): Running loop #17 2024-09-19T23:14:19.512Z,1726787659.512 [Default:CheckIn] Running Loop=17 2024-09-19T23:14:19.512Z,1726787659.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:14:19.512Z,1726787659.512 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:14:21.530Z,1726787661.530 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231420.00,A,3647.92514,N,12148.89494,W,19.419,247.90,190924,,,D*4F 2024-09-19T23:14:21.532Z,1726787661.532 [NAL9602](INFO): GPS fix at 20240919T231420: (36.798752, -121.814916) 2024-09-19T23:14:21.541Z,1726787661.541 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:14:21.542Z,1726787661.542 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:14:25.323Z,1726787665.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:14:28.026Z,1726787668.026 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T23:14:36.123Z,1726787676.123 [NAL9602](INFO): SBD MO Status=1, MOMSN=17220, MT Status=0, MTMSN=0 2024-09-19T23:14:36.173Z,1726787676.173 [NAL9602](INFO): Sent 75 bytes from file Logs/20240919T212924/Courier0061.lzma 2024-09-19T23:14:36.174Z,1726787676.174 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:14:47.476Z,1726787687.476 [NAL9602](INFO): SBD MO Status=1, MOMSN=17221, MT Status=0, MTMSN=0 2024-09-19T23:14:47.527Z,1726787687.527 [NAL9602](INFO): Sent 141 bytes from file Logs/20240919T212924/Express0062.lzma 2024-09-19T23:14:47.527Z,1726787687.527 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:14:56.445Z,1726787696.445 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:15:04.443Z,1726787704.443 [NAL9602](INFO): SBD MO Status=0, MOMSN=17222, MT Status=0, MTMSN=0 2024-09-19T23:15:04.516Z,1726787704.516 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:15:04.516Z,1726787704.516 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:15:04.516Z,1726787704.516 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:15:27.577Z,1726787727.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:15:35.146Z,1726787735.146 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:15:35.253Z,1726787735.253 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:15:39.289Z,1726787739.289 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003104 2024-09-19T23:16:59.929Z,1726787819.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:17:31.054Z,1726787851.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:18:02.177Z,1726787882.177 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:18:33.301Z,1726787913.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:19:04.438Z,1726787944.438 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:19:07.450Z,1726787947.450 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743515 2024-09-19T23:20:05.024Z,1726788005.024 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:20:05.025Z,1726788005.025 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:20:05.025Z,1726788005.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:20:05.042Z,1726788005.042 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:20:05.434Z,1726788005.434 [Default:CheckIn:D] Stopped 2024-09-19T23:20:05.434Z,1726788005.434 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:20:05.681Z,1726788005.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.360384 min 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn:E] Stopped 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn] Stopped 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn](INFO): Running loop #18 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn] Running Loop=18 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:20:05.836Z,1726788005.836 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:20:07.846Z,1726788007.846 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232007.00,A,3647.47247,N,12151.19235,W,21.868,253.28,190924,,,D*4F 2024-09-19T23:20:07.848Z,1726788007.848 [NAL9602](INFO): GPS fix at 20240919T232007: (36.791208, -121.853206) 2024-09-19T23:20:07.872Z,1726788007.872 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:20:07.872Z,1726788007.872 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:20:16.033Z,1726788016.033 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T23:20:22.076Z,1726788022.076 [NAL9602](INFO): SBD MO Status=1, MOMSN=17223, MT Status=0, MTMSN=0 2024-09-19T23:20:22.126Z,1726788022.126 [NAL9602](INFO): Sent 75 bytes from file Logs/20240919T212924/Courier0064.lzma 2024-09-19T23:20:22.126Z,1726788022.126 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:20:30.598Z,1726788030.598 [NAL9602](INFO): SBD MO Status=1, MOMSN=17224, MT Status=0, MTMSN=0 2024-09-19T23:20:30.645Z,1726788030.645 [NAL9602](INFO): Sent 141 bytes from file Logs/20240919T212924/Express0065.lzma 2024-09-19T23:20:30.645Z,1726788030.645 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:20:36.805Z,1726788036.805 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:20:42.343Z,1726788042.343 [NAL9602](INFO): SBD MO Status=0, MOMSN=17225, MT Status=0, MTMSN=0 2024-09-19T23:20:42.418Z,1726788042.418 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:20:42.418Z,1726788042.418 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:20:42.418Z,1726788042.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:21:07.945Z,1726788067.945 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:21:13.043Z,1726788073.043 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:21:13.069Z,1726788073.069 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:21:39.077Z,1726788099.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:22:10.202Z,1726788130.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:22:41.327Z,1726788161.327 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:23:12.450Z,1726788192.450 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:23:43.573Z,1726788223.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:24:14.705Z,1726788254.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:24:45.829Z,1726788285.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:25:16.953Z,1726788316.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:25:42.919Z,1726788342.919 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:25:42.919Z,1726788342.919 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:25:42.919Z,1726788342.919 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:25:42.919Z,1726788342.919 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:25:43.325Z,1726788343.325 [Default:CheckIn:D] Stopped 2024-09-19T23:25:43.325Z,1726788343.325 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:25:43.735Z,1726788343.735 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.991911 min 2024-09-19T23:25:43.735Z,1726788343.735 [Default:CheckIn:E] Stopped 2024-09-19T23:25:43.735Z,1726788343.735 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:25:43.735Z,1726788343.735 [Default:CheckIn] Stopped 2024-09-19T23:25:43.735Z,1726788343.735 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:25:43.735Z,1726788343.735 [Default:CheckIn](INFO): Running loop #19 2024-09-19T23:25:43.736Z,1726788343.736 [Default:CheckIn] Running Loop=19 2024-09-19T23:25:43.736Z,1726788343.736 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:25:43.736Z,1726788343.736 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:25:45.754Z,1726788345.754 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232545.00,A,3646.89957,N,12153.72251,W,21.596,252.12,190924,,,D*4C 2024-09-19T23:25:45.756Z,1726788345.756 [NAL9602](INFO): GPS fix at 20240919T232545: (36.781660, -121.895375) 2024-09-19T23:25:45.766Z,1726788345.766 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:25:45.766Z,1726788345.766 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:25:48.078Z,1726788348.078 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:25:54.679Z,1726788354.679 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:25:58.307Z,1726788358.307 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:26:04.795Z,1726788364.795 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:26:11.635Z,1726788371.635 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T23:26:19.201Z,1726788379.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:26:24.175Z,1726788384.175 [NAL9602](INFO): SBD MO Status=1, MOMSN=17226, MT Status=0, MTMSN=0 2024-09-19T23:26:24.225Z,1726788384.225 [NAL9602](INFO): Sent 76 bytes from file Logs/20240919T212924/Courier0067.lzma 2024-09-19T23:26:24.226Z,1726788384.226 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:26:35.529Z,1726788395.529 [NAL9602](INFO): SBD MO Status=1, MOMSN=17227, MT Status=0, MTMSN=0 2024-09-19T23:26:35.582Z,1726788395.582 [NAL9602](INFO): Sent 140 bytes from file Logs/20240919T212924/Express0068.lzma 2024-09-19T23:26:35.582Z,1726788395.582 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:26:45.622Z,1726788405.622 [NAL9602](INFO): SBD MO Status=0, MOMSN=17228, MT Status=0, MTMSN=0 2024-09-19T23:26:45.716Z,1726788405.716 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:26:45.716Z,1726788405.716 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:26:45.716Z,1726788405.716 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:26:50.325Z,1726788410.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:27:16.324Z,1726788436.324 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:27:16.370Z,1726788436.370 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:27:21.465Z,1726788441.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:27:52.590Z,1726788472.590 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:28:23.721Z,1726788503.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:28:54.847Z,1726788534.847 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:29:25.969Z,1726788565.969 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:29:57.093Z,1726788597.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:30:28.229Z,1726788628.229 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:30:59.365Z,1726788659.365 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:31:30.501Z,1726788690.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:31:46.206Z,1726788706.206 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:31:46.206Z,1726788706.206 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:31:46.206Z,1726788706.206 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:31:46.206Z,1726788706.206 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:31:46.609Z,1726788706.609 [Default:CheckIn:D] Stopped 2024-09-19T23:31:46.609Z,1726788706.609 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:31:47.020Z,1726788707.020 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.046647 min 2024-09-19T23:31:47.020Z,1726788707.020 [Default:CheckIn:E] Stopped 2024-09-19T23:31:47.020Z,1726788707.020 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:31:47.020Z,1726788707.020 [Default:CheckIn] Stopped 2024-09-19T23:31:47.020Z,1726788707.020 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:31:47.020Z,1726788707.020 [Default:CheckIn](INFO): Running loop #20 2024-09-19T23:31:47.020Z,1726788707.020 [Default:CheckIn] Running Loop=20 2024-09-19T23:31:47.020Z,1726788707.020 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:31:47.021Z,1726788707.021 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:31:49.027Z,1726788709.027 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233148.00,A,3646.36840,N,12156.42925,W,24.084,256.00,190924,,,D*4D 2024-09-19T23:31:49.029Z,1726788709.029 [NAL9602](INFO): GPS fix at 20240919T233148: (36.772807, -121.940488) 2024-09-19T23:31:49.057Z,1726788709.057 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:31:49.057Z,1726788709.057 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:31:56.363Z,1726788716.363 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T23:32:01.625Z,1726788721.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:32:02.402Z,1726788722.402 [NAL9602](INFO): SBD MO Status=1, MOMSN=17229, MT Status=0, MTMSN=0 2024-09-19T23:32:02.453Z,1726788722.453 [NAL9602](INFO): Sent 75 bytes from file Logs/20240919T212924/Courier0070.lzma 2024-09-19T23:32:02.453Z,1726788722.453 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:32:12.138Z,1726788732.138 [NAL9602](INFO): SBD MO Status=1, MOMSN=17230, MT Status=0, MTMSN=0 2024-09-19T23:32:12.185Z,1726788732.185 [NAL9602](INFO): Sent 141 bytes from file Logs/20240919T212924/Express0071.lzma 2024-09-19T23:32:12.186Z,1726788732.186 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:32:20.623Z,1726788740.623 [NAL9602](INFO): SBD MO Status=0, MOMSN=17231, MT Status=0, MTMSN=0 2024-09-19T23:32:20.702Z,1726788740.702 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:32:20.702Z,1726788740.702 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:32:20.702Z,1726788740.702 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:32:32.757Z,1726788752.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:32:51.324Z,1726788771.324 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:32:51.346Z,1726788771.346 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:33:03.885Z,1726788783.885 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:33:35.045Z,1726788815.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:34:06.193Z,1726788846.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:34:37.317Z,1726788877.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:35:08.441Z,1726788908.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:35:39.565Z,1726788939.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:36:10.701Z,1726788970.701 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:36:41.833Z,1726789001.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:37:12.957Z,1726789032.957 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:37:21.205Z,1726789041.205 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:37:21.205Z,1726789041.205 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:37:21.205Z,1726789041.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:37:21.205Z,1726789041.205 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:37:21.611Z,1726789041.611 [Default:CheckIn:D] Stopped 2024-09-19T23:37:21.611Z,1726789041.611 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:37:22.021Z,1726789042.021 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.629997 min 2024-09-19T23:37:22.021Z,1726789042.021 [Default:CheckIn:E] Stopped 2024-09-19T23:37:22.021Z,1726789042.021 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:37:22.021Z,1726789042.021 [Default:CheckIn] Stopped 2024-09-19T23:37:22.021Z,1726789042.021 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:37:22.021Z,1726789042.021 [Default:CheckIn](INFO): Running loop #21 2024-09-19T23:37:22.021Z,1726789042.021 [Default:CheckIn] Running Loop=21 2024-09-19T23:37:22.022Z,1726789042.022 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:37:22.022Z,1726789042.022 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:37:24.026Z,1726789044.026 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233723.00,A,3645.85717,N,12159.09914,W,23.346,258.46,190924,,,D*44 2024-09-19T23:37:24.028Z,1726789044.028 [NAL9602](INFO): GPS fix at 20240919T233723: (36.764286, -121.984986) 2024-09-19T23:37:24.058Z,1726789044.058 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:37:24.058Z,1726789044.058 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:37:33.767Z,1726789053.767 [Reporter](INFO): NAL9602.sigQuality 3 count 2024-09-19T23:37:44.082Z,1726789064.082 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:37:47.913Z,1726789067.913 [NAL9602](INFO): SBD MO Status=2, MOMSN=17232, MT Status=2, MTMSN=0 2024-09-19T23:37:47.913Z,1726789067.913 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T23:37:51.991Z,1726789071.991 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:37:55.220Z,1726789075.220 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:38:02.900Z,1726789082.900 [Reporter](INFO): NAL9602.sigQuality 4 count 2024-09-19T23:38:15.205Z,1726789095.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:38:15.836Z,1726789095.836 [NAL9602](INFO): SBD MO Status=1, MOMSN=17232, MT Status=0, MTMSN=0 2024-09-19T23:38:15.885Z,1726789095.885 [NAL9602](INFO): Sent 76 bytes from file Logs/20240919T212924/Courier0073.lzma 2024-09-19T23:38:15.885Z,1726789095.885 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:38:25.993Z,1726789105.993 [NAL9602](INFO): SBD MO Status=1, MOMSN=17233, MT Status=0, MTMSN=0 2024-09-19T23:38:26.042Z,1726789106.042 [NAL9602](INFO): Sent 141 bytes from file Logs/20240919T212924/Express0074.lzma 2024-09-19T23:38:26.042Z,1726789106.042 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:38:35.275Z,1726789115.275 [NAL9602](INFO): SBD MO Status=0, MOMSN=17234, MT Status=0, MTMSN=0 2024-09-19T23:38:35.361Z,1726789115.361 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:38:35.361Z,1726789115.361 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:38:35.361Z,1726789115.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:38:46.329Z,1726789126.329 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:39:05.982Z,1726789145.982 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:39:06.003Z,1726789146.003 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:39:17.453Z,1726789157.453 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:39:48.577Z,1726789188.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:40:19.701Z,1726789219.701 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:40:50.826Z,1726789250.826 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:41:21.953Z,1726789281.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:41:53.077Z,1726789313.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:42:24.201Z,1726789344.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:42:55.325Z,1726789375.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:43:26.449Z,1726789406.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:43:35.859Z,1726789415.859 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:43:35.860Z,1726789415.860 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:43:35.860Z,1726789415.860 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:43:35.860Z,1726789415.860 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:43:36.280Z,1726789416.280 [Default:CheckIn:D] Stopped 2024-09-19T23:43:36.280Z,1726789416.280 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:43:36.663Z,1726789416.663 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.874479 min 2024-09-19T23:43:36.663Z,1726789416.663 [Default:CheckIn:E] Stopped 2024-09-19T23:43:36.663Z,1726789416.663 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:43:36.663Z,1726789416.663 [Default:CheckIn] Stopped 2024-09-19T23:43:36.663Z,1726789416.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:43:36.664Z,1726789416.664 [Default:CheckIn](INFO): Running loop #22 2024-09-19T23:43:36.664Z,1726789416.664 [Default:CheckIn] Running Loop=22 2024-09-19T23:43:36.664Z,1726789416.664 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:43:36.664Z,1726789416.664 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:43:38.688Z,1726789418.688 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234337.00,A,3645.32050,N,12202.02819,W,25.581,235.53,190924,,,D*44 2024-09-19T23:43:38.690Z,1726789418.690 [NAL9602](INFO): GPS fix at 20240919T234337: (36.755342, -122.033803) 2024-09-19T23:43:38.700Z,1726789418.700 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:43:38.700Z,1726789418.700 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:43:46.395Z,1726789426.395 [Reporter](INFO): NAL9602.sigQuality 1 count 2024-09-19T23:43:57.581Z,1726789437.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:43:59.335Z,1726789439.335 [NAL9602](INFO): SBD MO Status=2, MOMSN=17235, MT Status=2, MTMSN=0 2024-09-19T23:43:59.336Z,1726789439.336 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-09-19T23:44:03.809Z,1726789443.809 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T23:44:19.601Z,1726789459.601 [NAL9602](INFO): SBD MO Status=1, MOMSN=17235, MT Status=0, MTMSN=0 2024-09-19T23:44:19.649Z,1726789459.649 [NAL9602](INFO): Sent 76 bytes from file Logs/20240919T212924/Courier0076.lzma 2024-09-19T23:44:19.650Z,1726789459.650 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:44:28.705Z,1726789468.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:44:35.391Z,1726789475.391 [NAL9602](INFO): SBD MO Status=1, MOMSN=17236, MT Status=0, MTMSN=0 2024-09-19T23:44:35.453Z,1726789475.453 [NAL9602](INFO): Sent 141 bytes from file Logs/20240919T212924/Express0077.lzma 2024-09-19T23:44:35.453Z,1726789475.453 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:44:45.894Z,1726789485.894 [NAL9602](INFO): SBD MO Status=0, MOMSN=17237, MT Status=0, MTMSN=0 2024-09-19T23:44:45.985Z,1726789485.985 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:44:45.985Z,1726789485.985 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:44:45.985Z,1726789485.985 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:44:59.829Z,1726789499.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:45:16.600Z,1726789516.600 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:45:16.621Z,1726789516.621 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:45:30.957Z,1726789530.957 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:46:02.081Z,1726789562.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:46:33.245Z,1726789593.245 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:47:04.369Z,1726789624.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:47:35.501Z,1726789655.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:48:06.633Z,1726789686.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:48:37.777Z,1726789717.777 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:49:08.901Z,1726789748.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:49:40.025Z,1726789780.025 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:49:46.545Z,1726789786.545 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:49:46.545Z,1726789786.545 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:49:46.545Z,1726789786.545 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:49:46.545Z,1726789786.545 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:49:46.891Z,1726789786.891 [Default:CheckIn:D] Stopped 2024-09-19T23:49:46.891Z,1726789786.891 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:49:47.311Z,1726789787.311 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.051343 min 2024-09-19T23:49:47.311Z,1726789787.311 [Default:CheckIn:E] Stopped 2024-09-19T23:49:47.311Z,1726789787.311 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:49:47.311Z,1726789787.311 [Default:CheckIn] Stopped 2024-09-19T23:49:47.311Z,1726789787.311 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:49:47.311Z,1726789787.311 [Default:CheckIn](INFO): Running loop #23 2024-09-19T23:49:47.311Z,1726789787.311 [Default:CheckIn] Running Loop=23 2024-09-19T23:49:47.312Z,1726789787.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:49:47.312Z,1726789787.312 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:49:49.299Z,1726789789.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234948.00,A,3644.73133,N,12205.13484,W,22.840,256.41,190924,,,D*48 2024-09-19T23:49:49.301Z,1726789789.301 [NAL9602](INFO): GPS fix at 20240919T234948: (36.745522, -122.085581) 2024-09-19T23:49:49.331Z,1726789789.331 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:49:49.331Z,1726789789.331 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:49:59.444Z,1726789799.444 [Reporter](INFO): NAL9602.sigQuality 2 count 2024-09-19T23:50:11.149Z,1726789811.149 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:50:19.651Z,1726789819.651 [NAL9602](INFO): SBD MO Status=1, MOMSN=17238, MT Status=0, MTMSN=0 2024-09-19T23:50:19.709Z,1726789819.709 [NAL9602](INFO): Sent 76 bytes from file Logs/20240919T212924/Courier0079.lzma 2024-09-19T23:50:19.709Z,1726789819.709 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:50:27.793Z,1726789827.793 [NAL9602](INFO): SBD MO Status=1, MOMSN=17239, MT Status=0, MTMSN=0 2024-09-19T23:50:27.846Z,1726789827.846 [NAL9602](INFO): Sent 142 bytes from file Logs/20240919T212924/Express0080.lzma 2024-09-19T23:50:27.846Z,1726789827.846 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:50:35.871Z,1726789835.871 [NAL9602](INFO): SBD MO Status=0, MOMSN=17240, MT Status=0, MTMSN=0 2024-09-19T23:50:35.961Z,1726789835.961 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:50:35.973Z,1726789835.973 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:50:35.973Z,1726789835.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:50:42.273Z,1726789842.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:51:06.570Z,1726789866.570 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:51:06.622Z,1726789866.622 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:51:13.398Z,1726789873.398 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:51:44.531Z,1726789904.531 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:52:15.669Z,1726789935.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:52:28.991Z,1726789948.991 [BPC1](ERROR): Battery stick #60 (s/n: 045D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0xFFFF. 2024-09-19T23:52:28.991Z,1726789948.991 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2024-09-19T23:52:28.994Z,1726789948.994 [BPC1](INFO): Received data from all battery sticks. 2024-09-19T23:52:46.793Z,1726789966.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:53:17.929Z,1726789997.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:53:49.061Z,1726790029.061 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:54:20.185Z,1726790060.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:54:51.309Z,1726790091.309 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:55:22.437Z,1726790122.437 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:55:36.451Z,1726790136.451 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-19T23:55:36.451Z,1726790136.451 [Default:CheckIn:C.Wait] Stopped 2024-09-19T23:55:36.451Z,1726790136.451 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-19T23:55:36.451Z,1726790136.451 [Default:CheckIn:D] Running Loop=1 2024-09-19T23:55:36.855Z,1726790136.855 [Default:CheckIn:D] Stopped 2024-09-19T23:55:36.855Z,1726790136.855 [Default:CheckIn:E] Running Loop=1 2024-09-19T23:55:37.252Z,1726790137.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.884066 min 2024-09-19T23:55:37.252Z,1726790137.252 [Default:CheckIn:E] Stopped 2024-09-19T23:55:37.252Z,1726790137.252 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-19T23:55:37.252Z,1726790137.252 [Default:CheckIn] Stopped 2024-09-19T23:55:37.252Z,1726790137.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-19T23:55:37.252Z,1726790137.252 [Default:CheckIn](INFO): Running loop #24 2024-09-19T23:55:37.252Z,1726790137.252 [Default:CheckIn] Running Loop=24 2024-09-19T23:55:37.253Z,1726790137.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-19T23:55:37.253Z,1726790137.253 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-19T23:55:39.270Z,1726790139.270 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235538.00,A,3644.24180,N,12208.03203,W,21.479,266.53,190924,,,D*48 2024-09-19T23:55:39.272Z,1726790139.272 [NAL9602](INFO): GPS fix at 20240919T235538: (36.737363, -122.133867) 2024-09-19T23:55:39.281Z,1726790139.281 [Default:CheckIn:Read_GPS] Stopped 2024-09-19T23:55:39.281Z,1726790139.281 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-19T23:55:48.191Z,1726790148.191 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-19T23:55:53.561Z,1726790153.561 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:55:53.974Z,1726790153.974 [NAL9602](INFO): SBD MO Status=1, MOMSN=17241, MT Status=0, MTMSN=0 2024-09-19T23:55:54.033Z,1726790154.033 [NAL9602](INFO): Sent 89 bytes from file Logs/20240919T212924/Courier0082.lzma 2024-09-19T23:55:54.033Z,1726790154.033 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:56:05.443Z,1726790165.443 [NAL9602](INFO): SBD MO Status=1, MOMSN=17242, MT Status=0, MTMSN=0 2024-09-19T23:56:05.498Z,1726790165.498 [NAL9602](INFO): Sent 141 bytes from file Logs/20240919T212924/Express0083.lzma 2024-09-19T23:56:05.498Z,1726790165.498 [NAL9602](INFO): Packets left to send: 0 2024-09-19T23:56:15.549Z,1726790175.549 [NAL9602](INFO): SBD MO Status=0, MOMSN=17243, MT Status=0, MTMSN=0 2024-09-19T23:56:15.622Z,1726790175.622 [Default:CheckIn:Read_Iridium] Stopped 2024-09-19T23:56:15.622Z,1726790175.622 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-19T23:56:15.622Z,1726790175.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-19T23:56:24.689Z,1726790184.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:56:46.244Z,1726790206.244 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-19T23:56:46.286Z,1726790206.286 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-19T23:56:55.813Z,1726790215.813 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:57:26.937Z,1726790246.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:57:58.069Z,1726790278.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:58:29.193Z,1726790309.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:59:00.325Z,1726790340.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-19T23:59:31.449Z,1726790371.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:00:02.573Z,1726790402.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:00:33.697Z,1726790433.697 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:01:04.821Z,1726790464.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:01:16.126Z,1726790476.126 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-20T00:01:16.126Z,1726790476.126 [Default:CheckIn:C.Wait] Stopped 2024-09-20T00:01:16.126Z,1726790476.126 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-20T00:01:16.126Z,1726790476.126 [Default:CheckIn:D] Running Loop=1 2024-09-20T00:01:16.530Z,1726790476.530 [Default:CheckIn:D] Stopped 2024-09-20T00:01:16.530Z,1726790476.530 [Default:CheckIn:E] Running Loop=1 2024-09-20T00:01:16.935Z,1726790476.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.545329 min 2024-09-20T00:01:16.936Z,1726790476.936 [Default:CheckIn:E] Stopped 2024-09-20T00:01:16.936Z,1726790476.936 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-20T00:01:16.936Z,1726790476.936 [Default:CheckIn] Stopped 2024-09-20T00:01:16.936Z,1726790476.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-20T00:01:16.936Z,1726790476.936 [Default:CheckIn](INFO): Running loop #25 2024-09-20T00:01:16.936Z,1726790476.936 [Default:CheckIn] Running Loop=25 2024-09-20T00:01:16.936Z,1726790476.936 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-20T00:01:16.936Z,1726790476.936 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-20T00:01:18.952Z,1726790478.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000118.00,A,3644.11938,N,12210.62555,W,9.214,286.67,200924,,,D*79 2024-09-20T00:01:18.954Z,1726790478.954 [NAL9602](INFO): GPS fix at 20240920T000118: (36.735323, -122.177093) 2024-09-20T00:01:18.964Z,1726790478.964 [Default:CheckIn:Read_GPS] Stopped 2024-09-20T00:01:18.964Z,1726790478.964 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-20T00:01:23.834Z,1726790483.834 [Reporter](INFO): NAL9602.sigQuality 5 count 2024-09-20T00:01:33.541Z,1726790493.541 [NAL9602](INFO): SBD MO Status=1, MOMSN=17244, MT Status=0, MTMSN=0 2024-09-20T00:01:33.589Z,1726790493.589 [NAL9602](INFO): Sent 76 bytes from file Logs/20240919T212924/Courier0085.lzma 2024-09-20T00:01:33.590Z,1726790493.590 [NAL9602](INFO): Packets left to send: 0 2024-09-20T00:01:35.945Z,1726790495.945 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:01:46.107Z,1726790506.107 [NAL9602](INFO): SBD MO Status=1, MOMSN=17245, MT Status=0, MTMSN=0 2024-09-20T00:01:46.165Z,1726790506.165 [NAL9602](INFO): Sent 140 bytes from file Logs/20240919T212924/Express0086.lzma 2024-09-20T00:01:46.165Z,1726790506.165 [NAL9602](INFO): Packets left to send: 0 2024-09-20T00:01:53.387Z,1726790513.387 [NAL9602](INFO): SBD MO Status=0, MOMSN=17246, MT Status=0, MTMSN=0 2024-09-20T00:01:53.473Z,1726790513.473 [Default:CheckIn:Read_Iridium] Stopped 2024-09-20T00:01:53.473Z,1726790513.473 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-20T00:01:53.473Z,1726790513.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-20T00:02:07.077Z,1726790527.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:02:24.084Z,1726790544.084 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-20T00:02:24.134Z,1726790544.134 [Reporter](INFO): NAL9602.sigQuality 0 count 2024-09-20T00:02:38.210Z,1726790558.210 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:03:09.334Z,1726790589.334 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:03:40.457Z,1726790620.457 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:04:11.585Z,1726790651.585 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:04:42.713Z,1726790682.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:05:13.837Z,1726790713.837 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:05:44.969Z,1726790744.969 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:06:16.093Z,1726790776.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:06:47.217Z,1726790807.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-09-20T00:06:53.964Z,1726790813.964 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-20T00:06:53.964Z,1726790813.964 [Default:CheckIn:C.Wait] Stopped 2024-09-20T00:06:53.964Z,1726790813.964 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-20T00:06:53.964Z,1726790813.964 [Default:CheckIn:D] Running Loop=1 2024-09-20T00:06:54.365Z,1726790814.365 [Default:CheckIn:D] Stopped 2024-09-20T00:06:54.366Z,1726790814.366 [Default:CheckIn:E] Running Loop=1 2024-09-20T00:06:54.775Z,1726790814.775 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.175911 min 2024-09-20T00:06:54.775Z,1726790814.775 [Default:CheckIn:E] Stopped 2024-09-20T00:06:54.775Z,1726790814.775 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-20T00:06:54.775Z,1726790814.775 [Default:CheckIn] Stopped 2024-09-20T00:06:54.775Z,1726790814.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-20T00:06:54.776Z,1726790814.776 [Default:CheckIn](INFO): Running loop #26 2024-09-20T00:06:54.776Z,1726790814.776 [Default:CheckIn] Running Loop=26 2024-09-20T00:06:54.777Z,1726790814.777 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-20T00:06:54.778Z,1726790814.778 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-20T00:06:56.790Z,1726790816.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000656.00,A,3644.20249,N,12210.73959,W,0.914,38.09,200924,,,A*43 2024-09-20T00:06:56.793Z,1726790816.793 [NAL9602](INFO): GPS fix at 20240920T000656: (36.736708, -122.178993) 2024-09-20T00:06:56.814Z,1726790816.814 [Default:CheckIn:Read_GPS] Stopped 2024-09-20T00:06:56.814Z,1726790816.814 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-20T00:07:04.510Z,1726790824.510 [Reporter](INFO): NAL9602.sigQuality 4 count 2024-09-20T00:07:11.376Z,1726790831.376 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=17247, MT Status=1, MTMSN=4111 2024-09-20T00:07:11.429Z,1726790831.429 [NAL9602](INFO): Sent 74 bytes from file Logs/20240919T212924/Courier0088.lzma 2024-09-20T00:07:11.430Z,1726790831.430 [NAL9602](INFO): Packets left to send: 0 2024-09-20T00:07:11.865Z,1726790831.865 [NAL9602](INFO): Received command: restart logs